builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0187 starttime: 1464909510.28 results: success (0) buildid: 20160602141735 builduid: 70706e1908e54dba95a3575b5e94187c revision: 277ad8a2a286a3666ad512aeb40adb05155ee745 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.276839) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.277280) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.277582) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.342747) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.343084) ========= 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.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-02 16:18:30-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 6.50M=0.002s 2016-06-02 16:18:30 (6.50 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.471898 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.835016) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.835327) ========= 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.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.020448 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:30.883225) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 16:18:30.883606) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 277ad8a2a286a3666ad512aeb40adb05155ee745 --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 277ad8a2a286a3666ad512aeb40adb05155ee745 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-02 16:18:30,959 truncating revision to first 12 chars 2016-06-02 16:18:30,959 Setting DEBUG logging. 2016-06-02 16:18:30,959 attempt 1/10 2016-06-02 16:18:30,959 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/277ad8a2a286?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 16:18:32,005 unpacking tar archive at: mozilla-aurora-277ad8a2a286/testing/mozharness/ program finished with exit code 0 elapsedTime=1.314490 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 16:18:32.218483) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:32.218797) ========= script_repo_revision: 277ad8a2a286a3666ad512aeb40adb05155ee745 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:32.219188) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:32.219482) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 16:18:32.236673) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-02 16:18:32.237065) ========= /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.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 16:18:32 INFO - MultiFileLogger online at 20160602 16:18:32 in /builds/slave/test 16:18:32 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 16:18:32 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:18:32 INFO - {'append_to_log': False, 16:18:32 INFO - 'base_work_dir': '/builds/slave/test', 16:18:32 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:18:32 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:18:32 INFO - 'branch': 'Mozilla-Aurora', 16:18:32 INFO - 'buildbot_json_path': 'buildprops.json', 16:18:32 INFO - 'config_files': ('talos/mac_config.py',), 16:18:32 INFO - 'default_actions': ('clobber', 16:18:32 INFO - 'read-buildbot-config', 16:18:32 INFO - 'download-and-extract', 16:18:32 INFO - 'populate-webroot', 16:18:32 INFO - 'create-virtualenv', 16:18:32 INFO - 'install', 16:18:32 INFO - 'run-tests'), 16:18:32 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:18:32 INFO - 'download_minidump_stackwalk': True, 16:18:32 INFO - 'download_symbols': 'ondemand', 16:18:32 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:18:32 INFO - 'tooltool.py': '/tools/tooltool.py', 16:18:32 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:18:32 INFO - '/tools/misc-python/virtualenv.py')}, 16:18:32 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:18:32 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:18:32 INFO - 'installer_path': 'installer.exe', 16:18:32 INFO - 'log_level': 'info', 16:18:32 INFO - 'log_name': 'talos', 16:18:32 INFO - 'log_to_console': True, 16:18:32 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:18:32 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:18:32 INFO - 'opt_config_files': (), 16:18:32 INFO - 'pip_index': False, 16:18:32 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:32 INFO - 'cmd': ('bash', 16:18:32 INFO - '-c', 16:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:32 INFO - 'enabled': True, 16:18:32 INFO - 'halt_on_failure': False, 16:18:32 INFO - 'name': 'check_screen_resolution'},), 16:18:32 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:18:32 INFO - 'cmd': ('bash', 16:18:32 INFO - '-c', 16:18:32 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:18:32 INFO - 'enabled': True, 16:18:32 INFO - 'halt_on_failure': False, 16:18:32 INFO - 'name': 'check_screen_resolution'},), 16:18:32 INFO - 'run_cmd_checks_enabled': True, 16:18:32 INFO - 'sps_profile': False, 16:18:32 INFO - 'sps_profile_interval': 0, 16:18:32 INFO - 'suite': 'dromaeojs', 16:18:32 INFO - 'system_bits': '32', 16:18:32 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:18:32 INFO - 'title': 't-yosemite-r7-0187', 16:18:32 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:18:32 INFO - 'use_talos_json': True, 16:18:32 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:18:32 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:18:32 INFO - 'work_dir': 'build'} 16:18:32 INFO - ##### 16:18:32 INFO - ##### Running clobber step. 16:18:32 INFO - ##### 16:18:32 INFO - Running pre-action listener: _resource_record_pre_action 16:18:32 INFO - Running main action method: clobber 16:18:32 INFO - rmtree: /builds/slave/test/build 16:18:32 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:18:33 INFO - Running post-action listener: _resource_record_post_action 16:18:33 INFO - ##### 16:18:33 INFO - ##### Running read-buildbot-config step. 16:18:33 INFO - ##### 16:18:33 INFO - Running pre-action listener: _resource_record_pre_action 16:18:33 INFO - Running main action method: read_buildbot_config 16:18:33 INFO - Using buildbot properties: 16:18:33 INFO - { 16:18:33 INFO - "project": "", 16:18:33 INFO - "product": "firefox", 16:18:33 INFO - "script_repo_revision": "production", 16:18:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:18:33 INFO - "repository": "", 16:18:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:18:33 INFO - "buildid": "20160602141735", 16:18:33 INFO - "pgo_build": "False", 16:18:33 INFO - "basedir": "/builds/slave/test", 16:18:33 INFO - "buildnumber": 13, 16:18:33 INFO - "slavename": "t-yosemite-r7-0187", 16:18:33 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:18:33 INFO - "platform": "yosemite_r7", 16:18:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:18:33 INFO - "branch": "mozilla-aurora", 16:18:33 INFO - "repo_path": "releases/mozilla-aurora", 16:18:33 INFO - "revision": "277ad8a2a286a3666ad512aeb40adb05155ee745", 16:18:33 INFO - "stage_platform": "macosx64", 16:18:33 INFO - "builduid": "70706e1908e54dba95a3575b5e94187c", 16:18:33 INFO - "slavebuilddir": "test" 16:18:33 INFO - } 16:18:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 16:18:33 INFO - Running post-action listener: _resource_record_post_action 16:18:33 INFO - ##### 16:18:33 INFO - ##### Running download-and-extract step. 16:18:33 INFO - ##### 16:18:33 INFO - Running pre-action listener: _resource_record_pre_action 16:18:33 INFO - Running main action method: download_and_extract 16:18:33 INFO - mkdir: /builds/slave/test/build/tests 16:18:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:33 INFO - https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:18:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 16:18:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 16:18:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:42 INFO - Downloaded 1199 bytes. 16:18:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 16:18:42 INFO - Using the following test package requirements: 16:18:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 16:18:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 16:18:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 16:18:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'jsshell-mac.zip'], 16:18:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 16:18:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 16:18:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 16:18:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 16:18:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 16:18:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 16:18:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 16:18:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:18: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')]} 16:18:42 INFO - https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 16:18:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 16:18:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:44 INFO - Downloaded 18463811 bytes. 16:18:44 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'] 16:18:44 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 16:18:47 INFO - Return code: 0 16:18:47 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 16:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:47 INFO - https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:18:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 16:18:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 16:18:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:52 INFO - Downloaded 18463811 bytes. 16:18:52 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'] 16:18:52 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 16:18:56 INFO - Return code: 0 16:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:56 INFO - https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:18:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 16:18:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 16:18:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:18:57 INFO - Downloaded 10840145 bytes. 16:18:57 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'] 16:18:57 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 16:18:57 INFO - Return code: 0 16:18:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:18:57 INFO - https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 16:18:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 16:18:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 16:18:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 16:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/b5C0kRReR7a6hl4kkZL_IA/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 16:19:05 INFO - Downloaded 114171200 bytes. 16:19:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 16:19:05 INFO - mkdir: /builds/slave/test/properties 16:19:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:19:05 INFO - Writing to file /builds/slave/test/properties/build_url 16:19:05 INFO - Contents: 16:19:05 INFO - build_url:https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 16:19:06 INFO - Running post-action listener: _resource_record_post_action 16:19:06 INFO - Running post-action listener: set_extra_try_arguments 16:19:06 INFO - ##### 16:19:06 INFO - ##### Running populate-webroot step. 16:19:06 INFO - ##### 16:19:06 INFO - Running pre-action listener: _resource_record_pre_action 16:19:06 INFO - Running main action method: populate_webroot 16:19:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:19:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:19:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:19:06 INFO - '--tppagecycles', 16:19:06 INFO - '7'], 16:19:06 INFO - 'tests': ['tsvgm']}}, 16:19:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:19:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:19:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:19:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:19:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:19:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['damp', 'tps']}, 16:19:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'tests': ['damp', 'tps']}, 16:19:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['dromaeo_dom']}, 16:19:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:19:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'other-e10s': {'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['a11yr', 16:19:06 INFO - 'ts_paint', 16:19:06 INFO - 'tpaint', 16:19:06 INFO - 'sessionrestore', 16:19:06 INFO - 'sessionrestore_no_auto_restore', 16:19:06 INFO - 'tabpaint']}, 16:19:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['tsvgx', 16:19:06 INFO - 'tsvgr_opacity', 16:19:06 INFO - 'tart', 16:19:06 INFO - 'tscrollx', 16:19:06 INFO - 'cart']}, 16:19:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:19:06 INFO - 'tsvgr_opacity', 16:19:06 INFO - 'tart', 16:19:06 INFO - 'tscrollx', 16:19:06 INFO - 'cart']}, 16:19:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'talos_options': ['--disable-e10s'], 16:19:06 INFO - 'tests': ['tp5o']}, 16:19:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'tests': ['tp5o']}, 16:19:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'talos_options': ['--disable-e10s', 16:19:06 INFO - '--xperf_path', 16:19:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:06 INFO - 'tests': ['tp5n']}, 16:19:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:19:06 INFO - 'talos_options': ['--xperf_path', 16:19:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:19:06 INFO - 'tests': ['tp5n']}}, 16:19:06 INFO - 'talos.zip': {'path': '', 16:19:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:19:06 INFO - Running post-action listener: _resource_record_post_action 16:19:06 INFO - ##### 16:19:06 INFO - ##### Running create-virtualenv step. 16:19:06 INFO - ##### 16:19:06 INFO - Running pre-action listener: _resource_record_pre_action 16:19:06 INFO - Running main action method: create_virtualenv 16:19:06 INFO - Creating virtualenv /builds/slave/test/build/venv 16:19:06 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:19:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:19:06 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:19:06 INFO - Using real prefix '/tools/python27' 16:19:06 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:19:07 INFO - Installing distribute.............................................................................................................................................................................................done. 16:19:10 INFO - Installing pip.................done. 16:19:10 INFO - Return code: 0 16:19:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:10 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:10 INFO - 'HOME': '/Users/cltbld', 16:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:10 INFO - 'LOGNAME': 'cltbld', 16:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:10 INFO - 'MOZ_NO_REMOTE': '1', 16:19:10 INFO - 'NO_EM_RESTART': '1', 16:19:10 INFO - 'PAGER': '/bin/cat', 16:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:10 INFO - 'PWD': '/builds/slave/test', 16:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:10 INFO - 'SHELL': '/bin/bash', 16:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:10 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:10 INFO - 'USER': 'cltbld', 16:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:10 INFO - 'XPC_FLAGS': '0x0', 16:19:10 INFO - 'XPC_SERVICE_NAME': '0', 16:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:19:10 INFO - Cleaning up... 16:19:10 INFO - Return code: 0 16:19:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:10 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:10 INFO - 'HOME': '/Users/cltbld', 16:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:10 INFO - 'LOGNAME': 'cltbld', 16:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:10 INFO - 'MOZ_NO_REMOTE': '1', 16:19:10 INFO - 'NO_EM_RESTART': '1', 16:19:10 INFO - 'PAGER': '/bin/cat', 16:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:10 INFO - 'PWD': '/builds/slave/test', 16:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:10 INFO - 'SHELL': '/bin/bash', 16:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:10 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:10 INFO - 'USER': 'cltbld', 16:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:10 INFO - 'XPC_FLAGS': '0x0', 16:19:10 INFO - 'XPC_SERVICE_NAME': '0', 16:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:10 INFO - Downloading/unpacking psutil>=0.7.1 16:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:19:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:14 INFO - Installing collected packages: psutil 16:19:14 INFO - Running setup.py install for psutil 16:19:14 INFO - building 'psutil._psutil_osx' extension 16:19:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:19:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 16:19:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 16:19:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 16:19:14 INFO - building 'psutil._psutil_posix' extension 16:19:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 16:19:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:19:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:19:14 INFO - ^ 16:19:14 INFO - 1 warning generated. 16:19:14 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 16:19:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:19:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:19:15 INFO - Successfully installed psutil 16:19:15 INFO - Cleaning up... 16:19:15 INFO - Return code: 0 16:19:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 16:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 16:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:15 INFO - 'HOME': '/Users/cltbld', 16:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:15 INFO - 'LOGNAME': 'cltbld', 16:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:15 INFO - 'MOZ_NO_REMOTE': '1', 16:19:15 INFO - 'NO_EM_RESTART': '1', 16:19:15 INFO - 'PAGER': '/bin/cat', 16:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:15 INFO - 'PWD': '/builds/slave/test', 16:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:15 INFO - 'SHELL': '/bin/bash', 16:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:15 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:15 INFO - 'USER': 'cltbld', 16:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:15 INFO - 'XPC_FLAGS': '0x0', 16:19:15 INFO - 'XPC_SERVICE_NAME': '0', 16:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:19:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:19:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:19:17 INFO - Installing collected packages: mozsystemmonitor 16:19:17 INFO - Running setup.py install for mozsystemmonitor 16:19:18 INFO - Successfully installed mozsystemmonitor 16:19:18 INFO - Cleaning up... 16:19:18 INFO - Return code: 0 16:19:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:19: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')]} 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19: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')]} 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:18 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:19:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:18 INFO - 'HOME': '/Users/cltbld', 16:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:18 INFO - 'LOGNAME': 'cltbld', 16:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:18 INFO - 'MOZ_NO_REMOTE': '1', 16:19:18 INFO - 'NO_EM_RESTART': '1', 16:19:18 INFO - 'PAGER': '/bin/cat', 16:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:18 INFO - 'PWD': '/builds/slave/test', 16:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:18 INFO - 'SHELL': '/bin/bash', 16:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:18 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:18 INFO - 'USER': 'cltbld', 16:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:18 INFO - 'XPC_FLAGS': '0x0', 16:19:18 INFO - 'XPC_SERVICE_NAME': '0', 16:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:18 INFO - Downloading/unpacking blobuploader==1.2.4 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:20 INFO - Downloading blobuploader-1.2.4.tar.gz 16:19:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:19:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:19:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:21 INFO - Downloading docopt-0.6.1.tar.gz 16:19:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:19:22 INFO - Installing collected packages: blobuploader, requests, docopt 16:19:22 INFO - Running setup.py install for blobuploader 16:19:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:19:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:19:22 INFO - Running setup.py install for requests 16:19:22 INFO - Running setup.py install for docopt 16:19:22 INFO - Successfully installed blobuploader requests docopt 16:19:22 INFO - Cleaning up... 16:19:22 INFO - Return code: 0 16:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:22 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:19:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:22 INFO - 'HOME': '/Users/cltbld', 16:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:22 INFO - 'LOGNAME': 'cltbld', 16:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:22 INFO - 'MOZ_NO_REMOTE': '1', 16:19:22 INFO - 'NO_EM_RESTART': '1', 16:19:22 INFO - 'PAGER': '/bin/cat', 16:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:22 INFO - 'PWD': '/builds/slave/test', 16:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:22 INFO - 'SHELL': '/bin/bash', 16:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:22 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:22 INFO - 'USER': 'cltbld', 16:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:22 INFO - 'XPC_FLAGS': '0x0', 16:19:22 INFO - 'XPC_SERVICE_NAME': '0', 16:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-hoWZJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-U4KiVm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-ch4JkE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Y9bW_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-4Sxvmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-gYJy2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-6MIXRP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:23 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-fneLiA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-n4k2KG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-OdoEjm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-HN2Rd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-px1yuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-mdNQ3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-RLxi_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-jKkZwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-F7eP3X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:24 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-XBhCXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:19:24 INFO - Running setup.py install for manifestparser 16:19:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozcrash 16:19:25 INFO - Running setup.py install for mozdebug 16:19:25 INFO - Running setup.py install for mozdevice 16:19:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozfile 16:19:25 INFO - Running setup.py install for mozhttpd 16:19:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozinfo 16:19:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:19:25 INFO - Running setup.py install for mozInstall 16:19:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozleak 16:19:26 INFO - Running setup.py install for mozlog 16:19:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for moznetwork 16:19:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozprocess 16:19:26 INFO - Running setup.py install for mozprofile 16:19:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:19:26 INFO - Running setup.py install for mozrunner 16:19:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Running setup.py install for mozscreenshot 16:19:27 INFO - Running setup.py install for moztest 16:19:27 INFO - Running setup.py install for mozversion 16:19:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:19:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:19:27 INFO - Cleaning up... 16:19:27 INFO - Return code: 0 16:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:27 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:27 INFO - 'HOME': '/Users/cltbld', 16:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:27 INFO - 'LOGNAME': 'cltbld', 16:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:27 INFO - 'MOZ_NO_REMOTE': '1', 16:19:27 INFO - 'NO_EM_RESTART': '1', 16:19:27 INFO - 'PAGER': '/bin/cat', 16:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:27 INFO - 'PWD': '/builds/slave/test', 16:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:27 INFO - 'SHELL': '/bin/bash', 16:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:27 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:27 INFO - 'USER': 'cltbld', 16:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:27 INFO - 'XPC_FLAGS': '0x0', 16:19:27 INFO - 'XPC_SERVICE_NAME': '0', 16:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:19:27 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-gk1Ikk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:19:27 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:19:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:19:27 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-UHPBqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-zT_Ulk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-PgXFVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-aqawlt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-7SDq_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Z_ZfBq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-BQLxji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-NBbkkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-NTMxz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-DN4vgv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:19:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:19:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:19:28 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-rDebsA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:19:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-QMEhqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:19:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-0gtfvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-Ryd6mq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:19:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-1yvXQJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:19:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:19:29 INFO - Running setup.py (path:/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/pip-jo52A0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:19:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:31 INFO - Downloading blessings-1.6.tar.gz 16:19:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:19:32 INFO - Installing collected packages: blessings 16:19:32 INFO - Running setup.py install for blessings 16:19:32 INFO - Successfully installed blessings 16:19:32 INFO - Cleaning up... 16:19:32 INFO - Return code: 0 16:19:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:19:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:32 INFO - Reading from file tmpfile_stdout 16:19:32 INFO - Current package versions: 16:19:32 INFO - blessings == 1.6 16:19:32 INFO - blobuploader == 1.2.4 16:19:32 INFO - docopt == 0.6.1 16:19:32 INFO - manifestparser == 1.1 16:19:32 INFO - mozInstall == 1.12 16:19:32 INFO - mozcrash == 0.17 16:19:32 INFO - mozdebug == 0.1 16:19:32 INFO - mozdevice == 0.48 16:19:32 INFO - mozfile == 1.2 16:19:32 INFO - mozhttpd == 0.7 16:19:32 INFO - mozinfo == 0.9 16:19:32 INFO - mozleak == 0.1 16:19:32 INFO - mozlog == 3.1 16:19:32 INFO - moznetwork == 0.27 16:19:32 INFO - mozprocess == 0.22 16:19:32 INFO - mozprofile == 0.28 16:19:32 INFO - mozrunner == 6.11 16:19:32 INFO - mozscreenshot == 0.1 16:19:32 INFO - mozsystemmonitor == 0.0 16:19:32 INFO - moztest == 0.7 16:19:32 INFO - mozversion == 1.4 16:19:32 INFO - psutil == 3.1.1 16:19:32 INFO - requests == 1.2.3 16:19:32 INFO - wsgiref == 0.1.2 16:19:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:32 INFO - 'HOME': '/Users/cltbld', 16:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:32 INFO - 'LOGNAME': 'cltbld', 16:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:32 INFO - 'MOZ_NO_REMOTE': '1', 16:19:32 INFO - 'NO_EM_RESTART': '1', 16:19:32 INFO - 'PAGER': '/bin/cat', 16:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:32 INFO - 'PWD': '/builds/slave/test', 16:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:32 INFO - 'SHELL': '/bin/bash', 16:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:32 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:32 INFO - 'USER': 'cltbld', 16:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:32 INFO - 'XPC_FLAGS': '0x0', 16:19:32 INFO - 'XPC_SERVICE_NAME': '0', 16:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:19:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:19:32 INFO - Cleaning up... 16:19:32 INFO - Return code: 0 16:19:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:32 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:19:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:32 INFO - 'HOME': '/Users/cltbld', 16:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:32 INFO - 'LOGNAME': 'cltbld', 16:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:32 INFO - 'MOZ_NO_REMOTE': '1', 16:19:32 INFO - 'NO_EM_RESTART': '1', 16:19:32 INFO - 'PAGER': '/bin/cat', 16:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:32 INFO - 'PWD': '/builds/slave/test', 16:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:32 INFO - 'SHELL': '/bin/bash', 16:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:32 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:32 INFO - 'USER': 'cltbld', 16:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:32 INFO - 'XPC_FLAGS': '0x0', 16:19:32 INFO - 'XPC_SERVICE_NAME': '0', 16:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:33 INFO - Downloading/unpacking jsonschema 16:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:19:36 INFO - Downloading/unpacking functools32 (from jsonschema) 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:36 INFO - Downloading functools32-3.2.3-2.tar.gz 16:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:19:36 INFO - warning: no files found matching '*.txt' 16:19:36 INFO - no previously-included directories found matching 'build' 16:19:36 INFO - no previously-included directories found matching 'dist' 16:19:36 INFO - no previously-included directories found matching '.git*' 16:19:36 INFO - Installing collected packages: jsonschema, functools32 16:19:36 INFO - Running setup.py install for functools32 16:19:36 INFO - warning: no files found matching '*.txt' 16:19:36 INFO - no previously-included directories found matching 'build' 16:19:36 INFO - no previously-included directories found matching 'dist' 16:19:36 INFO - no previously-included directories found matching '.git*' 16:19:36 INFO - Successfully installed jsonschema functools32 16:19:36 INFO - Cleaning up... 16:19:36 INFO - Return code: 0 16:19:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:19:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:19:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:19:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:19:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:19:37 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 0x109a76a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a86420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f847b47fcd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, '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.jwL77eHgAG/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.pRrVblZ4jM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 16:19:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:19:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:19:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:19:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:19:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:19:37 INFO - 'HOME': '/Users/cltbld', 16:19:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:19:37 INFO - 'LOGNAME': 'cltbld', 16:19:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:19:37 INFO - 'MOZ_NO_REMOTE': '1', 16:19:37 INFO - 'NO_EM_RESTART': '1', 16:19:37 INFO - 'PAGER': '/bin/cat', 16:19:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:19:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:19:37 INFO - 'PWD': '/builds/slave/test', 16:19:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:19:37 INFO - 'SHELL': '/bin/bash', 16:19:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:19:37 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:19:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:19:37 INFO - 'USER': 'cltbld', 16:19:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:19:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:19:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:19:37 INFO - 'XPC_FLAGS': '0x0', 16:19:37 INFO - 'XPC_SERVICE_NAME': '0', 16:19:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:19:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:19:37 INFO - Downloading/unpacking flake8 16:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:19:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:40 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:19:40 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:40 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:19:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:19:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:19:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:19:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:19:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:19:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:19:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:19:41 INFO - no previously-included directories found matching 'docs/_build' 16:19:41 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:19:41 INFO - Running setup.py install for pep8 16:19:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:19:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:19:41 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:19:41 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:19:41 INFO - no previously-included directories found matching 'docs/_build' 16:19:41 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:19:41 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:19:41 INFO - Cleaning up... 16:19:41 INFO - Return code: 0 16:19:41 INFO - Running post-action listener: _resource_record_post_action 16:19:41 INFO - Running post-action listener: _start_resource_monitoring 16:19:41 INFO - Starting resource monitoring. 16:19:41 INFO - ##### 16:19:41 INFO - ##### Running install step. 16:19:41 INFO - ##### 16:19:41 INFO - Running pre-action listener: _resource_record_pre_action 16:19:41 INFO - Running main action method: install 16:19:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:19:41 INFO - Reading from file tmpfile_stdout 16:19:41 INFO - Detecting whether we're running mozinstall >=1.0... 16:19:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:19:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:19:41 INFO - Reading from file tmpfile_stdout 16:19:41 INFO - Output received: 16:19:41 INFO - Usage: mozinstall [options] installer 16:19:41 INFO - Options: 16:19:41 INFO - -h, --help show this help message and exit 16:19:41 INFO - -d DEST, --destination=DEST 16:19:41 INFO - Directory to install application into. [default: 16:19:41 INFO - "/builds/slave/test"] 16:19:41 INFO - --app=APP Application being installed. [default: firefox] 16:19:41 INFO - mkdir: /builds/slave/test/build/application 16:19:41 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'] 16:19:41 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 16:20:13 INFO - Reading from file tmpfile_stdout 16:20:13 INFO - Output received: 16:20:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:20:13 INFO - Running post-action listener: _resource_record_post_action 16:20:13 INFO - ##### 16:20:13 INFO - ##### Running run-tests step. 16:20:13 INFO - ##### 16:20:13 INFO - Running pre-action listener: _resource_record_pre_action 16:20:13 INFO - Running main action method: run_tests 16:20:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:20:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:20:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:20:13 INFO - 2016-06-02 16:20:13.638 screenresolution[2276:30086] starting screenresolution argv=screenresolution get 16:20:13 INFO - 2016-06-02 16:20:13.641 screenresolution[2276:30086] Display 0: 1600x1200x32@60 16:20:13 INFO - 2016-06-02 16:20:13.647 screenresolution[2277:30088] starting screenresolution argv=screenresolution list 16:20:13 INFO - Available Modes on Display 0 16:20:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:20:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:20:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:20:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:20:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:20:13 INFO - Intel Iris: 16:20:13 INFO - Chipset Model: Intel Iris 16:20:13 INFO - Type: GPU 16:20:13 INFO - Bus: Built-In 16:20:13 INFO - VRAM (Dynamic, Max): 1536 MB 16:20:13 INFO - Vendor: Intel (0x8086) 16:20:13 INFO - Device ID: 0x0a2e 16:20:13 INFO - Revision ID: 0x0009 16:20:13 INFO - Displays: 16:20:13 INFO - Display: 16:20:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:20:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:20:13 INFO - Display Serial Number: 1600x1200 60 16:20:13 INFO - Main Display: Yes 16:20:13 INFO - Mirror: Off 16:20:13 INFO - Online: Yes 16:20:13 INFO - Rotation: Supported 16:20:13 INFO - Return code: 0 16:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:20:13 INFO - Python 2.7.3 16:20:13 INFO - Return code: 0 16:20:13 INFO - grabbing minidump binary from tooltool 16:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:20:13 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 0x109915e00>, '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 0x10977edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109910b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:20:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:20:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 16:20:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:20:13 INFO - Return code: 0 16:20:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:20:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:20:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:20:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:20:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:20:15 INFO - Return code: 0 16:20:15 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/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:20:15 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/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0187 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:20:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.jwL77eHgAG/Render', 16:20:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:20:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:20:15 INFO - 'HOME': '/Users/cltbld', 16:20:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:20:15 INFO - 'LOGNAME': 'cltbld', 16:20:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:20:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:20:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:20:15 INFO - 'MOZ_NO_REMOTE': '1', 16:20:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:20:15 INFO - 'NO_EM_RESTART': '1', 16:20:15 INFO - 'PAGER': '/bin/cat', 16:20:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:20:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:20:15 INFO - 'PWD': '/builds/slave/test', 16:20:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:20:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:20:15 INFO - 'SHELL': '/bin/bash', 16:20:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pRrVblZ4jM/Listeners', 16:20:15 INFO - 'TMPDIR': '/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/', 16:20:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:20:15 INFO - 'USER': 'cltbld', 16:20:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:20:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:20:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:20:15 INFO - 'XPC_FLAGS': '0x0', 16:20:15 INFO - 'XPC_SERVICE_NAME': '0', 16:20:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:20:15 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/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0187', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:20:15 INFO - mozversion application_buildid: 20160602141735 16:20:15 INFO - mozversion application_changeset: 277ad8a2a286a3666ad512aeb40adb05155ee745 16:20:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:20:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:20:15 INFO - mozversion application_name: Firefox 16:20:15 INFO - mozversion application_remotingname: firefox-dev 16:20:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:20:15 INFO - mozversion application_vendor: Mozilla 16:20:15 INFO - mozversion application_version: 48.0a2 16:20:15 INFO - mozversion platform_buildid: 20160602141735 16:20:15 INFO - mozversion platform_changeset: 277ad8a2a286a3666ad512aeb40adb05155ee745 16:20:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:20:15 INFO - mozversion platform_version: 48.0a2 16:20:15 INFO - using testdate: 1464909615 16:20:15 INFO - actual date: 1464909615 16:20:15 INFO - starting webserver on 'localhost:50685' 16:20:15 INFO - SUITE-START | Running 2 tests 16:20:15 INFO - TEST-START | dromaeo_css 16:20:15 INFO - operating with platform_type : mac_ 16:20:15 INFO - Initialising browser for dromaeo_css test... 16:20:15 INFO - TEST-INFO | started process 2295 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpHc2Vrn/profile http://localhost:50685/getInfo.html) 16:20:17 INFO - PROCESS | 2295 | __metrics Screen width/height:1600/1200 16:20:17 INFO - PROCESS | 2295 | colorDepth:24 16:20:17 INFO - PROCESS | 2295 | Browser inner width/height: 1024/689 16:20:17 INFO - PROCESS | 2295 | __metrics 16:20:17 INFO - PROCESS | 2295 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:20:17 ERROR - PROCESS | 2295 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:20:18 INFO - TEST-INFO | 2295: exit 0 16:20:18 INFO - Browser initialized. 16:20:18 INFO - Running cycle 1/1 for dromaeo_css test... 16:20:18 INFO - TEST-INFO | started process 2297 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpHc2Vrn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:30 INFO - PROCESS | 2297 | RSS: Main: 211525632 16:20:30 INFO - PROCESS | 2297 | 16:21:21 INFO - PROCESS | 2297 | 1464909681928 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:23:31 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50685/tests/dromaeo/cssquery-ext.html) 16:23:34 INFO - PROCESS | 2297 | RSS: Main: 407576576 16:23:34 INFO - PROCESS | 2297 | 16:26:37 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-ext.html (next: http://localhost:50685/tests/dromaeo/cssquery-jquery.html) 16:26:37 INFO - PROCESS | 2297 | RSS: Main: 353865728 16:26:37 INFO - PROCESS | 2297 | 16:29:40 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50685/tests/dromaeo/cssquery-mootools.html) 16:29:40 INFO - PROCESS | 2297 | RSS: Main: 352821248 16:29:40 INFO - PROCESS | 2297 | 16:32:42 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50685/tests/dromaeo/cssquery-prototype.html) 16:32:43 INFO - PROCESS | 2297 | RSS: Main: 355684352 16:32:43 INFO - PROCESS | 2297 | 16:35:46 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50685/tests/dromaeo/cssquery-yui.html) 16:35:47 INFO - PROCESS | 2297 | RSS: Main: 321814528 16:35:47 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | Cycle 1(1): loaded http://localhost:50685/tests/dromaeo/cssquery-yui.html (next: http://localhost:50685/tests/dromaeo/cssquery-dojo.html) 16:38:50 INFO - PROCESS | 2297 | RSS: Main: 360775680 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | __start_tp_report 16:38:50 INFO - PROCESS | 2297 | _x_x_mozilla_page_load 16:38:50 INFO - PROCESS | 2297 | _x_x_mozilla_page_load_details 16:38:50 INFO - PROCESS | 2297 | |i|pagename|runs| 16:38:50 INFO - PROCESS | 2297 | |0;dojo.html;3353.76523643345;3433.141714571357;3443.535122758428;3456.8962931112046;3494.755367124301;5657.745401456934;5679.119736440854;5690;5694.886102277952;5710;5671.574631902603;5721.313442386909;5724.513416359604;5726.455986681269;5727.255456790622;5003.2995380646735;5085.593152547805;5133.281340612308;5244.554212891909;5274.604404669646;5103.132467480518;5116.948830511685;5122.206058061007;5147.330846989886;5239.161734122541;5155.072087024334;5185.092608793446;5213.817516386927;5221.556167725748;5228.424873263938;5172.612054095958;5191.896162076775;5228.843134705965;5243.947560524384;5277.287566178555;5042.168042273021;5089.185730283174;5102.693838369672;5137.409197942247;5139.717315547643;5482.835514934519;5502.422245664227;5518.696471694056;5535.944640553583;5538.944610553883;6185.257769067695;6194.81415557534;6195.28754193269;6204.596701214452;6217.87564248717;5728.51307638847;6079.148919151322;6188.752449901998;6237.064440333941;6256.749730010794;5282.366116066097;5737.2254745609225;5786.363500014995;5790.536757059425;5804.390538993429;6219.284782250099;6237.875242495124;6247.843803904848;6261.937380626136;6264.279607845065;6145.8156255312415;6161.630302181883;6165.198524191826;6172.6913654318005;6175.320714721377;6080.118382834465;6108.068892179526;6120.873812997669;6235.532335074888;6251.74993000284;6159.353267906895;6191.5665903387235;6198.969005154945;6200.100985357098;6213.937860621426;6152.630842149485;6159.876802463926;6211.223597050369;6215.34738852423;6233.657148856856;4807.567318941242;4813.422389313235;4880.804767809319;4893.828715994994;4947.208446648591;4397.560243975577;4401.779911004437;4423.535528769497;4430.933535996963;4436.711613745097;5627.831165065055;5653.660780353192;5654.491095801315;5656.264685590929;5668.433156684381;6110.877782444326;6169.784057557964;6184.6598437086395;6210.627362358273;6245.437910587978;5653.265075540154;5664.178694089334;5672.602917795714;5678.233438485751;5681.318241811009;5378.91931621026;5382.4886635769535;5385.192221166856;5394.637236543254;5396.001739678172;6183.876322473526;6199.225096862892;6251.437370636664;6264.937350626436;6290.276618188876;6211.316755156839;6229.3770622937345;6232.034034724625;6246.281677606951;6266.592671476339;6278.686065696788;6296.590721603081;6299.307076221704;6302.212223472066;6302.716377763103;6024.668643224665;6082.178905847654;6125.1424800527075;6131.448169664752;6133.877322453437;6141.486007784364;6142.437386316015;6176.505879529716;6181.598196117238;6222.844428889314;5261.89476210466;6054.303755068224;6062.363451837494;6076.787312444043;6080.452759251689;6031.09533569968;6058.121572371893;6079.300880398634;6099.329073801967;6101.816945491642;5715.628484148517;5831.825045248649;5881.38245484218;5894.646321220741;5914.674692891932;6091.794158553512;6099.207103076572;6131.816045518642;6133.693315334304;6139.785107521216;5739.684317362585;5847.181394604844;5857.882842343214;5957.49361304294;5986.521078313812 16:38:50 INFO - PROCESS | 2297 | |1;ext.html;526.897255035268;532.1299675031324;533.3253434405475;543.5787264869726;545.2312239741963;30756.23109422263;38214;39160.99999999996;39226.8038659807;39278.41082383759;42193.789031054876;42408;42778;42781.57218427815;42795.78602106993;8489.151084891524;9447.81104377912;9650;9663.323567350288;9673.419594824332;8614.353923455734;11507.482163302671;11740.94129529351;11759;11759.7648047039;11508.827367589492;11571.826422603668;11575.594854180106;11604.187706860523;11628.476718547643;8674.86987695185;11463.36951467669;11552.422378881065;11586.536538538448;11837.644870653894;11392.71518212043;11513.366764827932;11515.884841151565;11517.827232591519;11528.193026488065;38570.421443678366;38881.41677874834;38931.41602875959;38965.636202733054;38987;3206.615206175274;3212.213007813077;3220.2915358621067;3308.2556424804225;3357.1103657530775;28144;28411.431771364663;28427;28505.144845654664;28917;2690.7174746651713;2696.8247063940976;2730.631364765751;2730.645016147888;2814.549672052482;420360;424539.8773006146;424591;424899;424970;35480.46779298312;36141.63858361435;62867.685661572315;63290.73418531603;63315.36684633187;35248.766293179615;35451.64548354535;35585.82207088948;35645.82177089098;35674.92975210748;15588.376464941502;15746.68506629861;15747.133907634961;15809.051456912623;15813.446529371648;12355.25868447896;12411.441485133191;12477.625671229878;12493.312867792358;13148.276844773629;8120.051187714906;8268.421210515206;8277.089520152778;8295.460795048302;8380.622871970776;2910.6652734935756;3023.56158357037;3287.1289108386295;3350.4806754952533;3452.8100954447746;1019.6889948565758;1040.843873418978;1076.56399158341;1138.8120960041501;1151.125144889873;29749.570157733597;30097;30150;30247.243818904262;30402.84798575993;782.9295363417319;927.7726956895604;1081.8377243413393;1151.5508951508918;1181.0020532649896;14120.788188177186;14137.929310353382;14396;14597.854021459649;14715.55853324402;635365;643421;649733;651732;652802;58168.12747808786;58687.70656146777;58698.11952820711;58721.412785872446;58728.7063564688;1362.2984163155982;1470.6617477980124;1498.7901693762883;1501.3394106593064;1505.3226048278539;28669.713302866705;28781.7121828779;29312;29378.55932161062;29486.967956121433;49305;58288.87966763429;59714.104288436574;59844;59969.40030599638;365783;471846.281537194;478097;478276;480040;35208.64791352054;41581.652984776854;42255.94360141021;42512.57487425086;42891.57108428876;41768.3002992794;43208;43217.3517397233;43298.35052474152;44193.77903110464;28779.8561007202;32707.018789436355;35012.47481287834;36154.276914461036;36834.89495315145;33364.49953250655;35441.82279088588;36089.63910360863;36796.448053278684;37928.86213413602;37544.43683344698;37602;39010.804945976226;39027.80486097665;39038.6096139035;31894.202644934063;33058.5041224377;33273.833630831694;33296.833515832266;33328;24736.876315618305;24816.751832482165;24864.50270994606;25289.494210116063;25335.61996570016 16:38:50 INFO - PROCESS | 2297 | |2;jquery.html;336.82653433481755;337.18065101802625;347.8243487039045;403.52787238930455;409.93236116040856;20125.496862578424;20855.0615222315;21606.811625360617;21768.346949591538;22164.891755412245;20771.896140519315;21472.24847130351;21716.3485095447;21761.673574896387;21773.34679959604;5830.621009634381;6019.909701354483;6034.155218269446;6055.091736239555;6071.939280607182;3940.468036815036;4135.627793498585;4412.580804823537;5720.170575266584;6009.399060093978;5988.431099045605;6001.5198784097165;6002.459778619924;6009.669468179249;6024.006039003559;5721.427857214287;5963.731632076524;5968.731407086691;5969.88060238795;5987.6108052976415;5756.942430575683;5779.711014449303;5785;5787.536997040269;5789.768409263625;14000.509982150677;17768.667349948806;24005.159819406235;25435.728213589246;25516.617250741252;2139.497218153733;2169.40341406113;2266.897989590472;2272.749997500274;2640.392709676766;9681;9701.563429645683;10058.9497052515;10139.54372053252;10148.391096534158;1382.3917476310392;1622.732249178884;1692.7207010843197;1701.2259421962976;1814.2198854492694;109960;117722.41138794337;118323;119659.40170299093;119684.80315196911;9249.861252081224;11706.88784565482;11711.941440292743;11736.471858766376;11860.703482413008;14975;15063.322150503254;15071.397144114113;15082.54752357431;15101.546953591409;8490.787730306669;8775.736727898173;8779;8835.69075082369;8896.377253592187;8887.379920512887;9101.499417531966;9130.676051972427;9183.816323673489;9206.217471514996;4599.333096701027;4639.443266807937;4647.326137710081;4690.366800481891;4720.315554244616;1640.6554623529194;1816.418746001267;1918.194358369488;2006.6989951507098;3079.784415090967;949.5916755795074;953.8569214617725;977.5307852230969;993.3245393132739;1020.9132223761063;18548.165332560067;20654.07056682453;20745;20940.952952352625;21017.579648406947;986.7187851462298;1005.8139244239838;1031.3914790273625;1054.4938429553856;1078.8813230544633;10685.946570267099;10915.56337746497;11060.502277397536;11112.833307500394;11175.944120279346;580477;593413;598113;603537;619557;119608;120851.39574302072;123565;126471.41171529738;131327;1008.3848852200152;1021.9080282774586;1044.2637940252105;1045.7124290820086;1048.4744696466776;17757.911210443865;18095.185716642518;18222.72665909986;18612.976286304383;18945;52737;54757.178642321196;55226.171607426724;56235.312940611846;57486.7125664369;75771.62114189615;80409.19590804017;81185.59407203163;82143.17856821355;84914.57542712247;6777;8091.231333023354;8200.712975045844;8261.545614991233;8262;6738.326167383419;8571.699990500303;9110.772230694285;12492.687682808002;12632.936835315764;10631;10649.627263045757;10902.672919812418;10929.78140437171;11121.833172502256;10415.53130109162;10450;10497.635307409988;10810.891891080988;10965.342079475256;8581.399302048796;10354.326968747007;10751.247412681038;10801;11205.327680339205;7977.48146370484;8160.183981601793;8250.399526045096;8377.748667539983;8481.15188481147;10111.949440252753;11065.336079835235;11317.603883864025;11386.772264554827;11451.828222576834 16:38:50 INFO - PROCESS | 2297 | |3;mootools.html;1453.9894773132678;1575.251755416177;1608.9275982580787;1863.7111247756602;1989.6219718253521;8096.878546821795;8680.001799793017;8846.557672116385;8860.20258176764;8878.112188781119;8638.784030399243;8712.433691810043;8785.121487851211;8789.340799440051;8810.559472026389;2167.3822960456273;2224.877631730254;2456.91400800972;2475.8390704604235;2477.3930387055198;2246.921357752479;2251.909923603054;2256.5148493073984;2429.854208747472;2491.9626205606933;2026.2199053364486;2433.051110067075;2433.5497932882386;2438.085717855804;2441.6215486599517;2348.095983046522;2393.533261014094;2418.5404773093055;2426.599611064172;2430.453148041694;2140.892955352226;2364.869932153731;2409.5180963807134;2409.638554216864;2418.709754829432;8648.308135349156;8720.258778003878;8721.476711397336;8740;8741.562921853945;4577.473590537097;5297.364316282071;5453.590980676465;5501.752421141058;5509.917351239735;3367.4948757686298;3434.3989801784587;3489.5812502499607;3491.6333784952467;3498.7725797823314;985.6451677396132;1009.7071849163734;1018.9643362482352;1023.5854478936043;1028.8199565076081;12395.690107747288;12406.62780116598;12406.937965310206;12411.4414851331;12424.37878106091;4782;4851.757412129379;4949.257611358253;4994.2508623706735;5009.474005229471;10153.933836947013;10299.485025748683;10300.330478519023;10313.12338451225;10321.277510574339;5520.558355331564;5542;5548.0845660466375;5581.972090139523;5601.663900166003;5011.649184557119;5023.497650234947;5063.772130254148;5067.163917953497;5074.568661663726;3744.7378683491893;3845.7884816335372;4017.879463616096;4109.486314210723;4114.362273847559;1318.0905175429116;1340.7464021140265;1342.2617560341773;1359.2048651538762;1365.6616515814558;910.4117183277325;911.5670056723003;925.2551695884878;927.9536023198813;964.4261664309724;5892.793752218738;5966.731497082642;6010.188624535632;6030.427109424598;6204.937950620436;894.2309613732185;909.4997751236793;940.722486866376;941.7615835176721;962.5572236771023;3149.6062992125985;3315.3203593263324;3878.3212937735784;3968.9603103969166;4024.195160967818;10561.841572376421;10717.624883129052;10898.400587967737;10998.890011099787;11029.889701102886;10853.294535855144;10867.945660271647;10879.564817407216;10891.18316126278;10902.291351062155;8200.015998080149;8386.622601982803;8405.831883362298;8471.279941204943;8512.531810750344;5615.578831587565;5654.971725141348;5732.16883551891;5732.942670573241;5956.463918247378;9518.666846660333;9747.707568772948;9809;9889.554970026222;9892.75268118274;9874.456904870302;10040.046195611405;10052.547635356574;10185.930477299777;10205.795884082128;7369.410447164107;7428.2571742825285;7608.619569021637;7629.580373079534;7631;7250.564966102051;7407.888881666887;8152.714654987047;8324.6670133194;8326.542040187847;7330.157031941182;7446.590437525988;7462.738804141829;7537.510061846182;7618.657160427905;7270.200277969525;7277.417806575567;7301.926980730337;7426.74006409773;7464.141623713343;6793.592384456949;6972.51192416526;7036.788896333118;7051.471139664443;7055.4708396871465;5184.429712731672;5206.271122042841;5239.7118158500125;5255.973720131527;5316.946830531645;5154.201098829687;5184.533391994739;5195.636305458582;5262.763175657028;5484.698341591251 16:38:50 INFO - PROCESS | 2297 | |4;prototype.html;313.9890103846366;320.038284953714;330.9106541233866;332.29884942770764;334.2162628635848;6398.552101352907;6556.836079098025;6597.175353080865;6610.07458955746;6670.699818508167;6462.773802916899;6573.80278591643;6601.603903765777;6631.237407698116;6643.900341494881;4902.093112774148;4907.2148456247005;4929.408470983487;4935.629827762914;4947.777350019258;4187.518435379925;4653.069386122772;4702.6473014524045;4789.497102804207;4930;4681.765911704419;4722.716637001791;4733.881652958669;4745.454272758625;4818.638602104838;4784.545468180518;4788.856334309976;4805.831795887128;4806.975965120169;4814.566688998007;3509.5262139611336;4117.382392641098;4132.359484279941;4150.418941348214;4152.647025002879;5087.364079490064;6354.936450635527;6366.108744775735;6369.458596019297;6374.458171055466;5202.323697919246;5322.228276899867;5795.594308398414;5805.390434004411;5832.416758324176;4928.753562321869;5604.439556044404;5688.943110568883;6822.703686299587;7009.334113259283;4249.957500424987;4555.157295900236;4563.406757121553;4563.794629241692;4566.429196350457;6592.143021407186;7231.602261875595;7527.548347099137;7539.283768042027;7543.924560754377;3757.267332870119;4637.3275874997935;4637.814487420465;4649.442066951988;4656.208444564432;4705.505921878222;4711.646626502958;4712.293156026623;4715.7642117893965;4716.151092803259;5395.595330350239;5572.554195664337;5575.637583557056;5580.302462192226;5651.265335506358;6915.308469153145;6966.303369663095;6982;6986.266442023616;7084.716611335592;1368.5620601407652;1615.4426722780622;1633.5834362237554;1639.0739232333694;1640.196303811124;981.0532835813474;988.0761488008735;998.930074894765;1001.4542074569378;1033.571068006784;6347.143135676717;6386.329435409308;6387.5209359297305;6615.404613584704;6620.702068406933;6349.761796449648;6993.755218567326;7395.926040739524;7397.926020739831;7416.517926334771;6593.505487088391;6596.769113081019;6625.039369291426;6650;6748.797536073926;3416.4192087344713;3617.2584620152807;3759.2105657811617;3899;3901.5318161820196;7728.26581474759;7739.922600774033;7740.729074482366;7741.535507869433;7762.5730584818375;7497.887531687029;7580.05249343832;7584.734534291273;7596.734114305973;7602.353799927068;7681.078270607562;7704.422168337397;7720.3437707795465;7721.884171737428;7731.729389471341;7078.787636370917;7312.963435183003;7320.487565870338;7347.302006309392;7376.4836461447185;7202.243764404662;7240.855182896207;7277.126744790659;7287.489875708861;7289.416846652149;6865.244823069358;6948.409385202314;7160.713571457291;7184.568925864465;7808.726694565663;4817.470078291315;4882.829100981448;4887.99388376139;4888.6577939543895;4896.6572339935865;7029.789106326818;7241.855162896818;7288.599127047851;7307.634618269171;7313.195548489768;5670.6597604142235;6444.226692796894;6999.160100787938;7049.400800931979;7054.435645148274;5634.74643641029;7009.7897063088185;7054.01243825875;7055.823604409931;7056.047433596399;6881.483888708267;6882.346177113166;6896.310368963063;6896.827579310558;6904.965475172592;6772.25505194438;7005.404540614105;7013.088298521159;7025.2623474536485;7069.505134640526;6638.444198983329;6735.4611631070375;6781.321867813179;6920.653967301514;6948.166220053626 16:38:50 INFO - PROCESS | 2297 | |5;yui.html;657.3426573426576;679.3614002837332;691.712939130261;716.7348081209954;753.4612751882403;14456.204908730042;14754.336054877529;14910.552683419515;14931.776023359658;14939.5518134456;14223.928880355585;14263.144211347326;14278.357473913673;14299;14364;730.3062091013531;739.5230076600616;894.5080205886769;907.0521305236011;926.083177654121;913.9360244782868;920.8250432417819;926.476540754475;927.1007123090591;930.9208717259041;906.9229115525155;962.7544976031103;975.2539307429787;975.5756246032963;976.1848856205078;753.6985205917649;845.3701992015957;948.4641177734627;967.690339091491;971.6647756523988;711.1963481266137;715.588536591462;720.5820624038046;721.6824597177277;722.6495149852317;14663.266836658231;14720.705585888223;14741;14745.631359215999;14764.409423623043;11685.824712629317;11689.298642081503;11868;11899.821502677467;12096.81854772179;9299.76750581241;10794.676159715222;10830.241883068189;10954.945225273903;11318.660440186808;3381.249344670717;3435.914102147441;3453.188500702333;3530.5233793437815;3674.7427680062406;77836.05409864742;78476.60761696156;78680.99999999943;79895;79932;9314.115159059878;9400.905990940004;9446.102620251066;9799.951000245095;10145.695629131138;21318.253861114787;21527.67708484374;21532.89233553822;21535;21539.89230053871;7418.332350088518;7439.962800186073;7546.584937828472;7669.463137580427;7685.577293248925;3979.6418322350546;4078.3678529827926;4097.549269580344;4154.086101057763;4157.417961485395;3575.66031227033;3624.039629498185;3629.25600251953;3635.472856435802;3635.8909232723063;661.0612929639923;664.2693037658537;669.1501792723215;673.7170388436869;679.4632240530025;535.7749745107064;548.1585765849402;575.6057100885906;599.7391134856351;619.123939625425;7082.433405327562;8014.917986071922;8106.675732970615;8225.300849427866;8234.588270586446;504.36854646544293;562.1764115570709;590.4862769390658;605.4853374631616;616.1374076293225;3820.121372084436;3873.69010479161;3922.37242041272;3942.0539070623418;3963.425303331001;89049;92137;92379;92440.53779731058;92760.53619731993;74237.25762742411;74865;74881;75417.11457213614;76643.61678191573;64778.67610661917;65153;65439.01841472382;65457.01814472787;68098.63802723918;7064;8461.111583283666;8469.110743372104;8661.52361620117;8680.435771674822;43294.78352608217;43370.698879033676;43434;44685.55314446944;46188;56243.15635265556;59551.702241488514;59809.504762381286;60410.39589604224;61437.69281153566;10738.892611073788;11065.723356916142;11109.222354435113;11167.88832111701;11224.270422422516;11677.182597218112;11788.646340609796;11841.5855445059;11974.46114924813;12411.379431028594;9351.438913665203;10630.415327157081;10903.83644245321;11481.712957175803;11823.23148995347;11188.608398706006;11254.38100904458;11397.943010284895;11410.915962983503;11566.537338506701;11022.834657479983;11027.669169924595;11164.720881978015;11785.116116291141;11945.761084778082;9231.399959002643;9257.629694812402;9579.808403832023;9619.230461563198;9711.56297966579;8535.274501667427;8808.735737927873;9434.622615095319;9518.190953769008;9537.046295370408 16:38:50 INFO - PROCESS | 2297 | __end_tp_report 16:38:50 INFO - PROCESS | 2297 | __start_cc_report 16:38:50 INFO - PROCESS | 2297 | _x_x_mozilla_cycle_collect,2093 16:38:50 INFO - PROCESS | 2297 | __end_cc_report 16:38:50 INFO - PROCESS | 2297 | __startTimestamp1464910730415__endTimestamp 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | ------- Summary: start ------- 16:38:50 INFO - PROCESS | 2297 | Number of tests: 6 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#0] dojo.html Cycles:165 Average:5701.73 Median:5881.38 stddev:626.03 (10.6%) stddev-sans-first:600.24 16:38:50 INFO - PROCESS | 2297 | Values: 3353.8 3433.1 3443.5 3456.9 3494.8 5657.7 5679.1 5690.0 5694.9 5710.0 5671.6 5721.3 5724.5 5726.5 5727.3 5003.3 5085.6 5133.3 5244.6 5274.6 5103.1 5116.9 5122.2 5147.3 5239.2 5155.1 5185.1 5213.8 5221.6 5228.4 5172.6 5191.9 5228.8 5243.9 5277.3 5042.2 5089.2 5102.7 5137.4 5139.7 5482.8 5502.4 5518.7 5535.9 5538.9 6185.3 6194.8 6195.3 6204.6 6217.9 5728.5 6079.1 6188.8 6237.1 6256.7 5282.4 5737.2 5786.4 5790.5 5804.4 6219.3 6237.9 6247.8 6261.9 6264.3 6145.8 6161.6 6165.2 6172.7 6175.3 6080.1 6108.1 6120.9 6235.5 6251.7 6159.4 6191.6 6199.0 6200.1 6213.9 6152.6 6159.9 6211.2 6215.3 6233.7 4807.6 4813.4 4880.8 4893.8 4947.2 4397.6 4401.8 4423.5 4430.9 4436.7 5627.8 5653.7 5654.5 5656.3 5668.4 6110.9 6169.8 6184.7 6210.6 6245.4 5653.3 5664.2 5672.6 5678.2 5681.3 5378.9 5382.5 5385.2 5394.6 5396.0 6183.9 6199.2 6251.4 6264.9 6290.3 6211.3 6229.4 6232.0 6246.3 6266.6 6278.7 6296.6 6299.3 6302.2 6302.7 6024.7 6082.2 6125.1 6131.4 6133.9 6141.5 6142.4 6176.5 6181.6 6222.8 5261.9 6054.3 6062.4 6076.8 6080.5 6031.1 6058.1 6079.3 6099.3 6101.8 5715.6 5831.8 5881.4 5894.6 5914.7 6091.8 6099.2 6131.8 6133.7 6139.8 5739.7 5847.2 5857.9 5957.5 5986.5 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#1] ext.html Cycles:180 Average:64019.44 Median:35110.56 stddev:138610.95 (394.8%) stddev-sans-first:138917.82 16:38:50 INFO - PROCESS | 2297 | Values: 526.9 532.1 533.3 543.6 545.2 30756.2 38214.0 39161.0 39226.8 39278.4 42193.8 42408.0 42778.0 42781.6 42795.8 8489.2 9447.8 9650.0 9663.3 9673.4 8614.4 11507.5 11740.9 11759.0 11759.8 11508.8 11571.8 11575.6 11604.2 11628.5 8674.9 11463.4 11552.4 11586.5 11837.6 11392.7 11513.4 11515.9 11517.8 11528.2 38570.4 38881.4 38931.4 38965.6 38987.0 3206.6 3212.2 3220.3 3308.3 3357.1 28144.0 28411.4 28427.0 28505.1 28917.0 2690.7 2696.8 2730.6 2730.6 2814.5 420360.0 424539.9 424591.0 424899.0 424970.0 35480.5 36141.6 62867.7 63290.7 63315.4 35248.8 35451.6 35585.8 35645.8 35674.9 15588.4 15746.7 15747.1 15809.1 15813.4 12355.3 12411.4 12477.6 12493.3 13148.3 8120.1 8268.4 8277.1 8295.5 8380.6 2910.7 3023.6 3287.1 3350.5 3452.8 1019.7 1040.8 1076.6 1138.8 1151.1 29749.6 30097.0 30150.0 30247.2 30402.8 782.9 927.8 1081.8 1151.6 1181.0 14120.8 14137.9 14396.0 14597.9 14715.6 635365.0 643421.0 649733.0 651732.0 652802.0 58168.1 58687.7 58698.1 58721.4 58728.7 1362.3 1470.7 1498.8 1501.3 1505.3 28669.7 28781.7 29312.0 29378.6 29487.0 49305.0 58288.9 59714.1 59844.0 59969.4 365783.0 471846.3 478097.0 478276.0 480040.0 35208.6 41581.7 42255.9 42512.6 42891.6 41768.3 43208.0 43217.4 43298.4 44193.8 28779.9 32707.0 35012.5 36154.3 36834.9 33364.5 35441.8 36089.6 36796.4 37928.9 37544.4 37602.0 39010.8 39027.8 39038.6 31894.2 33058.5 33273.8 33296.8 33328.0 24736.9 24816.8 24864.5 25289.5 25335.6 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#2] jquery.html Cycles:180 Average:34894.53 Median:12215.89 stddev:99964.35 (818.3%) stddev-sans-first:100211.09 16:38:50 INFO - PROCESS | 2297 | Values: 336.8 337.2 347.8 403.5 409.9 20125.5 20855.1 21606.8 21768.3 22164.9 20771.9 21472.2 21716.3 21761.7 21773.3 5830.6 6019.9 6034.2 6055.1 6071.9 3940.5 4135.6 4412.6 5720.2 6009.4 5988.4 6001.5 6002.5 6009.7 6024.0 5721.4 5963.7 5968.7 5969.9 5987.6 5756.9 5779.7 5785.0 5787.5 5789.8 14000.5 17768.7 24005.2 25435.7 25516.6 2139.5 2169.4 2266.9 2272.7 2640.4 9681.0 9701.6 10058.9 10139.5 10148.4 1382.4 1622.7 1692.7 1701.2 1814.2 109960.0 117722.4 118323.0 119659.4 119684.8 9249.9 11706.9 11711.9 11736.5 11860.7 14975.0 15063.3 15071.4 15082.5 15101.5 8490.8 8775.7 8779.0 8835.7 8896.4 8887.4 9101.5 9130.7 9183.8 9206.2 4599.3 4639.4 4647.3 4690.4 4720.3 1640.7 1816.4 1918.2 2006.7 3079.8 949.6 953.9 977.5 993.3 1020.9 18548.2 20654.1 20745.0 20941.0 21017.6 986.7 1005.8 1031.4 1054.5 1078.9 10685.9 10915.6 11060.5 11112.8 11175.9 580477.0 593413.0 598113.0 603537.0 619557.0 119608.0 120851.4 123565.0 126471.4 131327.0 1008.4 1021.9 1044.3 1045.7 1048.5 17757.9 18095.2 18222.7 18613.0 18945.0 52737.0 54757.2 55226.2 56235.3 57486.7 75771.6 80409.2 81185.6 82143.2 84914.6 6777.0 8091.2 8200.7 8261.5 8262.0 6738.3 8571.7 9110.8 12492.7 12632.9 10631.0 10649.6 10902.7 10929.8 11121.8 10415.5 10450.0 10497.6 10810.9 10965.3 8581.4 10354.3 10751.2 10801.0 11205.3 7977.5 8160.2 8250.4 8377.7 8481.2 10111.9 11065.3 11317.6 11386.8 11451.8 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#3] mootools.html Cycles:180 Average:5774.18 Median:5184.48 stddev:3249.00 (62.7%) stddev-sans-first:3241.89 16:38:50 INFO - PROCESS | 2297 | Values: 1454.0 1575.3 1608.9 1863.7 1989.6 8096.9 8680.0 8846.6 8860.2 8878.1 8638.8 8712.4 8785.1 8789.3 8810.6 2167.4 2224.9 2456.9 2475.8 2477.4 2246.9 2251.9 2256.5 2429.9 2492.0 2026.2 2433.1 2433.5 2438.1 2441.6 2348.1 2393.5 2418.5 2426.6 2430.5 2140.9 2364.9 2409.5 2409.6 2418.7 8648.3 8720.3 8721.5 8740.0 8741.6 4577.5 5297.4 5453.6 5501.8 5509.9 3367.5 3434.4 3489.6 3491.6 3498.8 985.6 1009.7 1019.0 1023.6 1028.8 12395.7 12406.6 12406.9 12411.4 12424.4 4782.0 4851.8 4949.3 4994.3 5009.5 10153.9 10299.5 10300.3 10313.1 10321.3 5520.6 5542.0 5548.1 5582.0 5601.7 5011.6 5023.5 5063.8 5067.2 5074.6 3744.7 3845.8 4017.9 4109.5 4114.4 1318.1 1340.7 1342.3 1359.2 1365.7 910.4 911.6 925.3 928.0 964.4 5892.8 5966.7 6010.2 6030.4 6204.9 894.2 909.5 940.7 941.8 962.6 3149.6 3315.3 3878.3 3969.0 4024.2 10561.8 10717.6 10898.4 10998.9 11029.9 10853.3 10867.9 10879.6 10891.2 10902.3 8200.0 8386.6 8405.8 8471.3 8512.5 5615.6 5655.0 5732.2 5732.9 5956.5 9518.7 9747.7 9809.0 9889.6 9892.8 9874.5 10040.0 10052.5 10185.9 10205.8 7369.4 7428.3 7608.6 7629.6 7631.0 7250.6 7407.9 8152.7 8324.7 8326.5 7330.2 7446.6 7462.7 7537.5 7618.7 7270.2 7277.4 7301.9 7426.7 7464.1 6793.6 6972.5 7036.8 7051.5 7055.5 5184.4 5206.3 5239.7 5256.0 5316.9 5154.2 5184.5 5195.6 5262.8 5484.7 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#4] prototype.html Cycles:180 Average:5703.02 Median:6582.97 stddev:1847.38 (28.1%) stddev-sans-first:1807.74 16:38:50 INFO - PROCESS | 2297 | Values: 314.0 320.0 330.9 332.3 334.2 6398.6 6556.8 6597.2 6610.1 6670.7 6462.8 6573.8 6601.6 6631.2 6643.9 4902.1 4907.2 4929.4 4935.6 4947.8 4187.5 4653.1 4702.6 4789.5 4930.0 4681.8 4722.7 4733.9 4745.5 4818.6 4784.5 4788.9 4805.8 4807.0 4814.6 3509.5 4117.4 4132.4 4150.4 4152.6 5087.4 6354.9 6366.1 6369.5 6374.5 5202.3 5322.2 5795.6 5805.4 5832.4 4928.8 5604.4 5688.9 6822.7 7009.3 4250.0 4555.2 4563.4 4563.8 4566.4 6592.1 7231.6 7527.5 7539.3 7543.9 3757.3 4637.3 4637.8 4649.4 4656.2 4705.5 4711.6 4712.3 4715.8 4716.2 5395.6 5572.6 5575.6 5580.3 5651.3 6915.3 6966.3 6982.0 6986.3 7084.7 1368.6 1615.4 1633.6 1639.1 1640.2 981.1 988.1 998.9 1001.5 1033.6 6347.1 6386.3 6387.5 6615.4 6620.7 6349.8 6993.8 7395.9 7397.9 7416.5 6593.5 6596.8 6625.0 6650.0 6748.8 3416.4 3617.3 3759.2 3899.0 3901.5 7728.3 7739.9 7740.7 7741.5 7762.6 7497.9 7580.1 7584.7 7596.7 7602.4 7681.1 7704.4 7720.3 7721.9 7731.7 7078.8 7313.0 7320.5 7347.3 7376.5 7202.2 7240.9 7277.1 7287.5 7289.4 6865.2 6948.4 7160.7 7184.6 7808.7 4817.5 4882.8 4888.0 4888.7 4896.7 7029.8 7241.9 7288.6 7307.6 7313.2 5670.7 6444.2 6999.2 7049.4 7054.4 5634.7 7009.8 7054.0 7055.8 7056.0 6881.5 6882.3 6896.3 6896.8 6905.0 6772.3 7005.4 7013.1 7025.3 7069.5 6638.4 6735.5 6781.3 6920.7 6948.2 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - PROCESS | 2297 | [#5] yui.html Cycles:180 Average:17604.69 Median:31021.59 stddev:24505.43 (79.0%) stddev-sans-first:24541.14 16:38:50 INFO - PROCESS | 2297 | Values: 657.3 679.4 691.7 716.7 753.5 14456.2 14754.3 14910.6 14931.8 14939.6 14223.9 14263.1 14278.4 14299.0 14364.0 730.3 739.5 894.5 907.1 926.1 913.9 920.8 926.5 927.1 930.9 906.9 962.8 975.3 975.6 976.2 753.7 845.4 948.5 967.7 971.7 711.2 715.6 720.6 721.7 722.6 14663.3 14720.7 14741.0 14745.6 14764.4 11685.8 11689.3 11868.0 11899.8 12096.8 9299.8 10794.7 10830.2 10954.9 11318.7 3381.2 3435.9 3453.2 3530.5 3674.7 77836.1 78476.6 78681.0 79895.0 79932.0 9314.1 9400.9 9446.1 9800.0 10145.7 21318.3 21527.7 21532.9 21535.0 21539.9 7418.3 7440.0 7546.6 7669.5 7685.6 3979.6 4078.4 4097.5 4154.1 4157.4 3575.7 3624.0 3629.3 3635.5 3635.9 661.1 664.3 669.2 673.7 679.5 535.8 548.2 575.6 599.7 619.1 7082.4 8014.9 8106.7 8225.3 8234.6 504.4 562.2 590.5 605.5 616.1 3820.1 3873.7 3922.4 3942.1 3963.4 89049.0 92137.0 92379.0 92440.5 92760.5 74237.3 74865.0 74881.0 75417.1 76643.6 64778.7 65153.0 65439.0 65457.0 68098.6 7064.0 8461.1 8469.1 8661.5 8680.4 43294.8 43370.7 43434.0 44685.6 46188.0 56243.2 59551.7 59809.5 60410.4 61437.7 10738.9 11065.7 11109.2 11167.9 11224.3 11677.2 11788.6 11841.6 11974.5 12411.4 9351.4 10630.4 10903.8 11481.7 11823.2 11188.6 11254.4 11397.9 11410.9 11566.5 11022.8 11027.7 11164.7 11785.1 11945.8 9231.4 9257.6 9579.8 9619.2 9711.6 8535.3 8808.7 9434.6 9518.2 9537.0 16:38:50 INFO - PROCESS | 2297 | -------- Summary: end -------- 16:38:50 INFO - PROCESS | 2297 | 16:38:50 INFO - TEST-INFO | 2297: exit 0 16:38:50 INFO - TEST-OK | dromaeo_css | took 1115711ms 16:38:50 INFO - TEST-START | kraken 16:38:50 INFO - operating with platform_type : mac_ 16:38:50 INFO - Initialising browser for kraken test... 16:38:51 INFO - TEST-INFO | started process 2300 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpTez9hD/profile http://localhost:50685/getInfo.html) 16:38:53 INFO - PROCESS | 2300 | __metrics Screen width/height:1600/1200 16:38:53 INFO - PROCESS | 2300 | colorDepth:24 16:38:53 INFO - PROCESS | 2300 | Browser inner width/height: 1024/689 16:38:53 INFO - PROCESS | 2300 | __metrics 16:38:53 INFO - PROCESS | 2300 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:38:53 ERROR - PROCESS | 2300 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:38:53 INFO - TEST-INFO | 2300: exit 0 16:38:53 INFO - Browser initialized. 16:38:53 INFO - Running cycle 1/1 for kraken test... 16:38:53 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/T/tmpTez9hD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:39:05 INFO - PROCESS | 2302 | RSS: Main: 211394560 16:39:05 INFO - PROCESS | 2302 | 16:39:08 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50685/tests/kraken/driver.html?testName=audio-beat-detection) 16:39:09 INFO - PROCESS | 2302 | RSS: Main: 272244736 16:39:09 INFO - PROCESS | 2302 | 16:39:12 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50685/tests/kraken/driver.html?testName=audio-dft) 16:39:12 INFO - PROCESS | 2302 | RSS: Main: 287977472 16:39:12 INFO - PROCESS | 2302 | 16:39:15 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50685/tests/kraken/driver.html?testName=audio-fft) 16:39:16 INFO - PROCESS | 2302 | RSS: Main: 375005184 16:39:16 INFO - PROCESS | 2302 | 16:39:18 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50685/tests/kraken/driver.html?testName=audio-oscillator) 16:39:19 INFO - PROCESS | 2302 | RSS: Main: 279072768 16:39:19 INFO - PROCESS | 2302 | 16:39:21 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50685/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:39:21 INFO - PROCESS | 2302 | RSS: Main: 280829952 16:39:21 INFO - PROCESS | 2302 | 16:39:24 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50685/tests/kraken/driver.html?testName=imaging-darkroom) 16:39:25 INFO - PROCESS | 2302 | RSS: Main: 318681088 16:39:25 INFO - PROCESS | 2302 | 16:39:28 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50685/tests/kraken/driver.html?testName=imaging-desaturate) 16:39:28 INFO - PROCESS | 2302 | RSS: Main: 319991808 16:39:28 INFO - PROCESS | 2302 | 16:39:31 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50685/tests/kraken/driver.html?testName=json-parse-financial) 16:39:32 INFO - PROCESS | 2302 | RSS: Main: 318427136 16:39:32 INFO - PROCESS | 2302 | 16:39:33 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50685/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:39:34 INFO - PROCESS | 2302 | RSS: Main: 309780480 16:39:34 INFO - PROCESS | 2302 | 16:39:36 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:39:36 INFO - PROCESS | 2302 | RSS: Main: 317050880 16:39:36 INFO - PROCESS | 2302 | 16:39:38 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:39:39 INFO - PROCESS | 2302 | RSS: Main: 318935040 16:39:39 INFO - PROCESS | 2302 | 16:39:42 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:39:42 INFO - PROCESS | 2302 | RSS: Main: 324472832 16:39:42 INFO - PROCESS | 2302 | 16:39:45 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:39:46 INFO - PROCESS | 2302 | RSS: Main: 322363392 16:39:46 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:50685/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50685/tests/kraken/driver.html?testName=ai-astar) 16:39:48 INFO - PROCESS | 2302 | RSS: Main: 386523136 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | __start_tp_report 16:39:48 INFO - PROCESS | 2302 | _x_x_mozilla_page_load 16:39:48 INFO - PROCESS | 2302 | _x_x_mozilla_page_load_details 16:39:48 INFO - PROCESS | 2302 | |i|pagename|runs| 16:39:48 INFO - PROCESS | 2302 | |0;ai-astar;115;113;120;112;113;112;112;113;114;135 16:39:48 INFO - PROCESS | 2302 | |1;audio-beat-detection;155;120;157;165;149;175;163;120;171;117 16:39:48 INFO - PROCESS | 2302 | |2;audio-dft;189;187;188;184;185;185;184;184;187;184 16:39:48 INFO - PROCESS | 2302 | |3;audio-fft;70;78;74;154;55;56;59;66;138;56 16:39:48 INFO - PROCESS | 2302 | |4;audio-oscillator;92;91;140;78;81;92;124;79;76;126 16:39:48 INFO - PROCESS | 2302 | |5;imaging-gaussian-blur;87;83;87;84;86;83;88;84;86;83 16:39:48 INFO - PROCESS | 2302 | |6;imaging-darkroom;93;93;93;92;93;92;93;92;92;94 16:39:48 INFO - PROCESS | 2302 | |7;imaging-desaturate;84;86;85;84;84;84;86;87;82;84 16:39:48 INFO - PROCESS | 2302 | |8;json-parse-financial;61;65;63;63;64;65;66;78;61;58 16:39:48 INFO - PROCESS | 2302 | |9;json-stringify-tinderbox;64;69;80;74;69;66;67;67;67;69 16:39:48 INFO - PROCESS | 2302 | |10;stanford-crypto-aes;77;82;81;81;81;80;80;79;81;84 16:39:48 INFO - PROCESS | 2302 | |11;stanford-crypto-ccm;150;127;152;150;154;146;132;128;147;147 16:39:48 INFO - PROCESS | 2302 | |12;stanford-crypto-pbkdf2;167;167;173;168;225;219;173;166;165;164 16:39:48 INFO - PROCESS | 2302 | |13;stanford-crypto-sha256-iterative;68;67;67;69;68;71;66;67;67;64 16:39:48 INFO - PROCESS | 2302 | __end_tp_report 16:39:48 INFO - PROCESS | 2302 | __start_cc_report 16:39:48 INFO - PROCESS | 2302 | _x_x_mozilla_cycle_collect,2026 16:39:48 INFO - PROCESS | 2302 | __end_cc_report 16:39:48 INFO - PROCESS | 2302 | __startTimestamp1464910788468__endTimestamp 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | ------- Summary: start ------- 16:39:48 INFO - PROCESS | 2302 | Number of tests: 14 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#0] ai-astar Cycles:10 Average:115.90 Median:113.50 stddev:7.13 (6.3%) stddev-sans-first:7.55 16:39:48 INFO - PROCESS | 2302 | Values: 115.0 113.0 120.0 112.0 113.0 112.0 112.0 113.0 114.0 135.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#1] audio-beat-detection Cycles:10 Average:149.20 Median:160.00 stddev:22.16 (13.8%) stddev-sans-first:23.40 16:39:48 INFO - PROCESS | 2302 | Values: 155.0 120.0 157.0 165.0 149.0 175.0 163.0 120.0 171.0 117.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#2] audio-dft Cycles:10 Average:185.70 Median:186.00 stddev:1.89 (1.0%) stddev-sans-first:1.58 16:39:48 INFO - PROCESS | 2302 | Values: 189.0 187.0 188.0 184.0 185.0 185.0 184.0 184.0 187.0 184.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#3] audio-fft Cycles:10 Average:80.60 Median:62.50 stddev:35.58 (56.9%) stddev-sans-first:37.53 16:39:48 INFO - PROCESS | 2302 | Values: 70.0 78.0 74.0 154.0 55.0 56.0 59.0 66.0 138.0 56.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#4] audio-oscillator Cycles:10 Average:97.90 Median:80.00 stddev:23.28 (29.1%) stddev-sans-first:24.60 16:39:48 INFO - PROCESS | 2302 | Values: 92.0 91.0 140.0 78.0 81.0 92.0 124.0 79.0 76.0 126.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#5] imaging-gaussian-blur Cycles:10 Average:85.10 Median:86.00 stddev:1.91 (2.2%) stddev-sans-first:1.90 16:39:48 INFO - PROCESS | 2302 | Values: 87.0 83.0 87.0 84.0 86.0 83.0 88.0 84.0 86.0 83.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#6] imaging-darkroom Cycles:10 Average:92.70 Median:93.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 16:39:48 INFO - PROCESS | 2302 | Values: 93.0 93.0 93.0 92.0 93.0 92.0 93.0 92.0 92.0 94.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.43 (1.7%) stddev-sans-first:1.50 16:39:48 INFO - PROCESS | 2302 | Values: 84.0 86.0 85.0 84.0 84.0 84.0 86.0 87.0 82.0 84.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#8] json-parse-financial Cycles:10 Average:64.40 Median:64.50 stddev:5.34 (8.3%) stddev-sans-first:5.52 16:39:48 INFO - PROCESS | 2302 | Values: 61.0 65.0 63.0 63.0 64.0 65.0 66.0 78.0 61.0 58.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#9] json-stringify-tinderbox Cycles:10 Average:69.20 Median:69.00 stddev:4.61 (6.7%) stddev-sans-first:4.49 16:39:48 INFO - PROCESS | 2302 | Values: 64.0 69.0 80.0 74.0 69.0 66.0 67.0 67.0 67.0 69.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:81.00 stddev:1.84 (2.3%) stddev-sans-first:1.41 16:39:48 INFO - PROCESS | 2302 | Values: 77.0 82.0 81.0 81.0 81.0 80.0 80.0 79.0 81.0 84.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#11] stanford-crypto-ccm Cycles:10 Average:143.30 Median:148.50 stddev:10.23 (6.9%) stddev-sans-first:10.56 16:39:48 INFO - PROCESS | 2302 | Values: 150.0 127.0 152.0 150.0 154.0 146.0 132.0 128.0 147.0 147.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:170.50 stddev:23.06 (13.5%) stddev-sans-first:24.07 16:39:48 INFO - PROCESS | 2302 | Values: 167.0 167.0 173.0 168.0 225.0 219.0 173.0 166.0 165.0 164.0 16:39:48 INFO - PROCESS | 2302 | 16:39:48 INFO - PROCESS | 2302 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:67.50 stddev:1.84 (2.7%) stddev-sans-first:1.94 16:39:48 INFO - PROCESS | 2302 | Values: 68.0 67.0 67.0 69.0 68.0 71.0 66.0 67.0 67.0 64.0 16:39:48 INFO - PROCESS | 2302 | -------- Summary: end -------- 16:39:48 INFO - PROCESS | 2302 | 16:39:49 INFO - TEST-INFO | 2302: exit 0 16:39:49 INFO - TEST-OK | kraken | took 58268ms 16:39:49 INFO - SUITE-END | took 1173s 16:39:49 INFO - Completed test suite (00:19:34) 16:39:49 INFO - javascript benchmark 16:39:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3353.76523643345, 3433.141714571357, 3443.535122758428, 3456.8962931112046, 3494.755367124301, 5657.745401456934, 5679.119736440854, 5690.0, 5694.886102277952, 5710.0, 5671.574631902603, 5721.313442386909, 5724.513416359604, 5726.455986681269, 5727.255456790622, 5003.2995380646735, 5085.593152547805, 5133.281340612308, 5244.554212891909, 5274.604404669646, 5103.132467480518, 5116.948830511685, 5122.206058061007, 5147.330846989886, 5239.161734122541, 5155.072087024334, 5185.092608793446, 5213.817516386927, 5221.556167725748, 5228.424873263938, 5172.612054095958, 5191.896162076775, 5228.843134705965, 5243.947560524384, 5277.287566178555, 5042.168042273021, 5089.185730283174, 5102.693838369672, 5137.409197942247, 5139.717315547643, 5482.835514934519, 5502.422245664227, 5518.696471694056, 5535.944640553583, 5538.944610553883, 6185.257769067695, 6194.81415557534, 6195.28754193269, 6204.596701214452, 6217.87564248717, 5728.51307638847, 6079.148919151322, 6188.752449901998, 6237.064440333941, 6256.749730010794, 5282.366116066097, 5737.2254745609225, 5786.363500014995, 5790.536757059425, 5804.390538993429, 6219.284782250099, 6237.875242495124, 6247.843803904848, 6261.937380626136, 6264.279607845065, 6145.8156255312415, 6161.630302181883, 6165.198524191826, 6172.6913654318005, 6175.320714721377, 6080.118382834465, 6108.068892179526, 6120.873812997669, 6235.532335074888, 6251.74993000284, 6159.353267906895, 6191.5665903387235, 6198.969005154945, 6200.100985357098, 6213.937860621426, 6152.630842149485, 6159.876802463926, 6211.223597050369, 6215.34738852423, 6233.657148856856, 4807.567318941242, 4813.422389313235, 4880.804767809319, 4893.828715994994, 4947.208446648591, 4397.560243975577, 4401.779911004437, 4423.535528769497, 4430.933535996963, 4436.711613745097, 5627.831165065055, 5653.660780353192, 5654.491095801315, 5656.264685590929, 5668.433156684381, 6110.877782444326, 6169.784057557964, 6184.6598437086395, 6210.627362358273, 6245.437910587978, 5653.265075540154, 5664.178694089334, 5672.602917795714, 5678.233438485751, 5681.318241811009, 5378.91931621026, 5382.4886635769535, 5385.192221166856, 5394.637236543254, 5396.001739678172, 6183.876322473526, 6199.225096862892, 6251.437370636664, 6264.937350626436, 6290.276618188876, 6211.316755156839, 6229.3770622937345, 6232.034034724625, 6246.281677606951, 6266.592671476339, 6278.686065696788, 6296.590721603081, 6299.307076221704, 6302.212223472066, 6302.716377763103, 6024.668643224665, 6082.178905847654, 6125.1424800527075, 6131.448169664752, 6133.877322453437, 6141.486007784364, 6142.437386316015, 6176.505879529716, 6181.598196117238, 6222.844428889314, 5261.89476210466, 6054.303755068224, 6062.363451837494, 6076.787312444043, 6080.452759251689, 6031.09533569968, 6058.121572371893, 6079.300880398634, 6099.329073801967, 6101.816945491642, 5715.628484148517, 5831.825045248649, 5881.38245484218, 5894.646321220741, 5914.674692891932, 6091.794158553512, 6099.207103076572, 6131.816045518642, 6133.693315334304, 6139.785107521216, 5739.684317362585, 5847.181394604844, 5857.882842343214, 5957.49361304294, 5986.521078313812], "value": 5662.359070654014, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.897255035268, 532.1299675031324, 533.3253434405475, 543.5787264869726, 545.2312239741963, 30756.23109422263, 38214.0, 39160.99999999996, 39226.8038659807, 39278.41082383759, 42193.789031054876, 42408.0, 42778.0, 42781.57218427815, 42795.78602106993, 8489.151084891524, 9447.81104377912, 9650.0, 9663.323567350288, 9673.419594824332, 8614.353923455734, 11507.482163302671, 11740.94129529351, 11759.0, 11759.7648047039, 11508.827367589492, 11571.826422603668, 11575.594854180106, 11604.187706860523, 11628.476718547643, 8674.86987695185, 11463.36951467669, 11552.422378881065, 11586.536538538448, 11837.644870653894, 11392.71518212043, 11513.366764827932, 11515.884841151565, 11517.827232591519, 11528.193026488065, 38570.421443678366, 38881.41677874834, 38931.41602875959, 38965.636202733054, 38987.0, 3206.615206175274, 3212.213007813077, 3220.2915358621067, 3308.2556424804225, 3357.1103657530775, 28144.0, 28411.431771364663, 28427.0, 28505.144845654664, 28917.0, 2690.7174746651713, 2696.8247063940976, 2730.631364765751, 2730.645016147888, 2814.549672052482, 420360.0, 424539.8773006146, 424591.0, 424899.0, 424970.0, 35480.46779298312, 36141.63858361435, 62867.685661572315, 63290.73418531603, 63315.36684633187, 35248.766293179615, 35451.64548354535, 35585.82207088948, 35645.82177089098, 35674.92975210748, 15588.376464941502, 15746.68506629861, 15747.133907634961, 15809.051456912623, 15813.446529371648, 12355.25868447896, 12411.441485133191, 12477.625671229878, 12493.312867792358, 13148.276844773629, 8120.051187714906, 8268.421210515206, 8277.089520152778, 8295.460795048302, 8380.622871970776, 2910.6652734935756, 3023.56158357037, 3287.1289108386295, 3350.4806754952533, 3452.8100954447746, 1019.6889948565758, 1040.843873418978, 1076.56399158341, 1138.8120960041501, 1151.125144889873, 29749.570157733597, 30097.0, 30150.0, 30247.243818904262, 30402.84798575993, 782.9295363417319, 927.7726956895604, 1081.8377243413393, 1151.5508951508918, 1181.0020532649896, 14120.788188177186, 14137.929310353382, 14396.0, 14597.854021459649, 14715.55853324402, 635365.0, 643421.0, 649733.0, 651732.0, 652802.0, 58168.12747808786, 58687.70656146777, 58698.11952820711, 58721.412785872446, 58728.7063564688, 1362.2984163155982, 1470.6617477980124, 1498.7901693762883, 1501.3394106593064, 1505.3226048278539, 28669.713302866705, 28781.7121828779, 29312.0, 29378.55932161062, 29486.967956121433, 49305.0, 58288.87966763429, 59714.104288436574, 59844.0, 59969.40030599638, 365783.0, 471846.281537194, 478097.0, 478276.0, 480040.0, 35208.64791352054, 41581.652984776854, 42255.94360141021, 42512.57487425086, 42891.57108428876, 41768.3002992794, 43208.0, 43217.3517397233, 43298.35052474152, 44193.77903110464, 28779.8561007202, 32707.018789436355, 35012.47481287834, 36154.276914461036, 36834.89495315145, 33364.49953250655, 35441.82279088588, 36089.63910360863, 36796.448053278684, 37928.86213413602, 37544.43683344698, 37602.0, 39010.804945976226, 39027.80486097665, 39038.6096139035, 31894.202644934063, 33058.5041224377, 33273.833630831694, 33296.833515832266, 33328.0, 24736.876315618305, 24816.751832482165, 24864.50270994606, 25289.494210116063, 25335.61996570016], "value": 18798.43353780785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [336.82653433481755, 337.18065101802625, 347.8243487039045, 403.52787238930455, 409.93236116040856, 20125.496862578424, 20855.0615222315, 21606.811625360617, 21768.346949591538, 22164.891755412245, 20771.896140519315, 21472.24847130351, 21716.3485095447, 21761.673574896387, 21773.34679959604, 5830.621009634381, 6019.909701354483, 6034.155218269446, 6055.091736239555, 6071.939280607182, 3940.468036815036, 4135.627793498585, 4412.580804823537, 5720.170575266584, 6009.399060093978, 5988.431099045605, 6001.5198784097165, 6002.459778619924, 6009.669468179249, 6024.006039003559, 5721.427857214287, 5963.731632076524, 5968.731407086691, 5969.88060238795, 5987.6108052976415, 5756.942430575683, 5779.711014449303, 5785.0, 5787.536997040269, 5789.768409263625, 14000.509982150677, 17768.667349948806, 24005.159819406235, 25435.728213589246, 25516.617250741252, 2139.497218153733, 2169.40341406113, 2266.897989590472, 2272.749997500274, 2640.392709676766, 9681.0, 9701.563429645683, 10058.9497052515, 10139.54372053252, 10148.391096534158, 1382.3917476310392, 1622.732249178884, 1692.7207010843197, 1701.2259421962976, 1814.2198854492694, 109960.0, 117722.41138794337, 118323.0, 119659.40170299093, 119684.80315196911, 9249.861252081224, 11706.88784565482, 11711.941440292743, 11736.471858766376, 11860.703482413008, 14975.0, 15063.322150503254, 15071.397144114113, 15082.54752357431, 15101.546953591409, 8490.787730306669, 8775.736727898173, 8779.0, 8835.69075082369, 8896.377253592187, 8887.379920512887, 9101.499417531966, 9130.676051972427, 9183.816323673489, 9206.217471514996, 4599.333096701027, 4639.443266807937, 4647.326137710081, 4690.366800481891, 4720.315554244616, 1640.6554623529194, 1816.418746001267, 1918.194358369488, 2006.6989951507098, 3079.784415090967, 949.5916755795074, 953.8569214617725, 977.5307852230969, 993.3245393132739, 1020.9132223761063, 18548.165332560067, 20654.07056682453, 20745.0, 20940.952952352625, 21017.579648406947, 986.7187851462298, 1005.8139244239838, 1031.3914790273625, 1054.4938429553856, 1078.8813230544633, 10685.946570267099, 10915.56337746497, 11060.502277397536, 11112.833307500394, 11175.944120279346, 580477.0, 593413.0, 598113.0, 603537.0, 619557.0, 119608.0, 120851.39574302072, 123565.0, 126471.41171529738, 131327.0, 1008.3848852200152, 1021.9080282774586, 1044.2637940252105, 1045.7124290820086, 1048.4744696466776, 17757.911210443865, 18095.185716642518, 18222.72665909986, 18612.976286304383, 18945.0, 52737.0, 54757.178642321196, 55226.171607426724, 56235.312940611846, 57486.7125664369, 75771.62114189615, 80409.19590804017, 81185.59407203163, 82143.17856821355, 84914.57542712247, 6777.0, 8091.231333023354, 8200.712975045844, 8261.545614991233, 8262.0, 6738.326167383419, 8571.699990500303, 9110.772230694285, 12492.687682808002, 12632.936835315764, 10631.0, 10649.627263045757, 10902.672919812418, 10929.78140437171, 11121.833172502256, 10415.53130109162, 10450.0, 10497.635307409988, 10810.891891080988, 10965.342079475256, 8581.399302048796, 10354.326968747007, 10751.247412681038, 10801.0, 11205.327680339205, 7977.48146370484, 8160.183981601793, 8250.399526045096, 8377.748667539983, 8481.15188481147, 10111.949440252753, 11065.336079835235, 11317.603883864025, 11386.772264554827, 11451.828222576834], "value": 9490.46297267262, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1453.9894773132678, 1575.251755416177, 1608.9275982580787, 1863.7111247756602, 1989.6219718253521, 8096.878546821795, 8680.001799793017, 8846.557672116385, 8860.20258176764, 8878.112188781119, 8638.784030399243, 8712.433691810043, 8785.121487851211, 8789.340799440051, 8810.559472026389, 2167.3822960456273, 2224.877631730254, 2456.91400800972, 2475.8390704604235, 2477.3930387055198, 2246.921357752479, 2251.909923603054, 2256.5148493073984, 2429.854208747472, 2491.9626205606933, 2026.2199053364486, 2433.051110067075, 2433.5497932882386, 2438.085717855804, 2441.6215486599517, 2348.095983046522, 2393.533261014094, 2418.5404773093055, 2426.599611064172, 2430.453148041694, 2140.892955352226, 2364.869932153731, 2409.5180963807134, 2409.638554216864, 2418.709754829432, 8648.308135349156, 8720.258778003878, 8721.476711397336, 8740.0, 8741.562921853945, 4577.473590537097, 5297.364316282071, 5453.590980676465, 5501.752421141058, 5509.917351239735, 3367.4948757686298, 3434.3989801784587, 3489.5812502499607, 3491.6333784952467, 3498.7725797823314, 985.6451677396132, 1009.7071849163734, 1018.9643362482352, 1023.5854478936043, 1028.8199565076081, 12395.690107747288, 12406.62780116598, 12406.937965310206, 12411.4414851331, 12424.37878106091, 4782.0, 4851.757412129379, 4949.257611358253, 4994.2508623706735, 5009.474005229471, 10153.933836947013, 10299.485025748683, 10300.330478519023, 10313.12338451225, 10321.277510574339, 5520.558355331564, 5542.0, 5548.0845660466375, 5581.972090139523, 5601.663900166003, 5011.649184557119, 5023.497650234947, 5063.772130254148, 5067.163917953497, 5074.568661663726, 3744.7378683491893, 3845.7884816335372, 4017.879463616096, 4109.486314210723, 4114.362273847559, 1318.0905175429116, 1340.7464021140265, 1342.2617560341773, 1359.2048651538762, 1365.6616515814558, 910.4117183277325, 911.5670056723003, 925.2551695884878, 927.9536023198813, 964.4261664309724, 5892.793752218738, 5966.731497082642, 6010.188624535632, 6030.427109424598, 6204.937950620436, 894.2309613732185, 909.4997751236793, 940.722486866376, 941.7615835176721, 962.5572236771023, 3149.6062992125985, 3315.3203593263324, 3878.3212937735784, 3968.9603103969166, 4024.195160967818, 10561.841572376421, 10717.624883129052, 10898.400587967737, 10998.890011099787, 11029.889701102886, 10853.294535855144, 10867.945660271647, 10879.564817407216, 10891.18316126278, 10902.291351062155, 8200.015998080149, 8386.622601982803, 8405.831883362298, 8471.279941204943, 8512.531810750344, 5615.578831587565, 5654.971725141348, 5732.16883551891, 5732.942670573241, 5956.463918247378, 9518.666846660333, 9747.707568772948, 9809.0, 9889.554970026222, 9892.75268118274, 9874.456904870302, 10040.046195611405, 10052.547635356574, 10185.930477299777, 10205.795884082128, 7369.410447164107, 7428.2571742825285, 7608.619569021637, 7629.580373079534, 7631.0, 7250.564966102051, 7407.888881666887, 8152.714654987047, 8324.6670133194, 8326.542040187847, 7330.157031941182, 7446.590437525988, 7462.738804141829, 7537.510061846182, 7618.657160427905, 7270.200277969525, 7277.417806575567, 7301.926980730337, 7426.74006409773, 7464.141623713343, 6793.592384456949, 6972.51192416526, 7036.788896333118, 7051.471139664443, 7055.4708396871465, 5184.429712731672, 5206.271122042841, 5239.7118158500125, 5255.973720131527, 5316.946830531645, 5154.201098829687, 5184.533391994739, 5195.636305458582, 5262.763175657028, 5484.698341591251], "value": 4635.904422867584, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.9890103846366, 320.038284953714, 330.9106541233866, 332.29884942770764, 334.2162628635848, 6398.552101352907, 6556.836079098025, 6597.175353080865, 6610.07458955746, 6670.699818508167, 6462.773802916899, 6573.80278591643, 6601.603903765777, 6631.237407698116, 6643.900341494881, 4902.093112774148, 4907.2148456247005, 4929.408470983487, 4935.629827762914, 4947.777350019258, 4187.518435379925, 4653.069386122772, 4702.6473014524045, 4789.497102804207, 4930.0, 4681.765911704419, 4722.716637001791, 4733.881652958669, 4745.454272758625, 4818.638602104838, 4784.545468180518, 4788.856334309976, 4805.831795887128, 4806.975965120169, 4814.566688998007, 3509.5262139611336, 4117.382392641098, 4132.359484279941, 4150.418941348214, 4152.647025002879, 5087.364079490064, 6354.936450635527, 6366.108744775735, 6369.458596019297, 6374.458171055466, 5202.323697919246, 5322.228276899867, 5795.594308398414, 5805.390434004411, 5832.416758324176, 4928.753562321869, 5604.439556044404, 5688.943110568883, 6822.703686299587, 7009.334113259283, 4249.957500424987, 4555.157295900236, 4563.406757121553, 4563.794629241692, 4566.429196350457, 6592.143021407186, 7231.602261875595, 7527.548347099137, 7539.283768042027, 7543.924560754377, 3757.267332870119, 4637.3275874997935, 4637.814487420465, 4649.442066951988, 4656.208444564432, 4705.505921878222, 4711.646626502958, 4712.293156026623, 4715.7642117893965, 4716.151092803259, 5395.595330350239, 5572.554195664337, 5575.637583557056, 5580.302462192226, 5651.265335506358, 6915.308469153145, 6966.303369663095, 6982.0, 6986.266442023616, 7084.716611335592, 1368.5620601407652, 1615.4426722780622, 1633.5834362237554, 1639.0739232333694, 1640.196303811124, 981.0532835813474, 988.0761488008735, 998.930074894765, 1001.4542074569378, 1033.571068006784, 6347.143135676717, 6386.329435409308, 6387.5209359297305, 6615.404613584704, 6620.702068406933, 6349.761796449648, 6993.755218567326, 7395.926040739524, 7397.926020739831, 7416.517926334771, 6593.505487088391, 6596.769113081019, 6625.039369291426, 6650.0, 6748.797536073926, 3416.4192087344713, 3617.2584620152807, 3759.2105657811617, 3899.0, 3901.5318161820196, 7728.26581474759, 7739.922600774033, 7740.729074482366, 7741.535507869433, 7762.5730584818375, 7497.887531687029, 7580.05249343832, 7584.734534291273, 7596.734114305973, 7602.353799927068, 7681.078270607562, 7704.422168337397, 7720.3437707795465, 7721.884171737428, 7731.729389471341, 7078.787636370917, 7312.963435183003, 7320.487565870338, 7347.302006309392, 7376.4836461447185, 7202.243764404662, 7240.855182896207, 7277.126744790659, 7287.489875708861, 7289.416846652149, 6865.244823069358, 6948.409385202314, 7160.713571457291, 7184.568925864465, 7808.726694565663, 4817.470078291315, 4882.829100981448, 4887.99388376139, 4888.6577939543895, 4896.6572339935865, 7029.789106326818, 7241.855162896818, 7288.599127047851, 7307.634618269171, 7313.195548489768, 5670.6597604142235, 6444.226692796894, 6999.160100787938, 7049.400800931979, 7054.435645148274, 5634.74643641029, 7009.7897063088185, 7054.01243825875, 7055.823604409931, 7056.047433596399, 6881.483888708267, 6882.346177113166, 6896.310368963063, 6896.827579310558, 6904.965475172592, 6772.25505194438, 7005.404540614105, 7013.088298521159, 7025.2623474536485, 7069.505134640526, 6638.444198983329, 6735.4611631070375, 6781.321867813179, 6920.653967301514, 6948.166220053626], "value": 5081.8361309597285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [657.3426573426576, 679.3614002837332, 691.712939130261, 716.7348081209954, 753.4612751882403, 14456.204908730042, 14754.336054877529, 14910.552683419515, 14931.776023359658, 14939.5518134456, 14223.928880355585, 14263.144211347326, 14278.357473913673, 14299.0, 14364.0, 730.3062091013531, 739.5230076600616, 894.5080205886769, 907.0521305236011, 926.083177654121, 913.9360244782868, 920.8250432417819, 926.476540754475, 927.1007123090591, 930.9208717259041, 906.9229115525155, 962.7544976031103, 975.2539307429787, 975.5756246032963, 976.1848856205078, 753.6985205917649, 845.3701992015957, 948.4641177734627, 967.690339091491, 971.6647756523988, 711.1963481266137, 715.588536591462, 720.5820624038046, 721.6824597177277, 722.6495149852317, 14663.266836658231, 14720.705585888223, 14741.0, 14745.631359215999, 14764.409423623043, 11685.824712629317, 11689.298642081503, 11868.0, 11899.821502677467, 12096.81854772179, 9299.76750581241, 10794.676159715222, 10830.241883068189, 10954.945225273903, 11318.660440186808, 3381.249344670717, 3435.914102147441, 3453.188500702333, 3530.5233793437815, 3674.7427680062406, 77836.05409864742, 78476.60761696156, 78680.99999999943, 79895.0, 79932.0, 9314.115159059878, 9400.905990940004, 9446.102620251066, 9799.951000245095, 10145.695629131138, 21318.253861114787, 21527.67708484374, 21532.89233553822, 21535.0, 21539.89230053871, 7418.332350088518, 7439.962800186073, 7546.584937828472, 7669.463137580427, 7685.577293248925, 3979.6418322350546, 4078.3678529827926, 4097.549269580344, 4154.086101057763, 4157.417961485395, 3575.66031227033, 3624.039629498185, 3629.25600251953, 3635.472856435802, 3635.8909232723063, 661.0612929639923, 664.2693037658537, 669.1501792723215, 673.7170388436869, 679.4632240530025, 535.7749745107064, 548.1585765849402, 575.6057100885906, 599.7391134856351, 619.123939625425, 7082.433405327562, 8014.917986071922, 8106.675732970615, 8225.300849427866, 8234.588270586446, 504.36854646544293, 562.1764115570709, 590.4862769390658, 605.4853374631616, 616.1374076293225, 3820.121372084436, 3873.69010479161, 3922.37242041272, 3942.0539070623418, 3963.425303331001, 89049.0, 92137.0, 92379.0, 92440.53779731058, 92760.53619731993, 74237.25762742411, 74865.0, 74881.0, 75417.11457213614, 76643.61678191573, 64778.67610661917, 65153.0, 65439.01841472382, 65457.01814472787, 68098.63802723918, 7064.0, 8461.111583283666, 8469.110743372104, 8661.52361620117, 8680.435771674822, 43294.78352608217, 43370.698879033676, 43434.0, 44685.55314446944, 46188.0, 56243.15635265556, 59551.702241488514, 59809.504762381286, 60410.39589604224, 61437.69281153566, 10738.892611073788, 11065.723356916142, 11109.222354435113, 11167.88832111701, 11224.270422422516, 11677.182597218112, 11788.646340609796, 11841.5855445059, 11974.46114924813, 12411.379431028594, 9351.438913665203, 10630.415327157081, 10903.83644245321, 11481.712957175803, 11823.23148995347, 11188.608398706006, 11254.38100904458, 11397.943010284895, 11410.915962983503, 11566.537338506701, 11022.834657479983, 11027.669169924595, 11164.720881978015, 11785.116116291141, 11945.761084778082, 9231.399959002643, 9257.629694812402, 9579.808403832023, 9619.230461563198, 9711.56297966579, 8535.274501667427, 8808.735737927873, 9434.622615095319, 9518.190953769008, 9537.046295370408], "value": 6798.618024608456, "unit": "score"}], "name": "dromaeo_css", "value": 7381.925136820379, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 120.0, 112.0, 113.0, 112.0, 112.0, 113.0, 114.0, 135.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 157.0, 165.0, 149.0, 175.0, 163.0, 120.0, 171.0, 117.0], "value": 149.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 187.0, 188.0, 184.0, 185.0, 185.0, 184.0, 184.0, 187.0, 184.0], "value": 185.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 74.0, 154.0, 55.0, 56.0, 59.0, 66.0, 138.0, 56.0], "value": 80.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 140.0, 78.0, 81.0, 92.0, 124.0, 79.0, 76.0, 126.0], "value": 97.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 87.0, 84.0, 86.0, 83.0, 88.0, 84.0, 86.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 92.0, 93.0, 92.0, 93.0, 92.0, 92.0, 94.0], "value": 92.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 85.0, 84.0, 84.0, 84.0, 86.0, 87.0, 82.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 63.0, 63.0, 64.0, 65.0, 66.0, 78.0, 61.0, 58.0], "value": 64.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 80.0, 74.0, 69.0, 66.0, 67.0, 67.0, 67.0, 69.0], "value": 69.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 81.0, 81.0, 81.0, 80.0, 80.0, 79.0, 81.0, 84.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 127.0, 152.0, 150.0, 154.0, 146.0, 132.0, 128.0, 147.0, 147.0], "value": 143.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 173.0, 168.0, 225.0, 219.0, 173.0, 166.0, 165.0, 164.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 69.0, 68.0, 71.0, 66.0, 67.0, 67.0, 64.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1495.3, "alertThreshold": 2.0}]} 16:39:49 INFO - Return code: 0 16:39:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:39:49 ERROR - # TBPL SUCCESS # 16:39:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:39:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:39:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:39:49 INFO - 2016-06-02 16:39:49.776 screenresolution[2306:32136] starting screenresolution argv=screenresolution get 16:39:49 INFO - 2016-06-02 16:39:49.779 screenresolution[2306:32136] Display 0: 1600x1200x32@60 16:39:49 INFO - 2016-06-02 16:39:49.785 screenresolution[2307:32139] starting screenresolution argv=screenresolution list 16:39:49 INFO - Available Modes on Display 0 16:39:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:39:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:39:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:39:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:39:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:39:50 INFO - Intel Iris: 16:39:50 INFO - Chipset Model: Intel Iris 16:39:50 INFO - Type: GPU 16:39:50 INFO - Bus: Built-In 16:39:50 INFO - VRAM (Dynamic, Max): 1536 MB 16:39:50 INFO - Vendor: Intel (0x8086) 16:39:50 INFO - Device ID: 0x0a2e 16:39:50 INFO - Revision ID: 0x0009 16:39:50 INFO - Displays: 16:39:50 INFO - Display: 16:39:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:39:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:39:50 INFO - Display Serial Number: 1600x1200 60 16:39:50 INFO - Main Display: Yes 16:39:50 INFO - Mirror: Off 16:39:50 INFO - Online: Yes 16:39:50 INFO - Rotation: Supported 16:39:50 INFO - Return code: 0 16:39:50 INFO - Running post-action listener: _resource_record_post_action 16:39:50 INFO - Running post-run listener: _resource_record_post_run 16:39:51 INFO - Total resource usage - Wall time: 1208s; CPU: 52.0%; Read bytes: 319488; Write bytes: 654487552; Read time: 3; Write time: 1835 16:39:51 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354562560; Write bytes: 356234752; Read time: 27724; Write time: 695 16:39:51 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 319488; Write bytes: 297404928; Read time: 3; Write time: 1136 16:39:51 INFO - Running post-run listener: _upload_blobber_files 16:39:51 INFO - Blob upload gear active. 16:39:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:39:51 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:39:51 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'] 16:39:51 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 16:39:51 INFO - (blobuploader) - INFO - Open directory for files ... 16:39:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:39:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:39:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:52 INFO - (blobuploader) - INFO - Done attempting. 16:39:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:39:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:39:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:39:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:39:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:39:53 INFO - (blobuploader) - INFO - Done attempting. 16:39:53 INFO - (blobuploader) - INFO - Iteration through files over. 16:39:53 INFO - Return code: 0 16:39:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:39:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:39:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7dac577d29aa8d7e7277c329c083b5b082149a7d8459e90d9188a55bf53f70349d6137f899c125d962dc021471b492a91890efc7d7f569dabcdc099c1eb6f25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee64d99304919215cde572df5e58cc5904dce78ba013540ed1818ef58d0b12a7b003a9f88581c97acbf84657f67b1016057f4a68c743a60f41884d0962c76c95"} 16:39:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:39:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:39:53 INFO - Contents: 16:39:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e7dac577d29aa8d7e7277c329c083b5b082149a7d8459e90d9188a55bf53f70349d6137f899c125d962dc021471b492a91890efc7d7f569dabcdc099c1eb6f25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee64d99304919215cde572df5e58cc5904dce78ba013540ed1818ef58d0b12a7b003a9f88581c97acbf84657f67b1016057f4a68c743a60f41884d0962c76c95"} 16:39:53 INFO - Running post-run listener: copy_logs_to_upload_dir 16:39:53 INFO - Copying logs to upload dir... 16:39:53 INFO - mkdir: /builds/slave/test/build/upload/logs 16:39:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1281.331330 ========= master_lag: 0.81 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2016-06-02 16:39:54.375652) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 16:39:54.378876) ========= 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.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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/e7dac577d29aa8d7e7277c329c083b5b082149a7d8459e90d9188a55bf53f70349d6137f899c125d962dc021471b492a91890efc7d7f569dabcdc099c1eb6f25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee64d99304919215cde572df5e58cc5904dce78ba013540ed1818ef58d0b12a7b003a9f88581c97acbf84657f67b1016057f4a68c743a60f41884d0962c76c95"} build_url:https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010977 build_url: 'https://queue.taskcluster.net/v1/task/b5C0kRReR7a6hl4kkZL_IA/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/e7dac577d29aa8d7e7277c329c083b5b082149a7d8459e90d9188a55bf53f70349d6137f899c125d962dc021471b492a91890efc7d7f569dabcdc099c1eb6f25", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee64d99304919215cde572df5e58cc5904dce78ba013540ed1818ef58d0b12a7b003a9f88581c97acbf84657f67b1016057f4a68c743a60f41884d0962c76c95"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 16:39:54.413046) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:39:54.413340) ========= 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.jwL77eHgAG/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.pRrVblZ4jM/Listeners TMPDIR=/var/folders/kx/qvhm4_gn43dcvvj7tsq112q000000w/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.005326 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 16:39:54.464720) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 16:39:54.465448) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 16:39:54.466157) ========= ========= Total master_lag: 0.94 =========