builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0135 starttime: 1464651357.29 results: success (0) buildid: 20160526140250 builduid: 01574422c30242cc92a6c0b32dab29da revision: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.289407) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.290413) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.290769) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.566603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.566962) ========= 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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 16:35:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 56.2M=0s 2016-05-30 16:35:58 (56.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.500795 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:58.093611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:58.093931) ========= 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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.020609 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:58.143141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:35:58.143565) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2ee4473c729acb2ba7dc723e7affe79ce14bff85 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2ee4473c729acb2ba7dc723e7affe79ce14bff85 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 16:35:58,218 truncating revision to first 12 chars 2016-05-30 16:35:58,218 Setting DEBUG logging. 2016-05-30 16:35:58,219 attempt 1/10 2016-05-30 16:35:58,219 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2ee4473c729a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 16:35:59,513 unpacking tar archive at: mozilla-beta-2ee4473c729a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.575968 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:35:59.741101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:59.741438) ========= script_repo_revision: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:59.741864) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:59.742179) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:59.761624) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2016-05-30 16:35:59.761960) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:35:59 INFO - MultiFileLogger online at 20160530 16:35:59 in /builds/slave/test 16:35:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:35:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:35:59 INFO - {'append_to_log': False, 16:35:59 INFO - 'base_work_dir': '/builds/slave/test', 16:35:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:35:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:35:59 INFO - 'branch': 'Mozilla-Beta', 16:35:59 INFO - 'buildbot_json_path': 'buildprops.json', 16:35:59 INFO - 'config_files': ('talos/mac_config.py',), 16:35:59 INFO - 'default_actions': ('clobber', 16:35:59 INFO - 'read-buildbot-config', 16:35:59 INFO - 'download-and-extract', 16:35:59 INFO - 'populate-webroot', 16:35:59 INFO - 'create-virtualenv', 16:35:59 INFO - 'install', 16:35:59 INFO - 'run-tests'), 16:35:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:35:59 INFO - 'download_minidump_stackwalk': True, 16:35:59 INFO - 'download_symbols': 'ondemand', 16:35:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:35:59 INFO - 'tooltool.py': '/tools/tooltool.py', 16:35:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:35:59 INFO - '/tools/misc-python/virtualenv.py')}, 16:35:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:35:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:35:59 INFO - 'installer_path': 'installer.exe', 16:35:59 INFO - 'log_level': 'info', 16:35:59 INFO - 'log_name': 'talos', 16:35:59 INFO - 'log_to_console': True, 16:35:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:35:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:35:59 INFO - 'opt_config_files': (), 16:35:59 INFO - 'pip_index': False, 16:35:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:35:59 INFO - 'cmd': ('bash', 16:35:59 INFO - '-c', 16:35:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:35:59 INFO - 'enabled': True, 16:35:59 INFO - 'halt_on_failure': False, 16:35:59 INFO - 'name': 'check_screen_resolution'},), 16:35:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:35:59 INFO - 'cmd': ('bash', 16:35:59 INFO - '-c', 16:35:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:35:59 INFO - 'enabled': True, 16:35:59 INFO - 'halt_on_failure': False, 16:35:59 INFO - 'name': 'check_screen_resolution'},), 16:35:59 INFO - 'run_cmd_checks_enabled': True, 16:35:59 INFO - 'sps_profile': False, 16:35:59 INFO - 'sps_profile_interval': 0, 16:35:59 INFO - 'suite': 'dromaeojs', 16:35:59 INFO - 'system_bits': '32', 16:35:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:35:59 INFO - 'title': 't-yosemite-r7-0135', 16:35:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:35:59 INFO - 'use_talos_json': True, 16:35:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:35:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:35:59 INFO - 'work_dir': 'build'} 16:35:59 INFO - ##### 16:35:59 INFO - ##### Running clobber step. 16:35:59 INFO - ##### 16:35:59 INFO - Running pre-action listener: _resource_record_pre_action 16:35:59 INFO - Running main action method: clobber 16:35:59 INFO - rmtree: /builds/slave/test/build 16:35:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:36:01 INFO - Running post-action listener: _resource_record_post_action 16:36:01 INFO - ##### 16:36:01 INFO - ##### Running read-buildbot-config step. 16:36:01 INFO - ##### 16:36:01 INFO - Running pre-action listener: _resource_record_pre_action 16:36:01 INFO - Running main action method: read_buildbot_config 16:36:01 INFO - Using buildbot properties: 16:36:01 INFO - { 16:36:01 INFO - "project": "", 16:36:01 INFO - "product": "firefox", 16:36:01 INFO - "script_repo_revision": "production", 16:36:01 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:36:01 INFO - "repository": "", 16:36:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:36:01 INFO - "buildid": "20160526140250", 16:36:01 INFO - "pgo_build": "False", 16:36:01 INFO - "basedir": "/builds/slave/test", 16:36:01 INFO - "buildnumber": 11, 16:36:01 INFO - "slavename": "t-yosemite-r7-0135", 16:36:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:36:01 INFO - "platform": "yosemite_r7", 16:36:01 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:36:01 INFO - "branch": "mozilla-beta", 16:36:01 INFO - "repo_path": "releases/mozilla-beta", 16:36:01 INFO - "revision": "2ee4473c729acb2ba7dc723e7affe79ce14bff85", 16:36:01 INFO - "stage_platform": "macosx64", 16:36:01 INFO - "builduid": "01574422c30242cc92a6c0b32dab29da", 16:36:01 INFO - "slavebuilddir": "test" 16:36:01 INFO - } 16:36:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:36:01 INFO - Running post-action listener: _resource_record_post_action 16:36:01 INFO - ##### 16:36:01 INFO - ##### Running download-and-extract step. 16:36:01 INFO - ##### 16:36:01 INFO - Running pre-action listener: _resource_record_pre_action 16:36:01 INFO - Running main action method: download_and_extract 16:36:01 INFO - mkdir: /builds/slave/test/build/tests 16:36:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:36:01 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:36:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:36:32 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json: timed out 16:36:32 INFO - retry: attempt #1 caught exception: timed out 16:36:32 INFO - retry: Failed, sleeping 30 seconds before retrying 16:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #2 16:37:11 INFO - Downloaded 1241 bytes. 16:37:11 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:37:11 INFO - Using the following test package requirements: 16:37:11 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:11 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:37:11 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:37:11 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:37:11 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:37:11 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:11 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:37:11 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:37:11 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:37:11 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:11 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:11 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:37:11 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:37:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:11 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:37:12 INFO - Downloaded 17816878 bytes. 16:37:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:15 INFO - Return code: 0 16:37:15 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:37:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:15 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:37:18 INFO - Downloaded 17816878 bytes. 16:37:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:21 INFO - Return code: 0 16:37:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:21 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:37:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:37:22 INFO - Downloaded 10845194 bytes. 16:37:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:37:23 INFO - Return code: 0 16:37:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:23 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:37:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:37:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:37:26 INFO - Downloaded 88466316 bytes. 16:37:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:26 INFO - mkdir: /builds/slave/test/properties 16:37:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:37:26 INFO - Writing to file /builds/slave/test/properties/build_url 16:37:26 INFO - Contents: 16:37:26 INFO - build_url:https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:27 INFO - Running post-action listener: _resource_record_post_action 16:37:27 INFO - Running post-action listener: set_extra_try_arguments 16:37:27 INFO - ##### 16:37:27 INFO - ##### Running populate-webroot step. 16:37:27 INFO - ##### 16:37:27 INFO - Running pre-action listener: _resource_record_pre_action 16:37:27 INFO - Running main action method: populate_webroot 16:37:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:37:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:37:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:37:27 INFO - '--tppagecycles', 16:37:27 INFO - '7'], 16:37:27 INFO - 'tests': ['tsvgm']}}, 16:37:27 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:37:27 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:37:27 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:37:27 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:37:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'tests': ['damp', 'tps']}, 16:37:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['damp', 'tps']}, 16:37:27 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:37:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:37:27 INFO - 'other': {'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'other_l64': {'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'other_nol64': {'tests': ['a11yr', 16:37:27 INFO - 'ts_paint', 16:37:27 INFO - 'tpaint', 16:37:27 INFO - 'sessionrestore', 16:37:27 INFO - 'sessionrestore_no_auto_restore', 16:37:27 INFO - 'tabpaint']}, 16:37:27 INFO - 'svgr': {'tests': ['tsvgx', 16:37:27 INFO - 'tsvgr_opacity', 16:37:27 INFO - 'tart', 16:37:27 INFO - 'tscrollx', 16:37:27 INFO - 'cart']}, 16:37:27 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['tsvgx', 16:37:27 INFO - 'tsvgr_opacity', 16:37:27 INFO - 'tart', 16:37:27 INFO - 'tscrollx', 16:37:27 INFO - 'cart']}, 16:37:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'tests': ['tp5o']}, 16:37:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'talos_options': ['--e10s'], 16:37:27 INFO - 'tests': ['tp5o']}, 16:37:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'talos_options': ['--xperf_path', 16:37:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:27 INFO - 'tests': ['tp5n']}, 16:37:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:27 INFO - 'talos_options': ['--e10s', 16:37:27 INFO - '--xperf_path', 16:37:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:27 INFO - 'tests': ['tp5n']}}, 16:37:27 INFO - 'talos.zip': {'path': '', 16:37:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:37:27 INFO - Running post-action listener: _resource_record_post_action 16:37:27 INFO - ##### 16:37:27 INFO - ##### Running create-virtualenv step. 16:37:27 INFO - ##### 16:37:27 INFO - Running pre-action listener: _resource_record_pre_action 16:37:27 INFO - Running main action method: create_virtualenv 16:37:27 INFO - Creating virtualenv /builds/slave/test/build/venv 16:37:27 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:37:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:37:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:37:27 INFO - Using real prefix '/tools/python27' 16:37:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:37:28 INFO - Installing distribute.............................................................................................................................................................................................done. 16:37:31 INFO - Installing pip.................done. 16:37:31 INFO - Return code: 0 16:37:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:31 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:37:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:31 INFO - 'HOME': '/Users/cltbld', 16:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:31 INFO - 'LOGNAME': 'cltbld', 16:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:31 INFO - 'MOZ_NO_REMOTE': '1', 16:37:31 INFO - 'NO_EM_RESTART': '1', 16:37:31 INFO - 'PAGER': '/bin/cat', 16:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:31 INFO - 'PWD': '/builds/slave/test', 16:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:31 INFO - 'SHELL': '/bin/bash', 16:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:31 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:31 INFO - 'USER': 'cltbld', 16:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:31 INFO - 'XPC_FLAGS': '0x0', 16:37:31 INFO - 'XPC_SERVICE_NAME': '0', 16:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:31 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:37:31 INFO - Cleaning up... 16:37:31 INFO - Return code: 0 16:37:31 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:31 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:31 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 16:37:31 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 16:37:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:31 INFO - 'HOME': '/Users/cltbld', 16:37:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:31 INFO - 'LOGNAME': 'cltbld', 16:37:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:31 INFO - 'MOZ_NO_REMOTE': '1', 16:37:31 INFO - 'NO_EM_RESTART': '1', 16:37:31 INFO - 'PAGER': '/bin/cat', 16:37:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:31 INFO - 'PWD': '/builds/slave/test', 16:37:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:31 INFO - 'SHELL': '/bin/bash', 16:37:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:31 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:31 INFO - 'USER': 'cltbld', 16:37:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:31 INFO - 'XPC_FLAGS': '0x0', 16:37:31 INFO - 'XPC_SERVICE_NAME': '0', 16:37:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:31 INFO - Downloading/unpacking psutil>=0.7.1 16:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:37:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:34 INFO - Installing collected packages: psutil 16:37:34 INFO - Running setup.py install for psutil 16:37:34 INFO - building 'psutil._psutil_osx' extension 16:37:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:37:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:37:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:37:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:37:35 INFO - building 'psutil._psutil_posix' extension 16:37:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:37:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:37:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:37:35 INFO - ^ 16:37:35 INFO - 1 warning generated. 16:37:35 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:37:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:37:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:37:35 INFO - Successfully installed psutil 16:37:35 INFO - Cleaning up... 16:37:35 INFO - Return code: 0 16:37:35 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:35 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:35 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 16:37:35 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 16:37:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:35 INFO - 'HOME': '/Users/cltbld', 16:37:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:35 INFO - 'LOGNAME': 'cltbld', 16:37:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:35 INFO - 'MOZ_NO_REMOTE': '1', 16:37:35 INFO - 'NO_EM_RESTART': '1', 16:37:35 INFO - 'PAGER': '/bin/cat', 16:37:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:35 INFO - 'PWD': '/builds/slave/test', 16:37:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:35 INFO - 'SHELL': '/bin/bash', 16:37:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:35 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:35 INFO - 'USER': 'cltbld', 16:37:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:35 INFO - 'XPC_FLAGS': '0x0', 16:37:35 INFO - 'XPC_SERVICE_NAME': '0', 16:37:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:38 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:37:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:37:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:37:38 INFO - Installing collected packages: mozsystemmonitor 16:37:38 INFO - Running setup.py install for mozsystemmonitor 16:37:38 INFO - Successfully installed mozsystemmonitor 16:37:38 INFO - Cleaning up... 16:37:38 INFO - Return code: 0 16:37:38 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:38 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:37:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:37:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:38 INFO - 'HOME': '/Users/cltbld', 16:37:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:38 INFO - 'LOGNAME': 'cltbld', 16:37:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:38 INFO - 'MOZ_NO_REMOTE': '1', 16:37:38 INFO - 'NO_EM_RESTART': '1', 16:37:38 INFO - 'PAGER': '/bin/cat', 16:37:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:38 INFO - 'PWD': '/builds/slave/test', 16:37:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:38 INFO - 'SHELL': '/bin/bash', 16:37:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:38 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:38 INFO - 'USER': 'cltbld', 16:37:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:38 INFO - 'XPC_FLAGS': '0x0', 16:37:38 INFO - 'XPC_SERVICE_NAME': '0', 16:37:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:38 INFO - Downloading/unpacking blobuploader==1.2.4 16:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:41 INFO - Downloading blobuploader-1.2.4.tar.gz 16:37:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:37:41 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:37:42 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:42 INFO - Downloading docopt-0.6.1.tar.gz 16:37:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:37:42 INFO - Installing collected packages: blobuploader, requests, docopt 16:37:42 INFO - Running setup.py install for blobuploader 16:37:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:37:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:37:42 INFO - Running setup.py install for requests 16:37:43 INFO - Running setup.py install for docopt 16:37:43 INFO - Successfully installed blobuploader requests docopt 16:37:43 INFO - Cleaning up... 16:37:43 INFO - Return code: 0 16:37:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37: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')]} 16:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37: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')]} 16:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:43 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:37:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:43 INFO - 'HOME': '/Users/cltbld', 16:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:43 INFO - 'LOGNAME': 'cltbld', 16:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:43 INFO - 'MOZ_NO_REMOTE': '1', 16:37:43 INFO - 'NO_EM_RESTART': '1', 16:37:43 INFO - 'PAGER': '/bin/cat', 16:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:43 INFO - 'PWD': '/builds/slave/test', 16:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:43 INFO - 'SHELL': '/bin/bash', 16:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:43 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:43 INFO - 'USER': 'cltbld', 16:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:43 INFO - 'XPC_FLAGS': '0x0', 16:37:43 INFO - 'XPC_SERVICE_NAME': '0', 16:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-1C8YSN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:43 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-nhINyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Yi0lQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-iHHNux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Q6Tupc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-IJcvpk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-rFsu2x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-HdNMNX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-kpj60p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-vmTvY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:44 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-68jur7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-NeQThn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-JF7qyn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-hBneQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-t3N28B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Np8GAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:45 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-hO42iI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:45 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:37:45 INFO - Running setup.py install for manifestparser 16:37:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:37:45 INFO - Running setup.py install for mozcrash 16:37:45 INFO - Running setup.py install for mozdebug 16:37:46 INFO - Running setup.py install for mozdevice 16:37:46 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Running setup.py install for mozfile 16:37:46 INFO - Running setup.py install for mozhttpd 16:37:46 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Running setup.py install for mozinfo 16:37:46 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Running setup.py install for mozInstall 16:37:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:37:46 INFO - Running setup.py install for mozleak 16:37:46 INFO - Running setup.py install for mozlog 16:37:47 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Running setup.py install for moznetwork 16:37:47 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Running setup.py install for mozprocess 16:37:47 INFO - Running setup.py install for mozprofile 16:37:47 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Running setup.py install for mozrunner 16:37:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:37:47 INFO - Running setup.py install for mozscreenshot 16:37:47 INFO - Running setup.py install for moztest 16:37:48 INFO - Running setup.py install for mozversion 16:37:48 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:37:48 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:37:48 INFO - Cleaning up... 16:37:48 INFO - Return code: 0 16:37:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:48 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:37:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:37:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:48 INFO - 'HOME': '/Users/cltbld', 16:37:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:48 INFO - 'LOGNAME': 'cltbld', 16:37:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:48 INFO - 'MOZ_NO_REMOTE': '1', 16:37:48 INFO - 'NO_EM_RESTART': '1', 16:37:48 INFO - 'PAGER': '/bin/cat', 16:37:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:48 INFO - 'PWD': '/builds/slave/test', 16:37:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:48 INFO - 'SHELL': '/bin/bash', 16:37:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:48 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:48 INFO - 'USER': 'cltbld', 16:37:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:48 INFO - 'XPC_FLAGS': '0x0', 16:37:48 INFO - 'XPC_SERVICE_NAME': '0', 16:37:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:37:48 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-Iiy5Ij-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:37:48 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-zitZns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:37:48 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-ZrGab7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:37:48 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-OJmMYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:37:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:37:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:37:48 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-B850bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-l48jGz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-QYTV9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-D3V00g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-h7CeiY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-WnP9sS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:37:49 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)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-HhqArc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-RC8sUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:37:49 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)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-W0xAvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:37:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:37:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:37:49 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-CmbBUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:37:50 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)) 16:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:50 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-fNq99F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:37:50 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-8j3EMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:37:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:37:50 INFO - Running setup.py (path:/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/pip-018jXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:37:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:37:50 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:52 INFO - Downloading blessings-1.6.tar.gz 16:37:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:37:52 INFO - Installing collected packages: blessings 16:37:52 INFO - Running setup.py install for blessings 16:37:53 INFO - Successfully installed blessings 16:37:53 INFO - Cleaning up... 16:37:53 INFO - Return code: 0 16:37:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:37:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:37:53 INFO - Reading from file tmpfile_stdout 16:37:53 INFO - Current package versions: 16:37:53 INFO - blessings == 1.6 16:37:53 INFO - blobuploader == 1.2.4 16:37:53 INFO - docopt == 0.6.1 16:37:53 INFO - manifestparser == 1.1 16:37:53 INFO - mozInstall == 1.12 16:37:53 INFO - mozcrash == 0.17 16:37:53 INFO - mozdebug == 0.1 16:37:53 INFO - mozdevice == 0.48 16:37:53 INFO - mozfile == 1.2 16:37:53 INFO - mozhttpd == 0.7 16:37:53 INFO - mozinfo == 0.9 16:37:53 INFO - mozleak == 0.1 16:37:53 INFO - mozlog == 3.1 16:37:53 INFO - moznetwork == 0.27 16:37:53 INFO - mozprocess == 0.22 16:37:53 INFO - mozprofile == 0.28 16:37:53 INFO - mozrunner == 6.11 16:37:53 INFO - mozscreenshot == 0.1 16:37:53 INFO - mozsystemmonitor == 0.0 16:37:53 INFO - moztest == 0.7 16:37:53 INFO - mozversion == 1.4 16:37:53 INFO - psutil == 3.1.1 16:37:53 INFO - requests == 1.2.3 16:37:53 INFO - wsgiref == 0.1.2 16:37:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:53 INFO - 'HOME': '/Users/cltbld', 16:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:53 INFO - 'LOGNAME': 'cltbld', 16:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:53 INFO - 'MOZ_NO_REMOTE': '1', 16:37:53 INFO - 'NO_EM_RESTART': '1', 16:37:53 INFO - 'PAGER': '/bin/cat', 16:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:53 INFO - 'PWD': '/builds/slave/test', 16:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:53 INFO - 'SHELL': '/bin/bash', 16:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:53 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:53 INFO - 'USER': 'cltbld', 16:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:53 INFO - 'XPC_FLAGS': '0x0', 16:37:53 INFO - 'XPC_SERVICE_NAME': '0', 16:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:37:53 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:37:53 INFO - Cleaning up... 16:37:53 INFO - Return code: 0 16:37:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:37:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:37:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:53 INFO - 'HOME': '/Users/cltbld', 16:37:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:53 INFO - 'LOGNAME': 'cltbld', 16:37:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:53 INFO - 'MOZ_NO_REMOTE': '1', 16:37:53 INFO - 'NO_EM_RESTART': '1', 16:37:53 INFO - 'PAGER': '/bin/cat', 16:37:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:53 INFO - 'PWD': '/builds/slave/test', 16:37:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:53 INFO - 'SHELL': '/bin/bash', 16:37:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:53 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:53 INFO - 'USER': 'cltbld', 16:37:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:53 INFO - 'XPC_FLAGS': '0x0', 16:37:53 INFO - 'XPC_SERVICE_NAME': '0', 16:37:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:53 INFO - Downloading/unpacking jsonschema 16:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:37:56 INFO - Downloading/unpacking functools32 (from jsonschema) 16:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:56 INFO - Downloading functools32-3.2.3-2.tar.gz 16:37:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:37:56 INFO - warning: no files found matching '*.txt' 16:37:56 INFO - no previously-included directories found matching 'build' 16:37:56 INFO - no previously-included directories found matching 'dist' 16:37:56 INFO - no previously-included directories found matching '.git*' 16:37:56 INFO - Installing collected packages: jsonschema, functools32 16:37:56 INFO - Running setup.py install for functools32 16:37:57 INFO - warning: no files found matching '*.txt' 16:37:57 INFO - no previously-included directories found matching 'build' 16:37:57 INFO - no previously-included directories found matching 'dist' 16:37:57 INFO - no previously-included directories found matching '.git*' 16:37:57 INFO - Successfully installed jsonschema functools32 16:37:57 INFO - Cleaning up... 16:37:57 INFO - Return code: 0 16:37:57 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:37:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:37:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:37:57 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 0x1042ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043064c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90a2ca01a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, '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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:37:57 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 16:37:57 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 16:37:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:37:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:37:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:37:57 INFO - 'HOME': '/Users/cltbld', 16:37:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:37:57 INFO - 'LOGNAME': 'cltbld', 16:37:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:37:57 INFO - 'MOZ_NO_REMOTE': '1', 16:37:57 INFO - 'NO_EM_RESTART': '1', 16:37:57 INFO - 'PAGER': '/bin/cat', 16:37:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:37:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:37:57 INFO - 'PWD': '/builds/slave/test', 16:37:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:37:57 INFO - 'SHELL': '/bin/bash', 16:37:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:37:57 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:37:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:37:57 INFO - 'USER': 'cltbld', 16:37:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:37:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:37:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:37:57 INFO - 'XPC_FLAGS': '0x0', 16:37:57 INFO - 'XPC_SERVICE_NAME': '0', 16:37:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:37:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:37:57 INFO - Downloading/unpacking flake8 16:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:59 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:37:59 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:37:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:37:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:37:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:00 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:38:00 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:00 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:38:01 INFO - no previously-included directories found matching 'docs/_build' 16:38:01 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:38:01 INFO - Running setup.py install for pep8 16:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:38:01 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:38:01 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:38:01 INFO - no previously-included directories found matching 'docs/_build' 16:38:01 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:38:01 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:38:01 INFO - Cleaning up... 16:38:01 INFO - Return code: 0 16:38:01 INFO - Running post-action listener: _resource_record_post_action 16:38:01 INFO - Running post-action listener: _start_resource_monitoring 16:38:01 INFO - Starting resource monitoring. 16:38:01 INFO - ##### 16:38:01 INFO - ##### Running install step. 16:38:01 INFO - ##### 16:38:01 INFO - Running pre-action listener: _resource_record_pre_action 16:38:01 INFO - Running main action method: install 16:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:01 INFO - Reading from file tmpfile_stdout 16:38:01 INFO - Detecting whether we're running mozinstall >=1.0... 16:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:38:01 INFO - Reading from file tmpfile_stdout 16:38:01 INFO - Output received: 16:38:01 INFO - Usage: mozinstall [options] installer 16:38:01 INFO - Options: 16:38:01 INFO - -h, --help show this help message and exit 16:38:01 INFO - -d DEST, --destination=DEST 16:38:01 INFO - Directory to install application into. [default: 16:38:01 INFO - "/builds/slave/test"] 16:38:01 INFO - --app=APP Application being installed. [default: firefox] 16:38:01 INFO - mkdir: /builds/slave/test/build/application 16:38:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:38:24 INFO - Reading from file tmpfile_stdout 16:38:24 INFO - Output received: 16:38:24 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:38:24 INFO - Running post-action listener: _resource_record_post_action 16:38:24 INFO - ##### 16:38:24 INFO - ##### Running run-tests step. 16:38:24 INFO - ##### 16:38:24 INFO - Running pre-action listener: _resource_record_pre_action 16:38:24 INFO - Running main action method: run_tests 16:38:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:24 INFO - 2016-05-30 16:38:24.253 screenresolution[1934:20453] starting screenresolution argv=screenresolution get 16:38:24 INFO - 2016-05-30 16:38:24.255 screenresolution[1934:20453] Display 0: 1600x1200x32@60 16:38:24 INFO - 2016-05-30 16:38:24.261 screenresolution[1935:20456] starting screenresolution argv=screenresolution list 16:38:24 INFO - Available Modes on Display 0 16:38:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:24 INFO - Intel Iris: 16:38:24 INFO - Chipset Model: Intel Iris 16:38:24 INFO - Type: GPU 16:38:24 INFO - Bus: Built-In 16:38:24 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:24 INFO - Vendor: Intel (0x8086) 16:38:24 INFO - Device ID: 0x0a2e 16:38:24 INFO - Revision ID: 0x0009 16:38:24 INFO - Displays: 16:38:24 INFO - Display: 16:38:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:24 INFO - Display Serial Number: 1600x1200 60 16:38:24 INFO - Main Display: Yes 16:38:24 INFO - Mirror: Off 16:38:24 INFO - Online: Yes 16:38:24 INFO - Rotation: Supported 16:38:24 INFO - Return code: 0 16:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:38:24 INFO - Python 2.7.3 16:38:24 INFO - Return code: 0 16:38:24 INFO - grabbing minidump binary from tooltool 16:38:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:38:24 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 0x1041a3d30>, '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 0x104007db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10419ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:38:24 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 16:38:24 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 16:38:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:38:24 INFO - Return code: 0 16:38:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:38:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:38:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:38:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:38:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:38:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:38:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:38:25 INFO - Return code: 0 16:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 16:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0135 --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 16:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.K56t7Lvzsb/Render', 16:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:25 INFO - 'HOME': '/Users/cltbld', 16:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:25 INFO - 'LOGNAME': 'cltbld', 16:38:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:38:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:25 INFO - 'MOZ_NO_REMOTE': '1', 16:38:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:38:25 INFO - 'NO_EM_RESTART': '1', 16:38:25 INFO - 'PAGER': '/bin/cat', 16:38:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:25 INFO - 'PWD': '/builds/slave/test', 16:38:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:25 INFO - 'SHELL': '/bin/bash', 16:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IKNO2kV0Qp/Listeners', 16:38:25 INFO - 'TMPDIR': '/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/', 16:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:25 INFO - 'USER': 'cltbld', 16:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:25 INFO - 'XPC_FLAGS': '0x0', 16:38:25 INFO - 'XPC_SERVICE_NAME': '0', 16:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0135', '--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 16:38:26 INFO - mozversion application_buildid: 20160526140250 16:38:26 INFO - mozversion application_changeset: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 16:38:26 INFO - mozversion application_display_name: Firefox 16:38:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:38:26 INFO - mozversion application_name: Firefox 16:38:26 INFO - mozversion application_remotingname: firefox 16:38:26 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:38:26 INFO - mozversion application_vendor: Mozilla 16:38:26 INFO - mozversion application_version: 47.0 16:38:26 INFO - mozversion platform_buildid: 20160526140250 16:38:26 INFO - mozversion platform_changeset: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 16:38:26 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:38:26 INFO - mozversion platform_version: 47.0 16:38:26 INFO - using testdate: 1464651506 16:38:26 INFO - actual date: 1464651506 16:38:26 INFO - starting webserver on 'localhost:52625' 16:38:26 INFO - SUITE-START | Running 2 tests 16:38:26 INFO - TEST-START | dromaeo_css 16:38:26 INFO - operating with platform_type : mac_ 16:38:26 INFO - Initialising browser for dromaeo_css test... 16:38:26 INFO - TEST-INFO | started process 1953 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpaQq3bB/profile http://localhost:52625/getInfo.html) 16:38:28 INFO - PROCESS | 1953 | __metrics Screen width/height:1600/1200 16:38:28 INFO - PROCESS | 1953 | colorDepth:24 16:38:28 INFO - PROCESS | 1953 | Browser inner width/height: 1024/689 16:38:28 INFO - PROCESS | 1953 | __metrics 16:38:28 INFO - PROCESS | 1953 | 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" 16:38:28 INFO - PROCESS | 1953 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:38:28 INFO - TEST-INFO | 1953: exit 0 16:38:28 INFO - Browser initialized. 16:38:28 INFO - Running cycle 1/1 for dromaeo_css test... 16:38:28 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmpaQq3bB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:38:40 INFO - PROCESS | 1956 | RSS: Main: 156901376 16:38:40 INFO - PROCESS | 1956 | 16:39:31 INFO - PROCESS | 1956 | 1464651571746 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] 16:41:41 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52625/tests/dromaeo/cssquery-ext.html) 16:41:43 INFO - PROCESS | 1956 | RSS: Main: 278827008 16:41:43 INFO - PROCESS | 1956 | 16:44:45 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-ext.html (next: http://localhost:52625/tests/dromaeo/cssquery-jquery.html) 16:44:45 INFO - PROCESS | 1956 | RSS: Main: 292106240 16:44:45 INFO - PROCESS | 1956 | 16:47:47 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52625/tests/dromaeo/cssquery-mootools.html) 16:47:48 INFO - PROCESS | 1956 | RSS: Main: 296984576 16:47:48 INFO - PROCESS | 1956 | 16:50:50 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52625/tests/dromaeo/cssquery-prototype.html) 16:50:50 INFO - PROCESS | 1956 | RSS: Main: 256106496 16:50:50 INFO - PROCESS | 1956 | 16:53:53 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52625/tests/dromaeo/cssquery-yui.html) 16:53:54 INFO - PROCESS | 1956 | RSS: Main: 257282048 16:53:54 INFO - PROCESS | 1956 | 16:56:56 INFO - PROCESS | 1956 | Cycle 1(1): loaded http://localhost:52625/tests/dromaeo/cssquery-yui.html (next: http://localhost:52625/tests/dromaeo/cssquery-dojo.html) 16:56:57 INFO - PROCESS | 1956 | RSS: Main: 303902720 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | __start_tp_report 16:56:57 INFO - PROCESS | 1956 | _x_x_mozilla_page_load 16:56:57 INFO - PROCESS | 1956 | _x_x_mozilla_page_load_details 16:56:57 INFO - PROCESS | 1956 | |i|pagename|runs| 16:56:57 INFO - PROCESS | 1956 | |0;dojo.html;3548.858045678173;3600.081979095332;3615.1685112424157;3635.8545658173684;3650.926981460371;5892.834859866728;5917.792877249297;5936.228290322258;5940.435658612436;5995.460408563228;5882.058870580708;5925.614835035731;5940.435658612436;5944.138099975501;5948.643081415118;5174.844754657366;5277.102892508283;5319.494648008433;5364.865878353053;5405.5135037846585;5398.379186393556;5421.9457805422035;5430.8913821723545;5442.619016668835;5444.265024221719;5298.708571028592;5342.171963345688;5382.1926710993275;5388.703621300827;5429.267048948401;5393.6763794172275;5395.676259424408;5418.160185171305;5427.864303392407;5481.643693159932;5140.023395554833;5323.920141197885;5331.813386531491;5341.786328546854;5368.812091576776;5648.265725455705;5685.630434021776;5691.630044047165;5706.71466426677;5728.627639203439;6200.441960223557;6341.080543321193;6361.459275961549;6369.8407539811415;6377.043443483468;6312.337204593543;6313.9368606314265;6319.905201421982;6343.809685709436;6343.904841427382;5910;5937.762489500415;6046;6055.364186760415;6056.576039677268;6321.1150438938585;6340.017297318923;6342.556021078481;6346.30190679025;6371.171747672773;6286;6326.715297811609;6328.367163283635;6341.746330146835;6345.555811093272;6151.231096112986;6236.095766113888;6259.7496100156395;6288.276848162428;6324.494040476751;6316.368363163647;6325.367463253638;6333.145025421509;6341.302456729756;6359.841003974938;6219.440250377488;6354.809355719336;6360.9045864312075;6364.172657554488;6367.299597044322;4978.601711863114;5002.2496625506465;5009.348784658044;5011.123053465629;5011.1481048221885;4373.803178856895;4527.162474942146;4547.386102876136;4563.612092972068;4564.657650676146;5926.585139040312;5950.821475355746;5951.077582974646;5970.522358211333;5997.730102145414;6240.375962403814;6240.687965601793;6268.4671802897265;6271.9686401568615;6275.3724627536885;5619.072852979294;5795.304563452329;5894.233749612523;5928.9407105928385;5935.940640593625;5453.400125986138;5481.671099734029;5485.61600687948;5490.945090549123;5526.530244929146;6330.778422755181;6356.4279214870885;6387.680615969183;6389.424951754364;6392.296847346881;6246.219222597176;6263.342349053284;6274.309825919237;6346.4605508532295;6352.936470635419;5907.2911250650195;5929.644221346733;6370.1718776558755;6399.840003999937;6411;6331.746730130743;6337.081123237012;6344.555881088464;6345.2702939160745;6351.745930162741;6237.313895471585;6241.126242325987;6263.404976527223;6294.748210071729;6300.716467759054;6250.749970001149;6256.499480041677;6277.686115694106;6289.27673317556;6289.465395441439;6284.622922624657;6287.1512345832725;6289.371062893674;6301.6534090625455;6309.432151106423;5929.140274660236;6041;6120.44915957566;6123.08153776937;6130.398136742091;6070.332263451105;6165.198524191826;6173.459494130334;6175.59858609189;6188.0717892314715;6013.819585412444;6050.606710563799;6061.060535616986;6076.51387888959;6097.99383101801 16:56:57 INFO - PROCESS | 1956 | |1;ext.html;531.2880739808655;586.3608666553456;595.3689089565064;661.3849120318105;668.5387082912791;31051.68948310516;40108.19783604326;40128.59871401285;40133.39799903;40343.59656403435;43633.78183109088;44410;44417.33373999393;44477.332840007424;44568.554314456844;9702.369345992505;9926.702198934008;9926.801463970716;9929.801403971916;9949.403035817837;9633.66282180124;9799.559019844124;9805.803883922317;9812;9914.454704991223;9748.610055597803;9871.555779989918;9922.751931201705;9940.602375904955;9944.751381215456;9794.069563391466;9893.4558599277;9901.504924753734;9903;9923.503824808768;9785.315027948047;9851.014898510091;9925.602975880956;9929.90070099297;9941.254405919584;42703.57296427027;43183.78408107939;43207.56792432067;43219.56780432187;43324;3300.4224260754513;3414.992577189735;3466.30673865228;3577.678008979204;3937.2125574885135;28716;29601.703982960327;29609.40781184386;29793.616510679094;30092.8495357524;2479.789217916479;2746.6429364182527;2766.9446611067665;2821.5908693239367;2852.87162077707;477879;479306.60346698394;480270;480920;484111;46866.53133468622;48260.27609585859;68592;68857.3114268861;69335.3066469339;47120;47442.525574744504;47550.81122971884;47620;47711.28433073506;17048.48854534341;17187.742183867253;17245.310187592608;17251.878627889146;17327.393541225996;13657.112287701268;13705.931470342784;13719.245441500614;13721.725565488634;13724.65688357779;15511.301991410413;15527.301271442811;15533.533993979972;15558.844411555965;15626.921865390601;6432.903506447407;6576.934230657728;6591.769288074895;6596.10952521413;6601.471882249409;1021.0351218098974;1068.8610480637624;1081.7674200047027;1085.218642577343;1128.5993472317373;30399.392012160075;30415.695843041285;30881.382372352426;31037.68962310348;31059.378812423623;1136.153565593638;1162.505934977648;1195.6891208285733;1235.4502246500401;1254.5985284708988;13846.653833654038;16251.943623664423;16354.672906541802;16545.25546350417;16630.835841490978;533323;805106;805748;806555;811361;62938.37061629417;63228;63398.36601633925;63419;63430.365696342444;1430.7925350824073;1560.5630423481443;1574.157825563318;1585.888987770868;1705.4968784208688;26719.866400667874;28297.434051318447;29918.85040574783;30593.388132237676;30853.228669283446;46747.76626116848;47087.99752021117;47238.291425627955;47295.29057064216;47315.52684473249;391620;397702;403564;403900;405435;32315.19212019718;32936.67063329336;33130.5030424539;33541.83229083839;33603.83198083994;43796.34305485484;44007;44426.77786611046;44912.55087449084;45615.54384456113;30416.543751844183;36699;36845.44731828971;37536.2492750138;38461.423078653286;36498.635013649524;36734.63265367312;36742.8162859184;37029.81485092557;37458.812705936296;36430.27139457143;36704.81647591852;37483.625163748016;37544.43683344807;37614;28385.260635110186;31410.84294578604;31787.68212317847;32859.83570082134;32937.84717534875;24004.87997560001;25067.87194576212;25183.496330073664;25219.621705674796;25410.74589254084 16:56:57 INFO - PROCESS | 1956 | |2;jquery.html;382.44927304681255;383.2411824587317;383.5186840515152;388.04540829559284;392.39571060566726;19478.607958089062;21345.573088538218;22346.88826555867;22511.324660260198;22549.66175507369;22103.115875364965;22188.77811221887;22217.888910555426;22219.555608887855;22534.211302604414;5834.416558344164;5861.3552509223955;6086.239220097488;6176.135341052234;6181.412765787266;6071.3625069367745;6110.725017374229;6113.960626693451;6129.509639228873;6133.233345831771;5197.636165468419;5802.477777000069;6074.271087469509;6083.117947897552;6085.417791374252;5125.820596279131;5809.6804675742815;6013.909791353133;6044.425779550936;6060;5974.880502389928;5993.400659933972;6012.939870601282;6016.097585362187;6023.307319658208;13925.303734813218;14162.858371416258;18605.534861628566;25900.611490827654;25905;2156.482444213393;2256.763039880805;2366.0654041653625;2535.1887396033276;2674.2378422149595;9892.95053524735;9910.405375677483;10495.527701253386;10563.947180264126;13072.477100915952;1571.9842801571951;1751.2382113780545;1815.618720068787;1855.434092601756;1892.1958167778776;86099.55601775984;104170;109538;110205;110750.44624776905;9202;11661.76676466483;11676.591319303783;11717.12121590884;11803.291802491878;14583.927080364743;14606.9775115743;14639.341229644691;14677.266136693122;14687.045342052732;9063.909360906306;9109;9132.406393584395;9136.406133601295;9198.126178013077;9310.53447327631;9334.71995840126;9336.626534938663;9378.43729376226;9489;4507.639388848885;4578.839740609063;4586.4725556561425;4616.307553866947;4656.790444430008;1631.9347226110822;1648.4312912045325;1874.6063326701546;1923.5768131011155;1931.1020375525447;895.2658819767728;963.2294164668237;980.6077568972325;997.5062344139552;1078.229066217658;20029.899850500944;20364.28724994618;20850.47873803137;20970.580588388148;21336.359909202747;991.6265971629201;1009.5860697114148;1036.8652075230173;1049.7533079726259;1069.25152393325;11077.390743509184;11327.093832493381;11374.431278436045;11376.203665743318;11403.714907127389;673375;678639;681695;683574;687308;97118;110417.44791276153;114366;121929;125706;984.242133557157;1015.3653966271081;1016.7051555049101;1021.5607288865859;1043.608646757466;18317.175727092046;18372.908135459773;18726.782759120615;18824.905875470537;19198.712019319442;49156.77108072184;50571.74714126406;50760.23859642182;51094.97810043853;51375;63447.731045377914;69997;73715.6314218447;73953.63023185065;74246.62876685582;7998.9200108000505;8186.058603260702;8247.670093196204;8398.580070996548;8405.411621186458;7930.762077137695;12846.67883302925;13572.660683482993;13578.25319607431;13593;11697.23967942081;11774.82337764917;11829.881701183222;11914;11968.76062478728;9804.754881127743;11522.76954460923;11589.942050289694;11608.477618507357;11827.053835692952;11765.882341176479;11847.289162650268;11971.461284242405;12026.218295810744;12089.75820483603;7066.611336376549;8128.227818357246;8290.295324897452;8318.83362332762;8413.747587572383;10470.057694807503;11794.29234245948;11990.460429280878;11993.880061199625;12007.519699212284 16:56:57 INFO - PROCESS | 1956 | |3;mootools.html;1494.521753039028;1616.7817344658472;1631.078440681015;1960.4804726747404;2058.9279375221863;8329.416940814146;8888.244499217575;9082.000979892207;9085.636574537011;9095.045020272866;8766.167214114665;9038.638454461814;9058.456492610449;9071.591778369973;9085.182333589975;2558.705748838884;2581.7418258174125;2583.857887816179;2584.5993870950033;2586.4697736963876;2523.041244327162;2582.1607977407325;2588;2593.6757905261843;2601.908933187339;2575.407656239066;2589.883455244509;2611.1905309354065;2611.4646497467975;2613.7255588163157;2571.177223288539;2581.3159512729144;2592.5463043967325;2596.740325967407;2601.5447296723187;2547.1467058535413;2574.6652935118314;2585.482903419305;2593.623924530951;2598.311447466423;7733.265339792711;9103.180713735796;9108.81782364349;9154.267658587298;9160.450372977575;5599.440055994368;5716.685582292972;5766.567507436959;5767.077267637158;5774.682392468412;3096;3686.705063594906;3816.8091595420397;3829.636184562462;3831.3295173344554;1068.7381591510052;1071.9624813131582;1091.6779550032759;1100.1968562949091;1125.3754166437625;10594.523246453928;12107.878921210764;12207.450664720109;12221.144519883612;12258.938705306417;4979.900401992012;4996.875078123076;5086.77109530072;5098.1842905135;5109.948900511022;10532.36805791655;10584.364938103738;10585.947070264754;10594.205434592437;10597.20520960931;5750.223719797774;5793.102069179284;5796.768129274866;5797.275340582427;5811.88376232473;4629.652776041743;4760.976195119071;4950.653454258239;5154.974225128851;5229;4021.3163762160502;4079.5308539518337;4088.366303223005;4125.97937010313;4156.376543518496;1344.1330341929402;1355.098859258582;1356.2811709793825;1361.319340329835;1368.3090039529973;1406.683496213344;1419.8793102586396;1437.7771445425976;1443.7473442147793;1462.8098347214793;8465.703700370457;8491.19333663301;8515.57422128891;8518.488890666456;8533;512.7154429291779;518.7769259218496;532.4866629508713;542.4114835403632;543.5542854858978;6340.175777148941;6350.5554611177695;6378.234611846516;6401.487880969511;6420.229572451336;10881.782364352826;11405.885941140648;11410.657680269605;11417.657470275904;11429.314241145557;10649.201309901633;11170;11225.270357426742;11263.211575189822;11273.041791447818;8651.524166170795;8654.48073115615;8733.03936566977;8741.650333986569;8741.825163496567;8151.307138893023;8185.508869467851;8217.917820821714;8230.09468958426;8235.052968908652;8457.450265732708;9056.822613060524;9700.660476883275;10074.042965918226;10163.339382940085;9857.211423085993;10024.448655324028;10076.748081298027;10183.847242291224;10251.692449226237;7712.768616941501;7716.614169291625;7717.922820771721;7776.961115194388;7779.611019449118;8452.112528184452;8457.154284571494;8520.275776558814;8520.275776559061;8521.190486903733;7519.548827070394;7730.690772369042;7797;7847.843043138991;7897.289243968071;7488.475806693479;7525.58609276495;7548.584827834522;7682.500637458547;7687.346575540918;8108.3513318933165;8164.632591533514;8181.140980196994;8188.549629770419;8210.876836847572;7251.166115896528;7381.483296169431;7391.113066432063;7399.371053460516;7410.814729631802;7463.888041679271;7481.401487881065;7508.962455187908;7515.286047825448;7518.624068796429 16:56:57 INFO - PROCESS | 1956 | |4;prototype.html;334.49658264312205;343.5516650770743;345.6405338448013;347.71313666225336;353.04853420033004;6510.51171162163;6527.249366322874;6599.373059559334;6617.371349721775;6619.271880093198;6533.150690410252;6535.705893234804;6542.901856472156;6547.869042619145;6559.409653131217;3962.2669806085823;4683.250679891218;4761.238201887699;4787.114383839001;4928.334674818899;4843.055604157191;4850.927236091461;4887.584555312803;4893.360724157412;4899.51004899509;4864.416270047599;4866.902661946777;4867.318575399447;4870;4873.756312184377;4630.212863813143;4693.530646935314;4795.808167673289;4849.466558678542;4875.70745755254;4174.394712766662;4191.97904010481;4196.573165123842;4201.369794530845;4203.62167404935;6206.007038873804;6226.968865155645;6235.158253635746;6237.844053898689;6246.781362652286;5664.433556644344;5666.886662266773;5672;5673.12066629669;5677;6878.246047257917;6878.931210687879;6893.827654308632;6899.586024838526;6901.861962760717;3717.5724791649036;3863.2466668999555;4716.268978308368;4728.858134255978;4729.527047295277;6014.248218972629;7151.284871512859;7270.236625154335;7270.527415717961;7323.926760732378;3662.8168591570316;4286.421333119989;4777.307290442866;4779.426468823764;4781.569658730731;4181.51912530063;4727.983483550985;4857.295692124622;4861.586765124934;4862.902741945141;5355.7054362009685;5649.152627105885;5669.489745922887;5675.460831221028;5698.572607054488;5756.963746525582;6656.5340426169705;6902.102726645504;6928.272531384233;6950.409215216763;1613.4352976458147;1619.0528540803525;1633.6650986547968;1635.1006946179555;1638.852503274711;996.6362277768562;1040.8750949886062;1042.4162469017224;1060.5068643081004;1068.2895874243695;5973.940260597426;6154.261488621394;6316.2736285326855;6473.902891456632;6492.480601551864;7208.891866622005;7210.747623833141;7221.24176961422;7222.3860971817985;7249.311315425026;6627.1716035495565;6632.1709786276715;6636.203655561363;6641.734330626721;6657.367550082734;3808.3716186828865;3841.116543195025;3845.9230815384094;3848.134169811826;3849.634284742945;7318.634068296564;7558.735444259424;7618.428617853683;7620.657070431733;7630.122535908331;7452.776416707507;7458.142313634002;7470.850582988419;7481.289277518627;7493.063367079115;7563.886541701769;7566.583837888859;7569.659365328574;7574.810629734301;7585.810354741176;6926.376626103674;7210.459215558749;7218.675159617725;7223.386012189023;7223.638818059181;7147.606881621561;7152.6781294842895;7160.856782864209;7165.211826698955;7165.996760453437;6576.046473261446;7119.85760284781;7516.774496765106;7551.811204719706;7589.810254743676;3867.8452861885207;4651.232546629768;4659.860204193879;4670.719756814602;4670.9766451167525;7201.927980720336;7202.423806095395;7204.4956853019785;7204.819879503054;7218.927810722035;6776.390124888783;6994.825129371807;7006.894896576454;7018;7021.227664956953;5685.658860468385;6911.654417279216;7011.614361209979;7017.05269788572;7032.12098487689;6241.251049874044;6263.4362907338555;6860.691268892812;6900.2409734928115;6908;6755.290694477009;7013.509054366145;7018.649067546704;7023.894641580483;7026.824329391806;6288.654124023223;6636.2368327642935;6754.527183097332;6850.349216824394;6864.725410983505 16:56:57 INFO - PROCESS | 1956 | |5;yui.html;676.2932735291625;702.4380495603516;756.292866170131;775.5308038636624;800.1558355934491;15370.308336124874;15603.687926241468;15604;15627.765583516228;15658.53024409268;14428.711425771478;14519.20144392058;14529.709405811904;14533.78199327011;14569.708605827876;814.9837003259931;826.4793180296435;975.8341082016075;985.2265971212599;1010.914072303855;884.8363052835246;990.2424645146468;998.8501724741311;1000.3447741729143;1002.809466201423;1071.2180108520784;1071.3036526257956;1071.6356438810803;1073.0235485708008;1073.1039581949083;850.3579797253096;862.6463150108457;863.4517081653196;1019.4851599942056;1043.3583346242083;768.9846203075963;769.9653515591756;771.699037375424;773.8839174123871;775.9340456061242;15263.542093737206;15348.386064557517;15355.155466449341;15355.232238388036;15369;13253.072284940057;13465.528706495325;13654.795178072336;13658;13717.45130194791;10290.691279261635;10355.79288414235;10461.215408844368;10732.73168170794;10754.677359679145;3523.260115375774;3555.928881422383;3631.8365673544754;3655.287218992271;3748.887533374003;83974;84054.57972710159;86274;86425.7036144452;86447.13552864453;9759.707208783748;9825.213982881352;9927.40435573868;9954;10039.447830369254;22056;22245.88877055637;22267.997940092733;22268.55462890733;22269.44326391821;6590.637514936629;7667.348275396654;7723.227677232232;7968.880466793003;8048.798780030546;4356.607905288539;4364.8908777280185;4397.6921615487245;4461.420015398043;4474;3745.6628903398823;3790.469334293201;3793.886183414502;3795.69634429245;3799.411091280856;639.8336432527483;667.8497338098889;671.4930227678071;692.0242458134007;694.6422592364936;580.8547863034241;623.7847942459844;628.4752231886426;633.7116611941556;634.6065439427584;7929.920700793034;8415.789605259917;8427.620757065946;8620.424522920155;8720.738377848675;601.8886505996317;613.5183880653666;621.4624349937362;635.015725625279;669.9587337087152;3848.365019771706;3933.233019561158;4061.939070913939;4169.499660040815;4292.270314046619;79645.60177199077;79715.60142199251;80267.9933001668;80685.59657201676;80946.97632559233;75516.48967020628;75578.62210688912;75858;76467;77273;67364.66317668479;67735.5651213134;67917.66041169861;68299;68760;8592.183742544448;8678.566071696263;8737.388382813142;8819.559022049;8945.329100317633;47182.76408617935;48677.2698409517;49512.50487495079;49683;50649;52165.7391713039;68044.97932530916;68328.65835670789;69842.65078674574;70009;10945.671629851116;11090.112790976864;11457.828132578185;11522.711932201762;11641.88358116408;12116.878831211576;12172.75654486923;12495.625131246077;12528.185667931555;12903.806442903551;11762;11879.82180267314;11930.522779088738;12103.152779305365;12244.387780611112;11435.542578297107;11574.479148438171;11699.122565807429;11793.351365674627;12004.519819207135;11464;11599.76800464003;11656.475458604553;11795.233309834834;12332.32172230536;9659.71020869375;9659.806803864023;9960.203183745429;10045.173799196302;10346.793064138825;9845.907944357092;10019.949900250453;10176.142742183782;10266.024727650862;10322.277440579088 16:56:57 INFO - PROCESS | 1956 | __end_tp_report 16:56:57 INFO - PROCESS | 1956 | __start_cc_report 16:56:57 INFO - PROCESS | 1956 | _x_x_mozilla_cycle_collect,1002 16:56:57 INFO - PROCESS | 1956 | __end_cc_report 16:56:57 INFO - PROCESS | 1956 | __startTimestamp1464652617131__endTimestamp 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | ------- Summary: start ------- 16:56:57 INFO - PROCESS | 1956 | Number of tests: 6 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#0] dojo.html Cycles:165 Average:5867.72 Median:6070.33 stddev:612.91 (10.1%) stddev-sans-first:587.18 16:56:57 INFO - PROCESS | 1956 | Values: 3548.9 3600.1 3615.2 3635.9 3650.9 5892.8 5917.8 5936.2 5940.4 5995.5 5882.1 5925.6 5940.4 5944.1 5948.6 5174.8 5277.1 5319.5 5364.9 5405.5 5398.4 5421.9 5430.9 5442.6 5444.3 5298.7 5342.2 5382.2 5388.7 5429.3 5393.7 5395.7 5418.2 5427.9 5481.6 5140.0 5323.9 5331.8 5341.8 5368.8 5648.3 5685.6 5691.6 5706.7 5728.6 6200.4 6341.1 6361.5 6369.8 6377.0 6312.3 6313.9 6319.9 6343.8 6343.9 5910.0 5937.8 6046.0 6055.4 6056.6 6321.1 6340.0 6342.6 6346.3 6371.2 6286.0 6326.7 6328.4 6341.7 6345.6 6151.2 6236.1 6259.7 6288.3 6324.5 6316.4 6325.4 6333.1 6341.3 6359.8 6219.4 6354.8 6360.9 6364.2 6367.3 4978.6 5002.2 5009.3 5011.1 5011.1 4373.8 4527.2 4547.4 4563.6 4564.7 5926.6 5950.8 5951.1 5970.5 5997.7 6240.4 6240.7 6268.5 6272.0 6275.4 5619.1 5795.3 5894.2 5928.9 5935.9 5453.4 5481.7 5485.6 5490.9 5526.5 6330.8 6356.4 6387.7 6389.4 6392.3 6246.2 6263.3 6274.3 6346.5 6352.9 5907.3 5929.6 6370.2 6399.8 6411.0 6331.7 6337.1 6344.6 6345.3 6351.7 6237.3 6241.1 6263.4 6294.7 6300.7 6250.7 6256.5 6277.7 6289.3 6289.5 6284.6 6287.2 6289.4 6301.7 6309.4 5929.1 6041.0 6120.4 6123.1 6130.4 6070.3 6165.2 6173.5 6175.6 6188.1 6013.8 6050.6 6061.1 6076.5 6098.0 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#1] ext.html Cycles:180 Average:67783.33 Median:197778.61 stddev:152676.66 (77.2%) stddev-sans-first:153021.46 16:56:57 INFO - PROCESS | 1956 | Values: 531.3 586.4 595.4 661.4 668.5 31051.7 40108.2 40128.6 40133.4 40343.6 43633.8 44410.0 44417.3 44477.3 44568.6 9702.4 9926.7 9926.8 9929.8 9949.4 9633.7 9799.6 9805.8 9812.0 9914.5 9748.6 9871.6 9922.8 9940.6 9944.8 9794.1 9893.5 9901.5 9903.0 9923.5 9785.3 9851.0 9925.6 9929.9 9941.3 42703.6 43183.8 43207.6 43219.6 43324.0 3300.4 3415.0 3466.3 3577.7 3937.2 28716.0 29601.7 29609.4 29793.6 30092.8 2479.8 2746.6 2766.9 2821.6 2852.9 477879.0 479306.6 480270.0 480920.0 484111.0 46866.5 48260.3 68592.0 68857.3 69335.3 47120.0 47442.5 47550.8 47620.0 47711.3 17048.5 17187.7 17245.3 17251.9 17327.4 13657.1 13705.9 13719.2 13721.7 13724.7 15511.3 15527.3 15533.5 15558.8 15626.9 6432.9 6576.9 6591.8 6596.1 6601.5 1021.0 1068.9 1081.8 1085.2 1128.6 30399.4 30415.7 30881.4 31037.7 31059.4 1136.2 1162.5 1195.7 1235.5 1254.6 13846.7 16251.9 16354.7 16545.3 16630.8 533323.0 805106.0 805748.0 806555.0 811361.0 62938.4 63228.0 63398.4 63419.0 63430.4 1430.8 1560.6 1574.2 1585.9 1705.5 26719.9 28297.4 29918.9 30593.4 30853.2 46747.8 47088.0 47238.3 47295.3 47315.5 391620.0 397702.0 403564.0 403900.0 405435.0 32315.2 32936.7 33130.5 33541.8 33603.8 43796.3 44007.0 44426.8 44912.6 45615.5 30416.5 36699.0 36845.4 37536.2 38461.4 36498.6 36734.6 36742.8 37029.8 37458.8 36430.3 36704.8 37483.6 37544.4 37614.0 28385.3 31410.8 31787.7 32859.8 32937.8 24004.9 25067.9 25183.5 25219.6 25410.7 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#2] jquery.html Cycles:180 Average:36365.75 Median:12403.21 stddev:112322.92 (905.6%) stddev-sans-first:112605.51 16:56:57 INFO - PROCESS | 1956 | Values: 382.4 383.2 383.5 388.0 392.4 19478.6 21345.6 22346.9 22511.3 22549.7 22103.1 22188.8 22217.9 22219.6 22534.2 5834.4 5861.4 6086.2 6176.1 6181.4 6071.4 6110.7 6114.0 6129.5 6133.2 5197.6 5802.5 6074.3 6083.1 6085.4 5125.8 5809.7 6013.9 6044.4 6060.0 5974.9 5993.4 6012.9 6016.1 6023.3 13925.3 14162.9 18605.5 25900.6 25905.0 2156.5 2256.8 2366.1 2535.2 2674.2 9893.0 9910.4 10495.5 10563.9 13072.5 1572.0 1751.2 1815.6 1855.4 1892.2 86099.6 104170.0 109538.0 110205.0 110750.4 9202.0 11661.8 11676.6 11717.1 11803.3 14583.9 14607.0 14639.3 14677.3 14687.0 9063.9 9109.0 9132.4 9136.4 9198.1 9310.5 9334.7 9336.6 9378.4 9489.0 4507.6 4578.8 4586.5 4616.3 4656.8 1631.9 1648.4 1874.6 1923.6 1931.1 895.3 963.2 980.6 997.5 1078.2 20029.9 20364.3 20850.5 20970.6 21336.4 991.6 1009.6 1036.9 1049.8 1069.3 11077.4 11327.1 11374.4 11376.2 11403.7 673375.0 678639.0 681695.0 683574.0 687308.0 97118.0 110417.4 114366.0 121929.0 125706.0 984.2 1015.4 1016.7 1021.6 1043.6 18317.2 18372.9 18726.8 18824.9 19198.7 49156.8 50571.7 50760.2 51095.0 51375.0 63447.7 69997.0 73715.6 73953.6 74246.6 7998.9 8186.1 8247.7 8398.6 8405.4 7930.8 12846.7 13572.7 13578.3 13593.0 11697.2 11774.8 11829.9 11914.0 11968.8 9804.8 11522.8 11589.9 11608.5 11827.1 11765.9 11847.3 11971.5 12026.2 12089.8 7066.6 8128.2 8290.3 8318.8 8413.7 10470.1 11794.3 11990.5 11993.9 12007.5 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#3] mootools.html Cycles:180 Average:6276.70 Median:2836.88 stddev:3298.01 (116.3%) stddev-sans-first:3287.67 16:56:57 INFO - PROCESS | 1956 | Values: 1494.5 1616.8 1631.1 1960.5 2058.9 8329.4 8888.2 9082.0 9085.6 9095.0 8766.2 9038.6 9058.5 9071.6 9085.2 2558.7 2581.7 2583.9 2584.6 2586.5 2523.0 2582.2 2588.0 2593.7 2601.9 2575.4 2589.9 2611.2 2611.5 2613.7 2571.2 2581.3 2592.5 2596.7 2601.5 2547.1 2574.7 2585.5 2593.6 2598.3 7733.3 9103.2 9108.8 9154.3 9160.5 5599.4 5716.7 5766.6 5767.1 5774.7 3096.0 3686.7 3816.8 3829.6 3831.3 1068.7 1072.0 1091.7 1100.2 1125.4 10594.5 12107.9 12207.5 12221.1 12258.9 4979.9 4996.9 5086.8 5098.2 5109.9 10532.4 10584.4 10585.9 10594.2 10597.2 5750.2 5793.1 5796.8 5797.3 5811.9 4629.7 4761.0 4950.7 5155.0 5229.0 4021.3 4079.5 4088.4 4126.0 4156.4 1344.1 1355.1 1356.3 1361.3 1368.3 1406.7 1419.9 1437.8 1443.7 1462.8 8465.7 8491.2 8515.6 8518.5 8533.0 512.7 518.8 532.5 542.4 543.6 6340.2 6350.6 6378.2 6401.5 6420.2 10881.8 11405.9 11410.7 11417.7 11429.3 10649.2 11170.0 11225.3 11263.2 11273.0 8651.5 8654.5 8733.0 8741.7 8741.8 8151.3 8185.5 8217.9 8230.1 8235.1 8457.5 9056.8 9700.7 10074.0 10163.3 9857.2 10024.4 10076.7 10183.8 10251.7 7712.8 7716.6 7717.9 7777.0 7779.6 8452.1 8457.2 8520.3 8520.3 8521.2 7519.5 7730.7 7797.0 7847.8 7897.3 7488.5 7525.6 7548.6 7682.5 7687.3 8108.4 8164.6 8181.1 8188.5 8210.9 7251.2 7381.5 7391.1 7399.4 7410.8 7463.9 7481.4 7509.0 7515.3 7518.6 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#4] prototype.html Cycles:180 Average:5690.79 Median:6518.88 stddev:1816.91 (27.9%) stddev-sans-first:1776.98 16:56:57 INFO - PROCESS | 1956 | Values: 334.5 343.6 345.6 347.7 353.0 6510.5 6527.2 6599.4 6617.4 6619.3 6533.2 6535.7 6542.9 6547.9 6559.4 3962.3 4683.3 4761.2 4787.1 4928.3 4843.1 4850.9 4887.6 4893.4 4899.5 4864.4 4866.9 4867.3 4870.0 4873.8 4630.2 4693.5 4795.8 4849.5 4875.7 4174.4 4192.0 4196.6 4201.4 4203.6 6206.0 6227.0 6235.2 6237.8 6246.8 5664.4 5666.9 5672.0 5673.1 5677.0 6878.2 6878.9 6893.8 6899.6 6901.9 3717.6 3863.2 4716.3 4728.9 4729.5 6014.2 7151.3 7270.2 7270.5 7323.9 3662.8 4286.4 4777.3 4779.4 4781.6 4181.5 4728.0 4857.3 4861.6 4862.9 5355.7 5649.2 5669.5 5675.5 5698.6 5757.0 6656.5 6902.1 6928.3 6950.4 1613.4 1619.1 1633.7 1635.1 1638.9 996.6 1040.9 1042.4 1060.5 1068.3 5973.9 6154.3 6316.3 6473.9 6492.5 7208.9 7210.7 7221.2 7222.4 7249.3 6627.2 6632.2 6636.2 6641.7 6657.4 3808.4 3841.1 3845.9 3848.1 3849.6 7318.6 7558.7 7618.4 7620.7 7630.1 7452.8 7458.1 7470.9 7481.3 7493.1 7563.9 7566.6 7569.7 7574.8 7585.8 6926.4 7210.5 7218.7 7223.4 7223.6 7147.6 7152.7 7160.9 7165.2 7166.0 6576.0 7119.9 7516.8 7551.8 7589.8 3867.8 4651.2 4659.9 4670.7 4671.0 7201.9 7202.4 7204.5 7204.8 7218.9 6776.4 6994.8 7006.9 7018.0 7021.2 5685.7 6911.7 7011.6 7017.1 7032.1 6241.3 6263.4 6860.7 6900.2 6908.0 6755.3 7013.5 7018.6 7023.9 7026.8 6288.7 6636.2 6754.5 6850.3 6864.7 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - PROCESS | 1956 | [#5] yui.html Cycles:180 Average:18154.48 Median:4467.71 stddev:24693.67 (552.7%) stddev-sans-first:24728.07 16:56:57 INFO - PROCESS | 1956 | Values: 676.3 702.4 756.3 775.5 800.2 15370.3 15603.7 15604.0 15627.8 15658.5 14428.7 14519.2 14529.7 14533.8 14569.7 815.0 826.5 975.8 985.2 1010.9 884.8 990.2 998.9 1000.3 1002.8 1071.2 1071.3 1071.6 1073.0 1073.1 850.4 862.6 863.5 1019.5 1043.4 769.0 770.0 771.7 773.9 775.9 15263.5 15348.4 15355.2 15355.2 15369.0 13253.1 13465.5 13654.8 13658.0 13717.5 10290.7 10355.8 10461.2 10732.7 10754.7 3523.3 3555.9 3631.8 3655.3 3748.9 83974.0 84054.6 86274.0 86425.7 86447.1 9759.7 9825.2 9927.4 9954.0 10039.4 22056.0 22245.9 22268.0 22268.6 22269.4 6590.6 7667.3 7723.2 7968.9 8048.8 4356.6 4364.9 4397.7 4461.4 4474.0 3745.7 3790.5 3793.9 3795.7 3799.4 639.8 667.8 671.5 692.0 694.6 580.9 623.8 628.5 633.7 634.6 7929.9 8415.8 8427.6 8620.4 8720.7 601.9 613.5 621.5 635.0 670.0 3848.4 3933.2 4061.9 4169.5 4292.3 79645.6 79715.6 80268.0 80685.6 80947.0 75516.5 75578.6 75858.0 76467.0 77273.0 67364.7 67735.6 67917.7 68299.0 68760.0 8592.2 8678.6 8737.4 8819.6 8945.3 47182.8 48677.3 49512.5 49683.0 50649.0 52165.7 68045.0 68328.7 69842.7 70009.0 10945.7 11090.1 11457.8 11522.7 11641.9 12116.9 12172.8 12495.6 12528.2 12903.8 11762.0 11879.8 11930.5 12103.2 12244.4 11435.5 11574.5 11699.1 11793.4 12004.5 11464.0 11599.8 11656.5 11795.2 12332.3 9659.7 9659.8 9960.2 10045.2 10346.8 9845.9 10019.9 10176.1 10266.0 10322.3 16:56:57 INFO - PROCESS | 1956 | -------- Summary: end -------- 16:56:57 INFO - PROCESS | 1956 | 16:56:57 INFO - TEST-INFO | 1956: exit 0 16:56:57 INFO - TEST-OK | dromaeo_css | took 1111613ms 16:56:57 INFO - TEST-START | kraken 16:56:57 INFO - operating with platform_type : mac_ 16:56:57 INFO - Initialising browser for kraken test... 16:56:57 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmp3a3Ll9/profile http://localhost:52625/getInfo.html) 16:56:59 INFO - PROCESS | 1960 | __metrics Screen width/height:1600/1200 16:56:59 INFO - PROCESS | 1960 | colorDepth:24 16:56:59 INFO - PROCESS | 1960 | Browser inner width/height: 1024/689 16:56:59 INFO - PROCESS | 1960 | __metrics 16:57:00 INFO - PROCESS | 1960 | 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" 16:57:00 INFO - PROCESS | 1960 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:57:00 INFO - TEST-INFO | 1960: exit 0 16:57:00 INFO - Browser initialized. 16:57:00 INFO - Running cycle 1/1 for kraken test... 16:57:00 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/T/tmp3a3Ll9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:12 INFO - PROCESS | 1963 | RSS: Main: 156880896 16:57:12 INFO - PROCESS | 1963 | 16:57:15 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52625/tests/kraken/driver.html?testName=audio-beat-detection) 16:57:15 INFO - PROCESS | 1963 | RSS: Main: 218177536 16:57:15 INFO - PROCESS | 1963 | 16:57:18 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52625/tests/kraken/driver.html?testName=audio-dft) 16:57:18 INFO - PROCESS | 1963 | RSS: Main: 228630528 16:57:18 INFO - PROCESS | 1963 | 16:57:21 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52625/tests/kraken/driver.html?testName=audio-fft) 16:57:22 INFO - PROCESS | 1963 | RSS: Main: 218992640 16:57:22 INFO - PROCESS | 1963 | 16:57:24 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52625/tests/kraken/driver.html?testName=audio-oscillator) 16:57:25 INFO - PROCESS | 1963 | RSS: Main: 221020160 16:57:25 INFO - PROCESS | 1963 | 16:57:27 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52625/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:57:27 INFO - PROCESS | 1963 | RSS: Main: 221028352 16:57:27 INFO - PROCESS | 1963 | 16:57:30 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52625/tests/kraken/driver.html?testName=imaging-darkroom) 16:57:30 INFO - PROCESS | 1963 | RSS: Main: 221462528 16:57:30 INFO - PROCESS | 1963 | 16:57:33 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52625/tests/kraken/driver.html?testName=imaging-desaturate) 16:57:34 INFO - PROCESS | 1963 | RSS: Main: 221212672 16:57:34 INFO - PROCESS | 1963 | 16:57:37 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52625/tests/kraken/driver.html?testName=json-parse-financial) 16:57:37 INFO - PROCESS | 1963 | RSS: Main: 221331456 16:57:37 INFO - PROCESS | 1963 | 16:57:39 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52625/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:57:39 INFO - PROCESS | 1963 | RSS: Main: 255348736 16:57:39 INFO - PROCESS | 1963 | 16:57:41 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:57:42 INFO - PROCESS | 1963 | RSS: Main: 256380928 16:57:42 INFO - PROCESS | 1963 | 16:57:44 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:57:44 INFO - PROCESS | 1963 | RSS: Main: 261443584 16:57:44 INFO - PROCESS | 1963 | 16:57:47 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:57:48 INFO - PROCESS | 1963 | RSS: Main: 262746112 16:57:48 INFO - PROCESS | 1963 | 16:57:50 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:57:51 INFO - PROCESS | 1963 | RSS: Main: 262795264 16:57:51 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | Cycle 1(1): loaded http://localhost:52625/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52625/tests/kraken/driver.html?testName=ai-astar) 16:57:52 INFO - PROCESS | 1963 | RSS: Main: 325304320 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | __start_tp_report 16:57:52 INFO - PROCESS | 1963 | _x_x_mozilla_page_load 16:57:52 INFO - PROCESS | 1963 | _x_x_mozilla_page_load_details 16:57:52 INFO - PROCESS | 1963 | |i|pagename|runs| 16:57:52 INFO - PROCESS | 1963 | |0;ai-astar;111;111;112;111;113;110;131;112;111;111 16:57:52 INFO - PROCESS | 1963 | |1;audio-beat-detection;156;117;153;147;116;138;117;148;146;139 16:57:52 INFO - PROCESS | 1963 | |2;audio-dft;178;185;185;184;186;186;187;184;185;179 16:57:52 INFO - PROCESS | 1963 | |3;audio-fft;73;76;72;119;55;57;57;58;123;56 16:57:52 INFO - PROCESS | 1963 | |4;audio-oscillator;88;93;129;78;79;82;115;76;79;118 16:57:52 INFO - PROCESS | 1963 | |5;imaging-gaussian-blur;88;85;88;85;88;85;88;86;88;83 16:57:52 INFO - PROCESS | 1963 | |6;imaging-darkroom;93;94;94;94;93;92;95;101;148;124 16:57:52 INFO - PROCESS | 1963 | |7;imaging-desaturate;85;84;87;87;85;84;84;85;87;86 16:57:52 INFO - PROCESS | 1963 | |8;json-parse-financial;54;56;56;57;56;56;57;59;68;53 16:57:52 INFO - PROCESS | 1963 | |9;json-stringify-tinderbox;63;62;63;79;74;68;66;66;66;67 16:57:52 INFO - PROCESS | 1963 | |10;stanford-crypto-aes;81;81;82;80;80;77;99;79;83;85 16:57:52 INFO - PROCESS | 1963 | |11;stanford-crypto-ccm;131;134;129;118;116;120;117;116;122;128 16:57:52 INFO - PROCESS | 1963 | |12;stanford-crypto-pbkdf2;133;121;132;133;133;132;129;128;125;133 16:57:52 INFO - PROCESS | 1963 | |13;stanford-crypto-sha256-iterative;67;65;65;66;65;67;66;66;65;64 16:57:52 INFO - PROCESS | 1963 | __end_tp_report 16:57:52 INFO - PROCESS | 1963 | __start_cc_report 16:57:52 INFO - PROCESS | 1963 | _x_x_mozilla_cycle_collect,1214 16:57:52 INFO - PROCESS | 1963 | __end_cc_report 16:57:52 INFO - PROCESS | 1963 | __startTimestamp1464652672978__endTimestamp 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | ------- Summary: start ------- 16:57:52 INFO - PROCESS | 1963 | Number of tests: 14 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#0] ai-astar Cycles:10 Average:113.30 Median:111.50 stddev:6.27 (5.6%) stddev-sans-first:6.60 16:57:52 INFO - PROCESS | 1963 | Values: 111.0 111.0 112.0 111.0 113.0 110.0 131.0 112.0 111.0 111.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#1] audio-beat-detection Cycles:10 Average:137.70 Median:146.50 stddev:15.49 (10.6%) stddev-sans-first:14.95 16:57:52 INFO - PROCESS | 1963 | Values: 156.0 117.0 153.0 147.0 116.0 138.0 117.0 148.0 146.0 139.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#2] audio-dft Cycles:10 Average:183.90 Median:185.00 stddev:3.00 (1.6%) stddev-sans-first:2.30 16:57:52 INFO - PROCESS | 1963 | Values: 178.0 185.0 185.0 184.0 186.0 186.0 187.0 184.0 185.0 179.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#3] audio-fft Cycles:10 Average:74.60 Median:57.50 stddev:25.71 (44.7%) stddev-sans-first:27.27 16:57:52 INFO - PROCESS | 1963 | Values: 73.0 76.0 72.0 119.0 55.0 57.0 57.0 58.0 123.0 56.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#4] audio-oscillator Cycles:10 Average:93.70 Median:79.00 stddev:19.58 (24.8%) stddev-sans-first:20.66 16:57:52 INFO - PROCESS | 1963 | Values: 88.0 93.0 129.0 78.0 79.0 82.0 115.0 76.0 79.0 118.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:88.00 stddev:1.84 (2.1%) stddev-sans-first:1.86 16:57:52 INFO - PROCESS | 1963 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 88.0 86.0 88.0 83.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#6] imaging-darkroom Cycles:10 Average:102.80 Median:93.50 stddev:18.55 (19.8%) stddev-sans-first:19.34 16:57:52 INFO - PROCESS | 1963 | Values: 93.0 94.0 94.0 94.0 93.0 92.0 95.0 101.0 148.0 124.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:85.50 stddev:1.26 (1.5%) stddev-sans-first:1.33 16:57:52 INFO - PROCESS | 1963 | Values: 85.0 84.0 87.0 87.0 85.0 84.0 84.0 85.0 87.0 86.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#8] json-parse-financial Cycles:10 Average:57.20 Median:56.50 stddev:4.13 (7.3%) stddev-sans-first:4.22 16:57:52 INFO - PROCESS | 1963 | Values: 54.0 56.0 56.0 57.0 56.0 56.0 57.0 59.0 68.0 53.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:66.50 stddev:5.30 (8.0%) stddev-sans-first:5.37 16:57:52 INFO - PROCESS | 1963 | Values: 63.0 62.0 63.0 79.0 74.0 68.0 66.0 66.0 66.0 67.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:81.50 stddev:6.13 (7.5%) stddev-sans-first:6.47 16:57:52 INFO - PROCESS | 1963 | Values: 81.0 81.0 82.0 80.0 80.0 77.0 99.0 79.0 83.0 85.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#11] stanford-crypto-ccm Cycles:10 Average:123.10 Median:125.00 stddev:6.79 (5.4%) stddev-sans-first:6.57 16:57:52 INFO - PROCESS | 1963 | Values: 131.0 134.0 129.0 118.0 116.0 120.0 117.0 116.0 122.0 128.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:129.90 Median:132.50 stddev:4.15 (3.1%) stddev-sans-first:4.25 16:57:52 INFO - PROCESS | 1963 | Values: 133.0 121.0 132.0 133.0 133.0 132.0 129.0 128.0 125.0 133.0 16:57:52 INFO - PROCESS | 1963 | 16:57:52 INFO - PROCESS | 1963 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:66.00 stddev:0.97 (1.5%) stddev-sans-first:0.88 16:57:52 INFO - PROCESS | 1963 | Values: 67.0 65.0 65.0 66.0 65.0 67.0 66.0 66.0 65.0 64.0 16:57:52 INFO - PROCESS | 1963 | -------- Summary: end -------- 16:57:52 INFO - PROCESS | 1963 | 16:57:53 INFO - TEST-INFO | 1963: exit 0 16:57:53 INFO - TEST-OK | kraken | took 56032ms 16:57:53 INFO - SUITE-END | took 1167s 16:57:54 INFO - Completed test suite (00:19:28) 16:57:54 INFO - javascript benchmark 16:57:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3548.858045678173, 3600.081979095332, 3615.1685112424157, 3635.8545658173684, 3650.926981460371, 5892.834859866728, 5917.792877249297, 5936.228290322258, 5940.435658612436, 5995.460408563228, 5882.058870580708, 5925.614835035731, 5940.435658612436, 5944.138099975501, 5948.643081415118, 5174.844754657366, 5277.102892508283, 5319.494648008433, 5364.865878353053, 5405.5135037846585, 5398.379186393556, 5421.9457805422035, 5430.8913821723545, 5442.619016668835, 5444.265024221719, 5298.708571028592, 5342.171963345688, 5382.1926710993275, 5388.703621300827, 5429.267048948401, 5393.6763794172275, 5395.676259424408, 5418.160185171305, 5427.864303392407, 5481.643693159932, 5140.023395554833, 5323.920141197885, 5331.813386531491, 5341.786328546854, 5368.812091576776, 5648.265725455705, 5685.630434021776, 5691.630044047165, 5706.71466426677, 5728.627639203439, 6200.441960223557, 6341.080543321193, 6361.459275961549, 6369.8407539811415, 6377.043443483468, 6312.337204593543, 6313.9368606314265, 6319.905201421982, 6343.809685709436, 6343.904841427382, 5910.0, 5937.762489500415, 6046.0, 6055.364186760415, 6056.576039677268, 6321.1150438938585, 6340.017297318923, 6342.556021078481, 6346.30190679025, 6371.171747672773, 6286.0, 6326.715297811609, 6328.367163283635, 6341.746330146835, 6345.555811093272, 6151.231096112986, 6236.095766113888, 6259.7496100156395, 6288.276848162428, 6324.494040476751, 6316.368363163647, 6325.367463253638, 6333.145025421509, 6341.302456729756, 6359.841003974938, 6219.440250377488, 6354.809355719336, 6360.9045864312075, 6364.172657554488, 6367.299597044322, 4978.601711863114, 5002.2496625506465, 5009.348784658044, 5011.123053465629, 5011.1481048221885, 4373.803178856895, 4527.162474942146, 4547.386102876136, 4563.612092972068, 4564.657650676146, 5926.585139040312, 5950.821475355746, 5951.077582974646, 5970.522358211333, 5997.730102145414, 6240.375962403814, 6240.687965601793, 6268.4671802897265, 6271.9686401568615, 6275.3724627536885, 5619.072852979294, 5795.304563452329, 5894.233749612523, 5928.9407105928385, 5935.940640593625, 5453.400125986138, 5481.671099734029, 5485.61600687948, 5490.945090549123, 5526.530244929146, 6330.778422755181, 6356.4279214870885, 6387.680615969183, 6389.424951754364, 6392.296847346881, 6246.219222597176, 6263.342349053284, 6274.309825919237, 6346.4605508532295, 6352.936470635419, 5907.2911250650195, 5929.644221346733, 6370.1718776558755, 6399.840003999937, 6411.0, 6331.746730130743, 6337.081123237012, 6344.555881088464, 6345.2702939160745, 6351.745930162741, 6237.313895471585, 6241.126242325987, 6263.404976527223, 6294.748210071729, 6300.716467759054, 6250.749970001149, 6256.499480041677, 6277.686115694106, 6289.27673317556, 6289.465395441439, 6284.622922624657, 6287.1512345832725, 6289.371062893674, 6301.6534090625455, 6309.432151106423, 5929.140274660236, 6041.0, 6120.44915957566, 6123.08153776937, 6130.398136742091, 6070.332263451105, 6165.198524191826, 6173.459494130334, 6175.59858609189, 6188.0717892314715, 6013.819585412444, 6050.606710563799, 6061.060535616986, 6076.51387888959, 6097.99383101801], "unit": "score", "name": "dojo.html", "value": 5830.603814659549}, {"lowerIsBetter": false, "replicates": [531.2880739808655, 586.3608666553456, 595.3689089565064, 661.3849120318105, 668.5387082912791, 31051.68948310516, 40108.19783604326, 40128.59871401285, 40133.39799903, 40343.59656403435, 43633.78183109088, 44410.0, 44417.33373999393, 44477.332840007424, 44568.554314456844, 9702.369345992505, 9926.702198934008, 9926.801463970716, 9929.801403971916, 9949.403035817837, 9633.66282180124, 9799.559019844124, 9805.803883922317, 9812.0, 9914.454704991223, 9748.610055597803, 9871.555779989918, 9922.751931201705, 9940.602375904955, 9944.751381215456, 9794.069563391466, 9893.4558599277, 9901.504924753734, 9903.0, 9923.503824808768, 9785.315027948047, 9851.014898510091, 9925.602975880956, 9929.90070099297, 9941.254405919584, 42703.57296427027, 43183.78408107939, 43207.56792432067, 43219.56780432187, 43324.0, 3300.4224260754513, 3414.992577189735, 3466.30673865228, 3577.678008979204, 3937.2125574885135, 28716.0, 29601.703982960327, 29609.40781184386, 29793.616510679094, 30092.8495357524, 2479.789217916479, 2746.6429364182527, 2766.9446611067665, 2821.5908693239367, 2852.87162077707, 477879.0, 479306.60346698394, 480270.0, 480920.0, 484111.0, 46866.53133468622, 48260.27609585859, 68592.0, 68857.3114268861, 69335.3066469339, 47120.0, 47442.525574744504, 47550.81122971884, 47620.0, 47711.28433073506, 17048.48854534341, 17187.742183867253, 17245.310187592608, 17251.878627889146, 17327.393541225996, 13657.112287701268, 13705.931470342784, 13719.245441500614, 13721.725565488634, 13724.65688357779, 15511.301991410413, 15527.301271442811, 15533.533993979972, 15558.844411555965, 15626.921865390601, 6432.903506447407, 6576.934230657728, 6591.769288074895, 6596.10952521413, 6601.471882249409, 1021.0351218098974, 1068.8610480637624, 1081.7674200047027, 1085.218642577343, 1128.5993472317373, 30399.392012160075, 30415.695843041285, 30881.382372352426, 31037.68962310348, 31059.378812423623, 1136.153565593638, 1162.505934977648, 1195.6891208285733, 1235.4502246500401, 1254.5985284708988, 13846.653833654038, 16251.943623664423, 16354.672906541802, 16545.25546350417, 16630.835841490978, 533323.0, 805106.0, 805748.0, 806555.0, 811361.0, 62938.37061629417, 63228.0, 63398.36601633925, 63419.0, 63430.365696342444, 1430.7925350824073, 1560.5630423481443, 1574.157825563318, 1585.888987770868, 1705.4968784208688, 26719.866400667874, 28297.434051318447, 29918.85040574783, 30593.388132237676, 30853.228669283446, 46747.76626116848, 47087.99752021117, 47238.291425627955, 47295.29057064216, 47315.52684473249, 391620.0, 397702.0, 403564.0, 403900.0, 405435.0, 32315.19212019718, 32936.67063329336, 33130.5030424539, 33541.83229083839, 33603.83198083994, 43796.34305485484, 44007.0, 44426.77786611046, 44912.55087449084, 45615.54384456113, 30416.543751844183, 36699.0, 36845.44731828971, 37536.2492750138, 38461.423078653286, 36498.635013649524, 36734.63265367312, 36742.8162859184, 37029.81485092557, 37458.812705936296, 36430.27139457143, 36704.81647591852, 37483.625163748016, 37544.43683344807, 37614.0, 28385.260635110186, 31410.84294578604, 31787.68212317847, 32859.83570082134, 32937.84717534875, 24004.87997560001, 25067.87194576212, 25183.496330073664, 25219.621705674796, 25410.74589254084], "unit": "score", "name": "ext.html", "value": 19918.656071465695}, {"lowerIsBetter": false, "replicates": [382.44927304681255, 383.2411824587317, 383.5186840515152, 388.04540829559284, 392.39571060566726, 19478.607958089062, 21345.573088538218, 22346.88826555867, 22511.324660260198, 22549.66175507369, 22103.115875364965, 22188.77811221887, 22217.888910555426, 22219.555608887855, 22534.211302604414, 5834.416558344164, 5861.3552509223955, 6086.239220097488, 6176.135341052234, 6181.412765787266, 6071.3625069367745, 6110.725017374229, 6113.960626693451, 6129.509639228873, 6133.233345831771, 5197.636165468419, 5802.477777000069, 6074.271087469509, 6083.117947897552, 6085.417791374252, 5125.820596279131, 5809.6804675742815, 6013.909791353133, 6044.425779550936, 6060.0, 5974.880502389928, 5993.400659933972, 6012.939870601282, 6016.097585362187, 6023.307319658208, 13925.303734813218, 14162.858371416258, 18605.534861628566, 25900.611490827654, 25905.0, 2156.482444213393, 2256.763039880805, 2366.0654041653625, 2535.1887396033276, 2674.2378422149595, 9892.95053524735, 9910.405375677483, 10495.527701253386, 10563.947180264126, 13072.477100915952, 1571.9842801571951, 1751.2382113780545, 1815.618720068787, 1855.434092601756, 1892.1958167778776, 86099.55601775984, 104170.0, 109538.0, 110205.0, 110750.44624776905, 9202.0, 11661.76676466483, 11676.591319303783, 11717.12121590884, 11803.291802491878, 14583.927080364743, 14606.9775115743, 14639.341229644691, 14677.266136693122, 14687.045342052732, 9063.909360906306, 9109.0, 9132.406393584395, 9136.406133601295, 9198.126178013077, 9310.53447327631, 9334.71995840126, 9336.626534938663, 9378.43729376226, 9489.0, 4507.639388848885, 4578.839740609063, 4586.4725556561425, 4616.307553866947, 4656.790444430008, 1631.9347226110822, 1648.4312912045325, 1874.6063326701546, 1923.5768131011155, 1931.1020375525447, 895.2658819767728, 963.2294164668237, 980.6077568972325, 997.5062344139552, 1078.229066217658, 20029.899850500944, 20364.28724994618, 20850.47873803137, 20970.580588388148, 21336.359909202747, 991.6265971629201, 1009.5860697114148, 1036.8652075230173, 1049.7533079726259, 1069.25152393325, 11077.390743509184, 11327.093832493381, 11374.431278436045, 11376.203665743318, 11403.714907127389, 673375.0, 678639.0, 681695.0, 683574.0, 687308.0, 97118.0, 110417.44791276153, 114366.0, 121929.0, 125706.0, 984.242133557157, 1015.3653966271081, 1016.7051555049101, 1021.5607288865859, 1043.608646757466, 18317.175727092046, 18372.908135459773, 18726.782759120615, 18824.905875470537, 19198.712019319442, 49156.77108072184, 50571.74714126406, 50760.23859642182, 51094.97810043853, 51375.0, 63447.731045377914, 69997.0, 73715.6314218447, 73953.63023185065, 74246.62876685582, 7998.9200108000505, 8186.058603260702, 8247.670093196204, 8398.580070996548, 8405.411621186458, 7930.762077137695, 12846.67883302925, 13572.660683482993, 13578.25319607431, 13593.0, 11697.23967942081, 11774.82337764917, 11829.881701183222, 11914.0, 11968.76062478728, 9804.754881127743, 11522.76954460923, 11589.942050289694, 11608.477618507357, 11827.053835692952, 11765.882341176479, 11847.289162650268, 11971.461284242405, 12026.218295810744, 12089.75820483603, 7066.611336376549, 8128.227818357246, 8290.295324897452, 8318.83362332762, 8413.747587572383, 10470.057694807503, 11794.29234245948, 11990.460429280878, 11993.880061199625, 12007.519699212284], "unit": "score", "name": "jquery.html", "value": 9665.077693614334}, {"lowerIsBetter": false, "replicates": [1494.521753039028, 1616.7817344658472, 1631.078440681015, 1960.4804726747404, 2058.9279375221863, 8329.416940814146, 8888.244499217575, 9082.000979892207, 9085.636574537011, 9095.045020272866, 8766.167214114665, 9038.638454461814, 9058.456492610449, 9071.591778369973, 9085.182333589975, 2558.705748838884, 2581.7418258174125, 2583.857887816179, 2584.5993870950033, 2586.4697736963876, 2523.041244327162, 2582.1607977407325, 2588.0, 2593.6757905261843, 2601.908933187339, 2575.407656239066, 2589.883455244509, 2611.1905309354065, 2611.4646497467975, 2613.7255588163157, 2571.177223288539, 2581.3159512729144, 2592.5463043967325, 2596.740325967407, 2601.5447296723187, 2547.1467058535413, 2574.6652935118314, 2585.482903419305, 2593.623924530951, 2598.311447466423, 7733.265339792711, 9103.180713735796, 9108.81782364349, 9154.267658587298, 9160.450372977575, 5599.440055994368, 5716.685582292972, 5766.567507436959, 5767.077267637158, 5774.682392468412, 3096.0, 3686.705063594906, 3816.8091595420397, 3829.636184562462, 3831.3295173344554, 1068.7381591510052, 1071.9624813131582, 1091.6779550032759, 1100.1968562949091, 1125.3754166437625, 10594.523246453928, 12107.878921210764, 12207.450664720109, 12221.144519883612, 12258.938705306417, 4979.900401992012, 4996.875078123076, 5086.77109530072, 5098.1842905135, 5109.948900511022, 10532.36805791655, 10584.364938103738, 10585.947070264754, 10594.205434592437, 10597.20520960931, 5750.223719797774, 5793.102069179284, 5796.768129274866, 5797.275340582427, 5811.88376232473, 4629.652776041743, 4760.976195119071, 4950.653454258239, 5154.974225128851, 5229.0, 4021.3163762160502, 4079.5308539518337, 4088.366303223005, 4125.97937010313, 4156.376543518496, 1344.1330341929402, 1355.098859258582, 1356.2811709793825, 1361.319340329835, 1368.3090039529973, 1406.683496213344, 1419.8793102586396, 1437.7771445425976, 1443.7473442147793, 1462.8098347214793, 8465.703700370457, 8491.19333663301, 8515.57422128891, 8518.488890666456, 8533.0, 512.7154429291779, 518.7769259218496, 532.4866629508713, 542.4114835403632, 543.5542854858978, 6340.175777148941, 6350.5554611177695, 6378.234611846516, 6401.487880969511, 6420.229572451336, 10881.782364352826, 11405.885941140648, 11410.657680269605, 11417.657470275904, 11429.314241145557, 10649.201309901633, 11170.0, 11225.270357426742, 11263.211575189822, 11273.041791447818, 8651.524166170795, 8654.48073115615, 8733.03936566977, 8741.650333986569, 8741.825163496567, 8151.307138893023, 8185.508869467851, 8217.917820821714, 8230.09468958426, 8235.052968908652, 8457.450265732708, 9056.822613060524, 9700.660476883275, 10074.042965918226, 10163.339382940085, 9857.211423085993, 10024.448655324028, 10076.748081298027, 10183.847242291224, 10251.692449226237, 7712.768616941501, 7716.614169291625, 7717.922820771721, 7776.961115194388, 7779.611019449118, 8452.112528184452, 8457.154284571494, 8520.275776558814, 8520.275776559061, 8521.190486903733, 7519.548827070394, 7730.690772369042, 7797.0, 7847.843043138991, 7897.289243968071, 7488.475806693479, 7525.58609276495, 7548.584827834522, 7682.500637458547, 7687.346575540918, 8108.3513318933165, 8164.632591533514, 8181.140980196994, 8188.549629770419, 8210.876836847572, 7251.166115896528, 7381.483296169431, 7391.113066432063, 7399.371053460516, 7410.814729631802, 7463.888041679271, 7481.401487881065, 7508.962455187908, 7515.286047825448, 7518.624068796429], "unit": "score", "name": "mootools.html", "value": 5047.957734655391}, {"lowerIsBetter": false, "replicates": [334.49658264312205, 343.5516650770743, 345.6405338448013, 347.71313666225336, 353.04853420033004, 6510.51171162163, 6527.249366322874, 6599.373059559334, 6617.371349721775, 6619.271880093198, 6533.150690410252, 6535.705893234804, 6542.901856472156, 6547.869042619145, 6559.409653131217, 3962.2669806085823, 4683.250679891218, 4761.238201887699, 4787.114383839001, 4928.334674818899, 4843.055604157191, 4850.927236091461, 4887.584555312803, 4893.360724157412, 4899.51004899509, 4864.416270047599, 4866.902661946777, 4867.318575399447, 4870.0, 4873.756312184377, 4630.212863813143, 4693.530646935314, 4795.808167673289, 4849.466558678542, 4875.70745755254, 4174.394712766662, 4191.97904010481, 4196.573165123842, 4201.369794530845, 4203.62167404935, 6206.007038873804, 6226.968865155645, 6235.158253635746, 6237.844053898689, 6246.781362652286, 5664.433556644344, 5666.886662266773, 5672.0, 5673.12066629669, 5677.0, 6878.246047257917, 6878.931210687879, 6893.827654308632, 6899.586024838526, 6901.861962760717, 3717.5724791649036, 3863.2466668999555, 4716.268978308368, 4728.858134255978, 4729.527047295277, 6014.248218972629, 7151.284871512859, 7270.236625154335, 7270.527415717961, 7323.926760732378, 3662.8168591570316, 4286.421333119989, 4777.307290442866, 4779.426468823764, 4781.569658730731, 4181.51912530063, 4727.983483550985, 4857.295692124622, 4861.586765124934, 4862.902741945141, 5355.7054362009685, 5649.152627105885, 5669.489745922887, 5675.460831221028, 5698.572607054488, 5756.963746525582, 6656.5340426169705, 6902.102726645504, 6928.272531384233, 6950.409215216763, 1613.4352976458147, 1619.0528540803525, 1633.6650986547968, 1635.1006946179555, 1638.852503274711, 996.6362277768562, 1040.8750949886062, 1042.4162469017224, 1060.5068643081004, 1068.2895874243695, 5973.940260597426, 6154.261488621394, 6316.2736285326855, 6473.902891456632, 6492.480601551864, 7208.891866622005, 7210.747623833141, 7221.24176961422, 7222.3860971817985, 7249.311315425026, 6627.1716035495565, 6632.1709786276715, 6636.203655561363, 6641.734330626721, 6657.367550082734, 3808.3716186828865, 3841.116543195025, 3845.9230815384094, 3848.134169811826, 3849.634284742945, 7318.634068296564, 7558.735444259424, 7618.428617853683, 7620.657070431733, 7630.122535908331, 7452.776416707507, 7458.142313634002, 7470.850582988419, 7481.289277518627, 7493.063367079115, 7563.886541701769, 7566.583837888859, 7569.659365328574, 7574.810629734301, 7585.810354741176, 6926.376626103674, 7210.459215558749, 7218.675159617725, 7223.386012189023, 7223.638818059181, 7147.606881621561, 7152.6781294842895, 7160.856782864209, 7165.211826698955, 7165.996760453437, 6576.046473261446, 7119.85760284781, 7516.774496765106, 7551.811204719706, 7589.810254743676, 3867.8452861885207, 4651.232546629768, 4659.860204193879, 4670.719756814602, 4670.9766451167525, 7201.927980720336, 7202.423806095395, 7204.4956853019785, 7204.819879503054, 7218.927810722035, 6776.390124888783, 6994.825129371807, 7006.894896576454, 7018.0, 7021.227664956953, 5685.658860468385, 6911.654417279216, 7011.614361209979, 7017.05269788572, 7032.12098487689, 6241.251049874044, 6263.4362907338555, 6860.691268892812, 6900.2409734928115, 6908.0, 6755.290694477009, 7013.509054366145, 7018.649067546704, 7023.894641580483, 7026.824329391806, 6288.654124023223, 6636.2368327642935, 6754.527183097332, 6850.349216824394, 6864.725410983505], "unit": "score", "name": "prototype.html", "value": 5090.354209054601}, {"lowerIsBetter": false, "replicates": [676.2932735291625, 702.4380495603516, 756.292866170131, 775.5308038636624, 800.1558355934491, 15370.308336124874, 15603.687926241468, 15604.0, 15627.765583516228, 15658.53024409268, 14428.711425771478, 14519.20144392058, 14529.709405811904, 14533.78199327011, 14569.708605827876, 814.9837003259931, 826.4793180296435, 975.8341082016075, 985.2265971212599, 1010.914072303855, 884.8363052835246, 990.2424645146468, 998.8501724741311, 1000.3447741729143, 1002.809466201423, 1071.2180108520784, 1071.3036526257956, 1071.6356438810803, 1073.0235485708008, 1073.1039581949083, 850.3579797253096, 862.6463150108457, 863.4517081653196, 1019.4851599942056, 1043.3583346242083, 768.9846203075963, 769.9653515591756, 771.699037375424, 773.8839174123871, 775.9340456061242, 15263.542093737206, 15348.386064557517, 15355.155466449341, 15355.232238388036, 15369.0, 13253.072284940057, 13465.528706495325, 13654.795178072336, 13658.0, 13717.45130194791, 10290.691279261635, 10355.79288414235, 10461.215408844368, 10732.73168170794, 10754.677359679145, 3523.260115375774, 3555.928881422383, 3631.8365673544754, 3655.287218992271, 3748.887533374003, 83974.0, 84054.57972710159, 86274.0, 86425.7036144452, 86447.13552864453, 9759.707208783748, 9825.213982881352, 9927.40435573868, 9954.0, 10039.447830369254, 22056.0, 22245.88877055637, 22267.997940092733, 22268.55462890733, 22269.44326391821, 6590.637514936629, 7667.348275396654, 7723.227677232232, 7968.880466793003, 8048.798780030546, 4356.607905288539, 4364.8908777280185, 4397.6921615487245, 4461.420015398043, 4474.0, 3745.6628903398823, 3790.469334293201, 3793.886183414502, 3795.69634429245, 3799.411091280856, 639.8336432527483, 667.8497338098889, 671.4930227678071, 692.0242458134007, 694.6422592364936, 580.8547863034241, 623.7847942459844, 628.4752231886426, 633.7116611941556, 634.6065439427584, 7929.920700793034, 8415.789605259917, 8427.620757065946, 8620.424522920155, 8720.738377848675, 601.8886505996317, 613.5183880653666, 621.4624349937362, 635.015725625279, 669.9587337087152, 3848.365019771706, 3933.233019561158, 4061.939070913939, 4169.499660040815, 4292.270314046619, 79645.60177199077, 79715.60142199251, 80267.9933001668, 80685.59657201676, 80946.97632559233, 75516.48967020628, 75578.62210688912, 75858.0, 76467.0, 77273.0, 67364.66317668479, 67735.5651213134, 67917.66041169861, 68299.0, 68760.0, 8592.183742544448, 8678.566071696263, 8737.388382813142, 8819.559022049, 8945.329100317633, 47182.76408617935, 48677.2698409517, 49512.50487495079, 49683.0, 50649.0, 52165.7391713039, 68044.97932530916, 68328.65835670789, 69842.65078674574, 70009.0, 10945.671629851116, 11090.112790976864, 11457.828132578185, 11522.711932201762, 11641.88358116408, 12116.878831211576, 12172.75654486923, 12495.625131246077, 12528.185667931555, 12903.806442903551, 11762.0, 11879.82180267314, 11930.522779088738, 12103.152779305365, 12244.387780611112, 11435.542578297107, 11574.479148438171, 11699.122565807429, 11793.351365674627, 12004.519819207135, 11464.0, 11599.76800464003, 11656.475458604553, 11795.233309834834, 12332.32172230536, 9659.71020869375, 9659.806803864023, 9960.203183745429, 10045.173799196302, 10346.793064138825, 9845.907944357092, 10019.949900250453, 10176.142742183782, 10266.024727650862, 10322.277440579088], "unit": "score", "name": "yui.html", "value": 7141.62881370681}], "name": "dromaeo_css", "value": 7685.03237720025}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 111.0, 112.0, 111.0, 113.0, 110.0, 131.0, 112.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 113.3}, {"lowerIsBetter": true, "replicates": [156.0, 117.0, 153.0, 147.0, 116.0, 138.0, 117.0, 148.0, 146.0, 139.0], "unit": "score", "name": "audio-beat-detection", "value": 137.7}, {"lowerIsBetter": true, "replicates": [178.0, 185.0, 185.0, 184.0, 186.0, 186.0, 187.0, 184.0, 185.0, 179.0], "unit": "score", "name": "audio-dft", "value": 183.9}, {"lowerIsBetter": true, "replicates": [73.0, 76.0, 72.0, 119.0, 55.0, 57.0, 57.0, 58.0, 123.0, 56.0], "unit": "score", "name": "audio-fft", "value": 74.6}, {"lowerIsBetter": true, "replicates": [88.0, 93.0, 129.0, 78.0, 79.0, 82.0, 115.0, 76.0, 79.0, 118.0], "unit": "score", "name": "audio-oscillator", "value": 93.7}, {"lowerIsBetter": true, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 88.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.4}, {"lowerIsBetter": true, "replicates": [93.0, 94.0, 94.0, 94.0, 93.0, 92.0, 95.0, 101.0, 148.0, 124.0], "unit": "score", "name": "imaging-darkroom", "value": 102.8}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 87.0, 87.0, 85.0, 84.0, 84.0, 85.0, 87.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 85.4}, {"lowerIsBetter": true, "replicates": [54.0, 56.0, 56.0, 57.0, 56.0, 56.0, 57.0, 59.0, 68.0, 53.0], "unit": "score", "name": "json-parse-financial", "value": 57.2}, {"lowerIsBetter": true, "replicates": [63.0, 62.0, 63.0, 79.0, 74.0, 68.0, 66.0, 66.0, 66.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.4}, {"lowerIsBetter": true, "replicates": [81.0, 81.0, 82.0, 80.0, 80.0, 77.0, 99.0, 79.0, 83.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 82.7}, {"lowerIsBetter": true, "replicates": [131.0, 134.0, 129.0, 118.0, 116.0, 120.0, 117.0, 116.0, 122.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 123.1}, {"lowerIsBetter": true, "replicates": [133.0, 121.0, 132.0, 133.0, 133.0, 132.0, 129.0, 128.0, 125.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 129.9}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 65.0, 66.0, 65.0, 67.0, 66.0, 66.0, 65.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.6}], "name": "kraken", "value": 1403.6999999999998}]} 16:57:54 INFO - Return code: 0 16:57:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:57:54 INFO - # TBPL SUCCESS # 16:57:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:57:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:57:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:57:54 INFO - 2016-05-30 16:57:54.308 screenresolution[1967:22601] starting screenresolution argv=screenresolution get 16:57:54 INFO - 2016-05-30 16:57:54.311 screenresolution[1967:22601] Display 0: 1600x1200x32@60 16:57:54 INFO - 2016-05-30 16:57:54.317 screenresolution[1968:22604] starting screenresolution argv=screenresolution list 16:57:54 INFO - Available Modes on Display 0 16:57:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:57:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:57:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:57:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:57:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:57:54 INFO - Intel Iris: 16:57:54 INFO - Chipset Model: Intel Iris 16:57:54 INFO - Type: GPU 16:57:54 INFO - Bus: Built-In 16:57:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:57:54 INFO - Vendor: Intel (0x8086) 16:57:54 INFO - Device ID: 0x0a2e 16:57:54 INFO - Revision ID: 0x0009 16:57:54 INFO - Displays: 16:57:54 INFO - Display: 16:57:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:57:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:57:54 INFO - Display Serial Number: 1600x1200 60 16:57:54 INFO - Main Display: Yes 16:57:54 INFO - Mirror: Off 16:57:54 INFO - Online: Yes 16:57:54 INFO - Rotation: Supported 16:57:54 INFO - Return code: 0 16:57:54 INFO - Running post-action listener: _resource_record_post_action 16:57:54 INFO - Running post-run listener: _resource_record_post_run 16:57:55 INFO - Total resource usage - Wall time: 1192s; CPU: 53.0%; Read bytes: 1142784; Write bytes: 509702656; Read time: 19; Write time: 1554 16:57:55 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 198453760; Write bytes: 196565504; Read time: 17948; Write time: 338 16:57:55 INFO - run-tests - Wall time: 1170s; CPU: 53.0%; Read bytes: 1142784; Write bytes: 300954112; Read time: 19; Write time: 1127 16:57:55 INFO - Running post-run listener: _upload_blobber_files 16:57:55 INFO - Blob upload gear active. 16:57:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:57:55 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 16:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:57:56 INFO - (blobuploader) - INFO - Open directory for files ... 16:57:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:57:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:57:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:57 INFO - (blobuploader) - INFO - Done attempting. 16:57:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:57:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:57:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:57:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:57:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:57:58 INFO - (blobuploader) - INFO - Done attempting. 16:57:58 INFO - (blobuploader) - INFO - Iteration through files over. 16:57:58 INFO - Return code: 0 16:57:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:57:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:57:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0108f4830eb3764b3765ff19db29214795d0c69f884a0de2a6a49606711f2ed4fbe47bc03d38532d0e69d61a6a91976c00360465c552a62b668402a700e80008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/016e398ac67f35adff10568d7364806692b91138d6826cfb48b46bdd94848159e334c71f304cd2ce52949c89085978b6b5957f7dfbf92e47a7587c67aadc4f2f"} 16:57:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:57:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:57:58 INFO - Contents: 16:57:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0108f4830eb3764b3765ff19db29214795d0c69f884a0de2a6a49606711f2ed4fbe47bc03d38532d0e69d61a6a91976c00360465c552a62b668402a700e80008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/016e398ac67f35adff10568d7364806692b91138d6826cfb48b46bdd94848159e334c71f304cd2ce52949c89085978b6b5957f7dfbf92e47a7587c67aadc4f2f"} 16:57:58 INFO - Running post-run listener: copy_logs_to_upload_dir 16:57:58 INFO - Copying logs to upload dir... 16:57:58 INFO - mkdir: /builds/slave/test/build/upload/logs 16:57:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1318.594299 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 58 secs) (at 2016-05-30 16:57:58.425341) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:57:58.433074) ========= 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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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/Mozilla-Beta/sha512/0108f4830eb3764b3765ff19db29214795d0c69f884a0de2a6a49606711f2ed4fbe47bc03d38532d0e69d61a6a91976c00360465c552a62b668402a700e80008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/016e398ac67f35adff10568d7364806692b91138d6826cfb48b46bdd94848159e334c71f304cd2ce52949c89085978b6b5957f7dfbf92e47a7587c67aadc4f2f"} build_url:https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011338 build_url: 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0108f4830eb3764b3765ff19db29214795d0c69f884a0de2a6a49606711f2ed4fbe47bc03d38532d0e69d61a6a91976c00360465c552a62b668402a700e80008", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/016e398ac67f35adff10568d7364806692b91138d6826cfb48b46bdd94848159e334c71f304cd2ce52949c89085978b6b5957f7dfbf92e47a7587c67aadc4f2f"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:57:58.472553) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:57:58.472933) ========= 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.K56t7Lvzsb/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.IKNO2kV0Qp/Listeners TMPDIR=/var/folders/2n/nlxy_zj109l8ldbz191kgyg800000w/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.005128 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:57:58.521439) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:57:58.524837) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:57:58.525203) ========= ========= Total master_lag: 0.22 =========