builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0018 starttime: 1463037069.09 results: success (0) buildid: 20160511222516 builduid: 23079a7e4f56498cb22887933fbe0902 revision: 584c4d11538d9b1124c212906ec8e5b7d7254a19 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.086545) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.086923) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.091447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.115629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.115917) ========= 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.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-12 00:11:09-- 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% 16.7M=0.001s 2016-05-12 00:11:09 (16.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.137122 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.270506) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.270845) ========= 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.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.027933 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.321373) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:09.322110) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 584c4d11538d9b1124c212906ec8e5b7d7254a19 --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 584c4d11538d9b1124c212906ec8e5b7d7254a19 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-12 00:11:09,391 truncating revision to first 12 chars 2016-05-12 00:11:09,391 Setting DEBUG logging. 2016-05-12 00:11:09,392 attempt 1/10 2016-05-12 00:11:09,392 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/584c4d11538d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 00:11:09,988 unpacking tar archive at: mozilla-aurora-584c4d11538d/testing/mozharness/ program finished with exit code 0 elapsedTime=0.849563 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:10.191066) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:10.191424) ========= script_repo_revision: 584c4d11538d9b1124c212906ec8e5b7d7254a19 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:10.191816) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:10.192094) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 00:11:10.205529) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-12 00:11:10.205795) ========= /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.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:10 INFO - MultiFileLogger online at 20160512 00:11:10 in /builds/slave/test 00:11:10 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 00:11:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 00:11:10 INFO - {'append_to_log': False, 00:11:10 INFO - 'base_work_dir': '/builds/slave/test', 00:11:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 00:11:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 00:11:10 INFO - 'branch': 'Mozilla-Aurora', 00:11:10 INFO - 'buildbot_json_path': 'buildprops.json', 00:11:10 INFO - 'config_files': ('talos/mac_config.py',), 00:11:10 INFO - 'default_actions': ('clobber', 00:11:10 INFO - 'read-buildbot-config', 00:11:10 INFO - 'download-and-extract', 00:11:10 INFO - 'populate-webroot', 00:11:10 INFO - 'create-virtualenv', 00:11:10 INFO - 'install', 00:11:10 INFO - 'run-tests'), 00:11:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 00:11:10 INFO - 'download_minidump_stackwalk': True, 00:11:10 INFO - 'download_symbols': 'ondemand', 00:11:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 00:11:10 INFO - 'tooltool.py': '/tools/tooltool.py', 00:11:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 00:11:10 INFO - '/tools/misc-python/virtualenv.py')}, 00:11:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 00:11:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 00:11:10 INFO - 'installer_path': 'installer.exe', 00:11:10 INFO - 'log_level': 'info', 00:11:10 INFO - 'log_name': 'talos', 00:11:10 INFO - 'log_to_console': True, 00:11:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 00:11:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 00:11:10 INFO - 'opt_config_files': (), 00:11:10 INFO - 'pip_index': False, 00:11:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:11:10 INFO - 'cmd': ('bash', 00:11:10 INFO - '-c', 00:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:11:10 INFO - 'enabled': True, 00:11:10 INFO - 'halt_on_failure': False, 00:11:10 INFO - 'name': 'check_screen_resolution'},), 00:11:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 00:11:10 INFO - 'cmd': ('bash', 00:11:10 INFO - '-c', 00:11:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 00:11:10 INFO - 'enabled': True, 00:11:10 INFO - 'halt_on_failure': False, 00:11:10 INFO - 'name': 'check_screen_resolution'},), 00:11:10 INFO - 'run_cmd_checks_enabled': True, 00:11:10 INFO - 'sps_profile': False, 00:11:10 INFO - 'sps_profile_interval': 0, 00:11:10 INFO - 'suite': 'dromaeojs', 00:11:10 INFO - 'system_bits': '32', 00:11:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 00:11:10 INFO - 'title': 't-yosemite-r7-0018', 00:11:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 00:11:10 INFO - 'use_talos_json': True, 00:11:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 00:11:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 00:11:10 INFO - 'work_dir': 'build'} 00:11:10 INFO - ##### 00:11:10 INFO - ##### Running clobber step. 00:11:10 INFO - ##### 00:11:10 INFO - Running pre-action listener: _resource_record_pre_action 00:11:10 INFO - Running main action method: clobber 00:11:10 INFO - rmtree: /builds/slave/test/build 00:11:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 00:11:12 INFO - Running post-action listener: _resource_record_post_action 00:11:12 INFO - ##### 00:11:12 INFO - ##### Running read-buildbot-config step. 00:11:12 INFO - ##### 00:11:12 INFO - Running pre-action listener: _resource_record_pre_action 00:11:12 INFO - Running main action method: read_buildbot_config 00:11:12 INFO - Using buildbot properties: 00:11:12 INFO - { 00:11:12 INFO - "project": "", 00:11:12 INFO - "product": "firefox", 00:11:12 INFO - "script_repo_revision": "production", 00:11:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 00:11:12 INFO - "repository": "", 00:11:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 00:11:12 INFO - "buildid": "20160511222516", 00:11:12 INFO - "pgo_build": "False", 00:11:12 INFO - "basedir": "/builds/slave/test", 00:11:12 INFO - "buildnumber": 4, 00:11:12 INFO - "slavename": "t-yosemite-r7-0018", 00:11:12 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 00:11:12 INFO - "platform": "yosemite_r7", 00:11:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 00:11:12 INFO - "branch": "mozilla-aurora", 00:11:12 INFO - "repo_path": "releases/mozilla-aurora", 00:11:12 INFO - "revision": "584c4d11538d9b1124c212906ec8e5b7d7254a19", 00:11:12 INFO - "stage_platform": "macosx64", 00:11:12 INFO - "builduid": "23079a7e4f56498cb22887933fbe0902", 00:11:12 INFO - "slavebuilddir": "test" 00:11:12 INFO - } 00:11:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 00:11:12 INFO - Running post-action listener: _resource_record_post_action 00:11:12 INFO - ##### 00:11:12 INFO - ##### Running download-and-extract step. 00:11:12 INFO - ##### 00:11:12 INFO - Running pre-action listener: _resource_record_pre_action 00:11:12 INFO - Running main action method: download_and_extract 00:11:12 INFO - mkdir: /builds/slave/test/build/tests 00:11:12 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')]} 00:11:12 INFO - https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 00:11:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 00:11:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 00:11:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:14 INFO - Downloaded 1199 bytes. 00:11:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 00:11:14 INFO - Using the following test package requirements: 00:11:14 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 00:11:14 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 00:11:14 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 00:11:14 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'jsshell-mac.zip'], 00:11:14 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 00:11:14 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 00:11:14 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 00:11:14 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 00:11:14 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 00:11:14 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 00:11:14 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 00:11:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 00:11:14 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')]} 00:11:14 INFO - https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:11:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:11:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:11:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:15 INFO - Downloaded 18089471 bytes. 00:11:15 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'] 00:11:15 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 00:11:18 INFO - Return code: 0 00:11:18 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 00:11:18 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')]} 00:11:18 INFO - https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 00:11:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:11:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 00:11:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:20 INFO - Downloaded 18089471 bytes. 00:11:20 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'] 00:11:20 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 00:11:24 INFO - Return code: 0 00:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:24 INFO - https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 00:11:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 00:11:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 00:11:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:25 INFO - Downloaded 10840145 bytes. 00:11:25 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'] 00:11:25 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 00:11:26 INFO - Return code: 0 00:11:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:26 INFO - https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 00:11:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:11:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:11:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 00:11:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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 00:11:33 INFO - Downloaded 114123770 bytes. 00:11:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:11:33 INFO - mkdir: /builds/slave/test/properties 00:11:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 00:11:33 INFO - Writing to file /builds/slave/test/properties/build_url 00:11:33 INFO - Contents: 00:11:33 INFO - build_url:https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 00:11:34 INFO - Running post-action listener: _resource_record_post_action 00:11:34 INFO - Running post-action listener: set_extra_try_arguments 00:11:34 INFO - ##### 00:11:34 INFO - ##### Running populate-webroot step. 00:11:34 INFO - ##### 00:11:34 INFO - Running pre-action listener: _resource_record_pre_action 00:11:34 INFO - Running main action method: populate_webroot 00:11:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 00:11:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 00:11:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 00:11:34 INFO - '--tppagecycles', 00:11:34 INFO - '7'], 00:11:34 INFO - 'tests': ['tsvgm']}}, 00:11:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 00:11:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 00:11:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 00:11:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 00:11:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:11:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 00:11:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['damp', 'tps']}, 00:11:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'tests': ['damp', 'tps']}, 00:11:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['dromaeo_dom']}, 00:11:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 00:11:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'other-e10s': {'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['a11yr', 00:11:34 INFO - 'ts_paint', 00:11:34 INFO - 'tpaint', 00:11:34 INFO - 'sessionrestore', 00:11:34 INFO - 'sessionrestore_no_auto_restore', 00:11:34 INFO - 'tabpaint']}, 00:11:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['tsvgx', 00:11:34 INFO - 'tsvgr_opacity', 00:11:34 INFO - 'tart', 00:11:34 INFO - 'tscrollx', 00:11:34 INFO - 'cart']}, 00:11:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 00:11:34 INFO - 'tsvgr_opacity', 00:11:34 INFO - 'tart', 00:11:34 INFO - 'tscrollx', 00:11:34 INFO - 'cart']}, 00:11:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'talos_options': ['--disable-e10s'], 00:11:34 INFO - 'tests': ['tp5o']}, 00:11:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'tests': ['tp5o']}, 00:11:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'talos_options': ['--disable-e10s', 00:11:34 INFO - '--xperf_path', 00:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:11:34 INFO - 'tests': ['tp5n']}, 00:11:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 00:11:34 INFO - 'talos_options': ['--xperf_path', 00:11:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 00:11:34 INFO - 'tests': ['tp5n']}}, 00:11:34 INFO - 'talos.zip': {'path': '', 00:11:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 00:11:34 INFO - Running post-action listener: _resource_record_post_action 00:11:34 INFO - ##### 00:11:34 INFO - ##### Running create-virtualenv step. 00:11:34 INFO - ##### 00:11:34 INFO - Running pre-action listener: _resource_record_pre_action 00:11:34 INFO - Running main action method: create_virtualenv 00:11:34 INFO - Creating virtualenv /builds/slave/test/build/venv 00:11:34 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 00:11:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 00:11:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 00:11:34 INFO - Using real prefix '/tools/python27' 00:11:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 00:11:35 INFO - Installing distribute.............................................................................................................................................................................................done. 00:11:38 INFO - Installing pip.................done. 00:11:38 INFO - Return code: 0 00:11:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 00:11: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')]} 00:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11: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')]} 00:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 00:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 00:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:38 INFO - 'HOME': '/Users/cltbld', 00:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:38 INFO - 'LOGNAME': 'cltbld', 00:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:38 INFO - 'MOZ_NO_REMOTE': '1', 00:11:38 INFO - 'NO_EM_RESTART': '1', 00:11:38 INFO - 'PAGER': '/bin/cat', 00:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:38 INFO - 'PWD': '/builds/slave/test', 00:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:38 INFO - 'SHELL': '/bin/bash', 00:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:38 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:38 INFO - 'USER': 'cltbld', 00:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:38 INFO - 'XPC_FLAGS': '0x0', 00:11:38 INFO - 'XPC_SERVICE_NAME': '0', 00:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:38 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 00:11:38 INFO - Cleaning up... 00:11:38 INFO - Return code: 0 00:11:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 00:11: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')]} 00:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11: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')]} 00:11:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 00:11:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 00:11:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:38 INFO - 'HOME': '/Users/cltbld', 00:11:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:38 INFO - 'LOGNAME': 'cltbld', 00:11:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:38 INFO - 'MOZ_NO_REMOTE': '1', 00:11:38 INFO - 'NO_EM_RESTART': '1', 00:11:38 INFO - 'PAGER': '/bin/cat', 00:11:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:38 INFO - 'PWD': '/builds/slave/test', 00:11:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:38 INFO - 'SHELL': '/bin/bash', 00:11:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:38 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:38 INFO - 'USER': 'cltbld', 00:11:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:38 INFO - 'XPC_FLAGS': '0x0', 00:11:38 INFO - 'XPC_SERVICE_NAME': '0', 00:11:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:38 INFO - Downloading/unpacking psutil>=0.7.1 00:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:11:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:11:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:11:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:11:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 00:11:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:11:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:11:41 INFO - Installing collected packages: psutil 00:11:41 INFO - Running setup.py install for psutil 00:11:41 INFO - building 'psutil._psutil_osx' extension 00:11:41 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 00:11:42 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 00:11:42 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 00:11:42 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 00:11:42 INFO - building 'psutil._psutil_posix' extension 00:11:42 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 00:11:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 00:11:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 00:11:42 INFO - ^ 00:11:42 INFO - 1 warning generated. 00:11:42 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 00:11:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 00:11:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 00:11:42 INFO - Successfully installed psutil 00:11:42 INFO - Cleaning up... 00:11:42 INFO - Return code: 0 00:11:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 00:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:42 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:42 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 00:11:42 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 00:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:42 INFO - 'HOME': '/Users/cltbld', 00:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:42 INFO - 'LOGNAME': 'cltbld', 00:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:42 INFO - 'MOZ_NO_REMOTE': '1', 00:11:42 INFO - 'NO_EM_RESTART': '1', 00:11:42 INFO - 'PAGER': '/bin/cat', 00:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:42 INFO - 'PWD': '/builds/slave/test', 00:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:42 INFO - 'SHELL': '/bin/bash', 00:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:42 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:42 INFO - 'USER': 'cltbld', 00:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:42 INFO - 'XPC_FLAGS': '0x0', 00:11:42 INFO - 'XPC_SERVICE_NAME': '0', 00:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 00:11:43 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 00:11:43 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 00:11:43 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 00:11:43 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 00:11:43 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 00:11:43 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 00:11:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 00:11:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 00:11:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 00:11:45 INFO - Installing collected packages: mozsystemmonitor 00:11:45 INFO - Running setup.py install for mozsystemmonitor 00:11:45 INFO - Successfully installed mozsystemmonitor 00:11:45 INFO - Cleaning up... 00:11:45 INFO - Return code: 0 00:11:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 00:11:45 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')]} 00:11:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:45 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')]} 00:11:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:45 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:45 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 00:11:45 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 00:11:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:45 INFO - 'HOME': '/Users/cltbld', 00:11:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:45 INFO - 'LOGNAME': 'cltbld', 00:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:45 INFO - 'MOZ_NO_REMOTE': '1', 00:11:45 INFO - 'NO_EM_RESTART': '1', 00:11:45 INFO - 'PAGER': '/bin/cat', 00:11:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:45 INFO - 'PWD': '/builds/slave/test', 00:11:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:45 INFO - 'SHELL': '/bin/bash', 00:11:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:45 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:45 INFO - 'USER': 'cltbld', 00:11:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:45 INFO - 'XPC_FLAGS': '0x0', 00:11:45 INFO - 'XPC_SERVICE_NAME': '0', 00:11:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:46 INFO - Downloading/unpacking blobuploader==1.2.4 00:11:46 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 00:11:46 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 00:11:46 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 00:11:46 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 00:11:46 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 00:11:46 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 00:11:48 INFO - Downloading blobuploader-1.2.4.tar.gz 00:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 00:11:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 00:11:48 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 00:11:48 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 00:11:48 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 00:11:48 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 00:11:48 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 00:11:48 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 00:11:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 00:11:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 00:11:49 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 00:11:49 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 00:11:49 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 00:11:49 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 00:11:49 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 00:11:49 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 00:11:49 INFO - Downloading docopt-0.6.1.tar.gz 00:11:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 00:11:49 INFO - Installing collected packages: blobuploader, requests, docopt 00:11:49 INFO - Running setup.py install for blobuploader 00:11:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 00:11:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 00:11:49 INFO - Running setup.py install for requests 00:11:50 INFO - Running setup.py install for docopt 00:11:50 INFO - Successfully installed blobuploader requests docopt 00:11:50 INFO - Cleaning up... 00:11:50 INFO - Return code: 0 00:11:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:50 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:50 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 00:11:50 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 00:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:50 INFO - 'HOME': '/Users/cltbld', 00:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:50 INFO - 'LOGNAME': 'cltbld', 00:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:50 INFO - 'MOZ_NO_REMOTE': '1', 00:11:50 INFO - 'NO_EM_RESTART': '1', 00:11:50 INFO - 'PAGER': '/bin/cat', 00:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:50 INFO - 'PWD': '/builds/slave/test', 00:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:50 INFO - 'SHELL': '/bin/bash', 00:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:50 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:50 INFO - 'USER': 'cltbld', 00:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:50 INFO - 'XPC_FLAGS': '0x0', 00:11:50 INFO - 'XPC_SERVICE_NAME': '0', 00:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:11:50 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-D9TZHF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:11:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:11:50 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-naVUfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-hwF6Jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-RDd_qF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nQxTjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nLpt6j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-X8bI23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-X5qRHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-nvzmqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-zI8BNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-gCNCQy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:11:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:11:51 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-DymlW5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:11:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-o_ZrC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:11:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-qfqeOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:11:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eDF6sj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:11:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-ZlXybg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:11:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:11:52 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-lO48eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:11:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 00:11:52 INFO - Running setup.py install for manifestparser 00:11:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 00:11:52 INFO - Running setup.py install for mozcrash 00:11:52 INFO - Running setup.py install for mozdebug 00:11:53 INFO - Running setup.py install for mozdevice 00:11:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Running setup.py install for mozfile 00:11:53 INFO - Running setup.py install for mozhttpd 00:11:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Running setup.py install for mozinfo 00:11:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Running setup.py install for mozInstall 00:11:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 00:11:53 INFO - Running setup.py install for mozleak 00:11:53 INFO - Running setup.py install for mozlog 00:11:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Running setup.py install for moznetwork 00:11:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Running setup.py install for mozprocess 00:11:54 INFO - Running setup.py install for mozprofile 00:11:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Running setup.py install for mozrunner 00:11:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 00:11:54 INFO - Running setup.py install for mozscreenshot 00:11:54 INFO - Running setup.py install for moztest 00:11:55 INFO - Running setup.py install for mozversion 00:11:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 00:11:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 00:11:55 INFO - Cleaning up... 00:11:55 INFO - Return code: 0 00:11:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:11: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')]} 00:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11: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')]} 00:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:11:55 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:11:55 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 00:11:55 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 00:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:11:55 INFO - 'HOME': '/Users/cltbld', 00:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:11:55 INFO - 'LOGNAME': 'cltbld', 00:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:11:55 INFO - 'MOZ_NO_REMOTE': '1', 00:11:55 INFO - 'NO_EM_RESTART': '1', 00:11:55 INFO - 'PAGER': '/bin/cat', 00:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:11:55 INFO - 'PWD': '/builds/slave/test', 00:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:11:55 INFO - 'SHELL': '/bin/bash', 00:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:11:55 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:11:55 INFO - 'USER': 'cltbld', 00:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:11:55 INFO - 'XPC_FLAGS': '0x0', 00:11:55 INFO - 'XPC_SERVICE_NAME': '0', 00:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-HLkabP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 00:11:55 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)) 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-8AA5XK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 00:11:55 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)) 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-WTKjny-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 00:11:55 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)) 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eyWR5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 00:11:55 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)) 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-qmIo_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 00:11:55 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)) 00:11:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 00:11:55 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-6UgnRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-hHvX2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eWDa35-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-O_iMwQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-1h0Zg3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-eDGrq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-yOleoJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-4IvDZ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-9IxdMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 00:11:56 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)) 00:11:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 00:11:56 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-v4Yk9L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 00:11:57 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)) 00:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 00:11:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-4Ms1LI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 00:11:57 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)) 00:11:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 00:11:57 INFO - Running setup.py (path:/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/pip-1WOD9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 00:11:57 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)) 00:11:57 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)) 00:11:57 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)) 00:11:57 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)) 00:11:57 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)) 00:11:57 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)) 00:11:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 00:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:11:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:11:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:11:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:11:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:11:59 INFO - Downloading blessings-1.6.tar.gz 00:11:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 00:11:59 INFO - Installing collected packages: blessings 00:11:59 INFO - Running setup.py install for blessings 00:11:59 INFO - Successfully installed blessings 00:11:59 INFO - Cleaning up... 00:11:59 INFO - Return code: 0 00:11:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 00:11:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:11:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:12:00 INFO - Reading from file tmpfile_stdout 00:12:00 INFO - Current package versions: 00:12:00 INFO - blessings == 1.6 00:12:00 INFO - blobuploader == 1.2.4 00:12:00 INFO - docopt == 0.6.1 00:12:00 INFO - manifestparser == 1.1 00:12:00 INFO - mozInstall == 1.12 00:12:00 INFO - mozcrash == 0.17 00:12:00 INFO - mozdebug == 0.1 00:12:00 INFO - mozdevice == 0.48 00:12:00 INFO - mozfile == 1.2 00:12:00 INFO - mozhttpd == 0.7 00:12:00 INFO - mozinfo == 0.9 00:12:00 INFO - mozleak == 0.1 00:12:00 INFO - mozlog == 3.1 00:12:00 INFO - moznetwork == 0.27 00:12:00 INFO - mozprocess == 0.22 00:12:00 INFO - mozprofile == 0.28 00:12:00 INFO - mozrunner == 6.11 00:12:00 INFO - mozscreenshot == 0.1 00:12:00 INFO - mozsystemmonitor == 0.0 00:12:00 INFO - moztest == 0.7 00:12:00 INFO - mozversion == 1.4 00:12:00 INFO - psutil == 3.1.1 00:12:00 INFO - requests == 1.2.3 00:12:00 INFO - wsgiref == 0.1.2 00:12:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 00:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:00 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:12:00 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 00:12:00 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 00:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:12:00 INFO - 'HOME': '/Users/cltbld', 00:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:12:00 INFO - 'LOGNAME': 'cltbld', 00:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:12:00 INFO - 'MOZ_NO_REMOTE': '1', 00:12:00 INFO - 'NO_EM_RESTART': '1', 00:12:00 INFO - 'PAGER': '/bin/cat', 00:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:12:00 INFO - 'PWD': '/builds/slave/test', 00:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:12:00 INFO - 'SHELL': '/bin/bash', 00:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:12:00 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:12:00 INFO - 'USER': 'cltbld', 00:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:12:00 INFO - 'XPC_FLAGS': '0x0', 00:12:00 INFO - 'XPC_SERVICE_NAME': '0', 00:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 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)) 00:12:00 INFO - Cleaning up... 00:12:00 INFO - Return code: 0 00:12:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 00:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:12:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 00:12:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 00:12:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:12:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:12:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:12:00 INFO - 'HOME': '/Users/cltbld', 00:12:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:12:00 INFO - 'LOGNAME': 'cltbld', 00:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:12:00 INFO - 'MOZ_NO_REMOTE': '1', 00:12:00 INFO - 'NO_EM_RESTART': '1', 00:12:00 INFO - 'PAGER': '/bin/cat', 00:12:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:12:00 INFO - 'PWD': '/builds/slave/test', 00:12:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:12:00 INFO - 'SHELL': '/bin/bash', 00:12:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:12:00 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:12:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:12:00 INFO - 'USER': 'cltbld', 00:12:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:12:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:12:00 INFO - 'XPC_FLAGS': '0x0', 00:12:00 INFO - 'XPC_SERVICE_NAME': '0', 00:12:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:12:00 INFO - Downloading/unpacking jsonschema 00:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:12:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:12:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 00:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 00:12:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 00:12:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 00:12:03 INFO - Downloading/unpacking functools32 (from jsonschema) 00:12: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 00:12: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 00:12: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 00:12: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 00:12: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 00:12: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 00:12:03 INFO - Downloading functools32-3.2.3-2.tar.gz 00:12:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 00:12:03 INFO - warning: no files found matching '*.txt' 00:12:03 INFO - no previously-included directories found matching 'build' 00:12:03 INFO - no previously-included directories found matching 'dist' 00:12:03 INFO - no previously-included directories found matching '.git*' 00:12:03 INFO - Installing collected packages: jsonschema, functools32 00:12:03 INFO - Running setup.py install for functools32 00:12:03 INFO - warning: no files found matching '*.txt' 00:12:03 INFO - no previously-included directories found matching 'build' 00:12:03 INFO - no previously-included directories found matching 'dist' 00:12:03 INFO - no previously-included directories found matching '.git*' 00:12:03 INFO - Successfully installed jsonschema functools32 00:12:03 INFO - Cleaning up... 00:12:03 INFO - Return code: 0 00:12:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 00:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 00:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 00:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 00:12:03 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 0x102d60a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d70420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdc59c8ff10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, '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.diy56J9qZa/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.zAYa25bj0t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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 00:12:03 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 00:12:03 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 00:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:12:03 INFO - 'HOME': '/Users/cltbld', 00:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:12:03 INFO - 'LOGNAME': 'cltbld', 00:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:12:03 INFO - 'MOZ_NO_REMOTE': '1', 00:12:03 INFO - 'NO_EM_RESTART': '1', 00:12:03 INFO - 'PAGER': '/bin/cat', 00:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:12:03 INFO - 'PWD': '/builds/slave/test', 00:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:12:03 INFO - 'SHELL': '/bin/bash', 00:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:12:03 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:12:03 INFO - 'USER': 'cltbld', 00:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:12:03 INFO - 'XPC_FLAGS': '0x0', 00:12:03 INFO - 'XPC_SERVICE_NAME': '0', 00:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:12:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 00:12:04 INFO - Downloading/unpacking flake8 00:12: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 00:12: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 00:12: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 00:12: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 00:12: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 00:12: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 00:12:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 00:12:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 00:12:06 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:06 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 00:12:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 00:12:07 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 00:12:07 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 00:12:07 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 00:12:07 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 00:12:07 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 00:12:07 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 00:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 00:12:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:12:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:12:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:12:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:12:07 INFO - no previously-included directories found matching 'docs/_build' 00:12:07 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 00:12:07 INFO - Running setup.py install for pep8 00:12:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 00:12:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 00:12:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 00:12:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 00:12:08 INFO - no previously-included directories found matching 'docs/_build' 00:12:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 00:12:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 00:12:08 INFO - Cleaning up... 00:12:08 INFO - Return code: 0 00:12:08 INFO - Running post-action listener: _resource_record_post_action 00:12:08 INFO - Running post-action listener: _start_resource_monitoring 00:12:08 INFO - Starting resource monitoring. 00:12:08 INFO - ##### 00:12:08 INFO - ##### Running install step. 00:12:08 INFO - ##### 00:12:08 INFO - Running pre-action listener: _resource_record_pre_action 00:12:08 INFO - Running main action method: install 00:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 00:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 00:12:08 INFO - Reading from file tmpfile_stdout 00:12:08 INFO - Detecting whether we're running mozinstall >=1.0... 00:12:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 00:12:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 00:12:08 INFO - Reading from file tmpfile_stdout 00:12:08 INFO - Output received: 00:12:08 INFO - Usage: mozinstall [options] installer 00:12:08 INFO - Options: 00:12:08 INFO - -h, --help show this help message and exit 00:12:08 INFO - -d DEST, --destination=DEST 00:12:08 INFO - Directory to install application into. [default: 00:12:08 INFO - "/builds/slave/test"] 00:12:08 INFO - --app=APP Application being installed. [default: firefox] 00:12:08 INFO - mkdir: /builds/slave/test/build/application 00:12:08 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'] 00:12:08 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 00:12:41 INFO - Reading from file tmpfile_stdout 00:12:41 INFO - Output received: 00:12:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 00:12:41 INFO - Running post-action listener: _resource_record_post_action 00:12:41 INFO - ##### 00:12:41 INFO - ##### Running run-tests step. 00:12:41 INFO - ##### 00:12:41 INFO - Running pre-action listener: _resource_record_pre_action 00:12:41 INFO - Running main action method: run_tests 00:12:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:12:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:12:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:12:41 INFO - 2016-05-12 00:12:41.232 screenresolution[1638:7359] starting screenresolution argv=screenresolution get 00:12:41 INFO - 2016-05-12 00:12:41.234 screenresolution[1638:7359] Display 0: 1600x1200x32@60 00:12:41 INFO - 2016-05-12 00:12:41.239 screenresolution[1639:7361] starting screenresolution argv=screenresolution list 00:12:41 INFO - Available Modes on Display 0 00:12:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:12:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:12:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:12:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:12:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:12:41 INFO - Intel Iris: 00:12:41 INFO - Chipset Model: Intel Iris 00:12:41 INFO - Type: GPU 00:12:41 INFO - Bus: Built-In 00:12:41 INFO - VRAM (Dynamic, Max): 1536 MB 00:12:41 INFO - Vendor: Intel (0x8086) 00:12:41 INFO - Device ID: 0x0a2e 00:12:41 INFO - Revision ID: 0x0009 00:12:41 INFO - Displays: 00:12:41 INFO - Display: 00:12:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:12:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:12:41 INFO - Display Serial Number: 1600x1200 60 00:12:41 INFO - Main Display: Yes 00:12:41 INFO - Mirror: Off 00:12:41 INFO - Online: Yes 00:12:41 INFO - Rotation: Supported 00:12:41 INFO - Return code: 0 00:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 00:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 00:12:41 INFO - Python 2.7.3 00:12:41 INFO - Return code: 0 00:12:41 INFO - grabbing minidump binary from tooltool 00:12:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 00:12:41 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 0x102bffe00>, '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 0x102a68db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bfab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 00:12:41 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 00:12:41 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 00:12:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 00:12:41 INFO - Return code: 0 00:12:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 00:12:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 00:12:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 00:12:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 00:12:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 00:12:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 00:12:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 00:12:42 INFO - Return code: 0 00:12:42 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/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 00:12:42 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/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0018 --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 00:12:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.diy56J9qZa/Render', 00:12:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 00:12:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 00:12:42 INFO - 'HOME': '/Users/cltbld', 00:12:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 00:12:42 INFO - 'LOGNAME': 'cltbld', 00:12:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 00:12:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 00:12:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 00:12:42 INFO - 'MOZ_NO_REMOTE': '1', 00:12:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 00:12:42 INFO - 'NO_EM_RESTART': '1', 00:12:42 INFO - 'PAGER': '/bin/cat', 00:12:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 00:12:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 00:12:42 INFO - 'PWD': '/builds/slave/test', 00:12:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 00:12:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 00:12:42 INFO - 'SHELL': '/bin/bash', 00:12:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zAYa25bj0t/Listeners', 00:12:42 INFO - 'TMPDIR': '/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/', 00:12:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 00:12:42 INFO - 'USER': 'cltbld', 00:12:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 00:12:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 00:12:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 00:12:42 INFO - 'XPC_FLAGS': '0x0', 00:12:42 INFO - 'XPC_SERVICE_NAME': '0', 00:12:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 00:12:42 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/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0018', '--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 00:12:42 INFO - mozversion application_buildid: 20160511222516 00:12:42 INFO - mozversion application_changeset: 584c4d11538d9b1124c212906ec8e5b7d7254a19 00:12:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 00:12:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 00:12:42 INFO - mozversion application_name: Firefox 00:12:42 INFO - mozversion application_remotingname: firefox-dev 00:12:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:12:42 INFO - mozversion application_vendor: Mozilla 00:12:42 INFO - mozversion application_version: 48.0a2 00:12:42 INFO - mozversion platform_buildid: 20160511222516 00:12:42 INFO - mozversion platform_changeset: 584c4d11538d9b1124c212906ec8e5b7d7254a19 00:12:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 00:12:42 INFO - mozversion platform_version: 48.0a2 00:12:42 INFO - using testdate: 1463037162 00:12:42 INFO - actual date: 1463037162 00:12:42 INFO - starting webserver on 'localhost:49272' 00:12:42 INFO - SUITE-START | Running 2 tests 00:12:42 INFO - TEST-START | dromaeo_css 00:12:42 INFO - operating with platform_type : mac_ 00:12:42 INFO - Initialising browser for dromaeo_css test... 00:12:42 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmppPB3zO/profile http://localhost:49272/getInfo.html) 00:12:45 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 00:12:45 INFO - PROCESS | 1657 | colorDepth:24 00:12:45 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 00:12:45 INFO - PROCESS | 1657 | __metrics 00:12:45 INFO - PROCESS | 1657 | 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" 00:12:45 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:12:46 INFO - TEST-INFO | 1657: exit 0 00:12:46 INFO - Browser initialized. 00:12:46 INFO - Running cycle 1/1 for dromaeo_css test... 00:12:46 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmppPB3zO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:12:57 INFO - PROCESS | 1659 | RSS: Main: 210878464 00:12:57 INFO - PROCESS | 1659 | 00:13:48 INFO - PROCESS | 1659 | 1463037228913 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] 00:16:00 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 00:16:02 INFO - PROCESS | 1659 | RSS: Main: 338108416 00:16:02 INFO - PROCESS | 1659 | 00:19:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 00:19:05 INFO - PROCESS | 1659 | RSS: Main: 357814272 00:19:05 INFO - PROCESS | 1659 | 00:22:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 00:22:08 INFO - PROCESS | 1659 | RSS: Main: 358744064 00:22:08 INFO - PROCESS | 1659 | 00:25:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 00:25:11 INFO - PROCESS | 1659 | RSS: Main: 345587712 00:25:11 INFO - PROCESS | 1659 | 00:28:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 00:28:15 INFO - PROCESS | 1659 | RSS: Main: 324407296 00:28:15 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 00:31:17 INFO - PROCESS | 1659 | RSS: Main: 365895680 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | __start_tp_report 00:31:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 00:31:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 00:31:17 INFO - PROCESS | 1659 | |i|pagename|runs| 00:31:17 INFO - PROCESS | 1659 | |0;dojo.html;3129.843507824609;3414.7438942079334;3429.8285085745715;3431.0736101252646;3480.7389445791573;5566.2207290979195;5576.275084239049;5587.7485513151905;5611.523020543254;5614.410486898868;5561.888762224763;5611.989841828479;5615.831525054245;5622.859428514289;5624.0439125348685;5050.141475949097;5101.566366858821;5162.354705661793;5182.4299327073995;5222.608304377168;5189.014087323415;5193.45468725784;5204.791808327681;5208.479152084799;5219.269302297663;5095.363079615048;5144.536991670749;5154.536091751742;5155;5190.091733946563;5185.403678576974;5222.869428264324;5229;5240.895182096375;5283.656562323436;5023.6985780853265;5042.3949126104735;5067.569256613192;5163.173892177234;5168.3539557555305;5439.374471935738;5469.890602187934;5475.479829416199;5475.835724928259;5506.697131657757;6068.696565171724;6136.478399336062;6143.846403839895;6144.078388241799;6158.291796443422;6025.879482410327;6053.848653783647;6095.811316793227;6211.378862113797;6222.191115155046;5689.374168841424;5698.629589076697;5702.914456283159;5723.141528770634;5740.512056475163;6302.054691796176;6302.905456418157;6319.9368006320265;6333.081703153017;6333.873322533524;5816.651000939873;5931.228940237742;5990.610610310316;6023.819285421444;6058.848528786815;6065.211522502134;6110.847228819226;6112.74665817949;6120.050983828535;6254.3745625437095;6109.769069418392;6144.324124346318;6150.138980542728;6154.446099851035;6157.568970172135;6114.327423983358;6122.602030867979;6158.353372895871;6175.44421002112;6201.069839524108;4788.281757736298;4803.207470767354;4873.074115917964;4875.68308059975;4938.530839570235;4337.566243375637;4353.085851971122;4364.148990946735;4374.58441448062;4401.031773009932;5512.2558454608115;5517.200005999108;5535.225068490415;5540.113581826889;5575.749091290902;5332.093544097513;6026.819195424145;6081.2094427725;6127.5404344674325;6130.754769809158;5463.836084917459;5470.617056806065;5473.31583552056;5475.123980163235;5482.205080263339;5272;5288.365396152486;5295.443978382292;5295.894082118336;5337.946620533745;6145.139680444652;6206;6223.066540018853;6246.750129994749;6304.71628776697;6182.567220294536;6191.876162476635;6214.564980451325;6221.720022599085;6245.250569931637;6279.748810047729;6281.403266689657;6289.874202515833;6297.338779428277;6322;5883.146943693226;5979.162917191684;6008.8798224036145;6056.727447264796;6119.296280927751;5222.7127507987425;6116.701002199415;6187.257529096537;6194.938050619436;6200.379962003944;5973.372795856546;5981;6000.429959153874;6008.519318454426;6014.0678194879865;5792.102224155263;5863.589548731547;5930.318013428511;5953;5963.552733545088;5799.362070172193;5806.970965145148;5815.156802263733;5821.09772985188;5840.591158618856;5947.672877991752;5973.074173503114;5999.280086389661;6015.849603759766;6040.0939859019745;5714.514266287412;5720.2849643794525;5782.595218334676;5806.796762113306;5821.126830975388 00:31:17 INFO - PROCESS | 1659 | |1;ext.html;513.9820106296279;516.2669010005794;533.5145018033589;535.4377903201637;544.3739699345755;27316;31477;31576.842115789448;38456.23087538248;39049.609503904954;41834.37248441276;42323.78838105805;42341.51804686829;42389.57610423902;42527;9423.293253006035;9613.038696130367;9623.989481104489;9624.711258662217;9657.275704322205;9390.389624674408;9500.287478439144;9511.80976380472;9532.571034303473;9548.713538593853;8812.295016398673;9039.096090390974;9562.091601297865;9602.711918642417;9617.471039092847;9514.191293740041;9576.47329396883;9586.233101351945;9597.328187026911;9601.519924003807;9493.66772162978;9591.232701383873;9605.47169905662;9622.37454565451;9630.807383852354;39869.800650996556;40474.39288410676;40475.797621012;40524.79737601322;40556.59443405558;3194.169515925853;3319.137024373656;3329.5671562696693;3331.06730115568;3713.888583342477;24412;28229.717702822913;28779.424411511864;29009.854950725323;29222.69957038661;2657.627932089509;2721.4693134838712;2752.628395166667;2759.392933554635;2790.902318418866;324359;327495;329177;329437;329798;58867.70566147142;59953;59976;59999.70000149971;60018.39981600215;62384.68807655932;62815.6859215701;68276;69156.85058896382;69208.3079169212;16857;17025.48923532296;17061.40285090016;17061.658766824843;17065.573360665836;13144.145630533985;13211.93394033043;13228.272445015424;13236.338183090807;13245.668858278428;19184.601924759405;19186.520336991685;19200;19223.94268315256;19235;7949.244821741925;8073.83852322962;8081.878771818428;8110.675572977015;8134.349252059939;882.6028287270753;922.4327038871046;1007.6171054999054;1041.9843702344472;1058.5448257249302;30160.698393015788;30328.54507182394;30392.392152156834;30408.590754340497;30459.847700761355;1082.71849319176;1099.2580008494235;1127.22784892349;1184.5972369394447;1231.2981600487635;15400.998935069185;15421.306041228172;15467.5359739208;15627.765583516257;15851.841481585267;629215;635939;637682;638750;638850;59967;61712.69143654253;61836.38163618306;61870.69064654738;61914.38085619176;1371.9314034298245;1376.3668712392412;1437.5328018394064;1511.5389806109022;1532.3717275917043;23379.532409352058;23524;28948.855255723585;29466.26334341573;29764.702352976194;46739;47047;47069.05861882675;47124.05751885012;47158.76420617875;338916;340817;341186;341553;341878;26742.866285668446;26852.59721104224;42185.789071054445;43783;43903.34144987891;42461.938451537724;42818.929526762084;43224.56775432331;43632.56367436285;44111;35275.470867936485;36397.0900727484;36553.81723091367;36564.451533227555;37156.699515516826;36503.63496365002;37157.81421092877;37307.626923730415;37599.248015040095;38072.42891356577;32174.678253217167;33004.50493242555;36537.81731091328;37229.6277037237;39513;31345.8432707835;33732.15669608181;33796.49305260374;33872.49191262084;33994.15014624654;21374.717516949033;22627.773722262566;23164.957576909415;25244.51572028185;25643 00:31:17 INFO - PROCESS | 1659 | |2;jquery.html;402.7502948172134;402.8932332931773;406.15925035177156;408.2895761375208;420.1135603875823;13597.858227443472;20086;20406.285779997703;20785.896070519626;20802.271920482766;15122.773158402633;16016.119113448756;20039.799602003975;20499.975001249913;20535.486612834688;5492.505674489295;6078.5441091918065;6098.298695650013;6100.572959892809;6107.81676549702;5871.236739223917;5945.375735547748;6092.147099406065;6104.114903339013;6109.663968481732;5780.450857168583;5845.094010428369;5873.471387575118;5876;5887.705614719268;5655.491005809456;5810.244668193129;5843.737031833578;5852.473277405012;5856.033754430535;5241.528262456397;5274.657147285414;5279.260903473517;5280.9207861882105;5286.735663216824;25807.74192258072;25855.61216581753;25886.74113258881;26266.60600091;26385.868070659522;1877.8122187781248;2185.4864106934856;2236.1390864517116;2870.6555213374318;2916.0814343481734;14701.92649036748;14874.776878346833;14885.479008234659;14951.252437378196;15137.848621513755;1565.694689535541;1704;1735.1237624999421;1758.3230456274298;1820.8907465551977;108093;113667.86332136656;114523;115470.42264788707;117579.99999999914;10499.475026248658;10646.148308135485;10973.177011724152;11001.724956876142;11101.666949991513;13958.953078519151;13990;14014.17815683975;14028.509002184874;14055.648608784863;7865.213478652204;8111.513309201467;8350.081491036102;8797.868031979526;8813.779655508664;8754.737357879274;8878.156575125353;8916.643334266686;8986.05646407131;9001;6717.261101278856;6828.965855170792;6838.589684618939;6843.247242803288;6880;1775.902325372117;1786.8309685050228;1821.708111103421;1926.8651194416536;1968.1733671857853;950.4677380666849;1017.5014243021014;1076.2304951959386;1093.6773651772746;1100.070440477795;20836.166553337698;20909.79090209109;20923.267685631236;21335.46661333448;21387.78612213858;859.6948083430292;1006.8069337834689;1015.4719545836272;1046.1682962045193;1048.70112018076;10606;11138.33170009802;11247;11251.493682784294;11357.602483913188;605860;618083;622615;633730;634844.8257758681;135617;137391.31304343414;139988.3000585011;142023.86964195545;144840;1041.7135287795766;1054.6256079091966;1064.3975479951598;1068.764871728234;1069.1019543583427;18285.06927449034;18562.164702588147;18582.44252672422;18590.884546927227;18644.720329195345;55169;55450.16824747713;55481.445185549244;56320;56818.14772778329;78561;79468.80796787937;80194;81865;82474;8253.95873020631;8260.967379077616;8262.215089566482;8366.62350194255;8405.411621186458;12529.812052819398;12783.424745886272;13078.411471483616;13086.14940028895;13091.803622945854;8400.285975692135;10627.89372106269;10745.892541074489;11000.889991099986;11118.555257789598;5216.843494695166;7044.189918159477;9127.589258483516;9449.574769135543;10796;11397.031252343644;11572.363520006144;11622.593209237637;11698.216462276787;12047.277163370225;7708.383329333752;8232.300254478198;8258.091609922783;8286.4199506034;8430.15698430152;10989.61536346224;11064;11164.05105566036;11293.265937714023;11411.543538258376 00:31:17 INFO - PROCESS | 1659 | |3;mootools.html;1537.7001484710484;1559.2749371542234;1800.1899145384573;1955.110424756736;1960.4118764370694;7876.527408355502;8070.112287648354;8730.257928076113;8880.06759290275;8908.821823563532;7812.414068944823;7948.92051079489;8417.873731894011;8602.698905538291;8605.526696031715;2391.2945681024053;2396.86817225053;2435.427674496501;2436.6954130733657;2437.1104546840365;2132.093860109455;2186.1364760919428;2397.3167647220544;2426.4297889995923;2452.828302018859;2157.805797478227;2187.7812218778076;2190.6385446401323;2400.243923164198;2458.6312053191987;1973.7335459713042;2402.7236867760257;2433.3916520869784;2433.5132973405302;2434.8295619306655;2066.8243199328076;2080.4070839810734;2385.642153676945;2398.604230301998;2417.6373543968543;8553.230209281131;8667.479951202948;8669.393142480028;8708.564571771387;8720.912790872073;5261.631685781997;5458.863528411822;5460.071787796084;5461.552688537541;5463.480969307949;2855.25763301541;3539.1152211947015;3556.0931962349696;3567.554055743032;3567.928641427157;1020.7193021918957;1043.5669197283194;1050.2385770316537;1069.208785498733;1079.13129930406;11791.410429478492;11968.34174120423;11969.222000570022;11974.042076633848;12005.159638825286;4630.490646028934;4731.33761273422;4779.426468823764;4814.735189564608;4842.782074806642;10182.949085254526;10211.897881021243;10215.23385746072;10220;10223.028812262823;5359.946400536022;5385.542228910586;5410.567154627621;5411.107167317348;5416.7020813855615;4844.467108618049;4904.435989861212;4922.630802689741;4931.211006239057;4934.605231581465;3867.323218436762;3872.0513474199;3894.9610503895165;3910.9413358799643;3911.9608803912165;1150.2918166522707;1223.0399136677665;1224.0697070226513;1228.1341654133814;1234.4074844074894;854.7179430787825;889.217488610019;914.3325372478129;915.0757734688013;927.8097989912052;5644.153376993484;5650.943490565041;5779.566532509994;5783.537317014629;5787.421257874179;963.2582911158509;963.2919803944095;980.3774603126923;998.6754304850953;1011.8886922438526;3672.5042119314085;3736.1967177057063;3759.172981943968;3759.661630453272;3764.4917936078277;10487.737806554896;10500.369977801356;10709.625163119254;10718.785624287471;10860.511276992554;10547.630832920811;10568.365898046142;10570.841437378445;10586.258961872594;10676.786464270672;8149.348052155813;8284.171582841787;8376.329893608498;8400.663973441116;8408.2432581068;5389.27244821944;5403.567714582745;5447.428020057837;5542.334919809648;5609.747561359667;9136.49749263797;9297.767555811159;9344.906550934676;9452.574634141343;9464.526773661428;9058.592363343534;9584.952075239578;9594.760130996781;9600.279979001743;9818.607255709692;7452.366548843409;7565.73519926822;7627.466077374531;7632;7649.9617501912135;8101;8231.34149268045;8255.463394879314;8276.834463310579;8325.708600198965;6044.395560444096;7168.283171682999;7234.855302893808;7291;7511.924880751122;7030.859382812418;7044.084269044992;7057.682404291717;7061.576305421691;7147.999280100894;6626.4367528760595;6689.531732778659;6737.797866064026;6747.392734653905;6763.492738044568;4617.492075871584;4701.670883038155;4772.093302272557;4799.9760001199775;4804.039192161652;4734.455537613218;4775.283707443911;4819.542143496362;4849.127157111754;4925.8029678812445 00:31:17 INFO - PROCESS | 1659 | |4;prototype.html;313.86817536634624;320.1803383338652;326.6488524835801;328.435091642375;329.7362110311752;6439.806805795822;6614.437772789307;6615.17310336208;6703.396694297512;6727.159105111861;6225.221847269091;6534.934650653492;6550.115734375858;6587.670616469181;6636.634985075819;4967.627427942901;4968.503149685039;4970.502949705019;4976.129177393951;4991.450940396553;4730.408698912636;4858.202465087905;4975.676581022222;4979.30289759434;4985.900281994358;4881.316615673791;4887.975560122212;4895.485973972717;4913.778879950393;4918.877028074291;4866.7566621668775;4867.7079375237245;4893.290472881412;4897.559219670246;4919.90160196794;4203.117345357478;4209.936850947238;4213.2837417639075;4213.91572168558;4224.4508213931995;6323.083152942843;6432.614043157426;6462.870742585169;6464.224293084813;6476.773312934072;5556.333240011184;5760.942390576082;5765.336986246593;5779.219805326311;5785.47930686236;7017.157941047107;7038.6128762918015;7070.328318809705;7093.467989900726;7098.36114749675;4739.289106633998;4744.3595114659765;4744.4069491313585;4747.833825816115;4753.928691069602;6391.648459334781;7200.891986620152;7372.041634587523;7418.146913105008;7546.396288296923;4003.8388867228177;4429.734215946989;4585.312203169551;4611.2852507861335;4626.282926146453;4055.0876052887866;4554.863354099382;4711.387519622427;4717.599004084691;4746.240601503743;5562.638428502134;5584.413636568183;5589.916151257735;5590.524805391506;5594.580406469531;7093.32613401726;7094;7094.893576596355;7099.970504276851;7116.50184487081;1631.2496251724103;1686.7048266553304;1691.323470611765;1701.6086300151035;1705.744138379253;1083.4420273559124;1091.4815462655176;1112.844201811747;1113.276370359273;1118.6028959719345;6556.311587283362;6572.6056436613;6579;6580.374864387875;6589.802305930734;7349.889751653729;7361.705531778669;7362.7423040193335;7363.263673632594;7372.520786148883;5663.801766938137;6587.670616469253;6589.472842172615;6603.471722262208;6613.966930165415;3880.243352546283;3893.04620368006;3899.2981263372867;3902.180542086194;3906.3554513505524;7483.176850546435;7759.262870027338;7775.57234352116;7789.610519474004;7804;7282.745103921443;7616.543007419573;7650.349720273728;7663;7675.385969122457;7513.060867391576;7609.771706848804;7663.923360766321;7670.002899623125;7682.347000505019;7361.889571656322;7381.8154546136775;7396.038514993232;7405.407567394704;7411;7304.671289792079;7307.488475806643;7311.926880731125;7316.158641756266;7318.890216646861;7477.551346919202;7716.112647045662;7725.768226953201;7736.535807851547;7739.187385324685;4572.839950601713;4605.470370907388;4628.745419001987;4632.791524381343;4632.884177895445;7365.52124111931;7375;7377.594232317274;7379.483436159418;7381;7112.786616401516;7125.251848556034;7126.109236345457;7130.679119439534;7142.964285178541;6818.352256535622;6955.269696681978;7050.25972272904;7084.114485689289;7114.430845532448;6920.653967301715;6941.75703850383;6962.930370696228;6970.268121847133;6987.580745155104;7130.251323610946;7133.357997780017;7142.928570714226;7148.678309475982;7150.96424517874;6964;6968.825779355557;6969.16370035599;6976.267491913276;6984.546004509691 00:31:17 INFO - PROCESS | 1659 | |5;yui.html;691.4917535611324;748.8577170337638;757.3865169212938;768.5273556762589;780.5823884221938;14876.404943802236;14972;15322.540323790305;15325.003874748161;15344;14054.578362649112;14105.224212668325;14118.788218176709;14130.788038179435;14143.08069975451;727.8762610356225;737.9225181355961;832.2509741232878;856.1566856646199;857.2499063319596;737.0197637142587;738.4719925253443;739.205354244187;892.660788900217;920.6225447566499;968.1577027985627;969.3795970578835;971.5239532629032;972;972.9951350243239;965.3532133470557;965.5558443116175;965.6909788867565;965.9034096590354;965.9372140810896;563.6280055163613;570.0878594249209;571.1917636544285;571.6198727845978;714.4248879651878;14855.182964936925;15110;15121.773173402407;15127.773083403757;15144.01563898343;10872.130229581693;12022.51909923602;12475.251484910934;12816.743665126738;13015.219086854819;10028;10029.498525073717;10055.648052318207;10123.746906327327;10261.333013354108;3448.051785758911;3453.740969427278;3491.6857482826413;3571.785692858437;3577;78929.21070789277;79582.20417795806;81128;81431.74273029072;81814.59092704557;9464.337496375325;9514.857277140849;9548.809023819624;9713.271504637181;10074.748131296776;21544;21695;21914.780852191594;21920.232791852526;21960.23139190121;7097.396721278749;7382.889256661155;7404.96297518509;7697.923020769721;7735.574543400055;4019.7789121598594;4079.1841631673783;4120.464339635828;4183.91632167355;4195.91608167835;3524.136586536315;3583.4266517357096;3590.2998915211806;3597.5323207982797;3601.4957905893198;524.4257537995888;650.0801366066962;652.807421810567;655.6002933736507;672.4452326830384;486.868545492715;514.390447319927;560.7588736843196;588.9175515427844;597.3709713490563;6270.811875643738;7718.189590093071;7915.248051435219;8181.918180818116;8318.958405208055;571.0691572736413;576.637273357771;595.5711887441036;604.4378727783204;608.2791891608458;3745.887623371303;3794.1273507093524;3856.421536769507;3867.4143401648294;4043.393490976377;83571;85132.57433712792;85517.57241213754;85574;85696;70667.64666176739;73780.89328660074;75041.62479187569;75234.49531009459;76090.61954690191;65847.67076164589;66571;67657.3234267651;68122;68250.65874670594;7547.207543207884;7744.0319960005;7840.921590784019;7967.681292748225;7977;46230;47068.76465617787;47758.522414775405;47890.760546197045;47957.52042479531;60796.13630909428;63701.681491592244;64679;66038.66980665067;66799.99600012007;9823.754406139904;9891;10007.849882251918;10056.094951454405;10112.191024718151;10572.682819515427;10818.621348252773;10818.891811082103;11184.440777960906;11197.60808371703;10202.693919182437;10249.48752562354;10341.534630941776;10500.26498145153;10626.52180651888;8467.886616433381;10387.480625968823;10861.67414977552;11068.501917413574;11723.296602203895;10953.452327383759;10957.452127393757;10968.835467468154;11277.492512837107;11665.941670291595;9073.954630226806;9109.817803643757;9210;9438.48088355147;10015.549300281651;9401.247900168108;9496.762580935256;9631.325807193709;9648.228141748503;9661.758456038655 00:31:17 INFO - PROCESS | 1659 | __end_tp_report 00:31:17 INFO - PROCESS | 1659 | __start_cc_report 00:31:17 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1264 00:31:17 INFO - PROCESS | 1659 | __end_cc_report 00:31:17 INFO - PROCESS | 1659 | __startTimestamp1463038277933__endTimestamp 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | ------- Summary: start ------- 00:31:17 INFO - PROCESS | 1659 | Number of tests: 6 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5642.88 Median:5806.80 stddev:620.06 (10.7%) stddev-sans-first:589.79 00:31:17 INFO - PROCESS | 1659 | Values: 3129.8 3414.7 3429.8 3431.1 3480.7 5566.2 5576.3 5587.7 5611.5 5614.4 5561.9 5612.0 5615.8 5622.9 5624.0 5050.1 5101.6 5162.4 5182.4 5222.6 5189.0 5193.5 5204.8 5208.5 5219.3 5095.4 5144.5 5154.5 5155.0 5190.1 5185.4 5222.9 5229.0 5240.9 5283.7 5023.7 5042.4 5067.6 5163.2 5168.4 5439.4 5469.9 5475.5 5475.8 5506.7 6068.7 6136.5 6143.8 6144.1 6158.3 6025.9 6053.8 6095.8 6211.4 6222.2 5689.4 5698.6 5702.9 5723.1 5740.5 6302.1 6302.9 6319.9 6333.1 6333.9 5816.7 5931.2 5990.6 6023.8 6058.8 6065.2 6110.8 6112.7 6120.1 6254.4 6109.8 6144.3 6150.1 6154.4 6157.6 6114.3 6122.6 6158.4 6175.4 6201.1 4788.3 4803.2 4873.1 4875.7 4938.5 4337.6 4353.1 4364.1 4374.6 4401.0 5512.3 5517.2 5535.2 5540.1 5575.7 5332.1 6026.8 6081.2 6127.5 6130.8 5463.8 5470.6 5473.3 5475.1 5482.2 5272.0 5288.4 5295.4 5295.9 5337.9 6145.1 6206.0 6223.1 6246.8 6304.7 6182.6 6191.9 6214.6 6221.7 6245.3 6279.7 6281.4 6289.9 6297.3 6322.0 5883.1 5979.2 6008.9 6056.7 6119.3 5222.7 6116.7 6187.3 6194.9 6200.4 5973.4 5981.0 6000.4 6008.5 6014.1 5792.1 5863.6 5930.3 5953.0 5963.6 5799.4 5807.0 5815.2 5821.1 5840.6 5947.7 5973.1 5999.3 6015.8 6040.1 5714.5 5720.3 5782.6 5806.8 5821.1 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:58842.42 Median:20435.29 stddev:122285.69 (598.4%) stddev-sans-first:122550.31 00:31:17 INFO - PROCESS | 1659 | Values: 514.0 516.3 533.5 535.4 544.4 27316.0 31477.0 31576.8 38456.2 39049.6 41834.4 42323.8 42341.5 42389.6 42527.0 9423.3 9613.0 9624.0 9624.7 9657.3 9390.4 9500.3 9511.8 9532.6 9548.7 8812.3 9039.1 9562.1 9602.7 9617.5 9514.2 9576.5 9586.2 9597.3 9601.5 9493.7 9591.2 9605.5 9622.4 9630.8 39869.8 40474.4 40475.8 40524.8 40556.6 3194.2 3319.1 3329.6 3331.1 3713.9 24412.0 28229.7 28779.4 29009.9 29222.7 2657.6 2721.5 2752.6 2759.4 2790.9 324359.0 327495.0 329177.0 329437.0 329798.0 58867.7 59953.0 59976.0 59999.7 60018.4 62384.7 62815.7 68276.0 69156.9 69208.3 16857.0 17025.5 17061.4 17061.7 17065.6 13144.1 13211.9 13228.3 13236.3 13245.7 19184.6 19186.5 19200.0 19223.9 19235.0 7949.2 8073.8 8081.9 8110.7 8134.3 882.6 922.4 1007.6 1042.0 1058.5 30160.7 30328.5 30392.4 30408.6 30459.8 1082.7 1099.3 1127.2 1184.6 1231.3 15401.0 15421.3 15467.5 15627.8 15851.8 629215.0 635939.0 637682.0 638750.0 638850.0 59967.0 61712.7 61836.4 61870.7 61914.4 1371.9 1376.4 1437.5 1511.5 1532.4 23379.5 23524.0 28948.9 29466.3 29764.7 46739.0 47047.0 47069.1 47124.1 47158.8 338916.0 340817.0 341186.0 341553.0 341878.0 26742.9 26852.6 42185.8 43783.0 43903.3 42461.9 42818.9 43224.6 43632.6 44111.0 35275.5 36397.1 36553.8 36564.5 37156.7 36503.6 37157.8 37307.6 37599.2 38072.4 32174.7 33004.5 36537.8 37229.6 39513.0 31345.8 33732.2 33796.5 33872.5 33994.2 21374.7 22627.8 23165.0 25244.5 25643.0 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:36134.75 Median:20916.53 stddev:104028.49 (497.4%) stddev-sans-first:104285.72 00:31:17 INFO - PROCESS | 1659 | Values: 402.8 402.9 406.2 408.3 420.1 13597.9 20086.0 20406.3 20785.9 20802.3 15122.8 16016.1 20039.8 20500.0 20535.5 5492.5 6078.5 6098.3 6100.6 6107.8 5871.2 5945.4 6092.1 6104.1 6109.7 5780.5 5845.1 5873.5 5876.0 5887.7 5655.5 5810.2 5843.7 5852.5 5856.0 5241.5 5274.7 5279.3 5280.9 5286.7 25807.7 25855.6 25886.7 26266.6 26385.9 1877.8 2185.5 2236.1 2870.7 2916.1 14701.9 14874.8 14885.5 14951.3 15137.8 1565.7 1704.0 1735.1 1758.3 1820.9 108093.0 113667.9 114523.0 115470.4 117580.0 10499.5 10646.1 10973.2 11001.7 11101.7 13959.0 13990.0 14014.2 14028.5 14055.6 7865.2 8111.5 8350.1 8797.9 8813.8 8754.7 8878.2 8916.6 8986.1 9001.0 6717.3 6829.0 6838.6 6843.2 6880.0 1775.9 1786.8 1821.7 1926.9 1968.2 950.5 1017.5 1076.2 1093.7 1100.1 20836.2 20909.8 20923.3 21335.5 21387.8 859.7 1006.8 1015.5 1046.2 1048.7 10606.0 11138.3 11247.0 11251.5 11357.6 605860.0 618083.0 622615.0 633730.0 634844.8 135617.0 137391.3 139988.3 142023.9 144840.0 1041.7 1054.6 1064.4 1068.8 1069.1 18285.1 18562.2 18582.4 18590.9 18644.7 55169.0 55450.2 55481.4 56320.0 56818.1 78561.0 79468.8 80194.0 81865.0 82474.0 8254.0 8261.0 8262.2 8366.6 8405.4 12529.8 12783.4 13078.4 13086.1 13091.8 8400.3 10627.9 10745.9 11000.9 11118.6 5216.8 7044.2 9127.6 9449.6 10796.0 11397.0 11572.4 11622.6 11698.2 12047.3 7708.4 8232.3 8258.1 8286.4 8430.2 10989.6 11064.0 11164.1 11293.3 11411.5 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5625.42 Median:4876.78 stddev:3155.81 (64.7%) stddev-sans-first:3149.72 00:31:17 INFO - PROCESS | 1659 | Values: 1537.7 1559.3 1800.2 1955.1 1960.4 7876.5 8070.1 8730.3 8880.1 8908.8 7812.4 7948.9 8417.9 8602.7 8605.5 2391.3 2396.9 2435.4 2436.7 2437.1 2132.1 2186.1 2397.3 2426.4 2452.8 2157.8 2187.8 2190.6 2400.2 2458.6 1973.7 2402.7 2433.4 2433.5 2434.8 2066.8 2080.4 2385.6 2398.6 2417.6 8553.2 8667.5 8669.4 8708.6 8720.9 5261.6 5458.9 5460.1 5461.6 5463.5 2855.3 3539.1 3556.1 3567.6 3567.9 1020.7 1043.6 1050.2 1069.2 1079.1 11791.4 11968.3 11969.2 11974.0 12005.2 4630.5 4731.3 4779.4 4814.7 4842.8 10182.9 10211.9 10215.2 10220.0 10223.0 5359.9 5385.5 5410.6 5411.1 5416.7 4844.5 4904.4 4922.6 4931.2 4934.6 3867.3 3872.1 3895.0 3910.9 3912.0 1150.3 1223.0 1224.1 1228.1 1234.4 854.7 889.2 914.3 915.1 927.8 5644.2 5650.9 5779.6 5783.5 5787.4 963.3 963.3 980.4 998.7 1011.9 3672.5 3736.2 3759.2 3759.7 3764.5 10487.7 10500.4 10709.6 10718.8 10860.5 10547.6 10568.4 10570.8 10586.3 10676.8 8149.3 8284.2 8376.3 8400.7 8408.2 5389.3 5403.6 5447.4 5542.3 5609.7 9136.5 9297.8 9344.9 9452.6 9464.5 9058.6 9585.0 9594.8 9600.3 9818.6 7452.4 7565.7 7627.5 7632.0 7650.0 8101.0 8231.3 8255.5 8276.8 8325.7 6044.4 7168.3 7234.9 7291.0 7511.9 7030.9 7044.1 7057.7 7061.6 7148.0 6626.4 6689.5 6737.8 6747.4 6763.5 4617.5 4701.7 4772.1 4800.0 4804.0 4734.5 4775.3 4819.5 4849.1 4925.8 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:5825.49 Median:6587.67 stddev:1858.54 (28.2%) stddev-sans-first:1817.13 00:31:17 INFO - PROCESS | 1659 | Values: 313.9 320.2 326.6 328.4 329.7 6439.8 6614.4 6615.2 6703.4 6727.2 6225.2 6534.9 6550.1 6587.7 6636.6 4967.6 4968.5 4970.5 4976.1 4991.5 4730.4 4858.2 4975.7 4979.3 4985.9 4881.3 4888.0 4895.5 4913.8 4918.9 4866.8 4867.7 4893.3 4897.6 4919.9 4203.1 4209.9 4213.3 4213.9 4224.5 6323.1 6432.6 6462.9 6464.2 6476.8 5556.3 5760.9 5765.3 5779.2 5785.5 7017.2 7038.6 7070.3 7093.5 7098.4 4739.3 4744.4 4744.4 4747.8 4753.9 6391.6 7200.9 7372.0 7418.1 7546.4 4003.8 4429.7 4585.3 4611.3 4626.3 4055.1 4554.9 4711.4 4717.6 4746.2 5562.6 5584.4 5589.9 5590.5 5594.6 7093.3 7094.0 7094.9 7100.0 7116.5 1631.2 1686.7 1691.3 1701.6 1705.7 1083.4 1091.5 1112.8 1113.3 1118.6 6556.3 6572.6 6579.0 6580.4 6589.8 7349.9 7361.7 7362.7 7363.3 7372.5 5663.8 6587.7 6589.5 6603.5 6614.0 3880.2 3893.0 3899.3 3902.2 3906.4 7483.2 7759.3 7775.6 7789.6 7804.0 7282.7 7616.5 7650.3 7663.0 7675.4 7513.1 7609.8 7663.9 7670.0 7682.3 7361.9 7381.8 7396.0 7405.4 7411.0 7304.7 7307.5 7311.9 7316.2 7318.9 7477.6 7716.1 7725.8 7736.5 7739.2 4572.8 4605.5 4628.7 4632.8 4632.9 7365.5 7375.0 7377.6 7379.5 7381.0 7112.8 7125.3 7126.1 7130.7 7143.0 6818.4 6955.3 7050.3 7084.1 7114.4 6920.7 6941.8 6962.9 6970.3 6987.6 7130.3 7133.4 7142.9 7148.7 7151.0 6964.0 6968.8 6969.2 6976.3 6984.5 00:31:17 INFO - PROCESS | 1659 | 00:31:17 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:17601.40 Median:596.47 stddev:24490.33 (4105.9%) stddev-sans-first:24526.12 00:31:17 INFO - PROCESS | 1659 | Values: 691.5 748.9 757.4 768.5 780.6 14876.4 14972.0 15322.5 15325.0 15344.0 14054.6 14105.2 14118.8 14130.8 14143.1 727.9 737.9 832.3 856.2 857.2 737.0 738.5 739.2 892.7 920.6 968.2 969.4 971.5 972.0 973.0 965.4 965.6 965.7 965.9 965.9 563.6 570.1 571.2 571.6 714.4 14855.2 15110.0 15121.8 15127.8 15144.0 10872.1 12022.5 12475.3 12816.7 13015.2 10028.0 10029.5 10055.6 10123.7 10261.3 3448.1 3453.7 3491.7 3571.8 3577.0 78929.2 79582.2 81128.0 81431.7 81814.6 9464.3 9514.9 9548.8 9713.3 10074.7 21544.0 21695.0 21914.8 21920.2 21960.2 7097.4 7382.9 7405.0 7697.9 7735.6 4019.8 4079.2 4120.5 4183.9 4195.9 3524.1 3583.4 3590.3 3597.5 3601.5 524.4 650.1 652.8 655.6 672.4 486.9 514.4 560.8 588.9 597.4 6270.8 7718.2 7915.2 8181.9 8319.0 571.1 576.6 595.6 604.4 608.3 3745.9 3794.1 3856.4 3867.4 4043.4 83571.0 85132.6 85517.6 85574.0 85696.0 70667.6 73780.9 75041.6 75234.5 76090.6 65847.7 66571.0 67657.3 68122.0 68250.7 7547.2 7744.0 7840.9 7967.7 7977.0 46230.0 47068.8 47758.5 47890.8 47957.5 60796.1 63701.7 64679.0 66038.7 66800.0 9823.8 9891.0 10007.8 10056.1 10112.2 10572.7 10818.6 10818.9 11184.4 11197.6 10202.7 10249.5 10341.5 10500.3 10626.5 8467.9 10387.5 10861.7 11068.5 11723.3 10953.5 10957.5 10968.8 11277.5 11665.9 9074.0 9109.8 9210.0 9438.5 10015.5 9401.2 9496.8 9631.3 9648.2 9661.8 00:31:17 INFO - PROCESS | 1659 | -------- Summary: end -------- 00:31:17 INFO - PROCESS | 1659 | 00:31:18 INFO - TEST-INFO | 1659: exit 0 00:31:18 INFO - TEST-OK | dromaeo_css | took 1115695ms 00:31:18 INFO - TEST-START | kraken 00:31:18 INFO - operating with platform_type : mac_ 00:31:18 INFO - Initialising browser for kraken test... 00:31:18 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpa4iMqB/profile http://localhost:49272/getInfo.html) 00:31:20 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 00:31:20 INFO - PROCESS | 1670 | colorDepth:24 00:31:20 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 00:31:20 INFO - PROCESS | 1670 | __metrics 00:31:20 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" 00:31:20 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 00:31:21 INFO - TEST-INFO | 1670: exit 0 00:31:21 INFO - Browser initialized. 00:31:21 INFO - Running cycle 1/1 for kraken test... 00:31:21 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/T/tmpa4iMqB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 00:31:33 INFO - PROCESS | 1672 | RSS: Main: 211226624 00:31:33 INFO - PROCESS | 1672 | 00:31:36 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 00:31:37 INFO - PROCESS | 1672 | RSS: Main: 271208448 00:31:37 INFO - PROCESS | 1672 | 00:31:39 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 00:31:40 INFO - PROCESS | 1672 | RSS: Main: 285794304 00:31:40 INFO - PROCESS | 1672 | 00:31:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 00:31:43 INFO - PROCESS | 1672 | RSS: Main: 372432896 00:31:43 INFO - PROCESS | 1672 | 00:31:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 00:31:46 INFO - PROCESS | 1672 | RSS: Main: 279056384 00:31:46 INFO - PROCESS | 1672 | 00:31:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 00:31:49 INFO - PROCESS | 1672 | RSS: Main: 280031232 00:31:49 INFO - PROCESS | 1672 | 00:31:52 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 00:31:53 INFO - PROCESS | 1672 | RSS: Main: 317104128 00:31:53 INFO - PROCESS | 1672 | 00:31:55 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 00:31:56 INFO - PROCESS | 1672 | RSS: Main: 318517248 00:31:56 INFO - PROCESS | 1672 | 00:31:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 00:31:59 INFO - PROCESS | 1672 | RSS: Main: 317149184 00:31:59 INFO - PROCESS | 1672 | 00:32:01 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 00:32:02 INFO - PROCESS | 1672 | RSS: Main: 309100544 00:32:02 INFO - PROCESS | 1672 | 00:32:03 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 00:32:04 INFO - PROCESS | 1672 | RSS: Main: 315867136 00:32:04 INFO - PROCESS | 1672 | 00:32:06 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 00:32:07 INFO - PROCESS | 1672 | RSS: Main: 318951424 00:32:07 INFO - PROCESS | 1672 | 00:32:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 00:32:10 INFO - PROCESS | 1672 | RSS: Main: 323543040 00:32:10 INFO - PROCESS | 1672 | 00:32:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 00:32:14 INFO - PROCESS | 1672 | RSS: Main: 321699840 00:32:14 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 00:32:16 INFO - PROCESS | 1672 | RSS: Main: 385667072 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | __start_tp_report 00:32:16 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 00:32:16 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 00:32:16 INFO - PROCESS | 1672 | |i|pagename|runs| 00:32:16 INFO - PROCESS | 1672 | |0;ai-astar;113;115;119;113;111;113;114;114;114;137 00:32:16 INFO - PROCESS | 1672 | |1;audio-beat-detection;155;123;152;154;119;141;120;151;158;118 00:32:16 INFO - PROCESS | 1672 | |2;audio-dft;185;186;185;182;186;183;182;184;183;184 00:32:16 INFO - PROCESS | 1672 | |3;audio-fft;70;78;73;159;82;80;84;54;126;81 00:32:16 INFO - PROCESS | 1672 | |4;audio-oscillator;96;93;140;77;85;88;125;111;114;152 00:32:16 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;88;91;86;90;88;91;88;90;88 00:32:16 INFO - PROCESS | 1672 | |6;imaging-darkroom;97;96;96;96;96;95;96;96;95;95 00:32:16 INFO - PROCESS | 1672 | |7;imaging-desaturate;86;85;80;84;87;84;83;85;83;86 00:32:16 INFO - PROCESS | 1672 | |8;json-parse-financial;61;64;65;64;65;65;60;79;89;90 00:32:16 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;67;64;71;74;77;74;69;68;70 00:32:16 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;82;80;80;81;81;80;80;80;80;86 00:32:16 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;140;124;118;149;144;132;128;134;174;163 00:32:16 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;163;164;166;169;167;158;166;170;162;160 00:32:16 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;69;67;72;60;55;82;97;97;95;85 00:32:16 INFO - PROCESS | 1672 | __end_tp_report 00:32:16 INFO - PROCESS | 1672 | __start_cc_report 00:32:16 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2016 00:32:16 INFO - PROCESS | 1672 | __end_cc_report 00:32:16 INFO - PROCESS | 1672 | __startTimestamp1463038336388__endTimestamp 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | ------- Summary: start ------- 00:32:16 INFO - PROCESS | 1672 | Number of tests: 14 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:7.56 (6.6%) stddev-sans-first:7.92 00:32:16 INFO - PROCESS | 1672 | Values: 113.0 115.0 119.0 113.0 111.0 113.0 114.0 114.0 114.0 137.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:139.10 Median:151.50 stddev:17.05 (11.3%) stddev-sans-first:17.09 00:32:16 INFO - PROCESS | 1672 | Values: 155.0 123.0 152.0 154.0 119.0 141.0 120.0 151.0 158.0 118.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.50 stddev:1.49 (0.8%) stddev-sans-first:1.54 00:32:16 INFO - PROCESS | 1672 | Values: 185.0 186.0 185.0 182.0 186.0 183.0 182.0 184.0 183.0 184.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:88.70 Median:79.00 stddev:30.64 (38.8%) stddev-sans-first:31.74 00:32:16 INFO - PROCESS | 1672 | Values: 70.0 78.0 73.0 159.0 82.0 80.0 84.0 54.0 126.0 81.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:108.10 Median:81.00 stddev:24.84 (30.7%) stddev-sans-first:25.96 00:32:16 INFO - PROCESS | 1672 | Values: 96.0 93.0 140.0 77.0 85.0 88.0 125.0 111.0 114.0 152.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:90.00 stddev:1.63 (1.8%) stddev-sans-first:1.69 00:32:16 INFO - PROCESS | 1672 | Values: 90.0 88.0 91.0 86.0 90.0 88.0 91.0 88.0 90.0 88.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:95.80 Median:96.00 stddev:0.63 (0.7%) stddev-sans-first:0.50 00:32:16 INFO - PROCESS | 1672 | Values: 97.0 96.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:2.00 (2.4%) stddev-sans-first:2.03 00:32:16 INFO - PROCESS | 1672 | Values: 86.0 85.0 80.0 84.0 87.0 84.0 83.0 85.0 83.0 86.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:70.20 Median:65.00 stddev:11.40 (17.5%) stddev-sans-first:11.60 00:32:16 INFO - PROCESS | 1672 | Values: 61.0 64.0 65.0 64.0 65.0 65.0 60.0 79.0 89.0 90.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:70.50 stddev:4.18 (5.9%) stddev-sans-first:4.03 00:32:16 INFO - PROCESS | 1672 | Values: 65.0 67.0 64.0 71.0 74.0 77.0 74.0 69.0 68.0 70.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:80.50 stddev:1.89 (2.3%) stddev-sans-first:1.96 00:32:16 INFO - PROCESS | 1672 | Values: 82.0 80.0 80.0 81.0 81.0 80.0 80.0 80.0 80.0 86.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:142.00 stddev:17.52 (12.3%) stddev-sans-first:18.58 00:32:16 INFO - PROCESS | 1672 | Values: 140.0 124.0 118.0 149.0 144.0 132.0 128.0 134.0 174.0 163.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:166.00 stddev:3.84 (2.3%) stddev-sans-first:4.03 00:32:16 INFO - PROCESS | 1672 | Values: 163.0 164.0 166.0 169.0 167.0 158.0 166.0 170.0 162.0 160.0 00:32:16 INFO - PROCESS | 1672 | 00:32:16 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.90 Median:83.50 stddev:15.52 (18.6%) stddev-sans-first:16.12 00:32:16 INFO - PROCESS | 1672 | Values: 69.0 67.0 72.0 60.0 55.0 82.0 97.0 97.0 95.0 85.0 00:32:16 INFO - PROCESS | 1672 | -------- Summary: end -------- 00:32:16 INFO - PROCESS | 1672 | 00:32:17 INFO - TEST-INFO | 1672: exit 0 00:32:17 INFO - TEST-OK | kraken | took 58589ms 00:32:17 INFO - SUITE-END | took 1174s 00:32:17 INFO - Completed test suite (00:19:34) 00:32:17 INFO - javascript benchmark 00:32:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3129.843507824609, 3414.7438942079334, 3429.8285085745715, 3431.0736101252646, 3480.7389445791573, 5566.2207290979195, 5576.275084239049, 5587.7485513151905, 5611.523020543254, 5614.410486898868, 5561.888762224763, 5611.989841828479, 5615.831525054245, 5622.859428514289, 5624.0439125348685, 5050.141475949097, 5101.566366858821, 5162.354705661793, 5182.4299327073995, 5222.608304377168, 5189.014087323415, 5193.45468725784, 5204.791808327681, 5208.479152084799, 5219.269302297663, 5095.363079615048, 5144.536991670749, 5154.536091751742, 5155.0, 5190.091733946563, 5185.403678576974, 5222.869428264324, 5229.0, 5240.895182096375, 5283.656562323436, 5023.6985780853265, 5042.3949126104735, 5067.569256613192, 5163.173892177234, 5168.3539557555305, 5439.374471935738, 5469.890602187934, 5475.479829416199, 5475.835724928259, 5506.697131657757, 6068.696565171724, 6136.478399336062, 6143.846403839895, 6144.078388241799, 6158.291796443422, 6025.879482410327, 6053.848653783647, 6095.811316793227, 6211.378862113797, 6222.191115155046, 5689.374168841424, 5698.629589076697, 5702.914456283159, 5723.141528770634, 5740.512056475163, 6302.054691796176, 6302.905456418157, 6319.9368006320265, 6333.081703153017, 6333.873322533524, 5816.651000939873, 5931.228940237742, 5990.610610310316, 6023.819285421444, 6058.848528786815, 6065.211522502134, 6110.847228819226, 6112.74665817949, 6120.050983828535, 6254.3745625437095, 6109.769069418392, 6144.324124346318, 6150.138980542728, 6154.446099851035, 6157.568970172135, 6114.327423983358, 6122.602030867979, 6158.353372895871, 6175.44421002112, 6201.069839524108, 4788.281757736298, 4803.207470767354, 4873.074115917964, 4875.68308059975, 4938.530839570235, 4337.566243375637, 4353.085851971122, 4364.148990946735, 4374.58441448062, 4401.031773009932, 5512.2558454608115, 5517.200005999108, 5535.225068490415, 5540.113581826889, 5575.749091290902, 5332.093544097513, 6026.819195424145, 6081.2094427725, 6127.5404344674325, 6130.754769809158, 5463.836084917459, 5470.617056806065, 5473.31583552056, 5475.123980163235, 5482.205080263339, 5272.0, 5288.365396152486, 5295.443978382292, 5295.894082118336, 5337.946620533745, 6145.139680444652, 6206.0, 6223.066540018853, 6246.750129994749, 6304.71628776697, 6182.567220294536, 6191.876162476635, 6214.564980451325, 6221.720022599085, 6245.250569931637, 6279.748810047729, 6281.403266689657, 6289.874202515833, 6297.338779428277, 6322.0, 5883.146943693226, 5979.162917191684, 6008.8798224036145, 6056.727447264796, 6119.296280927751, 5222.7127507987425, 6116.701002199415, 6187.257529096537, 6194.938050619436, 6200.379962003944, 5973.372795856546, 5981.0, 6000.429959153874, 6008.519318454426, 6014.0678194879865, 5792.102224155263, 5863.589548731547, 5930.318013428511, 5953.0, 5963.552733545088, 5799.362070172193, 5806.970965145148, 5815.156802263733, 5821.09772985188, 5840.591158618856, 5947.672877991752, 5973.074173503114, 5999.280086389661, 6015.849603759766, 6040.0939859019745, 5714.514266287412, 5720.2849643794525, 5782.595218334676, 5806.796762113306, 5821.126830975388], "value": 5604.009966371828, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [513.9820106296279, 516.2669010005794, 533.5145018033589, 535.4377903201637, 544.3739699345755, 27316.0, 31477.0, 31576.842115789448, 38456.23087538248, 39049.609503904954, 41834.37248441276, 42323.78838105805, 42341.51804686829, 42389.57610423902, 42527.0, 9423.293253006035, 9613.038696130367, 9623.989481104489, 9624.711258662217, 9657.275704322205, 9390.389624674408, 9500.287478439144, 9511.80976380472, 9532.571034303473, 9548.713538593853, 8812.295016398673, 9039.096090390974, 9562.091601297865, 9602.711918642417, 9617.471039092847, 9514.191293740041, 9576.47329396883, 9586.233101351945, 9597.328187026911, 9601.519924003807, 9493.66772162978, 9591.232701383873, 9605.47169905662, 9622.37454565451, 9630.807383852354, 39869.800650996556, 40474.39288410676, 40475.797621012, 40524.79737601322, 40556.59443405558, 3194.169515925853, 3319.137024373656, 3329.5671562696693, 3331.06730115568, 3713.888583342477, 24412.0, 28229.717702822913, 28779.424411511864, 29009.854950725323, 29222.69957038661, 2657.627932089509, 2721.4693134838712, 2752.628395166667, 2759.392933554635, 2790.902318418866, 324359.0, 327495.0, 329177.0, 329437.0, 329798.0, 58867.70566147142, 59953.0, 59976.0, 59999.70000149971, 60018.39981600215, 62384.68807655932, 62815.6859215701, 68276.0, 69156.85058896382, 69208.3079169212, 16857.0, 17025.48923532296, 17061.40285090016, 17061.658766824843, 17065.573360665836, 13144.145630533985, 13211.93394033043, 13228.272445015424, 13236.338183090807, 13245.668858278428, 19184.601924759405, 19186.520336991685, 19200.0, 19223.94268315256, 19235.0, 7949.244821741925, 8073.83852322962, 8081.878771818428, 8110.675572977015, 8134.349252059939, 882.6028287270753, 922.4327038871046, 1007.6171054999054, 1041.9843702344472, 1058.5448257249302, 30160.698393015788, 30328.54507182394, 30392.392152156834, 30408.590754340497, 30459.847700761355, 1082.71849319176, 1099.2580008494235, 1127.22784892349, 1184.5972369394447, 1231.2981600487635, 15400.998935069185, 15421.306041228172, 15467.5359739208, 15627.765583516257, 15851.841481585267, 629215.0, 635939.0, 637682.0, 638750.0, 638850.0, 59967.0, 61712.69143654253, 61836.38163618306, 61870.69064654738, 61914.38085619176, 1371.9314034298245, 1376.3668712392412, 1437.5328018394064, 1511.5389806109022, 1532.3717275917043, 23379.532409352058, 23524.0, 28948.855255723585, 29466.26334341573, 29764.702352976194, 46739.0, 47047.0, 47069.05861882675, 47124.05751885012, 47158.76420617875, 338916.0, 340817.0, 341186.0, 341553.0, 341878.0, 26742.866285668446, 26852.59721104224, 42185.789071054445, 43783.0, 43903.34144987891, 42461.938451537724, 42818.929526762084, 43224.56775432331, 43632.56367436285, 44111.0, 35275.470867936485, 36397.0900727484, 36553.81723091367, 36564.451533227555, 37156.699515516826, 36503.63496365002, 37157.81421092877, 37307.626923730415, 37599.248015040095, 38072.42891356577, 32174.678253217167, 33004.50493242555, 36537.81731091328, 37229.6277037237, 39513.0, 31345.8432707835, 33732.15669608181, 33796.49305260374, 33872.49191262084, 33994.15014624654, 21374.717516949033, 22627.773722262566, 23164.957576909415, 25244.51572028185, 25643.0], "value": 19374.468089164606, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.7502948172134, 402.8932332931773, 406.15925035177156, 408.2895761375208, 420.1135603875823, 13597.858227443472, 20086.0, 20406.285779997703, 20785.896070519626, 20802.271920482766, 15122.773158402633, 16016.119113448756, 20039.799602003975, 20499.975001249913, 20535.486612834688, 5492.505674489295, 6078.5441091918065, 6098.298695650013, 6100.572959892809, 6107.81676549702, 5871.236739223917, 5945.375735547748, 6092.147099406065, 6104.114903339013, 6109.663968481732, 5780.450857168583, 5845.094010428369, 5873.471387575118, 5876.0, 5887.705614719268, 5655.491005809456, 5810.244668193129, 5843.737031833578, 5852.473277405012, 5856.033754430535, 5241.528262456397, 5274.657147285414, 5279.260903473517, 5280.9207861882105, 5286.735663216824, 25807.74192258072, 25855.61216581753, 25886.74113258881, 26266.60600091, 26385.868070659522, 1877.8122187781248, 2185.4864106934856, 2236.1390864517116, 2870.6555213374318, 2916.0814343481734, 14701.92649036748, 14874.776878346833, 14885.479008234659, 14951.252437378196, 15137.848621513755, 1565.694689535541, 1704.0, 1735.1237624999421, 1758.3230456274298, 1820.8907465551977, 108093.0, 113667.86332136656, 114523.0, 115470.42264788707, 117579.99999999914, 10499.475026248658, 10646.148308135485, 10973.177011724152, 11001.724956876142, 11101.666949991513, 13958.953078519151, 13990.0, 14014.17815683975, 14028.509002184874, 14055.648608784863, 7865.213478652204, 8111.513309201467, 8350.081491036102, 8797.868031979526, 8813.779655508664, 8754.737357879274, 8878.156575125353, 8916.643334266686, 8986.05646407131, 9001.0, 6717.261101278856, 6828.965855170792, 6838.589684618939, 6843.247242803288, 6880.0, 1775.902325372117, 1786.8309685050228, 1821.708111103421, 1926.8651194416536, 1968.1733671857853, 950.4677380666849, 1017.5014243021014, 1076.2304951959386, 1093.6773651772746, 1100.070440477795, 20836.166553337698, 20909.79090209109, 20923.267685631236, 21335.46661333448, 21387.78612213858, 859.6948083430292, 1006.8069337834689, 1015.4719545836272, 1046.1682962045193, 1048.70112018076, 10606.0, 11138.33170009802, 11247.0, 11251.493682784294, 11357.602483913188, 605860.0, 618083.0, 622615.0, 633730.0, 634844.8257758681, 135617.0, 137391.31304343414, 139988.3000585011, 142023.86964195545, 144840.0, 1041.7135287795766, 1054.6256079091966, 1064.3975479951598, 1068.764871728234, 1069.1019543583427, 18285.06927449034, 18562.164702588147, 18582.44252672422, 18590.884546927227, 18644.720329195345, 55169.0, 55450.16824747713, 55481.445185549244, 56320.0, 56818.14772778329, 78561.0, 79468.80796787937, 80194.0, 81865.0, 82474.0, 8253.95873020631, 8260.967379077616, 8262.215089566482, 8366.62350194255, 8405.411621186458, 12529.812052819398, 12783.424745886272, 13078.411471483616, 13086.14940028895, 13091.803622945854, 8400.285975692135, 10627.89372106269, 10745.892541074489, 11000.889991099986, 11118.555257789598, 5216.843494695166, 7044.189918159477, 9127.589258483516, 9449.574769135543, 10796.0, 11397.031252343644, 11572.363520006144, 11622.593209237637, 11698.216462276787, 12047.277163370225, 7708.383329333752, 8232.300254478198, 8258.091609922783, 8286.4199506034, 8430.15698430152, 10989.61536346224, 11064.0, 11164.05105566036, 11293.265937714023, 11411.543538258376], "value": 9785.973084844014, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1537.7001484710484, 1559.2749371542234, 1800.1899145384573, 1955.110424756736, 1960.4118764370694, 7876.527408355502, 8070.112287648354, 8730.257928076113, 8880.06759290275, 8908.821823563532, 7812.414068944823, 7948.92051079489, 8417.873731894011, 8602.698905538291, 8605.526696031715, 2391.2945681024053, 2396.86817225053, 2435.427674496501, 2436.6954130733657, 2437.1104546840365, 2132.093860109455, 2186.1364760919428, 2397.3167647220544, 2426.4297889995923, 2452.828302018859, 2157.805797478227, 2187.7812218778076, 2190.6385446401323, 2400.243923164198, 2458.6312053191987, 1973.7335459713042, 2402.7236867760257, 2433.3916520869784, 2433.5132973405302, 2434.8295619306655, 2066.8243199328076, 2080.4070839810734, 2385.642153676945, 2398.604230301998, 2417.6373543968543, 8553.230209281131, 8667.479951202948, 8669.393142480028, 8708.564571771387, 8720.912790872073, 5261.631685781997, 5458.863528411822, 5460.071787796084, 5461.552688537541, 5463.480969307949, 2855.25763301541, 3539.1152211947015, 3556.0931962349696, 3567.554055743032, 3567.928641427157, 1020.7193021918957, 1043.5669197283194, 1050.2385770316537, 1069.208785498733, 1079.13129930406, 11791.410429478492, 11968.34174120423, 11969.222000570022, 11974.042076633848, 12005.159638825286, 4630.490646028934, 4731.33761273422, 4779.426468823764, 4814.735189564608, 4842.782074806642, 10182.949085254526, 10211.897881021243, 10215.23385746072, 10220.0, 10223.028812262823, 5359.946400536022, 5385.542228910586, 5410.567154627621, 5411.107167317348, 5416.7020813855615, 4844.467108618049, 4904.435989861212, 4922.630802689741, 4931.211006239057, 4934.605231581465, 3867.323218436762, 3872.0513474199, 3894.9610503895165, 3910.9413358799643, 3911.9608803912165, 1150.2918166522707, 1223.0399136677665, 1224.0697070226513, 1228.1341654133814, 1234.4074844074894, 854.7179430787825, 889.217488610019, 914.3325372478129, 915.0757734688013, 927.8097989912052, 5644.153376993484, 5650.943490565041, 5779.566532509994, 5783.537317014629, 5787.421257874179, 963.2582911158509, 963.2919803944095, 980.3774603126923, 998.6754304850953, 1011.8886922438526, 3672.5042119314085, 3736.1967177057063, 3759.172981943968, 3759.661630453272, 3764.4917936078277, 10487.737806554896, 10500.369977801356, 10709.625163119254, 10718.785624287471, 10860.511276992554, 10547.630832920811, 10568.365898046142, 10570.841437378445, 10586.258961872594, 10676.786464270672, 8149.348052155813, 8284.171582841787, 8376.329893608498, 8400.663973441116, 8408.2432581068, 5389.27244821944, 5403.567714582745, 5447.428020057837, 5542.334919809648, 5609.747561359667, 9136.49749263797, 9297.767555811159, 9344.906550934676, 9452.574634141343, 9464.526773661428, 9058.592363343534, 9584.952075239578, 9594.760130996781, 9600.279979001743, 9818.607255709692, 7452.366548843409, 7565.73519926822, 7627.466077374531, 7632.0, 7649.9617501912135, 8101.0, 8231.34149268045, 8255.463394879314, 8276.834463310579, 8325.708600198965, 6044.395560444096, 7168.283171682999, 7234.855302893808, 7291.0, 7511.924880751122, 7030.859382812418, 7044.084269044992, 7057.682404291717, 7061.576305421691, 7147.999280100894, 6626.4367528760595, 6689.531732778659, 6737.797866064026, 6747.392734653905, 6763.492738044568, 4617.492075871584, 4701.670883038155, 4772.093302272557, 4799.9760001199775, 4804.039192161652, 4734.455537613218, 4775.283707443911, 4819.542143496362, 4849.127157111754, 4925.8029678812445], "value": 4533.433153524559, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.86817536634624, 320.1803383338652, 326.6488524835801, 328.435091642375, 329.7362110311752, 6439.806805795822, 6614.437772789307, 6615.17310336208, 6703.396694297512, 6727.159105111861, 6225.221847269091, 6534.934650653492, 6550.115734375858, 6587.670616469181, 6636.634985075819, 4967.627427942901, 4968.503149685039, 4970.502949705019, 4976.129177393951, 4991.450940396553, 4730.408698912636, 4858.202465087905, 4975.676581022222, 4979.30289759434, 4985.900281994358, 4881.316615673791, 4887.975560122212, 4895.485973972717, 4913.778879950393, 4918.877028074291, 4866.7566621668775, 4867.7079375237245, 4893.290472881412, 4897.559219670246, 4919.90160196794, 4203.117345357478, 4209.936850947238, 4213.2837417639075, 4213.91572168558, 4224.4508213931995, 6323.083152942843, 6432.614043157426, 6462.870742585169, 6464.224293084813, 6476.773312934072, 5556.333240011184, 5760.942390576082, 5765.336986246593, 5779.219805326311, 5785.47930686236, 7017.157941047107, 7038.6128762918015, 7070.328318809705, 7093.467989900726, 7098.36114749675, 4739.289106633998, 4744.3595114659765, 4744.4069491313585, 4747.833825816115, 4753.928691069602, 6391.648459334781, 7200.891986620152, 7372.041634587523, 7418.146913105008, 7546.396288296923, 4003.8388867228177, 4429.734215946989, 4585.312203169551, 4611.2852507861335, 4626.282926146453, 4055.0876052887866, 4554.863354099382, 4711.387519622427, 4717.599004084691, 4746.240601503743, 5562.638428502134, 5584.413636568183, 5589.916151257735, 5590.524805391506, 5594.580406469531, 7093.32613401726, 7094.0, 7094.893576596355, 7099.970504276851, 7116.50184487081, 1631.2496251724103, 1686.7048266553304, 1691.323470611765, 1701.6086300151035, 1705.744138379253, 1083.4420273559124, 1091.4815462655176, 1112.844201811747, 1113.276370359273, 1118.6028959719345, 6556.311587283362, 6572.6056436613, 6579.0, 6580.374864387875, 6589.802305930734, 7349.889751653729, 7361.705531778669, 7362.7423040193335, 7363.263673632594, 7372.520786148883, 5663.801766938137, 6587.670616469253, 6589.472842172615, 6603.471722262208, 6613.966930165415, 3880.243352546283, 3893.04620368006, 3899.2981263372867, 3902.180542086194, 3906.3554513505524, 7483.176850546435, 7759.262870027338, 7775.57234352116, 7789.610519474004, 7804.0, 7282.745103921443, 7616.543007419573, 7650.349720273728, 7663.0, 7675.385969122457, 7513.060867391576, 7609.771706848804, 7663.923360766321, 7670.002899623125, 7682.347000505019, 7361.889571656322, 7381.8154546136775, 7396.038514993232, 7405.407567394704, 7411.0, 7304.671289792079, 7307.488475806643, 7311.926880731125, 7316.158641756266, 7318.890216646861, 7477.551346919202, 7716.112647045662, 7725.768226953201, 7736.535807851547, 7739.187385324685, 4572.839950601713, 4605.470370907388, 4628.745419001987, 4632.791524381343, 4632.884177895445, 7365.52124111931, 7375.0, 7377.594232317274, 7379.483436159418, 7381.0, 7112.786616401516, 7125.251848556034, 7126.109236345457, 7130.679119439534, 7142.964285178541, 6818.352256535622, 6955.269696681978, 7050.25972272904, 7084.114485689289, 7114.430845532448, 6920.653967301715, 6941.75703850383, 6962.930370696228, 6970.268121847133, 6987.580745155104, 7130.251323610946, 7133.357997780017, 7142.928570714226, 7148.678309475982, 7150.96424517874, 6964.0, 6968.825779355557, 6969.16370035599, 6976.267491913276, 6984.546004509691], "value": 5201.349664906942, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [691.4917535611324, 748.8577170337638, 757.3865169212938, 768.5273556762589, 780.5823884221938, 14876.404943802236, 14972.0, 15322.540323790305, 15325.003874748161, 15344.0, 14054.578362649112, 14105.224212668325, 14118.788218176709, 14130.788038179435, 14143.08069975451, 727.8762610356225, 737.9225181355961, 832.2509741232878, 856.1566856646199, 857.2499063319596, 737.0197637142587, 738.4719925253443, 739.205354244187, 892.660788900217, 920.6225447566499, 968.1577027985627, 969.3795970578835, 971.5239532629032, 972.0, 972.9951350243239, 965.3532133470557, 965.5558443116175, 965.6909788867565, 965.9034096590354, 965.9372140810896, 563.6280055163613, 570.0878594249209, 571.1917636544285, 571.6198727845978, 714.4248879651878, 14855.182964936925, 15110.0, 15121.773173402407, 15127.773083403757, 15144.01563898343, 10872.130229581693, 12022.51909923602, 12475.251484910934, 12816.743665126738, 13015.219086854819, 10028.0, 10029.498525073717, 10055.648052318207, 10123.746906327327, 10261.333013354108, 3448.051785758911, 3453.740969427278, 3491.6857482826413, 3571.785692858437, 3577.0, 78929.21070789277, 79582.20417795806, 81128.0, 81431.74273029072, 81814.59092704557, 9464.337496375325, 9514.857277140849, 9548.809023819624, 9713.271504637181, 10074.748131296776, 21544.0, 21695.0, 21914.780852191594, 21920.232791852526, 21960.23139190121, 7097.396721278749, 7382.889256661155, 7404.96297518509, 7697.923020769721, 7735.574543400055, 4019.7789121598594, 4079.1841631673783, 4120.464339635828, 4183.91632167355, 4195.91608167835, 3524.136586536315, 3583.4266517357096, 3590.2998915211806, 3597.5323207982797, 3601.4957905893198, 524.4257537995888, 650.0801366066962, 652.807421810567, 655.6002933736507, 672.4452326830384, 486.868545492715, 514.390447319927, 560.7588736843196, 588.9175515427844, 597.3709713490563, 6270.811875643738, 7718.189590093071, 7915.248051435219, 8181.918180818116, 8318.958405208055, 571.0691572736413, 576.637273357771, 595.5711887441036, 604.4378727783204, 608.2791891608458, 3745.887623371303, 3794.1273507093524, 3856.421536769507, 3867.4143401648294, 4043.393490976377, 83571.0, 85132.57433712792, 85517.57241213754, 85574.0, 85696.0, 70667.64666176739, 73780.89328660074, 75041.62479187569, 75234.49531009459, 76090.61954690191, 65847.67076164589, 66571.0, 67657.3234267651, 68122.0, 68250.65874670594, 7547.207543207884, 7744.0319960005, 7840.921590784019, 7967.681292748225, 7977.0, 46230.0, 47068.76465617787, 47758.522414775405, 47890.760546197045, 47957.52042479531, 60796.13630909428, 63701.681491592244, 64679.0, 66038.66980665067, 66799.99600012007, 9823.754406139904, 9891.0, 10007.849882251918, 10056.094951454405, 10112.191024718151, 10572.682819515427, 10818.621348252773, 10818.891811082103, 11184.440777960906, 11197.60808371703, 10202.693919182437, 10249.48752562354, 10341.534630941776, 10500.26498145153, 10626.52180651888, 8467.886616433381, 10387.480625968823, 10861.67414977552, 11068.501917413574, 11723.296602203895, 10953.452327383759, 10957.452127393757, 10968.835467468154, 11277.492512837107, 11665.941670291595, 9073.954630226806, 9109.817803643757, 9210.0, 9438.48088355147, 10015.549300281651, 9401.247900168108, 9496.762580935256, 9631.325807193709, 9648.228141748503, 9661.758456038655], "value": 6692.230932807939, "unit": "score"}], "name": "dromaeo_css", "value": 7425.871158292211, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 115.0, 119.0, 113.0, 111.0, 113.0, 114.0, 114.0, 114.0, 137.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 152.0, 154.0, 119.0, 141.0, 120.0, 151.0, 158.0, 118.0], "value": 139.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 185.0, 182.0, 186.0, 183.0, 182.0, 184.0, 183.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 73.0, 159.0, 82.0, 80.0, 84.0, 54.0, 126.0, 81.0], "value": 88.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 93.0, 140.0, 77.0, 85.0, 88.0, 125.0, 111.0, 114.0, 152.0], "value": 108.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 86.0, 90.0, 88.0, 91.0, 88.0, 90.0, 88.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 80.0, 84.0, 87.0, 84.0, 83.0, 85.0, 83.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 64.0, 65.0, 65.0, 60.0, 79.0, 89.0, 90.0], "value": 70.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 71.0, 74.0, 77.0, 74.0, 69.0, 68.0, 70.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 80.0, 80.0, 81.0, 81.0, 80.0, 80.0, 80.0, 80.0, 86.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 124.0, 118.0, 149.0, 144.0, 132.0, 128.0, 134.0, 174.0, 163.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 166.0, 169.0, 167.0, 158.0, 166.0, 170.0, 162.0, 160.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 72.0, 60.0, 55.0, 82.0, 97.0, 97.0, 95.0, 85.0], "value": 77.9, "unit": "score"}], "name": "kraken", "value": 1509.4, "alertThreshold": 2.0}]} 00:32:17 INFO - Return code: 0 00:32:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 00:32:17 ERROR - # TBPL SUCCESS # 00:32:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 00:32:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 00:32:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 00:32:17 INFO - 2016-05-12 00:32:17.675 screenresolution[1676:9594] starting screenresolution argv=screenresolution get 00:32:17 INFO - 2016-05-12 00:32:17.678 screenresolution[1676:9594] Display 0: 1600x1200x32@60 00:32:17 INFO - 2016-05-12 00:32:17.684 screenresolution[1677:9597] starting screenresolution argv=screenresolution list 00:32:17 INFO - Available Modes on Display 0 00:32:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 00:32:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 00:32:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 00:32:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 00:32:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 00:32:17 INFO - Intel Iris: 00:32:17 INFO - Chipset Model: Intel Iris 00:32:17 INFO - Type: GPU 00:32:17 INFO - Bus: Built-In 00:32:17 INFO - VRAM (Dynamic, Max): 1536 MB 00:32:17 INFO - Vendor: Intel (0x8086) 00:32:17 INFO - Device ID: 0x0a2e 00:32:17 INFO - Revision ID: 0x0009 00:32:17 INFO - Displays: 00:32:17 INFO - Display: 00:32:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 00:32:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 00:32:17 INFO - Display Serial Number: 1600x1200 60 00:32:17 INFO - Main Display: Yes 00:32:17 INFO - Mirror: Off 00:32:17 INFO - Online: Yes 00:32:17 INFO - Rotation: Supported 00:32:17 INFO - Return code: 0 00:32:17 INFO - Running post-action listener: _resource_record_post_action 00:32:17 INFO - Running post-run listener: _resource_record_post_run 00:32:18 INFO - Total resource usage - Wall time: 1209s; CPU: 52.0%; Read bytes: 38000128; Write bytes: 652899840; Read time: 426; Write time: 2299 00:32:18 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 340531712; Write bytes: 335771136; Read time: 27223; Write time: 609 00:32:18 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 37041664; Write bytes: 296742912; Read time: 412; Write time: 1635 00:32:18 INFO - Running post-run listener: _upload_blobber_files 00:32:18 INFO - Blob upload gear active. 00:32:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 00:32:18 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 00:32:18 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'] 00:32:18 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 00:32:19 INFO - (blobuploader) - INFO - Open directory for files ... 00:32:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 00:32:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:32:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:32:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 00:32:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:32:20 INFO - (blobuploader) - INFO - Done attempting. 00:32:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 00:32:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 00:32:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 00:32:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 00:32:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 00:32:21 INFO - (blobuploader) - INFO - Done attempting. 00:32:21 INFO - (blobuploader) - INFO - Iteration through files over. 00:32:21 INFO - Return code: 0 00:32:21 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 00:32:21 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 00:32:21 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3fa75e6a243e87215448ad3cd868a54d810037f5d4a8025f6c97c5f9b77dd9899c5569bf86e07bb66174ba04782c21056274a66f60cd32a4977edbd0e21f1bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4dd8e5ef2aa86cf5a8010ac05f54c8d945de585a3fbdc5a2da6484e578c4261e0c042a208c9cf0d44b8fcae8b3921cc0430a2644c36b47747cab440fe6ea1e6"} 00:32:21 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 00:32:21 INFO - Writing to file /builds/slave/test/properties/blobber_files 00:32:21 INFO - Contents: 00:32:21 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3fa75e6a243e87215448ad3cd868a54d810037f5d4a8025f6c97c5f9b77dd9899c5569bf86e07bb66174ba04782c21056274a66f60cd32a4977edbd0e21f1bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4dd8e5ef2aa86cf5a8010ac05f54c8d945de585a3fbdc5a2da6484e578c4261e0c042a208c9cf0d44b8fcae8b3921cc0430a2644c36b47747cab440fe6ea1e6"} 00:32:21 INFO - Running post-run listener: copy_logs_to_upload_dir 00:32:21 INFO - Copying logs to upload dir... 00:32:21 INFO - mkdir: /builds/slave/test/build/upload/logs 00:32:21 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.316601 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-05-12 00:32:21.554669) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 00:32:21.558575) ========= 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.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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/b3fa75e6a243e87215448ad3cd868a54d810037f5d4a8025f6c97c5f9b77dd9899c5569bf86e07bb66174ba04782c21056274a66f60cd32a4977edbd0e21f1bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4dd8e5ef2aa86cf5a8010ac05f54c8d945de585a3fbdc5a2da6484e578c4261e0c042a208c9cf0d44b8fcae8b3921cc0430a2644c36b47747cab440fe6ea1e6"} build_url:https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/f3xPvs-pSdWMnSsnBYNVZQ/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/b3fa75e6a243e87215448ad3cd868a54d810037f5d4a8025f6c97c5f9b77dd9899c5569bf86e07bb66174ba04782c21056274a66f60cd32a4977edbd0e21f1bc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4dd8e5ef2aa86cf5a8010ac05f54c8d945de585a3fbdc5a2da6484e578c4261e0c042a208c9cf0d44b8fcae8b3921cc0430a2644c36b47747cab440fe6ea1e6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 00:32:21.585372) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:32:21.585681) ========= 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.diy56J9qZa/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.zAYa25bj0t/Listeners TMPDIR=/var/folders/hm/lhr5g3j56fn9glwbkp7zqg7c00000w/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.005078 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 00:32:21.633864) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 00:32:21.634176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 00:32:21.634494) ========= ========= Total master_lag: 0.15 =========