builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0198 starttime: 1461701369.07 results: success (0) buildid: 20160426062558 builduid: 18458ff6a626441ea2f2e0a4fd3bd5e1 revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.065747) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.066281) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.066673) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.087042) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.087509) ========= 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.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-26 13:09:29-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.38M=0.002s 2016-04-26 13:09:29 (6.38 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112674 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.221331) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.222062) ========= 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.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.034246 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:29.275754) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:09:29.276523) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev cbeae0c4410bef3864b3d3f4ad939321c61cb15b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-26 13:09:29,352 truncating revision to first 12 chars 2016-04-26 13:09:29,352 Setting DEBUG logging. 2016-04-26 13:09:29,352 attempt 1/10 2016-04-26 13:09:29,352 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/cbeae0c4410b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 13:09:29,938 unpacking tar archive at: mozilla-aurora-cbeae0c4410b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.832654 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-26 13:09:30.366945) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:30.367362) ========= script_repo_revision: cbeae0c4410bef3864b3d3f4ad939321c61cb15b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:30.367800) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:30.368107) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 13:09:30.382169) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 32 mins, 5 secs) (at 2016-04-26 13:09:30.382540) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:09:30 INFO - MultiFileLogger online at 20160426 13:09:30 in /builds/slave/test 13:09:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 13:09:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:09:30 INFO - {'append_to_log': False, 13:09:30 INFO - 'base_work_dir': '/builds/slave/test', 13:09:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 13:09:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:09:30 INFO - 'branch': 'Mozilla-Aurora', 13:09:30 INFO - 'buildbot_json_path': 'buildprops.json', 13:09:30 INFO - 'config_files': ('talos/mac_config.py',), 13:09:30 INFO - 'default_actions': ('clobber', 13:09:30 INFO - 'read-buildbot-config', 13:09:30 INFO - 'download-and-extract', 13:09:30 INFO - 'populate-webroot', 13:09:30 INFO - 'create-virtualenv', 13:09:30 INFO - 'install', 13:09:30 INFO - 'run-tests'), 13:09:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:09:30 INFO - 'download_minidump_stackwalk': True, 13:09:30 INFO - 'download_symbols': 'ondemand', 13:09:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:09:30 INFO - 'tooltool.py': '/tools/tooltool.py', 13:09:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:09:30 INFO - '/tools/misc-python/virtualenv.py')}, 13:09:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:09:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:09:30 INFO - 'installer_path': 'installer.exe', 13:09:30 INFO - 'log_level': 'info', 13:09:30 INFO - 'log_name': 'talos', 13:09:30 INFO - 'log_to_console': True, 13:09:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:09:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:09:30 INFO - 'opt_config_files': (), 13:09:30 INFO - 'pip_index': False, 13:09:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:30 INFO - 'cmd': ('bash', 13:09:30 INFO - '-c', 13:09:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:30 INFO - 'enabled': True, 13:09:30 INFO - 'halt_on_failure': False, 13:09:30 INFO - 'name': 'check_screen_resolution'},), 13:09:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:09:30 INFO - 'cmd': ('bash', 13:09:30 INFO - '-c', 13:09:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:09:30 INFO - 'enabled': True, 13:09:30 INFO - 'halt_on_failure': False, 13:09:30 INFO - 'name': 'check_screen_resolution'},), 13:09:30 INFO - 'run_cmd_checks_enabled': True, 13:09:30 INFO - 'sps_profile': False, 13:09:30 INFO - 'sps_profile_interval': 0, 13:09:30 INFO - 'suite': 'dromaeojs', 13:09:30 INFO - 'system_bits': '32', 13:09:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:09:30 INFO - 'title': 't-yosemite-r7-0198', 13:09:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:09:30 INFO - 'use_talos_json': True, 13:09:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:09:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:09:30 INFO - 'work_dir': 'build'} 13:09:30 INFO - ##### 13:09:30 INFO - ##### Running clobber step. 13:09:30 INFO - ##### 13:09:30 INFO - Running pre-action listener: _resource_record_pre_action 13:09:30 INFO - Running main action method: clobber 13:09:30 INFO - rmtree: /builds/slave/test/build 13:09:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:09:32 INFO - Running post-action listener: _resource_record_post_action 13:09:32 INFO - ##### 13:09:32 INFO - ##### Running read-buildbot-config step. 13:09:32 INFO - ##### 13:09:32 INFO - Running pre-action listener: _resource_record_pre_action 13:09:32 INFO - Running main action method: read_buildbot_config 13:09:32 INFO - Using buildbot properties: 13:09:32 INFO - { 13:09:32 INFO - "project": "", 13:09:32 INFO - "product": "firefox", 13:09:32 INFO - "script_repo_revision": "production", 13:09:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 13:09:32 INFO - "repository": "", 13:09:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 13:09:32 INFO - "buildid": "20160426062558", 13:09:32 INFO - "pgo_build": "False", 13:09:32 INFO - "basedir": "/builds/slave/test", 13:09:32 INFO - "buildnumber": 205, 13:09:32 INFO - "slavename": "t-yosemite-r7-0198", 13:09:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 13:09:32 INFO - "platform": "yosemite_r7", 13:09:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 13:09:32 INFO - "branch": "mozilla-aurora", 13:09:32 INFO - "repo_path": "releases/mozilla-aurora", 13:09:32 INFO - "revision": "cbeae0c4410bef3864b3d3f4ad939321c61cb15b", 13:09:32 INFO - "stage_platform": "macosx64", 13:09:32 INFO - "builduid": "18458ff6a626441ea2f2e0a4fd3bd5e1", 13:09:32 INFO - "slavebuilddir": "test" 13:09:32 INFO - } 13:09:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 13:09:32 INFO - Running post-action listener: _resource_record_post_action 13:09:32 INFO - ##### 13:09:32 INFO - ##### Running download-and-extract step. 13:09:32 INFO - ##### 13:09:32 INFO - Running pre-action listener: _resource_record_pre_action 13:09:32 INFO - Running main action method: download_and_extract 13:09:32 INFO - mkdir: /builds/slave/test/build/tests 13:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:09:32 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:09:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 13:09:34 INFO - Downloaded 1199 bytes. 13:09:34 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 13:09:34 INFO - Using the following test package requirements: 13:09:34 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:09:34 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 13:09:34 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 13:09:34 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'jsshell-mac.zip'], 13:09:34 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 13:09:34 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 13:09:34 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 13:09:34 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 13:09:34 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 13:09:34 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 13:09:34 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 13:09:34 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 13:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:09:34 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:09:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:09:35 INFO - Downloaded 18110203 bytes. 13:09:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:09:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:09:38 INFO - Return code: 0 13:09:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 13:09: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')]} 13:09:38 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:09:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 13:09:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 13:09:40 INFO - Downloaded 18110203 bytes. 13:09:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:09:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 13:09:44 INFO - Return code: 0 13:09:44 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')]} 13:09:44 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:09:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:09:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:09:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 13:09:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 13:09:45 INFO - Downloaded 10840145 bytes. 13:09:45 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 13:09:45 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 13:09:46 INFO - Return code: 0 13:09:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:09:46 INFO - https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 13:09:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:09:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:09:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 13:09:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 13:20:47 INFO - Downloaded 114070774 bytes. 13:20:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:20:47 INFO - mkdir: /builds/slave/test/properties 13:20:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:20:47 INFO - Writing to file /builds/slave/test/properties/build_url 13:20:47 INFO - Contents: 13:20:47 INFO - build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 13:20:49 INFO - Running post-action listener: _resource_record_post_action 13:20:49 INFO - Running post-action listener: set_extra_try_arguments 13:20:49 INFO - ##### 13:20:49 INFO - ##### Running populate-webroot step. 13:20:49 INFO - ##### 13:20:49 INFO - Running pre-action listener: _resource_record_pre_action 13:20:49 INFO - Running main action method: populate_webroot 13:20:49 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:20:49 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:20:49 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:20:49 INFO - '--tppagecycles', 13:20:49 INFO - '7'], 13:20:49 INFO - 'tests': ['tsvgm']}}, 13:20:49 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:20:49 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:20:49 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:20:49 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:20:49 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:20:49 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:20:49 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['damp', 'tps']}, 13:20:49 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'tests': ['damp', 'tps']}, 13:20:49 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['dromaeo_dom']}, 13:20:49 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:20:49 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'other-e10s': {'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['a11yr', 13:20:49 INFO - 'ts_paint', 13:20:49 INFO - 'tpaint', 13:20:49 INFO - 'sessionrestore', 13:20:49 INFO - 'sessionrestore_no_auto_restore', 13:20:49 INFO - 'tabpaint']}, 13:20:49 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['tsvgx', 13:20:49 INFO - 'tsvgr_opacity', 13:20:49 INFO - 'tart', 13:20:49 INFO - 'tscrollx', 13:20:49 INFO - 'cart']}, 13:20:49 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:20:49 INFO - 'tsvgr_opacity', 13:20:49 INFO - 'tart', 13:20:49 INFO - 'tscrollx', 13:20:49 INFO - 'cart']}, 13:20:49 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'talos_options': ['--disable-e10s'], 13:20:49 INFO - 'tests': ['tp5o']}, 13:20:49 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'tests': ['tp5o']}, 13:20:49 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'talos_options': ['--disable-e10s', 13:20:49 INFO - '--xperf_path', 13:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:20:49 INFO - 'tests': ['tp5n']}, 13:20:49 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:20:49 INFO - 'talos_options': ['--xperf_path', 13:20:49 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:20:49 INFO - 'tests': ['tp5n']}}, 13:20:49 INFO - 'talos.zip': {'path': '', 13:20:49 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:20:49 INFO - Running post-action listener: _resource_record_post_action 13:20:49 INFO - ##### 13:20:49 INFO - ##### Running create-virtualenv step. 13:20:49 INFO - ##### 13:20:49 INFO - Running pre-action listener: _resource_record_pre_action 13:20:49 INFO - Running main action method: create_virtualenv 13:20:49 INFO - Creating virtualenv /builds/slave/test/build/venv 13:20:49 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 13:20:49 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:20:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:20:49 INFO - Using real prefix '/tools/python27' 13:20:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:20:50 INFO - Installing distribute.............................................................................................................................................................................................done. 13:20:52 INFO - Installing pip.................done. 13:20:52 INFO - Return code: 0 13:20:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:20:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:20:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20: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')]} 13:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:20:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:20:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:20:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:20:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:20:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:20:53 INFO - 'HOME': '/Users/cltbld', 13:20:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:20:53 INFO - 'LOGNAME': 'cltbld', 13:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:20:53 INFO - 'MOZ_NO_REMOTE': '1', 13:20:53 INFO - 'NO_EM_RESTART': '1', 13:20:53 INFO - 'PAGER': '/bin/cat', 13:20:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:20:53 INFO - 'PWD': '/builds/slave/test', 13:20:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:20:53 INFO - 'SHELL': '/bin/bash', 13:20:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:20:53 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:20:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:20:53 INFO - 'USER': 'cltbld', 13:20:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:20:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:20:53 INFO - 'XPC_FLAGS': '0x0', 13:20:53 INFO - 'XPC_SERVICE_NAME': '0', 13:20:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:20:53 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 13:20:53 INFO - Cleaning up... 13:20:53 INFO - Return code: 0 13:20:53 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:20: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')]} 13:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20: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')]} 13:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20: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', '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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:20: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', 'psutil>=0.7.1'] in /builds/slave/test/build 13:20: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 psutil>=0.7.1 13:20:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:20:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:20:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:20:53 INFO - 'HOME': '/Users/cltbld', 13:20:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:20:53 INFO - 'LOGNAME': 'cltbld', 13:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:20:53 INFO - 'MOZ_NO_REMOTE': '1', 13:20:53 INFO - 'NO_EM_RESTART': '1', 13:20:53 INFO - 'PAGER': '/bin/cat', 13:20:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:20:53 INFO - 'PWD': '/builds/slave/test', 13:20:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:20:53 INFO - 'SHELL': '/bin/bash', 13:20:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:20:53 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:20:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:20:53 INFO - 'USER': 'cltbld', 13:20:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:20:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:20:53 INFO - 'XPC_FLAGS': '0x0', 13:20:53 INFO - 'XPC_SERVICE_NAME': '0', 13:20:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:20:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:20:53 INFO - Downloading/unpacking psutil>=0.7.1 13:20: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 13:20: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 13:20: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 13:20: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 13:20: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 13:20: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 13:20:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:20:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:20:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:20:56 INFO - Installing collected packages: psutil 13:20:56 INFO - Running setup.py install for psutil 13:20:56 INFO - building 'psutil._psutil_osx' extension 13:20:56 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 13:20:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:20:57 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:20:57 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 13:20:57 INFO - building 'psutil._psutil_posix' extension 13:20:57 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 13:20:57 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:20:57 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:20:57 INFO - ^ 13:20:57 INFO - 1 warning generated. 13:20:57 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 13:20:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:20:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:20:57 INFO - Successfully installed psutil 13:20:57 INFO - Cleaning up... 13:20:58 INFO - Return code: 0 13:20:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:20:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:20:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:20:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:20:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:20:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:20:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:20:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:20:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:20:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:20:58 INFO - 'HOME': '/Users/cltbld', 13:20:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:20:58 INFO - 'LOGNAME': 'cltbld', 13:20:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:20:58 INFO - 'MOZ_NO_REMOTE': '1', 13:20:58 INFO - 'NO_EM_RESTART': '1', 13:20:58 INFO - 'PAGER': '/bin/cat', 13:20:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:20:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:20:58 INFO - 'PWD': '/builds/slave/test', 13:20:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:20:58 INFO - 'SHELL': '/bin/bash', 13:20:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:20:58 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:20:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:20:58 INFO - 'USER': 'cltbld', 13:20:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:20:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:20:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:20:58 INFO - 'XPC_FLAGS': '0x0', 13:20:58 INFO - 'XPC_SERVICE_NAME': '0', 13:20:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:20:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:20:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:20:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:20:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:20:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:00 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:21:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:21:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:21:00 INFO - Installing collected packages: mozsystemmonitor 13:21:00 INFO - Running setup.py install for mozsystemmonitor 13:21:01 INFO - Successfully installed mozsystemmonitor 13:21:01 INFO - Cleaning up... 13:21:01 INFO - Return code: 0 13:21:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:01 INFO - 'HOME': '/Users/cltbld', 13:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:01 INFO - 'LOGNAME': 'cltbld', 13:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:01 INFO - 'MOZ_NO_REMOTE': '1', 13:21:01 INFO - 'NO_EM_RESTART': '1', 13:21:01 INFO - 'PAGER': '/bin/cat', 13:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:01 INFO - 'PWD': '/builds/slave/test', 13:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:01 INFO - 'SHELL': '/bin/bash', 13:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:01 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:01 INFO - 'USER': 'cltbld', 13:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:01 INFO - 'XPC_FLAGS': '0x0', 13:21:01 INFO - 'XPC_SERVICE_NAME': '0', 13:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:01 INFO - Downloading/unpacking blobuploader==1.2.4 13:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:03 INFO - Downloading blobuploader-1.2.4.tar.gz 13:21:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:21:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:21:03 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 13:21:03 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 13:21:03 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 13:21:03 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 13:21:03 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 13:21:03 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 13:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:21:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:04 INFO - Downloading docopt-0.6.1.tar.gz 13:21:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:21:05 INFO - Installing collected packages: blobuploader, requests, docopt 13:21:05 INFO - Running setup.py install for blobuploader 13:21:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:21:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:21:05 INFO - Running setup.py install for requests 13:21:05 INFO - Running setup.py install for docopt 13:21:05 INFO - Successfully installed blobuploader requests docopt 13:21:05 INFO - Cleaning up... 13:21:05 INFO - Return code: 0 13:21:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:05 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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:05 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 13:21:05 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 13:21:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:05 INFO - 'HOME': '/Users/cltbld', 13:21:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:05 INFO - 'LOGNAME': 'cltbld', 13:21:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:05 INFO - 'MOZ_NO_REMOTE': '1', 13:21:05 INFO - 'NO_EM_RESTART': '1', 13:21:05 INFO - 'PAGER': '/bin/cat', 13:21:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:05 INFO - 'PWD': '/builds/slave/test', 13:21:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:05 INFO - 'SHELL': '/bin/bash', 13:21:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:05 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:05 INFO - 'USER': 'cltbld', 13:21:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:05 INFO - 'XPC_FLAGS': '0x0', 13:21:05 INFO - 'XPC_SERVICE_NAME': '0', 13:21:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-i1BOn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-1B6E60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Ea3xTz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-0_aCmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-UOc0xH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ZESmIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-sDm3tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-r4fDT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:21:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:21:06 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-CGCXfm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-6s_nB4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-2T7HqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Qbhw5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-stuwtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ot4cEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-RyxN3H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-xzwz7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:21:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:21:07 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-cgWp2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:21:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:21:07 INFO - Running setup.py install for manifestparser 13:21:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:21:08 INFO - Running setup.py install for mozcrash 13:21:08 INFO - Running setup.py install for mozdebug 13:21:08 INFO - Running setup.py install for mozdevice 13:21:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:21:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:21:08 INFO - Running setup.py install for mozfile 13:21:08 INFO - Running setup.py install for mozhttpd 13:21:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:21:08 INFO - Running setup.py install for mozinfo 13:21:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:21:08 INFO - Running setup.py install for mozInstall 13:21:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Running setup.py install for mozleak 13:21:09 INFO - Running setup.py install for mozlog 13:21:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Running setup.py install for moznetwork 13:21:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Running setup.py install for mozprocess 13:21:09 INFO - Running setup.py install for mozprofile 13:21:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Running setup.py install for mozrunner 13:21:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:21:09 INFO - Running setup.py install for mozscreenshot 13:21:10 INFO - Running setup.py install for moztest 13:21:10 INFO - Running setup.py install for mozversion 13:21:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:21:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:21:10 INFO - Cleaning up... 13:21:10 INFO - Return code: 0 13:21:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:21:10 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')]} 13:21:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:10 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')]} 13:21:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:10 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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:10 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 13:21:10 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 13:21:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:10 INFO - 'HOME': '/Users/cltbld', 13:21:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:10 INFO - 'LOGNAME': 'cltbld', 13:21:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:10 INFO - 'MOZ_NO_REMOTE': '1', 13:21:10 INFO - 'NO_EM_RESTART': '1', 13:21:10 INFO - 'PAGER': '/bin/cat', 13:21:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:10 INFO - 'PWD': '/builds/slave/test', 13:21:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:10 INFO - 'SHELL': '/bin/bash', 13:21:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:10 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:10 INFO - 'USER': 'cltbld', 13:21:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:10 INFO - 'XPC_FLAGS': '0x0', 13:21:10 INFO - 'XPC_SERVICE_NAME': '0', 13:21:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:21:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-i1emHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:21:10 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)) 13:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:21:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-9n8B_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:21:10 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)) 13:21:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:21:10 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Gb0Y9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-ANXdry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-9drb5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:21:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FWtUhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-FINwZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-Rlbao4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-gsiVFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-taf5hP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-O_PHPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:21:11 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-B3F70f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:21:11 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)) 13:21:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:21:12 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-S3EPvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:21:12 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-5cbwo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:12 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-hOe6jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:21:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:21:12 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-yQ3iG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:21:12 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)) 13:21:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:21:12 INFO - Running setup.py (path:/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/pip-8XtfiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:21:12 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)) 13:21:12 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)) 13:21:12 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)) 13:21:12 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)) 13:21:12 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)) 13:21:12 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)) 13:21:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:21:12 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 13:21:12 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 13:21:12 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 13:21:12 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 13:21:12 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 13:21:12 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 13:21:14 INFO - Downloading blessings-1.6.tar.gz 13:21:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:21:15 INFO - Installing collected packages: blessings 13:21:15 INFO - Running setup.py install for blessings 13:21:15 INFO - Successfully installed blessings 13:21:15 INFO - Cleaning up... 13:21:15 INFO - Return code: 0 13:21:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:21:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:21:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:21:15 INFO - Reading from file tmpfile_stdout 13:21:15 INFO - Current package versions: 13:21:15 INFO - blessings == 1.6 13:21:15 INFO - blobuploader == 1.2.4 13:21:15 INFO - docopt == 0.6.1 13:21:15 INFO - manifestparser == 1.1 13:21:15 INFO - mozInstall == 1.12 13:21:15 INFO - mozcrash == 0.17 13:21:15 INFO - mozdebug == 0.1 13:21:15 INFO - mozdevice == 0.48 13:21:15 INFO - mozfile == 1.2 13:21:15 INFO - mozhttpd == 0.7 13:21:15 INFO - mozinfo == 0.9 13:21:15 INFO - mozleak == 0.1 13:21:15 INFO - mozlog == 3.1 13:21:15 INFO - moznetwork == 0.27 13:21:15 INFO - mozprocess == 0.22 13:21:15 INFO - mozprofile == 0.28 13:21:15 INFO - mozrunner == 6.11 13:21:15 INFO - mozscreenshot == 0.1 13:21:15 INFO - mozsystemmonitor == 0.0 13:21:15 INFO - moztest == 0.7 13:21:15 INFO - mozversion == 1.4 13:21:15 INFO - psutil == 3.1.1 13:21:15 INFO - requests == 1.2.3 13:21:15 INFO - wsgiref == 0.1.2 13:21:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:15 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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:15 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 13:21:15 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 13:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:15 INFO - 'HOME': '/Users/cltbld', 13:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:15 INFO - 'LOGNAME': 'cltbld', 13:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:15 INFO - 'MOZ_NO_REMOTE': '1', 13:21:15 INFO - 'NO_EM_RESTART': '1', 13:21:15 INFO - 'PAGER': '/bin/cat', 13:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:15 INFO - 'PWD': '/builds/slave/test', 13:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:15 INFO - 'SHELL': '/bin/bash', 13:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:15 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:15 INFO - 'USER': 'cltbld', 13:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:15 INFO - 'XPC_FLAGS': '0x0', 13:21:15 INFO - 'XPC_SERVICE_NAME': '0', 13:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 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)) 13:21:15 INFO - Cleaning up... 13:21:15 INFO - Return code: 0 13:21:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:21: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')]} 13:21:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21: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')]} 13:21:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:15 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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:15 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 13:21:15 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 13:21:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:15 INFO - 'HOME': '/Users/cltbld', 13:21:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:15 INFO - 'LOGNAME': 'cltbld', 13:21:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:15 INFO - 'MOZ_NO_REMOTE': '1', 13:21:15 INFO - 'NO_EM_RESTART': '1', 13:21:15 INFO - 'PAGER': '/bin/cat', 13:21:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:15 INFO - 'PWD': '/builds/slave/test', 13:21:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:15 INFO - 'SHELL': '/bin/bash', 13:21:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:15 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:15 INFO - 'USER': 'cltbld', 13:21:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:15 INFO - 'XPC_FLAGS': '0x0', 13:21:15 INFO - 'XPC_SERVICE_NAME': '0', 13:21:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:16 INFO - Downloading/unpacking jsonschema 13:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:21:18 INFO - Downloading/unpacking functools32 (from jsonschema) 13:21:18 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 13:21:18 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 13:21:18 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 13:21:18 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 13:21:18 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 13:21:18 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 13:21:18 INFO - Downloading functools32-3.2.3-2.tar.gz 13:21:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:21:18 INFO - warning: no files found matching '*.txt' 13:21:18 INFO - no previously-included directories found matching 'build' 13:21:18 INFO - no previously-included directories found matching 'dist' 13:21:18 INFO - no previously-included directories found matching '.git*' 13:21:18 INFO - Installing collected packages: jsonschema, functools32 13:21:18 INFO - Running setup.py install for functools32 13:21:19 INFO - warning: no files found matching '*.txt' 13:21:19 INFO - no previously-included directories found matching 'build' 13:21:19 INFO - no previously-included directories found matching 'dist' 13:21:19 INFO - no previously-included directories found matching '.git*' 13:21:19 INFO - Successfully installed jsonschema functools32 13:21:19 INFO - Cleaning up... 13:21:19 INFO - Return code: 0 13:21:19 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:21:19 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')]} 13:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:21:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:19 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')]} 13:21:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:21:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:21:19 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 0x103f49a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f59420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd72a5ea220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, '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.KILhp59Zjq/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.ThCtxUMxX1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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 13:21:19 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 13:21:19 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 13:21:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:19 INFO - 'HOME': '/Users/cltbld', 13:21:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:19 INFO - 'LOGNAME': 'cltbld', 13:21:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:19 INFO - 'MOZ_NO_REMOTE': '1', 13:21:19 INFO - 'NO_EM_RESTART': '1', 13:21:19 INFO - 'PAGER': '/bin/cat', 13:21:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:19 INFO - 'PWD': '/builds/slave/test', 13:21:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:19 INFO - 'SHELL': '/bin/bash', 13:21:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:19 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:19 INFO - 'USER': 'cltbld', 13:21:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:19 INFO - 'XPC_FLAGS': '0x0', 13:21:19 INFO - 'XPC_SERVICE_NAME': '0', 13:21:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:21:19 INFO - Downloading/unpacking flake8 13:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:21:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:21:21 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 13:21:21 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 13:21:21 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 13:21:21 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 13:21:21 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 13:21:21 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 13:21:22 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:21:22 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:22 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:21:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:21:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:21:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:21:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:21:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:21:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:21:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:21:23 INFO - no previously-included directories found matching 'docs/_build' 13:21:23 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:21:23 INFO - Running setup.py install for pep8 13:21:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:21:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:21:23 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:21:23 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:21:23 INFO - no previously-included directories found matching 'docs/_build' 13:21:23 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:21:23 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:21:23 INFO - Cleaning up... 13:21:23 INFO - Return code: 0 13:21:23 INFO - Running post-action listener: _resource_record_post_action 13:21:23 INFO - Running post-action listener: _start_resource_monitoring 13:21:23 INFO - Starting resource monitoring. 13:21:23 INFO - ##### 13:21:23 INFO - ##### Running install step. 13:21:23 INFO - ##### 13:21:23 INFO - Running pre-action listener: _resource_record_pre_action 13:21:23 INFO - Running main action method: install 13:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:21:23 INFO - Reading from file tmpfile_stdout 13:21:23 INFO - Detecting whether we're running mozinstall >=1.0... 13:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:21:23 INFO - Reading from file tmpfile_stdout 13:21:23 INFO - Output received: 13:21:23 INFO - Usage: mozinstall [options] installer 13:21:23 INFO - Options: 13:21:23 INFO - -h, --help show this help message and exit 13:21:23 INFO - -d DEST, --destination=DEST 13:21:23 INFO - Directory to install application into. [default: 13:21:23 INFO - "/builds/slave/test"] 13:21:23 INFO - --app=APP Application being installed. [default: firefox] 13:21:23 INFO - mkdir: /builds/slave/test/build/application 13:21:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:21:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 13:21:55 INFO - Reading from file tmpfile_stdout 13:21:55 INFO - Output received: 13:21:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 13:21:55 INFO - Running post-action listener: _resource_record_post_action 13:21:55 INFO - ##### 13:21:55 INFO - ##### Running run-tests step. 13:21:55 INFO - ##### 13:21:55 INFO - Running pre-action listener: _resource_record_pre_action 13:21:55 INFO - Running main action method: run_tests 13:21:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:21:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:21:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:21:55 INFO - 2016-04-26 13:21:55.477 screenresolution[1651:8646] starting screenresolution argv=screenresolution get 13:21:55 INFO - 2016-04-26 13:21:55.480 screenresolution[1651:8646] Display 0: 1600x1200x32@60 13:21:55 INFO - 2016-04-26 13:21:55.485 screenresolution[1652:8649] starting screenresolution argv=screenresolution list 13:21:55 INFO - Available Modes on Display 0 13:21:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:21:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:21:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:21:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:21:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:21:55 INFO - Intel Iris: 13:21:55 INFO - Chipset Model: Intel Iris 13:21:55 INFO - Type: GPU 13:21:55 INFO - Bus: Built-In 13:21:55 INFO - VRAM (Dynamic, Max): 1536 MB 13:21:55 INFO - Vendor: Intel (0x8086) 13:21:55 INFO - Device ID: 0x0a2e 13:21:55 INFO - Revision ID: 0x0009 13:21:55 INFO - Displays: 13:21:55 INFO - Display: 13:21:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:21:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:21:55 INFO - Display Serial Number: 1600x1200 60 13:21:55 INFO - Main Display: Yes 13:21:55 INFO - Mirror: Off 13:21:55 INFO - Online: Yes 13:21:55 INFO - Rotation: Supported 13:21:55 INFO - Return code: 0 13:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:21:55 INFO - Python 2.7.3 13:21:55 INFO - Return code: 0 13:21:55 INFO - grabbing minidump binary from tooltool 13:21:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:21:55 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 0x103de8e00>, '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 0x103c51db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103de3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:21:55 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 13:21:55 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 13:21:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:21:55 INFO - Return code: 0 13:21:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:21:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:21:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:21:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:21:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:21:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:21:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:21:56 INFO - Return code: 0 13:21:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 13:21:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0198 --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 13:21:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KILhp59Zjq/Render', 13:21:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:21:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:21:56 INFO - 'HOME': '/Users/cltbld', 13:21:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:21:56 INFO - 'LOGNAME': 'cltbld', 13:21:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:21:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:21:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:21:56 INFO - 'MOZ_NO_REMOTE': '1', 13:21:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:21:56 INFO - 'NO_EM_RESTART': '1', 13:21:56 INFO - 'PAGER': '/bin/cat', 13:21:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:21:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:21:56 INFO - 'PWD': '/builds/slave/test', 13:21:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:21:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:21:56 INFO - 'SHELL': '/bin/bash', 13:21:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ThCtxUMxX1/Listeners', 13:21:56 INFO - 'TMPDIR': '/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/', 13:21:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:21:56 INFO - 'USER': 'cltbld', 13:21:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:21:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:21:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:21:56 INFO - 'XPC_FLAGS': '0x0', 13:21:56 INFO - 'XPC_SERVICE_NAME': '0', 13:21:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:21:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0198', '--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 13:21:57 INFO - mozversion application_buildid: 20160426062558 13:21:57 INFO - mozversion application_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:21:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 13:21:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:21:57 INFO - mozversion application_name: Firefox 13:21:57 INFO - mozversion application_remotingname: firefox-dev 13:21:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:21:57 INFO - mozversion application_vendor: Mozilla 13:21:57 INFO - mozversion application_version: 48.0a2 13:21:57 INFO - mozversion platform_buildid: 20160426062558 13:21:57 INFO - mozversion platform_changeset: cbeae0c4410bef3864b3d3f4ad939321c61cb15b 13:21:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 13:21:57 INFO - mozversion platform_version: 48.0a2 13:21:57 INFO - using testdate: 1461702117 13:21:57 INFO - actual date: 1461702117 13:21:57 INFO - starting webserver on 'localhost:49277' 13:21:57 INFO - SUITE-START | Running 2 tests 13:21:57 INFO - TEST-START | dromaeo_css 13:21:57 INFO - operating with platform_type : mac_ 13:21:57 INFO - Initialising browser for dromaeo_css test... 13:21:57 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpilKZpe/profile http://localhost:49277/getInfo.html) 13:21:59 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 13:21:59 INFO - PROCESS | 1670 | colorDepth:24 13:21:59 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 13:21:59 INFO - PROCESS | 1670 | __metrics 13:21:59 INFO - PROCESS | 1670 | 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" 13:21:59 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:22:00 INFO - TEST-INFO | 1670: exit 0 13:22:00 INFO - Browser initialized. 13:22:00 INFO - Running cycle 1/1 for dromaeo_css test... 13:22:00 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpilKZpe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:22:12 INFO - PROCESS | 1673 | RSS: Main: 211435520 13:22:12 INFO - PROCESS | 1673 | 13:23:03 INFO - PROCESS | 1673 | 1461702183469 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] 13:25:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 13:25:15 INFO - PROCESS | 1673 | RSS: Main: 353521664 13:25:15 INFO - PROCESS | 1673 | 13:28:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 13:28:19 INFO - PROCESS | 1673 | RSS: Main: 355532800 13:28:19 INFO - PROCESS | 1673 | 13:31:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 13:31:21 INFO - PROCESS | 1673 | RSS: Main: 361734144 13:31:21 INFO - PROCESS | 1673 | 13:34:24 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 13:34:24 INFO - PROCESS | 1673 | RSS: Main: 362479616 13:34:24 INFO - PROCESS | 1673 | 13:37:28 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 13:37:28 INFO - PROCESS | 1673 | RSS: Main: 331829248 13:37:28 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 13:40:31 INFO - PROCESS | 1673 | RSS: Main: 368287744 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | __start_tp_report 13:40:31 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 13:40:31 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 13:40:31 INFO - PROCESS | 1673 | |i|pagename|runs| 13:40:31 INFO - PROCESS | 1673 | |0;dojo.html;3384.509246159307;3443.913902152446;3453.4301840196363;3467.6879080882713;3497.632748561401;5636.267285252921;5639.069553523674;5651.37834838168;5652.745626446809;5663.830085097448;5649.6327738697055;5662.462066103724;5666.829995100144;5667.4049224831415;5679.290088738908;5041.445441001487;5072.8731781705565;5117.360329958755;5143.691378517292;5163.535281824635;5133.794648214067;5136.768845401947;5152.995165942642;5157.071727089123;5157.406898206698;5129.3844738631415;5172.120739474299;5192.3249977503065;5212.139996900507;5217.921731174036;5199.168133098724;5213.296205012351;5216.243644671539;5218;5263;4997.150484417622;5048.823291184827;5095.719735414551;5122.462141475129;5131.1277082895995;4560.521145279731;5519;5545.694986775726;5554.527865131468;5586.944130558683;6145.108959200936;6184.288806787232;6201.937980620181;6207.937920620781;6211.378862113753;6043.6675982820925;6141.79287188273;6243.573232701399;6278.623282603058;6282.685865706696;5718.428157184331;5735.541156707453;5736.024875771171;5749.022666146765;5749.9937510935415;6260.436560709467;6263.937360626335;6266.24805023391;6273.278572964076;6284.842878928063;5906.852328691731;5989.131575921467;6035.064564992434;6038.607490513103;6108.267007959073;6232.875342493216;6246.625202487865;6249.000159974382;6254.155688981929;6255.218097737783;6086.543509236825;6128.448439640365;6131.662758548323;6132.41742034506;6151.7539298428455;5977.701114944235;6081.118237855486;6092.72582733778;6101.450869421773;6184.257889053342;4784.138855006063;4803.1114243864995;4817.8313759018265;4821.7589120543835;4855.223164293696;4333.544977777351;4391.055922976575;4407;4409.559044095629;4432.512423633398;5686.60193786431;5704.71476426177;5707.3721890592005;5718.914216286759;5741.483266506035;6248.218972628421;6263.4362907338555;6272.686365681698;6289.433950944437;6298.496120310364;4995.900081998339;5466.590005749585;5598.776048957996;5608.775648974077;5622.269105016322;5325.4142044375085;5331.1470164773455;5335.439778823245;5339.41266460689;5339.599530035263;6162.568620196544;6264.968675156595;6284.277308109626;6292.370762923762;6296.370362963667;6264.185655864709;6281.183446151971;6283.528735344776;6285.717142728679;6304.3380445054445;6258.7809426671665;6262.655553944577;6281.309056003744;6284.654344011124;6286.37136286386;5151.8196863109615;5952.226210592596;6058.939685554922;6096.963516202168;6106.480949119375;5523.944760552343;6197.163382943245;6287.591306565058;6291.056341548804;6313.589616674902;6073.757049717962;6075.483583895241;6096.695165241809;6104.359042300494;6112.816615501542;5891.705414729161;5997.760089596367;6008.369121242382;6099.359567245376;6110.7555697771595;5702.6293290935955;5819.359870414166;5823;5823.94176058234;5848.210491583752;6003.879922401615;6015.097735339734;6016.338202797776;6029.909551356645;6060.575759696955;5724;5832.854178645398;5878.2358293421585;5984.670843103671;5987.610805297816 13:40:31 INFO - PROCESS | 1673 | |1;ext.html;494.4091810286707;499.1439680947175;534.4121466386974;584.0654952076678;614.9392298285458;30252.243693907636;32810.34379312412;38405.03987400313;39491.80254098733;39596.406053909144;43845.7807710961;44475.110497790025;44514.10971780562;44533.332000020026;44586.33120503195;9545.57044932978;9781.217502599775;9787.657431989881;9805.607775688964;9825.65610203643;8462.4076314658;8664.566771661392;8666.6100025499;8671.306295496344;8704.347173961978;11483.655490335303;11742.941285293562;11755.941220293887;11773.587924422647;11776.587819426322;11628.360440175788;11844.644660660195;11857.644270671894;11880.227785193934;11888.28670279786;8460.78848028798;11235.269707468988;11466.254693444982;11609.883901160965;11806.52773889052;38741.41887871656;39275;39299.41050884239;39304.60695393038;39368.409473857915;3218.6942240487115;3236.935261294761;3247.870085196589;3529.154750556082;3915.3735402335774;27690.723092769218;29050.27374315659;29075.854620726972;29160.56259156114;29225.269368265752;2779.138467075213;2792.399634078683;2807.368342123026;2825.66092068951;2870.985645071782;495893;498173;498868.0113198858;498932;500466;60141.69929150326;60559.78880422367;60588.394116058276;60633;60722.69638651867;65169;65574.34425655683;65592.67203664046;65602.34397655963;65657;17163.742543861852;17849.929004259786;17871;17886.5528361792;17927.83469074506;14061.437542498186;14072;14140.575782726535;14158.929205353907;14164.150150990974;14325.85393168544;14751.77872331916;16034.679306413806;16504.6699066018;16687.582810429838;5518.972405138029;5809.037390930655;6711.7315307388135;6721.1262535871;6744.7976560703255;931.8182954323842;978.2907392140642;992.8609994600762;1054.9555939619875;1092.4373947417087;28567.57148642772;28876;29465.85267073694;29604.703952960626;29776.404471910442;866.5063497426347;928.679605536089;1018.6383833738915;1055.0135623192339;1118.3904771899333;13671.248081355621;14148.292585370897;14584.927075364556;15181.848181518044;15330.846691533165;635674;638986;648120;649675;650028;46737;53433.198502021725;54187;54342.72828635832;54354;1485.1460410264142;1493.1563666528377;1497.6779992301708;1506;1506.6835964447368;27338.863305683346;28793.85603071971;29166.27084322909;29299.414011720073;29377.11868643944;48435.5156448431;48998;49042.264366035255;49043.50956490389;50809.74595127001;487619;491174;491452;492274;492701.21896343166;25328;25493.745062549136;26080.478390431705;26163.476730464903;26165.345866353495;41842.372364413946;42922.785386074116;43395;43694.56305436905;44166.33750493809;34923.777667781505;35134.82432587821;36197.81901090478;36561.81719091388;36835.07912302107;35466.29067418689;36320.63679363278;36408.81795591111;37118;37842.62157378391;29313.85343073271;35838;36443.45334820033;37556.43665344967;37881;26666.86666566655;29995.550066749453;31244.06267811969;31722.365552688356;31763.841180794872;23584.410389740395;23865.642015369434;24265;24731.258062258163;25072.749272507772 13:40:31 INFO - PROCESS | 1673 | |2;jquery.html;373.4584851964651;376.2193448594166;382.31947134101307;389.1477663916022;411.95262544807355;19812.00939953002;20770;21014.57970840582;21126.04932778025;21145.15419383223;12421.006319494441;12435.626931192057;14553.199574023425;20624;21199.04604292807;5493.642913210629;5590.329160500745;5666.150077488389;5686.1186516089865;5705.4579814917515;5379.919301210485;5623.8594035149035;5627.212190293362;5637.126245431944;5646.096624540074;5626;5668.6111902583925;5673.035583950738;5686.260786097801;5780.190773291722;6053.727582258809;6084.574079814415;6097.085437184413;6100.603460775035;6109.663968481732;5727.169560413717;5945.464908158287;5961.254843144607;6072.210612620331;6082.787102451393;13722.588322350346;13739.450421983109;13894.513692020832;14029.719405611933;14064.929675351661;2151.429871084164;2255.571441426124;2370.2889133259955;2586.482703459316;2911.083008852205;10397.740056498573;10564.841527377095;13039.934800326033;14879.851201487954;15686.921565392215;1694.7288433850524;1798.7301904714266;1799.388208009283;1809.158741185342;1821.298799962011;111405.32892006627;115390.26914596288;117993;141571;146368;21207.469813254793;21758.673619895715;22142.114315427523;22214.66677999831;22228;36867.05063594906;37023.444648330296;37081;37867.81066094652;37953.62046379556;10729.892701073046;11050.834237486444;11334.886651133547;11669.883301167049;11689.473973671205;7271.818204544928;8890.199882010651;9229.907700923039;9566.760830979141;9629.422234665943;9089.409683175241;9385.249180065579;9959.651412200537;9998.80002399948;10113.797724045478;3764.792936388525;3770.6237223413254;3802.885913422602;3805.6955443564448;3865.3042452358845;836.5994866999505;893.5264309915755;916.4409710076848;940.9670661526814;988.4217732626494;17941.910290448464;18001.459956201335;21126.366209013755;21429.78570214278;21486.140554377962;988;990;990.9900900991042;992.1467537917385;1035.0322448510517;10934.343939363505;11205.99146076857;11224.32654040744;11295.830562541569;11322.773544529227;572022;572713;572853;610224;636759;74125;74253;75994;90264;101901;1041.2242879055002;1041.8541404203418;1056.587930707025;1060.7030031591166;1062.4528367890543;17113.14434278256;17689.46931592054;18420.631587368447;18611.627767444304;19084.809151908303;43003.13993720045;52115.73942130265;52362.21456678229;52943.735281323345;53751.92496149977;76860;77647.61176194083;78944;79725;81467.59266203632;6967.79096627102;7910.960445197968;8300.294474969694;8519.148085191679;8672.653093876173;9119.361644684808;10457.320274182153;12354.38228088574;12774.42515086842;13128.277944713132;11272.605458808903;11516.596919107791;11657.06743460538;11707.2975621463;11843.46704398287;11297.604583839526;11404.372759497975;11437.313761174357;11733.296002239893;11758.470868811095;11666.88333116658;11675.532978681098;11779.352135632622;11877.465514051715;11969.461374238006;8246.175382461706;8267.090620031911;8286.212809783061;8543.102974187621;8662.566871656518;8287.792805179919;10570.41862697559;11367.54529818831;11652.41737913084;11669.824952625548 13:40:31 INFO - PROCESS | 1673 | |3;mootools.html;1382.4055656067899;1591.7532782418725;1659.543625502987;1836.788769291531;1944.815242551957;7978.92021079789;8735.388522803407;8988.550572471375;8995.145461181193;9002.279817614592;8606.526641034741;8832.293416526678;8854.42446240994;8860.77848053799;8861.246794022516;1879.3798046644642;2205.9227927022557;2388.1880160745345;2437.4637579732475;2468.8024958003407;2127.819135373495;2472.1223965492263;2498.038255271724;2504.3488692939786;2507.6238564215423;2446.351716795051;2458.7049554053538;2477.170148000422;2482.466269752003;2484.6148846928663;2132.8080472757447;2388.5461762265286;2433.8052955763496;2461.4092617771607;2463.5935070713313;2431.817613678981;2448.2899959012043;2470.839395439291;2470.9011639534397;2475.4677744285063;8684.478931264144;8784.60469278884;8807.471551706854;8891.777705557348;8909.599068041955;5259.105951988171;5505.284313039279;5563.471470210364;5565.359983601857;5568.9443105569235;3247.886323978673;3605.6574625410544;3610.945835812465;3648.343298206322;3714.7771133732062;1023.6621914768109;1060.3955745225226;1061.2571200159919;1066.9466526673714;1103.5420300575252;12153.756924861542;12156.696082597917;12164;12181.573644922473;12192.146549741521;4809.495003024702;4887.193613053806;4939.851804445872;4976.552110310089;4993.825216117491;10244.334118282288;10250.897491024994;10258.128059115059;10258.333208341433;10273.845892311621;5433.728313584305;5525.11598144303;5585.134304182858;5594.13290939905;5611.663300202001;4327.437433133672;4510.120526497367;4697.342372067913;4896.485868983706;5080.161773307437;4045.554988951213;4045.8868786972357;4149.813258403379;4171.019810344566;4171.499420069611;1349.527665317131;1378.2901805570139;1378.5312993582231;1388.5695434415363;1390.5828251524501;800.7597720683772;908.68650315641;910.321810251367;937.2033771294482;944.8629948657406;5961.433663801932;6056.454919057306;6101.908471372933;6157.230346206724;6159.753609855646;441.4944888103168;445.9955400446019;452.31474316410663;452.54745254745256;453.41509452806173;3588.69496092835;3993.820278087493;4035.9798201008807;4047.494063242095;4072.1244932339096;9306.395084319633;10351.844722329171;10533.104686101768;10962.835557466644;10966.287191332698;10750.247482676294;10899.29154604964;10910.963458471433;10913.23607347494;10915.290506117235;7923.366130709529;8232.50604963704;8263.173682631688;8317.001959764866;8441.957790211132;5896.498797602252;5922.7927022554;5956.404359564009;6002.129691194839;6032.758689652364;10083.546240419346;10252.743681408025;10259.230557708053;10276.332038417479;10278.640247591298;8609;10019.248556358449;10109.93845646226;10320.277580569287;10464.058234758908;6944.930550694428;7161.4986950912935;7358.77923662291;7588.279113484431;7670.041244844395;7218.819529511804;8376.623051962555;8425.325973922194;8457.534835583856;8470.618822152896;7332.486725929134;7423.851522969402;7474.140473845578;7595.620218988918;7612.581308028111;6789.490788191004;7328.120625524971;7416.110066792019;7461.477696561188;7501.062367204099;7056.082709247765;7076.823079423056;7113.679884405317;7125.251848556034;7169.4981351304905;4821.251067698385;4881.877953051202;4973.129702302011;4992.126377883783;5016.322796422436;4412.889677758082;4916.778744956557;5039.21892106724;5139.691618502902;5319.095753721954 13:40:31 INFO - PROCESS | 1673 | |4;prototype.html;309.9581556489873;321.6510086556087;327.51527738946356;328.03884618069065;328.3695305014375;4280.59334363235;4854.219015399308;5000.524950129737;5368.489993450621;5482.287302650655;4596.448426188862;4653.627709783218;5328;5353.223782551528;5381.19282107684;4011.7392369496033;4159.771212583307;4178.728382655126;4191.245575796355;4206.348016057516;3548.1129717570607;3566.32239874423;4003.75977441353;4118.732282401635;4175.373693945917;3437.965620343802;4119.835206591748;4145.834166633331;4156.8129434175535;4187.623113919746;3501.0897166736577;3652.5982141964364;3832.4442955771397;4043.777592232441;4193.6645068394455;3353.8826141084946;3654.7441679082476;3656.561212654472;3670.751944338911;3682.871099511531;4567.862964111082;5006.69959802413;5194.896102077937;5238.97380513095;5241.973790131063;4075.3886916962397;4725.1730947084125;4769.189237829577;4793.400824896888;4815.229563269895;4287.957120428787;4408.977955110236;5527.015411677689;5552.167174923753;5637.267155269788;3997.181282516477;4017.658499027586;4020.396940458925;4022.557518672944;4072.2669919414493;5609.943900561024;5848.766049358063;5907.852303692399;5926.4073592640825;5983.371745966654;3921.960680419691;3981.303271927401;4051.39229115635;4082.2243773682903;4107.260693075215;3662.095710230665;4108.938365924513;4113.1568028554075;4125.814338354781;4138.275801734684;3792.81035948207;4140.482439695038;4707.692461397783;4738.062401311209;4762.64280178988;4304.849330273488;4740.407449068866;5321.494458026481;5742.54059675225;5744.655320680772;1436.4613270023742;1461.5311611830043;1466.4134346261367;1470.9043912145676;1477.674911519464;945.0455040409237;967.4292167621137;973.6446197137993;973.8441849304078;982.3221976835961;5770.394108618619;5782.710864456761;5804.4195580442465;5807.88384232313;5819.447152520504;5411.458854114557;6408.807735767934;6432.073406599986;6433.292337842833;6470.6441145737435;5239.764210610531;5505.807296744675;5747.48272655463;5769.048107062287;5803.245578074823;2836.3476400428017;3340.6659334066885;3356.8657253710066;3386.407378708716;3396.932061358759;5198.324217851655;5722.7424765885635;6680.799576012728;6697.263301036882;6710.630915299607;5086.974565127225;5185.455527169668;6023.337432882379;6055.333913269536;6078.331383547807;6111.930412177762;6117.84705382369;6139.728300528833;6160.9383906161265;6181.8454538636;4386.583274588909;5465.808696695596;5654.575906806924;5877.764889404376;5915.171875937286;5390.784368625368;5580.972095139498;5764.798232061858;5798.710064496842;5828.358880523054;5820.243368362086;5983.790567330123;6021.2473440819895;6131.547381047617;6144.508439324933;3679.466477360821;3939.7045221607923;3956.7625942443547;3963.286608410514;3967.682585393104;5280.445553216857;5501.807436739695;5782.624129431573;5943.821685349447;5982.6410415373475;4999.275105109666;5705.885882282414;5709.7430615623225;5717.885012422537;5750.48245657876;4810.488343509328;5613.691246981455;5672.631278966854;5678.829635110953;5750.383575473066;4619.214733495381;5057.949420505748;5068.8088299250385;5284.128118860345;5562.610617256753;4768.995532814305;5035.748212589283;5036.496350364934;5215.504527069922;5232.581393488588;4647.977444962103;4735.360726301905;5573.1855413016165;5581.581381396492;5588.189712491747 13:40:31 INFO - PROCESS | 1673 | |5;yui.html;679.4224908827497;740.3189066059225;747.3012733094561;760.1941941541965;778.9065312162542;14650.413983440649;14782.18697971611;15258.923705381472;15556.299966501534;15570;14009.719805603881;14062.804661603775;14075.436982520712;14146.434142634307;14206;736.8857827036791;761.4669731188163;918.4581097152678;919.7562645898843;933.5145724223399;929.0477260807693;931.4457897550947;935.0929598289648;938.4275591888942;938.5776400619715;989.9208063354914;993.4387071304726;996.3025881882675;997.2819569909653;1000.5297510170209;770.0797546931419;957.0620791624177;990.2869933647762;993.6224234790806;995.21378111292;734.5629350536423;736.381439590741;737.4284929179875;738.7340557399332;738.9113306403212;14992;15063.096214227071;15066;15077.47228846996;15082.622934426618;12435.44040518179;12488.625341239778;12691.365431728469;12878.356082195853;13010.349482525931;9976.716361596425;10146.695599132037;10389.116925061378;10415.323004004715;10714.892851071467;2994.895178668758;3478.617352091268;3586;3588.9592018314656;3666.5600127984812;79863.6006819968;79922.6003869977;80051;80211;80377;9248.815023699488;9431.528423578793;9470;9511.476868772284;9529.237660987104;21027.369178924655;21124.78875211259;21490.247841325792;21524.677129843065;21555.892220538797;7648.273414025659;7730;7759.534427934342;7769.028871391076;7885.1326354101;4117.464729585135;4159.043420013353;4237.131388065439;4292.291771857671;4458.799354029076;2970.5544168374486;3577.910552236163;3578.8031658258524;3590.1383667919513;3591.6947059500235;590.5806877117209;611.1840692675239;644.5294934697697;644.5906849150823;650.6649075726004;500.2845930319678;592.8695686948864;614.830921496592;617.2777849915609;617.6016469377226;7516.924830751732;7784.143744188134;8229;8329.750107496784;8433.789155271168;535.1357557544616;589.4783116941537;590.2209084009153;599.8824150747866;630.1713247080075;3851.056491159684;3983.2630963271886;4012.217617564547;4084.8366065357645;4111.239420707178;72297.6385118071;90414;91631.08368916358;92395.61406578906;94892.52553737187;74124;75107.62446187844;76161.61919190368;77773.61113194398;78458.6077069611;64234;67291.3270867285;70509.64745176242;72416.55166896632;72969;8105.878411823709;8335.708250211213;8399.496030238206;8534.31725461974;8570.4000719949;44245;44821.32768008417;45100.77449612731;45672.54327456683;46650.76674616605;60504;60584.394156057875;63055.36944630495;63280;64524;11044.779104417707;11159.553617855196;11425.257358271685;11437;11519.88480115188;11654.70863228426;11890.16768826174;11930.582429614922;12210.084243681937;12896;8665.480071195749;11075.778484430428;11420.828687569527;11806.468708907947;11911.3448760319;11188.216824822184;11750.529978801094;11887.16789824704;11905.821412678644;12039.879601203877;11148.386838723947;11266.66200014001;11454.312741235553;11472;11548.364839933884;9783.46190959504;9954.104130628279;10006.249531285037;10353.482325883826;10360.430176340373;9813.509324533887;9921.851172232278;9986.700398988041;9990.400575965465;10033.09702126812 13:40:31 INFO - PROCESS | 1673 | __end_tp_report 13:40:31 INFO - PROCESS | 1673 | __start_cc_report 13:40:31 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1283 13:40:31 INFO - PROCESS | 1673 | __end_cc_report 13:40:31 INFO - PROCESS | 1673 | __startTimestamp1461703231759__endTimestamp 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | ------- Summary: start ------- 13:40:31 INFO - PROCESS | 1673 | Number of tests: 6 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5677.55 Median:5832.85 stddev:637.98 (10.9%) stddev-sans-first:614.05 13:40:31 INFO - PROCESS | 1673 | Values: 3384.5 3443.9 3453.4 3467.7 3497.6 5636.3 5639.1 5651.4 5652.7 5663.8 5649.6 5662.5 5666.8 5667.4 5679.3 5041.4 5072.9 5117.4 5143.7 5163.5 5133.8 5136.8 5153.0 5157.1 5157.4 5129.4 5172.1 5192.3 5212.1 5217.9 5199.2 5213.3 5216.2 5218.0 5263.0 4997.2 5048.8 5095.7 5122.5 5131.1 4560.5 5519.0 5545.7 5554.5 5586.9 6145.1 6184.3 6201.9 6207.9 6211.4 6043.7 6141.8 6243.6 6278.6 6282.7 5718.4 5735.5 5736.0 5749.0 5750.0 6260.4 6263.9 6266.2 6273.3 6284.8 5906.9 5989.1 6035.1 6038.6 6108.3 6232.9 6246.6 6249.0 6254.2 6255.2 6086.5 6128.4 6131.7 6132.4 6151.8 5977.7 6081.1 6092.7 6101.5 6184.3 4784.1 4803.1 4817.8 4821.8 4855.2 4333.5 4391.1 4407.0 4409.6 4432.5 5686.6 5704.7 5707.4 5718.9 5741.5 6248.2 6263.4 6272.7 6289.4 6298.5 4995.9 5466.6 5598.8 5608.8 5622.3 5325.4 5331.1 5335.4 5339.4 5339.6 6162.6 6265.0 6284.3 6292.4 6296.4 6264.2 6281.2 6283.5 6285.7 6304.3 6258.8 6262.7 6281.3 6284.7 6286.4 5151.8 5952.2 6058.9 6097.0 6106.5 5523.9 6197.2 6287.6 6291.1 6313.6 6073.8 6075.5 6096.7 6104.4 6112.8 5891.7 5997.8 6008.4 6099.4 6110.8 5702.6 5819.4 5823.0 5823.9 5848.2 6003.9 6015.1 6016.3 6029.9 6060.6 5724.0 5832.9 5878.2 5984.7 5987.6 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:67545.89 Median:36259.23 stddev:146735.06 (404.7%) stddev-sans-first:147060.33 13:40:31 INFO - PROCESS | 1673 | Values: 494.4 499.1 534.4 584.1 614.9 30252.2 32810.3 38405.0 39491.8 39596.4 43845.8 44475.1 44514.1 44533.3 44586.3 9545.6 9781.2 9787.7 9805.6 9825.7 8462.4 8664.6 8666.6 8671.3 8704.3 11483.7 11742.9 11755.9 11773.6 11776.6 11628.4 11844.6 11857.6 11880.2 11888.3 8460.8 11235.3 11466.3 11609.9 11806.5 38741.4 39275.0 39299.4 39304.6 39368.4 3218.7 3236.9 3247.9 3529.2 3915.4 27690.7 29050.3 29075.9 29160.6 29225.3 2779.1 2792.4 2807.4 2825.7 2871.0 495893.0 498173.0 498868.0 498932.0 500466.0 60141.7 60559.8 60588.4 60633.0 60722.7 65169.0 65574.3 65592.7 65602.3 65657.0 17163.7 17849.9 17871.0 17886.6 17927.8 14061.4 14072.0 14140.6 14158.9 14164.2 14325.9 14751.8 16034.7 16504.7 16687.6 5519.0 5809.0 6711.7 6721.1 6744.8 931.8 978.3 992.9 1055.0 1092.4 28567.6 28876.0 29465.9 29604.7 29776.4 866.5 928.7 1018.6 1055.0 1118.4 13671.2 14148.3 14584.9 15181.8 15330.8 635674.0 638986.0 648120.0 649675.0 650028.0 46737.0 53433.2 54187.0 54342.7 54354.0 1485.1 1493.2 1497.7 1506.0 1506.7 27338.9 28793.9 29166.3 29299.4 29377.1 48435.5 48998.0 49042.3 49043.5 50809.7 487619.0 491174.0 491452.0 492274.0 492701.2 25328.0 25493.7 26080.5 26163.5 26165.3 41842.4 42922.8 43395.0 43694.6 44166.3 34923.8 35134.8 36197.8 36561.8 36835.1 35466.3 36320.6 36408.8 37118.0 37842.6 29313.9 35838.0 36443.5 37556.4 37881.0 26666.9 29995.6 31244.1 31722.4 31763.8 23584.4 23865.6 24265.0 24731.3 25072.7 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34692.64 Median:19889.07 stddev:98328.63 (494.4%) stddev-sans-first:98570.70 13:40:31 INFO - PROCESS | 1673 | Values: 373.5 376.2 382.3 389.1 412.0 19812.0 20770.0 21014.6 21126.0 21145.2 12421.0 12435.6 14553.2 20624.0 21199.0 5493.6 5590.3 5666.2 5686.1 5705.5 5379.9 5623.9 5627.2 5637.1 5646.1 5626.0 5668.6 5673.0 5686.3 5780.2 6053.7 6084.6 6097.1 6100.6 6109.7 5727.2 5945.5 5961.3 6072.2 6082.8 13722.6 13739.5 13894.5 14029.7 14064.9 2151.4 2255.6 2370.3 2586.5 2911.1 10397.7 10564.8 13039.9 14879.9 15686.9 1694.7 1798.7 1799.4 1809.2 1821.3 111405.3 115390.3 117993.0 141571.0 146368.0 21207.5 21758.7 22142.1 22214.7 22228.0 36867.1 37023.4 37081.0 37867.8 37953.6 10729.9 11050.8 11334.9 11669.9 11689.5 7271.8 8890.2 9229.9 9566.8 9629.4 9089.4 9385.2 9959.7 9998.8 10113.8 3764.8 3770.6 3802.9 3805.7 3865.3 836.6 893.5 916.4 941.0 988.4 17941.9 18001.5 21126.4 21429.8 21486.1 988.0 990.0 991.0 992.1 1035.0 10934.3 11206.0 11224.3 11295.8 11322.8 572022.0 572713.0 572853.0 610224.0 636759.0 74125.0 74253.0 75994.0 90264.0 101901.0 1041.2 1041.9 1056.6 1060.7 1062.5 17113.1 17689.5 18420.6 18611.6 19084.8 43003.1 52115.7 52362.2 52943.7 53751.9 76860.0 77647.6 78944.0 79725.0 81467.6 6967.8 7911.0 8300.3 8519.1 8672.7 9119.4 10457.3 12354.4 12774.4 13128.3 11272.6 11516.6 11657.1 11707.3 11843.5 11297.6 11404.4 11437.3 11733.3 11758.5 11666.9 11675.5 11779.4 11877.5 11969.5 8246.2 8267.1 8286.2 8543.1 8662.6 8287.8 10570.4 11367.5 11652.4 11669.8 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5766.98 Median:2575.38 stddev:3248.41 (126.1%) stddev-sans-first:3240.81 13:40:31 INFO - PROCESS | 1673 | Values: 1382.4 1591.8 1659.5 1836.8 1944.8 7978.9 8735.4 8988.6 8995.1 9002.3 8606.5 8832.3 8854.4 8860.8 8861.2 1879.4 2205.9 2388.2 2437.5 2468.8 2127.8 2472.1 2498.0 2504.3 2507.6 2446.4 2458.7 2477.2 2482.5 2484.6 2132.8 2388.5 2433.8 2461.4 2463.6 2431.8 2448.3 2470.8 2470.9 2475.5 8684.5 8784.6 8807.5 8891.8 8909.6 5259.1 5505.3 5563.5 5565.4 5568.9 3247.9 3605.7 3610.9 3648.3 3714.8 1023.7 1060.4 1061.3 1066.9 1103.5 12153.8 12156.7 12164.0 12181.6 12192.1 4809.5 4887.2 4939.9 4976.6 4993.8 10244.3 10250.9 10258.1 10258.3 10273.8 5433.7 5525.1 5585.1 5594.1 5611.7 4327.4 4510.1 4697.3 4896.5 5080.2 4045.6 4045.9 4149.8 4171.0 4171.5 1349.5 1378.3 1378.5 1388.6 1390.6 800.8 908.7 910.3 937.2 944.9 5961.4 6056.5 6101.9 6157.2 6159.8 441.5 446.0 452.3 452.5 453.4 3588.7 3993.8 4036.0 4047.5 4072.1 9306.4 10351.8 10533.1 10962.8 10966.3 10750.2 10899.3 10911.0 10913.2 10915.3 7923.4 8232.5 8263.2 8317.0 8442.0 5896.5 5922.8 5956.4 6002.1 6032.8 10083.5 10252.7 10259.2 10276.3 10278.6 8609.0 10019.2 10109.9 10320.3 10464.1 6944.9 7161.5 7358.8 7588.3 7670.0 7218.8 8376.6 8425.3 8457.5 8470.6 7332.5 7423.9 7474.1 7595.6 7612.6 6789.5 7328.1 7416.1 7461.5 7501.1 7056.1 7076.8 7113.7 7125.3 7169.5 4821.3 4881.9 4973.1 4992.1 5016.3 4412.9 4916.8 5039.2 5139.7 5319.1 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:4632.85 Median:5063.38 stddev:1421.29 (28.1%) stddev-sans-first:1387.75 13:40:31 INFO - PROCESS | 1673 | Values: 310.0 321.7 327.5 328.0 328.4 4280.6 4854.2 5000.5 5368.5 5482.3 4596.4 4653.6 5328.0 5353.2 5381.2 4011.7 4159.8 4178.7 4191.2 4206.3 3548.1 3566.3 4003.8 4118.7 4175.4 3438.0 4119.8 4145.8 4156.8 4187.6 3501.1 3652.6 3832.4 4043.8 4193.7 3353.9 3654.7 3656.6 3670.8 3682.9 4567.9 5006.7 5194.9 5239.0 5242.0 4075.4 4725.2 4769.2 4793.4 4815.2 4288.0 4409.0 5527.0 5552.2 5637.3 3997.2 4017.7 4020.4 4022.6 4072.3 5609.9 5848.8 5907.9 5926.4 5983.4 3922.0 3981.3 4051.4 4082.2 4107.3 3662.1 4108.9 4113.2 4125.8 4138.3 3792.8 4140.5 4707.7 4738.1 4762.6 4304.8 4740.4 5321.5 5742.5 5744.7 1436.5 1461.5 1466.4 1470.9 1477.7 945.0 967.4 973.6 973.8 982.3 5770.4 5782.7 5804.4 5807.9 5819.4 5411.5 6408.8 6432.1 6433.3 6470.6 5239.8 5505.8 5747.5 5769.0 5803.2 2836.3 3340.7 3356.9 3386.4 3396.9 5198.3 5722.7 6680.8 6697.3 6710.6 5087.0 5185.5 6023.3 6055.3 6078.3 6111.9 6117.8 6139.7 6160.9 6181.8 4386.6 5465.8 5654.6 5877.8 5915.2 5390.8 5581.0 5764.8 5798.7 5828.4 5820.2 5983.8 6021.2 6131.5 6144.5 3679.5 3939.7 3956.8 3963.3 3967.7 5280.4 5501.8 5782.6 5943.8 5982.6 4999.3 5705.9 5709.7 5717.9 5750.5 4810.5 5613.7 5672.6 5678.8 5750.4 4619.2 5057.9 5068.8 5284.1 5562.6 4769.0 5035.7 5036.5 5215.5 5232.6 4648.0 4735.4 5573.2 5581.6 5588.2 13:40:31 INFO - PROCESS | 1673 | 13:40:31 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:17889.58 Median:60544.20 stddev:24771.82 (40.9%) stddev-sans-first:24807.60 13:40:31 INFO - PROCESS | 1673 | Values: 679.4 740.3 747.3 760.2 778.9 14650.4 14782.2 15258.9 15556.3 15570.0 14009.7 14062.8 14075.4 14146.4 14206.0 736.9 761.5 918.5 919.8 933.5 929.0 931.4 935.1 938.4 938.6 989.9 993.4 996.3 997.3 1000.5 770.1 957.1 990.3 993.6 995.2 734.6 736.4 737.4 738.7 738.9 14992.0 15063.1 15066.0 15077.5 15082.6 12435.4 12488.6 12691.4 12878.4 13010.3 9976.7 10146.7 10389.1 10415.3 10714.9 2994.9 3478.6 3586.0 3589.0 3666.6 79863.6 79922.6 80051.0 80211.0 80377.0 9248.8 9431.5 9470.0 9511.5 9529.2 21027.4 21124.8 21490.2 21524.7 21555.9 7648.3 7730.0 7759.5 7769.0 7885.1 4117.5 4159.0 4237.1 4292.3 4458.8 2970.6 3577.9 3578.8 3590.1 3591.7 590.6 611.2 644.5 644.6 650.7 500.3 592.9 614.8 617.3 617.6 7516.9 7784.1 8229.0 8329.8 8433.8 535.1 589.5 590.2 599.9 630.2 3851.1 3983.3 4012.2 4084.8 4111.2 72297.6 90414.0 91631.1 92395.6 94892.5 74124.0 75107.6 76161.6 77773.6 78458.6 64234.0 67291.3 70509.6 72416.6 72969.0 8105.9 8335.7 8399.5 8534.3 8570.4 44245.0 44821.3 45100.8 45672.5 46650.8 60504.0 60584.4 63055.4 63280.0 64524.0 11044.8 11159.6 11425.3 11437.0 11519.9 11654.7 11890.2 11930.6 12210.1 12896.0 8665.5 11075.8 11420.8 11806.5 11911.3 11188.2 11750.5 11887.2 11905.8 12039.9 11148.4 11266.7 11454.3 11472.0 11548.4 9783.5 9954.1 10006.2 10353.5 10360.4 9813.5 9921.9 9986.7 9990.4 10033.1 13:40:31 INFO - PROCESS | 1673 | -------- Summary: end -------- 13:40:31 INFO - PROCESS | 1673 | 13:40:32 INFO - TEST-INFO | 1673: exit 0 13:40:32 INFO - TEST-OK | dromaeo_css | took 1115143ms 13:40:32 INFO - TEST-START | kraken 13:40:32 INFO - operating with platform_type : mac_ 13:40:32 INFO - Initialising browser for kraken test... 13:40:32 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpUVy11H/profile http://localhost:49277/getInfo.html) 13:40:34 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 13:40:34 INFO - PROCESS | 1680 | colorDepth:24 13:40:34 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 13:40:34 INFO - PROCESS | 1680 | __metrics 13:40:34 INFO - PROCESS | 1680 | 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" 13:40:34 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:40:35 INFO - TEST-INFO | 1680: exit 0 13:40:35 INFO - Browser initialized. 13:40:35 INFO - Running cycle 1/1 for kraken test... 13:40:35 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/T/tmpUVy11H/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:40:47 INFO - PROCESS | 1682 | RSS: Main: 211791872 13:40:47 INFO - PROCESS | 1682 | 13:40:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 13:40:50 INFO - PROCESS | 1682 | RSS: Main: 269893632 13:40:50 INFO - PROCESS | 1682 | 13:40:53 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 13:40:54 INFO - PROCESS | 1682 | RSS: Main: 284405760 13:40:54 INFO - PROCESS | 1682 | 13:40:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 13:40:58 INFO - PROCESS | 1682 | RSS: Main: 371560448 13:40:58 INFO - PROCESS | 1682 | 13:41:00 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 13:41:00 INFO - PROCESS | 1682 | RSS: Main: 277979136 13:41:00 INFO - PROCESS | 1682 | 13:41:02 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:41:03 INFO - PROCESS | 1682 | RSS: Main: 278507520 13:41:03 INFO - PROCESS | 1682 | 13:41:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 13:41:06 INFO - PROCESS | 1682 | RSS: Main: 317575168 13:41:06 INFO - PROCESS | 1682 | 13:41:09 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 13:41:10 INFO - PROCESS | 1682 | RSS: Main: 319287296 13:41:10 INFO - PROCESS | 1682 | 13:41:13 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 13:41:13 INFO - PROCESS | 1682 | RSS: Main: 317243392 13:41:13 INFO - PROCESS | 1682 | 13:41:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:41:16 INFO - PROCESS | 1682 | RSS: Main: 306921472 13:41:16 INFO - PROCESS | 1682 | 13:41:18 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:41:18 INFO - PROCESS | 1682 | RSS: Main: 313741312 13:41:18 INFO - PROCESS | 1682 | 13:41:20 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:41:21 INFO - PROCESS | 1682 | RSS: Main: 316575744 13:41:21 INFO - PROCESS | 1682 | 13:41:24 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:41:25 INFO - PROCESS | 1682 | RSS: Main: 318615552 13:41:25 INFO - PROCESS | 1682 | 13:41:27 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:41:28 INFO - PROCESS | 1682 | RSS: Main: 315547648 13:41:28 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 13:41:30 INFO - PROCESS | 1682 | RSS: Main: 380301312 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | __start_tp_report 13:41:30 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 13:41:30 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 13:41:30 INFO - PROCESS | 1682 | |i|pagename|runs| 13:41:30 INFO - PROCESS | 1682 | |0;ai-astar;114;114;108;115;114;116;113;114;104;114 13:41:30 INFO - PROCESS | 1682 | |1;audio-beat-detection;160;146;171;156;120;141;120;150;157;116 13:41:30 INFO - PROCESS | 1682 | |2;audio-dft;183;185;184;193;259;206;183;195;257;201 13:41:30 INFO - PROCESS | 1682 | |3;audio-fft;73;78;73;150;58;59;76;88;162;55 13:41:30 INFO - PROCESS | 1682 | |4;audio-oscillator;91;89;142;78;80;89;128;78;75;152 13:41:30 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;87;85;89;85;88;85;88;85;88;84 13:41:30 INFO - PROCESS | 1682 | |6;imaging-darkroom;94;93;93;93;93;93;94;94;93;93 13:41:30 INFO - PROCESS | 1682 | |7;imaging-desaturate;84;88;123;129;130;83;84;86;96;129 13:41:30 INFO - PROCESS | 1682 | |8;json-parse-financial;60;64;67;66;64;67;60;82;59;57 13:41:30 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;62;65;64;73;71;75;70;69;93;95 13:41:30 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;72;81;80;82;80;80;79;79;81;85 13:41:30 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;135;158;126;142;144;146;149;138;229;161 13:41:30 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;169;166;164;168;166;161;169;166;165;168 13:41:30 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;67;68;68;69;70;72;78;86;92;67 13:41:30 INFO - PROCESS | 1682 | __end_tp_report 13:41:30 INFO - PROCESS | 1682 | __start_cc_report 13:41:30 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2028 13:41:30 INFO - PROCESS | 1682 | __end_cc_report 13:41:30 INFO - PROCESS | 1682 | __startTimestamp1461703290562__endTimestamp 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | ------- Summary: start ------- 13:41:30 INFO - PROCESS | 1682 | Number of tests: 14 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:112.60 Median:114.00 stddev:3.69 (3.2%) stddev-sans-first:3.88 13:41:30 INFO - PROCESS | 1682 | Values: 114.0 114.0 108.0 115.0 114.0 116.0 113.0 114.0 104.0 114.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:153.00 stddev:19.10 (12.5%) stddev-sans-first:19.32 13:41:30 INFO - PROCESS | 1682 | Values: 160.0 146.0 171.0 156.0 120.0 141.0 120.0 150.0 157.0 116.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:204.60 Median:198.00 stddev:29.23 (14.8%) stddev-sans-first:29.94 13:41:30 INFO - PROCESS | 1682 | Values: 183.0 185.0 184.0 193.0 259.0 206.0 183.0 195.0 257.0 201.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:87.20 Median:73.00 stddev:37.77 (51.7%) stddev-sans-first:39.71 13:41:30 INFO - PROCESS | 1682 | Values: 73.0 78.0 73.0 150.0 58.0 59.0 76.0 88.0 162.0 55.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:100.20 Median:79.00 stddev:29.00 (36.7%) stddev-sans-first:30.56 13:41:30 INFO - PROCESS | 1682 | Values: 91.0 89.0 142.0 78.0 80.0 89.0 128.0 78.0 75.0 152.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.50 stddev:1.78 (2.0%) stddev-sans-first:1.87 13:41:30 INFO - PROCESS | 1682 | Values: 87.0 85.0 89.0 85.0 88.0 85.0 88.0 85.0 88.0 84.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:93.00 stddev:0.48 (0.5%) stddev-sans-first:0.44 13:41:30 INFO - PROCESS | 1682 | Values: 94.0 93.0 93.0 93.0 93.0 93.0 94.0 94.0 93.0 93.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:103.20 Median:84.00 stddev:21.51 (25.6%) stddev-sans-first:21.67 13:41:30 INFO - PROCESS | 1682 | Values: 84.0 88.0 123.0 129.0 130.0 83.0 84.0 86.0 96.0 129.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:65.00 stddev:7.06 (10.9%) stddev-sans-first:7.29 13:41:30 INFO - PROCESS | 1682 | Values: 60.0 64.0 67.0 66.0 64.0 67.0 60.0 82.0 59.0 57.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:72.00 stddev:11.44 (15.9%) stddev-sans-first:11.32 13:41:30 INFO - PROCESS | 1682 | Values: 62.0 65.0 64.0 73.0 71.0 75.0 70.0 69.0 93.0 95.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:79.90 Median:80.50 stddev:3.28 (4.1%) stddev-sans-first:1.86 13:41:30 INFO - PROCESS | 1682 | Values: 72.0 81.0 80.0 82.0 80.0 80.0 79.0 79.0 81.0 85.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:152.80 Median:147.50 stddev:28.69 (19.5%) stddev-sans-first:29.70 13:41:30 INFO - PROCESS | 1682 | Values: 135.0 158.0 126.0 142.0 144.0 146.0 149.0 138.0 229.0 161.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.20 Median:167.00 stddev:2.49 (1.5%) stddev-sans-first:2.42 13:41:30 INFO - PROCESS | 1682 | Values: 169.0 166.0 164.0 168.0 166.0 161.0 169.0 166.0 165.0 168.0 13:41:30 INFO - PROCESS | 1682 | 13:41:30 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:71.00 stddev:8.81 (12.4%) stddev-sans-first:9.00 13:41:30 INFO - PROCESS | 1682 | Values: 67.0 68.0 68.0 69.0 70.0 72.0 78.0 86.0 92.0 67.0 13:41:30 INFO - PROCESS | 1682 | -------- Summary: end -------- 13:41:30 INFO - PROCESS | 1682 | 13:41:31 INFO - TEST-INFO | 1682: exit 0 13:41:31 INFO - TEST-OK | kraken | took 59046ms 13:41:31 INFO - SUITE-END | took 1174s 13:41:31 INFO - Completed test suite (00:19:34) 13:41:31 INFO - javascript benchmark 13:41:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3384.509246159307, 3443.913902152446, 3453.4301840196363, 3467.6879080882713, 3497.632748561401, 5636.267285252921, 5639.069553523674, 5651.37834838168, 5652.745626446809, 5663.830085097448, 5649.6327738697055, 5662.462066103724, 5666.829995100144, 5667.4049224831415, 5679.290088738908, 5041.445441001487, 5072.8731781705565, 5117.360329958755, 5143.691378517292, 5163.535281824635, 5133.794648214067, 5136.768845401947, 5152.995165942642, 5157.071727089123, 5157.406898206698, 5129.3844738631415, 5172.120739474299, 5192.3249977503065, 5212.139996900507, 5217.921731174036, 5199.168133098724, 5213.296205012351, 5216.243644671539, 5218.0, 5263.0, 4997.150484417622, 5048.823291184827, 5095.719735414551, 5122.462141475129, 5131.1277082895995, 4560.521145279731, 5519.0, 5545.694986775726, 5554.527865131468, 5586.944130558683, 6145.108959200936, 6184.288806787232, 6201.937980620181, 6207.937920620781, 6211.378862113753, 6043.6675982820925, 6141.79287188273, 6243.573232701399, 6278.623282603058, 6282.685865706696, 5718.428157184331, 5735.541156707453, 5736.024875771171, 5749.022666146765, 5749.9937510935415, 6260.436560709467, 6263.937360626335, 6266.24805023391, 6273.278572964076, 6284.842878928063, 5906.852328691731, 5989.131575921467, 6035.064564992434, 6038.607490513103, 6108.267007959073, 6232.875342493216, 6246.625202487865, 6249.000159974382, 6254.155688981929, 6255.218097737783, 6086.543509236825, 6128.448439640365, 6131.662758548323, 6132.41742034506, 6151.7539298428455, 5977.701114944235, 6081.118237855486, 6092.72582733778, 6101.450869421773, 6184.257889053342, 4784.138855006063, 4803.1114243864995, 4817.8313759018265, 4821.7589120543835, 4855.223164293696, 4333.544977777351, 4391.055922976575, 4407.0, 4409.559044095629, 4432.512423633398, 5686.60193786431, 5704.71476426177, 5707.3721890592005, 5718.914216286759, 5741.483266506035, 6248.218972628421, 6263.4362907338555, 6272.686365681698, 6289.433950944437, 6298.496120310364, 4995.900081998339, 5466.590005749585, 5598.776048957996, 5608.775648974077, 5622.269105016322, 5325.4142044375085, 5331.1470164773455, 5335.439778823245, 5339.41266460689, 5339.599530035263, 6162.568620196544, 6264.968675156595, 6284.277308109626, 6292.370762923762, 6296.370362963667, 6264.185655864709, 6281.183446151971, 6283.528735344776, 6285.717142728679, 6304.3380445054445, 6258.7809426671665, 6262.655553944577, 6281.309056003744, 6284.654344011124, 6286.37136286386, 5151.8196863109615, 5952.226210592596, 6058.939685554922, 6096.963516202168, 6106.480949119375, 5523.944760552343, 6197.163382943245, 6287.591306565058, 6291.056341548804, 6313.589616674902, 6073.757049717962, 6075.483583895241, 6096.695165241809, 6104.359042300494, 6112.816615501542, 5891.705414729161, 5997.760089596367, 6008.369121242382, 6099.359567245376, 6110.7555697771595, 5702.6293290935955, 5819.359870414166, 5823.0, 5823.94176058234, 5848.210491583752, 6003.879922401615, 6015.097735339734, 6016.338202797776, 6029.909551356645, 6060.575759696955, 5724.0, 5832.854178645398, 5878.2358293421585, 5984.670843103671, 5987.610805297816], "value": 5637.6826604515845, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.4091810286707, 499.1439680947175, 534.4121466386974, 584.0654952076678, 614.9392298285458, 30252.243693907636, 32810.34379312412, 38405.03987400313, 39491.80254098733, 39596.406053909144, 43845.7807710961, 44475.110497790025, 44514.10971780562, 44533.332000020026, 44586.33120503195, 9545.57044932978, 9781.217502599775, 9787.657431989881, 9805.607775688964, 9825.65610203643, 8462.4076314658, 8664.566771661392, 8666.6100025499, 8671.306295496344, 8704.347173961978, 11483.655490335303, 11742.941285293562, 11755.941220293887, 11773.587924422647, 11776.587819426322, 11628.360440175788, 11844.644660660195, 11857.644270671894, 11880.227785193934, 11888.28670279786, 8460.78848028798, 11235.269707468988, 11466.254693444982, 11609.883901160965, 11806.52773889052, 38741.41887871656, 39275.0, 39299.41050884239, 39304.60695393038, 39368.409473857915, 3218.6942240487115, 3236.935261294761, 3247.870085196589, 3529.154750556082, 3915.3735402335774, 27690.723092769218, 29050.27374315659, 29075.854620726972, 29160.56259156114, 29225.269368265752, 2779.138467075213, 2792.399634078683, 2807.368342123026, 2825.66092068951, 2870.985645071782, 495893.0, 498173.0, 498868.0113198858, 498932.0, 500466.0, 60141.69929150326, 60559.78880422367, 60588.394116058276, 60633.0, 60722.69638651867, 65169.0, 65574.34425655683, 65592.67203664046, 65602.34397655963, 65657.0, 17163.742543861852, 17849.929004259786, 17871.0, 17886.5528361792, 17927.83469074506, 14061.437542498186, 14072.0, 14140.575782726535, 14158.929205353907, 14164.150150990974, 14325.85393168544, 14751.77872331916, 16034.679306413806, 16504.6699066018, 16687.582810429838, 5518.972405138029, 5809.037390930655, 6711.7315307388135, 6721.1262535871, 6744.7976560703255, 931.8182954323842, 978.2907392140642, 992.8609994600762, 1054.9555939619875, 1092.4373947417087, 28567.57148642772, 28876.0, 29465.85267073694, 29604.703952960626, 29776.404471910442, 866.5063497426347, 928.679605536089, 1018.6383833738915, 1055.0135623192339, 1118.3904771899333, 13671.248081355621, 14148.292585370897, 14584.927075364556, 15181.848181518044, 15330.846691533165, 635674.0, 638986.0, 648120.0, 649675.0, 650028.0, 46737.0, 53433.198502021725, 54187.0, 54342.72828635832, 54354.0, 1485.1460410264142, 1493.1563666528377, 1497.6779992301708, 1506.0, 1506.6835964447368, 27338.863305683346, 28793.85603071971, 29166.27084322909, 29299.414011720073, 29377.11868643944, 48435.5156448431, 48998.0, 49042.264366035255, 49043.50956490389, 50809.74595127001, 487619.0, 491174.0, 491452.0, 492274.0, 492701.21896343166, 25328.0, 25493.745062549136, 26080.478390431705, 26163.476730464903, 26165.345866353495, 41842.372364413946, 42922.785386074116, 43395.0, 43694.56305436905, 44166.33750493809, 34923.777667781505, 35134.82432587821, 36197.81901090478, 36561.81719091388, 36835.07912302107, 35466.29067418689, 36320.63679363278, 36408.81795591111, 37118.0, 37842.62157378391, 29313.85343073271, 35838.0, 36443.45334820033, 37556.43665344967, 37881.0, 26666.86666566655, 29995.550066749453, 31244.06267811969, 31722.365552688356, 31763.841180794872, 23584.410389740395, 23865.642015369434, 24265.0, 24731.258062258163, 25072.749272507772], "value": 19623.697917483772, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.4584851964651, 376.2193448594166, 382.31947134101307, 389.1477663916022, 411.95262544807355, 19812.00939953002, 20770.0, 21014.57970840582, 21126.04932778025, 21145.15419383223, 12421.006319494441, 12435.626931192057, 14553.199574023425, 20624.0, 21199.04604292807, 5493.642913210629, 5590.329160500745, 5666.150077488389, 5686.1186516089865, 5705.4579814917515, 5379.919301210485, 5623.8594035149035, 5627.212190293362, 5637.126245431944, 5646.096624540074, 5626.0, 5668.6111902583925, 5673.035583950738, 5686.260786097801, 5780.190773291722, 6053.727582258809, 6084.574079814415, 6097.085437184413, 6100.603460775035, 6109.663968481732, 5727.169560413717, 5945.464908158287, 5961.254843144607, 6072.210612620331, 6082.787102451393, 13722.588322350346, 13739.450421983109, 13894.513692020832, 14029.719405611933, 14064.929675351661, 2151.429871084164, 2255.571441426124, 2370.2889133259955, 2586.482703459316, 2911.083008852205, 10397.740056498573, 10564.841527377095, 13039.934800326033, 14879.851201487954, 15686.921565392215, 1694.7288433850524, 1798.7301904714266, 1799.388208009283, 1809.158741185342, 1821.298799962011, 111405.32892006627, 115390.26914596288, 117993.0, 141571.0, 146368.0, 21207.469813254793, 21758.673619895715, 22142.114315427523, 22214.66677999831, 22228.0, 36867.05063594906, 37023.444648330296, 37081.0, 37867.81066094652, 37953.62046379556, 10729.892701073046, 11050.834237486444, 11334.886651133547, 11669.883301167049, 11689.473973671205, 7271.818204544928, 8890.199882010651, 9229.907700923039, 9566.760830979141, 9629.422234665943, 9089.409683175241, 9385.249180065579, 9959.651412200537, 9998.80002399948, 10113.797724045478, 3764.792936388525, 3770.6237223413254, 3802.885913422602, 3805.6955443564448, 3865.3042452358845, 836.5994866999505, 893.5264309915755, 916.4409710076848, 940.9670661526814, 988.4217732626494, 17941.910290448464, 18001.459956201335, 21126.366209013755, 21429.78570214278, 21486.140554377962, 988.0, 990.0, 990.9900900991042, 992.1467537917385, 1035.0322448510517, 10934.343939363505, 11205.99146076857, 11224.32654040744, 11295.830562541569, 11322.773544529227, 572022.0, 572713.0, 572853.0, 610224.0, 636759.0, 74125.0, 74253.0, 75994.0, 90264.0, 101901.0, 1041.2242879055002, 1041.8541404203418, 1056.587930707025, 1060.7030031591166, 1062.4528367890543, 17113.14434278256, 17689.46931592054, 18420.631587368447, 18611.627767444304, 19084.809151908303, 43003.13993720045, 52115.73942130265, 52362.21456678229, 52943.735281323345, 53751.92496149977, 76860.0, 77647.61176194083, 78944.0, 79725.0, 81467.59266203632, 6967.79096627102, 7910.960445197968, 8300.294474969694, 8519.148085191679, 8672.653093876173, 9119.361644684808, 10457.320274182153, 12354.38228088574, 12774.42515086842, 13128.277944713132, 11272.605458808903, 11516.596919107791, 11657.06743460538, 11707.2975621463, 11843.46704398287, 11297.604583839526, 11404.372759497975, 11437.313761174357, 11733.296002239893, 11758.470868811095, 11666.88333116658, 11675.532978681098, 11779.352135632622, 11877.465514051715, 11969.461374238006, 8246.175382461706, 8267.090620031911, 8286.212809783061, 8543.102974187621, 8662.566871656518, 8287.792805179919, 10570.41862697559, 11367.54529818831, 11652.41737913084, 11669.824952625548], "value": 10274.393511538738, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1382.4055656067899, 1591.7532782418725, 1659.543625502987, 1836.788769291531, 1944.815242551957, 7978.92021079789, 8735.388522803407, 8988.550572471375, 8995.145461181193, 9002.279817614592, 8606.526641034741, 8832.293416526678, 8854.42446240994, 8860.77848053799, 8861.246794022516, 1879.3798046644642, 2205.9227927022557, 2388.1880160745345, 2437.4637579732475, 2468.8024958003407, 2127.819135373495, 2472.1223965492263, 2498.038255271724, 2504.3488692939786, 2507.6238564215423, 2446.351716795051, 2458.7049554053538, 2477.170148000422, 2482.466269752003, 2484.6148846928663, 2132.8080472757447, 2388.5461762265286, 2433.8052955763496, 2461.4092617771607, 2463.5935070713313, 2431.817613678981, 2448.2899959012043, 2470.839395439291, 2470.9011639534397, 2475.4677744285063, 8684.478931264144, 8784.60469278884, 8807.471551706854, 8891.777705557348, 8909.599068041955, 5259.105951988171, 5505.284313039279, 5563.471470210364, 5565.359983601857, 5568.9443105569235, 3247.886323978673, 3605.6574625410544, 3610.945835812465, 3648.343298206322, 3714.7771133732062, 1023.6621914768109, 1060.3955745225226, 1061.2571200159919, 1066.9466526673714, 1103.5420300575252, 12153.756924861542, 12156.696082597917, 12164.0, 12181.573644922473, 12192.146549741521, 4809.495003024702, 4887.193613053806, 4939.851804445872, 4976.552110310089, 4993.825216117491, 10244.334118282288, 10250.897491024994, 10258.128059115059, 10258.333208341433, 10273.845892311621, 5433.728313584305, 5525.11598144303, 5585.134304182858, 5594.13290939905, 5611.663300202001, 4327.437433133672, 4510.120526497367, 4697.342372067913, 4896.485868983706, 5080.161773307437, 4045.554988951213, 4045.8868786972357, 4149.813258403379, 4171.019810344566, 4171.499420069611, 1349.527665317131, 1378.2901805570139, 1378.5312993582231, 1388.5695434415363, 1390.5828251524501, 800.7597720683772, 908.68650315641, 910.321810251367, 937.2033771294482, 944.8629948657406, 5961.433663801932, 6056.454919057306, 6101.908471372933, 6157.230346206724, 6159.753609855646, 441.4944888103168, 445.9955400446019, 452.31474316410663, 452.54745254745256, 453.41509452806173, 3588.69496092835, 3993.820278087493, 4035.9798201008807, 4047.494063242095, 4072.1244932339096, 9306.395084319633, 10351.844722329171, 10533.104686101768, 10962.835557466644, 10966.287191332698, 10750.247482676294, 10899.29154604964, 10910.963458471433, 10913.23607347494, 10915.290506117235, 7923.366130709529, 8232.50604963704, 8263.173682631688, 8317.001959764866, 8441.957790211132, 5896.498797602252, 5922.7927022554, 5956.404359564009, 6002.129691194839, 6032.758689652364, 10083.546240419346, 10252.743681408025, 10259.230557708053, 10276.332038417479, 10278.640247591298, 8609.0, 10019.248556358449, 10109.93845646226, 10320.277580569287, 10464.058234758908, 6944.930550694428, 7161.4986950912935, 7358.77923662291, 7588.279113484431, 7670.041244844395, 7218.819529511804, 8376.623051962555, 8425.325973922194, 8457.534835583856, 8470.618822152896, 7332.486725929134, 7423.851522969402, 7474.140473845578, 7595.620218988918, 7612.581308028111, 6789.490788191004, 7328.120625524971, 7416.110066792019, 7461.477696561188, 7501.062367204099, 7056.082709247765, 7076.823079423056, 7113.679884405317, 7125.251848556034, 7169.4981351304905, 4821.251067698385, 4881.877953051202, 4973.129702302011, 4992.126377883783, 5016.322796422436, 4412.889677758082, 4916.778744956557, 5039.21892106724, 5139.691618502902, 5319.095753721954], "value": 4563.2485685579595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [309.9581556489873, 321.6510086556087, 327.51527738946356, 328.03884618069065, 328.3695305014375, 4280.59334363235, 4854.219015399308, 5000.524950129737, 5368.489993450621, 5482.287302650655, 4596.448426188862, 4653.627709783218, 5328.0, 5353.223782551528, 5381.19282107684, 4011.7392369496033, 4159.771212583307, 4178.728382655126, 4191.245575796355, 4206.348016057516, 3548.1129717570607, 3566.32239874423, 4003.75977441353, 4118.732282401635, 4175.373693945917, 3437.965620343802, 4119.835206591748, 4145.834166633331, 4156.8129434175535, 4187.623113919746, 3501.0897166736577, 3652.5982141964364, 3832.4442955771397, 4043.777592232441, 4193.6645068394455, 3353.8826141084946, 3654.7441679082476, 3656.561212654472, 3670.751944338911, 3682.871099511531, 4567.862964111082, 5006.69959802413, 5194.896102077937, 5238.97380513095, 5241.973790131063, 4075.3886916962397, 4725.1730947084125, 4769.189237829577, 4793.400824896888, 4815.229563269895, 4287.957120428787, 4408.977955110236, 5527.015411677689, 5552.167174923753, 5637.267155269788, 3997.181282516477, 4017.658499027586, 4020.396940458925, 4022.557518672944, 4072.2669919414493, 5609.943900561024, 5848.766049358063, 5907.852303692399, 5926.4073592640825, 5983.371745966654, 3921.960680419691, 3981.303271927401, 4051.39229115635, 4082.2243773682903, 4107.260693075215, 3662.095710230665, 4108.938365924513, 4113.1568028554075, 4125.814338354781, 4138.275801734684, 3792.81035948207, 4140.482439695038, 4707.692461397783, 4738.062401311209, 4762.64280178988, 4304.849330273488, 4740.407449068866, 5321.494458026481, 5742.54059675225, 5744.655320680772, 1436.4613270023742, 1461.5311611830043, 1466.4134346261367, 1470.9043912145676, 1477.674911519464, 945.0455040409237, 967.4292167621137, 973.6446197137993, 973.8441849304078, 982.3221976835961, 5770.394108618619, 5782.710864456761, 5804.4195580442465, 5807.88384232313, 5819.447152520504, 5411.458854114557, 6408.807735767934, 6432.073406599986, 6433.292337842833, 6470.6441145737435, 5239.764210610531, 5505.807296744675, 5747.48272655463, 5769.048107062287, 5803.245578074823, 2836.3476400428017, 3340.6659334066885, 3356.8657253710066, 3386.407378708716, 3396.932061358759, 5198.324217851655, 5722.7424765885635, 6680.799576012728, 6697.263301036882, 6710.630915299607, 5086.974565127225, 5185.455527169668, 6023.337432882379, 6055.333913269536, 6078.331383547807, 6111.930412177762, 6117.84705382369, 6139.728300528833, 6160.9383906161265, 6181.8454538636, 4386.583274588909, 5465.808696695596, 5654.575906806924, 5877.764889404376, 5915.171875937286, 5390.784368625368, 5580.972095139498, 5764.798232061858, 5798.710064496842, 5828.358880523054, 5820.243368362086, 5983.790567330123, 6021.2473440819895, 6131.547381047617, 6144.508439324933, 3679.466477360821, 3939.7045221607923, 3956.7625942443547, 3963.286608410514, 3967.682585393104, 5280.445553216857, 5501.807436739695, 5782.624129431573, 5943.821685349447, 5982.6410415373475, 4999.275105109666, 5705.885882282414, 5709.7430615623225, 5717.885012422537, 5750.48245657876, 4810.488343509328, 5613.691246981455, 5672.631278966854, 5678.829635110953, 5750.383575473066, 4619.214733495381, 5057.949420505748, 5068.8088299250385, 5284.128118860345, 5562.610617256753, 4768.995532814305, 5035.748212589283, 5036.496350364934, 5215.504527069922, 5232.581393488588, 4647.977444962103, 4735.360726301905, 5573.1855413016165, 5581.581381396492, 5588.189712491747], "value": 4197.384311844452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.4224908827497, 740.3189066059225, 747.3012733094561, 760.1941941541965, 778.9065312162542, 14650.413983440649, 14782.18697971611, 15258.923705381472, 15556.299966501534, 15570.0, 14009.719805603881, 14062.804661603775, 14075.436982520712, 14146.434142634307, 14206.0, 736.8857827036791, 761.4669731188163, 918.4581097152678, 919.7562645898843, 933.5145724223399, 929.0477260807693, 931.4457897550947, 935.0929598289648, 938.4275591888942, 938.5776400619715, 989.9208063354914, 993.4387071304726, 996.3025881882675, 997.2819569909653, 1000.5297510170209, 770.0797546931419, 957.0620791624177, 990.2869933647762, 993.6224234790806, 995.21378111292, 734.5629350536423, 736.381439590741, 737.4284929179875, 738.7340557399332, 738.9113306403212, 14992.0, 15063.096214227071, 15066.0, 15077.47228846996, 15082.622934426618, 12435.44040518179, 12488.625341239778, 12691.365431728469, 12878.356082195853, 13010.349482525931, 9976.716361596425, 10146.695599132037, 10389.116925061378, 10415.323004004715, 10714.892851071467, 2994.895178668758, 3478.617352091268, 3586.0, 3588.9592018314656, 3666.5600127984812, 79863.6006819968, 79922.6003869977, 80051.0, 80211.0, 80377.0, 9248.815023699488, 9431.528423578793, 9470.0, 9511.476868772284, 9529.237660987104, 21027.369178924655, 21124.78875211259, 21490.247841325792, 21524.677129843065, 21555.892220538797, 7648.273414025659, 7730.0, 7759.534427934342, 7769.028871391076, 7885.1326354101, 4117.464729585135, 4159.043420013353, 4237.131388065439, 4292.291771857671, 4458.799354029076, 2970.5544168374486, 3577.910552236163, 3578.8031658258524, 3590.1383667919513, 3591.6947059500235, 590.5806877117209, 611.1840692675239, 644.5294934697697, 644.5906849150823, 650.6649075726004, 500.2845930319678, 592.8695686948864, 614.830921496592, 617.2777849915609, 617.6016469377226, 7516.924830751732, 7784.143744188134, 8229.0, 8329.750107496784, 8433.789155271168, 535.1357557544616, 589.4783116941537, 590.2209084009153, 599.8824150747866, 630.1713247080075, 3851.056491159684, 3983.2630963271886, 4012.217617564547, 4084.8366065357645, 4111.239420707178, 72297.6385118071, 90414.0, 91631.08368916358, 92395.61406578906, 94892.52553737187, 74124.0, 75107.62446187844, 76161.61919190368, 77773.61113194398, 78458.6077069611, 64234.0, 67291.3270867285, 70509.64745176242, 72416.55166896632, 72969.0, 8105.878411823709, 8335.708250211213, 8399.496030238206, 8534.31725461974, 8570.4000719949, 44245.0, 44821.32768008417, 45100.77449612731, 45672.54327456683, 46650.76674616605, 60504.0, 60584.394156057875, 63055.36944630495, 63280.0, 64524.0, 11044.779104417707, 11159.553617855196, 11425.257358271685, 11437.0, 11519.88480115188, 11654.70863228426, 11890.16768826174, 11930.582429614922, 12210.084243681937, 12896.0, 8665.480071195749, 11075.778484430428, 11420.828687569527, 11806.468708907947, 11911.3448760319, 11188.216824822184, 11750.529978801094, 11887.16789824704, 11905.821412678644, 12039.879601203877, 11148.386838723947, 11266.66200014001, 11454.312741235553, 11472.0, 11548.364839933884, 9783.46190959504, 9954.104130628279, 10006.249531285037, 10353.482325883826, 10360.430176340373, 9813.509324533887, 9921.851172232278, 9986.700398988041, 9990.400575965465, 10033.09702126812], "value": 6920.279935027848, "unit": "score"}], "name": "dromaeo_css", "value": 7294.742517056255, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 108.0, 115.0, 114.0, 116.0, 113.0, 114.0, 104.0, 114.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 146.0, 171.0, 156.0, 120.0, 141.0, 120.0, 150.0, 157.0, 116.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 193.0, 259.0, 206.0, 183.0, 195.0, 257.0, 201.0], "value": 204.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 73.0, 150.0, 58.0, 59.0, 76.0, 88.0, 162.0, 55.0], "value": 87.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 142.0, 78.0, 80.0, 89.0, 128.0, 78.0, 75.0, 152.0], "value": 100.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 89.0, 85.0, 88.0, 85.0, 88.0, 85.0, 88.0, 84.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 94.0, 93.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 123.0, 129.0, 130.0, 83.0, 84.0, 86.0, 96.0, 129.0], "value": 103.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 67.0, 66.0, 64.0, 67.0, 60.0, 82.0, 59.0, 57.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 64.0, 73.0, 71.0, 75.0, 70.0, 69.0, 93.0, 95.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 81.0, 80.0, 82.0, 80.0, 80.0, 79.0, 79.0, 81.0, 85.0], "value": 79.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 158.0, 126.0, 142.0, 144.0, 146.0, 149.0, 138.0, 229.0, 161.0], "value": 152.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 164.0, 168.0, 166.0, 161.0, 169.0, 166.0, 165.0, 168.0], "value": 166.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 69.0, 70.0, 72.0, 78.0, 86.0, 92.0, 67.0], "value": 73.7, "unit": "score"}], "name": "kraken", "value": 1542.1000000000001, "alertThreshold": 2.0}]} 13:41:31 INFO - Return code: 0 13:41:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:41:31 ERROR - # TBPL SUCCESS # 13:41:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:41:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:41:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:41:31 INFO - 2016-04-26 13:41:31.879 screenresolution[1686:10784] starting screenresolution argv=screenresolution get 13:41:31 INFO - 2016-04-26 13:41:31.882 screenresolution[1686:10784] Display 0: 1600x1200x32@60 13:41:31 INFO - 2016-04-26 13:41:31.888 screenresolution[1687:10787] starting screenresolution argv=screenresolution list 13:41:31 INFO - Available Modes on Display 0 13:41:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:41:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:41:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:41:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:41:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:41:32 INFO - Intel Iris: 13:41:32 INFO - Chipset Model: Intel Iris 13:41:32 INFO - Type: GPU 13:41:32 INFO - Bus: Built-In 13:41:32 INFO - VRAM (Dynamic, Max): 1536 MB 13:41:32 INFO - Vendor: Intel (0x8086) 13:41:32 INFO - Device ID: 0x0a2e 13:41:32 INFO - Revision ID: 0x0009 13:41:32 INFO - Displays: 13:41:32 INFO - Display: 13:41:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:41:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:41:32 INFO - Display Serial Number: 1600x1200 60 13:41:32 INFO - Main Display: Yes 13:41:32 INFO - Mirror: Off 13:41:32 INFO - Online: Yes 13:41:32 INFO - Rotation: Supported 13:41:32 INFO - Return code: 0 13:41:32 INFO - Running post-action listener: _resource_record_post_action 13:41:32 INFO - Running post-run listener: _resource_record_post_run 13:41:33 INFO - Total resource usage - Wall time: 1208s; CPU: 54.0%; Read bytes: 32222720; Write bytes: 653590528; Read time: 323; Write time: 2000 13:41:33 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 344994304; Write bytes: 346408960; Read time: 26513; Write time: 649 13:41:33 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31301120; Write bytes: 297125888; Read time: 310; Write time: 1316 13:41:33 INFO - Running post-run listener: _upload_blobber_files 13:41:33 INFO - Blob upload gear active. 13:41:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:41:33 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 13:41:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 13:41:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 13:41:33 INFO - (blobuploader) - INFO - Open directory for files ... 13:41:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:41:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:41:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:41:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:41:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:41:34 INFO - (blobuploader) - INFO - Done attempting. 13:41:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:41:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:41:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:41:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:41:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:41:35 INFO - (blobuploader) - INFO - Done attempting. 13:41:35 INFO - (blobuploader) - INFO - Iteration through files over. 13:41:35 INFO - Return code: 0 13:41:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:41:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:41:35 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b141d53b0fa3da879cfca710d0c5f7a7f89e4dd078e127a74a113bf331707c05707570358da97e8181474bb2401936e06cf82a532908b8515e5a2acff9e466e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ddbf693e6d0494d53540d3b9f6a0f0eadf0c3f327e1134b6c1199e7683464ae420575900f4eec80978610eadf49fb1b8a5525325c9003da4b3d25aa6e9b95d"} 13:41:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:41:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:41:35 INFO - Contents: 13:41:35 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b141d53b0fa3da879cfca710d0c5f7a7f89e4dd078e127a74a113bf331707c05707570358da97e8181474bb2401936e06cf82a532908b8515e5a2acff9e466e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ddbf693e6d0494d53540d3b9f6a0f0eadf0c3f327e1134b6c1199e7683464ae420575900f4eec80978610eadf49fb1b8a5525325c9003da4b3d25aa6e9b95d"} 13:41:35 INFO - Running post-run listener: copy_logs_to_upload_dir 13:41:35 INFO - Copying logs to upload dir... 13:41:35 INFO - mkdir: /builds/slave/test/build/upload/logs 13:41:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1924.948521 ========= master_lag: 0.99 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 32 mins, 5 secs) (at 2016-04-26 13:41:36.320047) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:41:36.323361) ========= 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.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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-Aurora/sha512/9b141d53b0fa3da879cfca710d0c5f7a7f89e4dd078e127a74a113bf331707c05707570358da97e8181474bb2401936e06cf82a532908b8515e5a2acff9e466e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ddbf693e6d0494d53540d3b9f6a0f0eadf0c3f327e1134b6c1199e7683464ae420575900f4eec80978610eadf49fb1b8a5525325c9003da4b3d25aa6e9b95d"} build_url:https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/Hl_NIrwjR0iDorxiLejNcw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9b141d53b0fa3da879cfca710d0c5f7a7f89e4dd078e127a74a113bf331707c05707570358da97e8181474bb2401936e06cf82a532908b8515e5a2acff9e466e", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e5ddbf693e6d0494d53540d3b9f6a0f0eadf0c3f327e1134b6c1199e7683464ae420575900f4eec80978610eadf49fb1b8a5525325c9003da4b3d25aa6e9b95d"}' ========= master_lag: 0.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-26 13:41:36.580821) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 13:41:36.584174) ========= 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.KILhp59Zjq/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.ThCtxUMxX1/Listeners TMPDIR=/var/folders/gq/1pvmf4y17k5ggl2p3mlg6gxh00000w/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.005209 ========= master_lag: 2.96 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 13:41:39.547947) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:41:39.548309) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-26 13:41:39.675371) ========= ========= Total master_lag: 4.49 =========