builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0048 starttime: 1464861080.46 results: success (0) buildid: 20160602004012 builduid: 8bdd07962cac41e69ed403da98af0905 revision: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.457540) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.458020) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.458324) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.533956) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.534287) ========= 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.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:20-- 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% 8.46M=0.001s 2016-06-02 02:51:20 (8.46 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.082721 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.639436) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.639789) ========= 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.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.027429 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:20.694746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 02:51:20.695117) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2d453fe195446be7ee2a26c05bd7deca5e0cd170 --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 2d453fe195446be7ee2a26c05bd7deca5e0cd170 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:20,774 truncating revision to first 12 chars 2016-06-02 02:51:20,774 Setting DEBUG logging. 2016-06-02 02:51:20,774 attempt 1/10 2016-06-02 02:51:20,774 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2d453fe19544?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 02:51:21,823 unpacking tar archive at: mozilla-aurora-2d453fe19544/testing/mozharness/ program finished with exit code 0 elapsedTime=1.304296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 02:51:22.019765) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:22.020124) ========= script_repo_revision: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:22.020556) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:22.020825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 02:51:22.038596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-06-02 02:51:22.039202) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:22 INFO - MultiFileLogger online at 20160602 02:51:22 in /builds/slave/test 02:51:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:51:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:51:22 INFO - {'append_to_log': False, 02:51:22 INFO - 'base_work_dir': '/builds/slave/test', 02:51:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:51:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:51:22 INFO - 'branch': 'Mozilla-Aurora', 02:51:22 INFO - 'buildbot_json_path': 'buildprops.json', 02:51:22 INFO - 'config_files': ('talos/mac_config.py',), 02:51:22 INFO - 'default_actions': ('clobber', 02:51:22 INFO - 'read-buildbot-config', 02:51:22 INFO - 'download-and-extract', 02:51:22 INFO - 'populate-webroot', 02:51:22 INFO - 'create-virtualenv', 02:51:22 INFO - 'install', 02:51:22 INFO - 'run-tests'), 02:51:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:51:22 INFO - 'download_minidump_stackwalk': True, 02:51:22 INFO - 'download_symbols': 'ondemand', 02:51:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:51:22 INFO - 'tooltool.py': '/tools/tooltool.py', 02:51:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:51:22 INFO - '/tools/misc-python/virtualenv.py')}, 02:51:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:51:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:51:22 INFO - 'installer_path': 'installer.exe', 02:51:22 INFO - 'log_level': 'info', 02:51:22 INFO - 'log_name': 'talos', 02:51:22 INFO - 'log_to_console': True, 02:51:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:51:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:51:22 INFO - 'opt_config_files': (), 02:51:22 INFO - 'pip_index': False, 02:51:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:51:22 INFO - 'cmd': ('bash', 02:51:22 INFO - '-c', 02:51:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:51:22 INFO - 'enabled': True, 02:51:22 INFO - 'halt_on_failure': False, 02:51:22 INFO - 'name': 'check_screen_resolution'},), 02:51:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:51:22 INFO - 'cmd': ('bash', 02:51:22 INFO - '-c', 02:51:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:51:22 INFO - 'enabled': True, 02:51:22 INFO - 'halt_on_failure': False, 02:51:22 INFO - 'name': 'check_screen_resolution'},), 02:51:22 INFO - 'run_cmd_checks_enabled': True, 02:51:22 INFO - 'sps_profile': False, 02:51:22 INFO - 'sps_profile_interval': 0, 02:51:22 INFO - 'suite': 'dromaeojs-e10s', 02:51:22 INFO - 'system_bits': '32', 02:51:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:51:22 INFO - 'title': 't-yosemite-r7-0048', 02:51:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:51:22 INFO - 'use_talos_json': True, 02:51:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:51:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:51:22 INFO - 'work_dir': 'build'} 02:51:22 INFO - ##### 02:51:22 INFO - ##### Running clobber step. 02:51:22 INFO - ##### 02:51:22 INFO - Running pre-action listener: _resource_record_pre_action 02:51:22 INFO - Running main action method: clobber 02:51:22 INFO - rmtree: /builds/slave/test/build 02:51:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:51:24 INFO - Running post-action listener: _resource_record_post_action 02:51:24 INFO - ##### 02:51:24 INFO - ##### Running read-buildbot-config step. 02:51:24 INFO - ##### 02:51:24 INFO - Running pre-action listener: _resource_record_pre_action 02:51:24 INFO - Running main action method: read_buildbot_config 02:51:24 INFO - Using buildbot properties: 02:51:24 INFO - { 02:51:24 INFO - "project": "", 02:51:24 INFO - "product": "firefox", 02:51:24 INFO - "script_repo_revision": "production", 02:51:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:51:24 INFO - "repository": "", 02:51:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:51:24 INFO - "buildid": "20160602004012", 02:51:24 INFO - "stage_platform": "macosx64", 02:51:24 INFO - "basedir": "/builds/slave/test", 02:51:24 INFO - "nightly_build": "True", 02:51:24 INFO - "buildnumber": 31, 02:51:24 INFO - "slavename": "t-yosemite-r7-0048", 02:51:24 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:51:24 INFO - "platform": "yosemite_r7", 02:51:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:51:24 INFO - "branch": "mozilla-aurora", 02:51:24 INFO - "repo_path": "releases/mozilla-aurora", 02:51:24 INFO - "revision": "2d453fe195446be7ee2a26c05bd7deca5e0cd170", 02:51:24 INFO - "pgo_build": "False", 02:51:24 INFO - "builduid": "8bdd07962cac41e69ed403da98af0905", 02:51:24 INFO - "slavebuilddir": "test" 02:51:24 INFO - } 02:51:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:51:24 INFO - Running post-action listener: _resource_record_post_action 02:51:24 INFO - ##### 02:51:24 INFO - ##### Running download-and-extract step. 02:51:24 INFO - ##### 02:51:24 INFO - Running pre-action listener: _resource_record_pre_action 02:51:24 INFO - Running main action method: download_and_extract 02:51:24 INFO - mkdir: /builds/slave/test/build/tests 02:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:24 INFO - https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:51:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:51:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:51:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:25 INFO - Downloaded 1199 bytes. 02:51:25 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:51:25 INFO - Using the following test package requirements: 02:51:25 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:51:25 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:51:25 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:51:25 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'jsshell-mac.zip'], 02:51:25 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:51:25 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:51:25 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:51:25 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:51:25 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:51:25 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:51:25 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:51:25 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:51:25 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')]} 02:51:25 INFO - https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:51:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:51:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:51:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:28 INFO - Downloaded 18463911 bytes. 02:51:28 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'] 02:51:28 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 02:51:31 INFO - Return code: 0 02:51:31 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 02:51:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:31 INFO - https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:51:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:51:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:51:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:33 INFO - Downloaded 18463911 bytes. 02:51:33 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'] 02:51:33 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 02:51:36 INFO - Return code: 0 02:51: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')]} 02:51:36 INFO - https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:51:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:51:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:51:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:37 INFO - Downloaded 10840145 bytes. 02:51:37 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'] 02:51:37 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 02:51:37 INFO - Return code: 0 02:51: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')]} 02:51:37 INFO - https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:51:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:51:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:51:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:51:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QIt0NV0ESjSORtFTRg2HtQ/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 02:51:41 INFO - Downloaded 114229107 bytes. 02:51:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:51:41 INFO - mkdir: /builds/slave/test/properties 02:51:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:51:41 INFO - Writing to file /builds/slave/test/properties/build_url 02:51:41 INFO - Contents: 02:51:41 INFO - build_url:https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:51:43 INFO - Running post-action listener: _resource_record_post_action 02:51:43 INFO - Running post-action listener: set_extra_try_arguments 02:51:43 INFO - ##### 02:51:43 INFO - ##### Running populate-webroot step. 02:51:43 INFO - ##### 02:51:43 INFO - Running pre-action listener: _resource_record_pre_action 02:51:43 INFO - Running main action method: populate_webroot 02:51:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:51:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:51:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:51:43 INFO - '--tppagecycles', 02:51:43 INFO - '7'], 02:51:43 INFO - 'tests': ['tsvgm']}}, 02:51:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:51:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:51:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:51:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:51:43 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:51:43 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:51:43 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['damp', 'tps']}, 02:51:43 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'tests': ['damp', 'tps']}, 02:51:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['dromaeo_dom']}, 02:51:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:51:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'other-e10s': {'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['a11yr', 02:51:43 INFO - 'ts_paint', 02:51:43 INFO - 'tpaint', 02:51:43 INFO - 'sessionrestore', 02:51:43 INFO - 'sessionrestore_no_auto_restore', 02:51:43 INFO - 'tabpaint']}, 02:51:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['tsvgx', 02:51:43 INFO - 'tsvgr_opacity', 02:51:43 INFO - 'tart', 02:51:43 INFO - 'tscrollx', 02:51:43 INFO - 'cart']}, 02:51:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:51:43 INFO - 'tsvgr_opacity', 02:51:43 INFO - 'tart', 02:51:43 INFO - 'tscrollx', 02:51:43 INFO - 'cart']}, 02:51:43 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'talos_options': ['--disable-e10s'], 02:51:43 INFO - 'tests': ['tp5o']}, 02:51:43 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'tests': ['tp5o']}, 02:51:43 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'talos_options': ['--disable-e10s', 02:51:43 INFO - '--xperf_path', 02:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:51:43 INFO - 'tests': ['tp5n']}, 02:51:43 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:51:43 INFO - 'talos_options': ['--xperf_path', 02:51:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:51:43 INFO - 'tests': ['tp5n']}}, 02:51:43 INFO - 'talos.zip': {'path': '', 02:51:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:51:43 INFO - Running post-action listener: _resource_record_post_action 02:51:43 INFO - ##### 02:51:43 INFO - ##### Running create-virtualenv step. 02:51:43 INFO - ##### 02:51:43 INFO - Running pre-action listener: _resource_record_pre_action 02:51:43 INFO - Running main action method: create_virtualenv 02:51:43 INFO - Creating virtualenv /builds/slave/test/build/venv 02:51:43 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 02:51:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:51:43 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:51:43 INFO - Using real prefix '/tools/python27' 02:51:43 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:51:43 INFO - Installing distribute.............................................................................................................................................................................................done. 02:51:46 INFO - Installing pip.................done. 02:51:46 INFO - Return code: 0 02:51:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:46 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:46 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 02:51:46 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 02:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:46 INFO - 'HOME': '/Users/cltbld', 02:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:46 INFO - 'LOGNAME': 'cltbld', 02:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:46 INFO - 'MOZ_NO_REMOTE': '1', 02:51:46 INFO - 'NO_EM_RESTART': '1', 02:51:46 INFO - 'PAGER': '/bin/cat', 02:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:46 INFO - 'PWD': '/builds/slave/test', 02:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:46 INFO - 'SHELL': '/bin/bash', 02:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:51:46 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:46 INFO - 'USER': 'cltbld', 02:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:46 INFO - 'XPC_FLAGS': '0x0', 02:51:46 INFO - 'XPC_SERVICE_NAME': '0', 02:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:47 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 02:51:47 INFO - Cleaning up... 02:51:47 INFO - Return code: 0 02:51:47 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:51: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')]} 02:51:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51: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')]} 02:51:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:47 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:47 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 02:51:47 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 02:51:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:51:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:47 INFO - 'HOME': '/Users/cltbld', 02:51:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:47 INFO - 'LOGNAME': 'cltbld', 02:51:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:47 INFO - 'MOZ_NO_REMOTE': '1', 02:51:47 INFO - 'NO_EM_RESTART': '1', 02:51:47 INFO - 'PAGER': '/bin/cat', 02:51:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:47 INFO - 'PWD': '/builds/slave/test', 02:51:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:47 INFO - 'SHELL': '/bin/bash', 02:51:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:51:47 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:51:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:47 INFO - 'USER': 'cltbld', 02:51:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:47 INFO - 'XPC_FLAGS': '0x0', 02:51:47 INFO - 'XPC_SERVICE_NAME': '0', 02:51:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:47 INFO - Downloading/unpacking psutil>=0.7.1 02:51:47 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 02:51:47 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 02:51:47 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 02:51:47 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 02:51:47 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 02:51:47 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 02:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:51:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:51:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:51:50 INFO - Installing collected packages: psutil 02:51:50 INFO - Running setup.py install for psutil 02:51:50 INFO - building 'psutil._psutil_osx' extension 02:51:50 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 02:51:50 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 02:51:50 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 02:51:51 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 02:51:51 INFO - building 'psutil._psutil_posix' extension 02:51:51 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 02:51:51 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:51:51 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:51:51 INFO - ^ 02:51:51 INFO - 1 warning generated. 02:51:51 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 02:51:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:51:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:51:51 INFO - Successfully installed psutil 02:51:51 INFO - Cleaning up... 02:51:51 INFO - Return code: 0 02:51:51 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:51 INFO - 'HOME': '/Users/cltbld', 02:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:51 INFO - 'LOGNAME': 'cltbld', 02:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:51 INFO - 'MOZ_NO_REMOTE': '1', 02:51:51 INFO - 'NO_EM_RESTART': '1', 02:51:51 INFO - 'PAGER': '/bin/cat', 02:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:51 INFO - 'PWD': '/builds/slave/test', 02:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:51 INFO - 'SHELL': '/bin/bash', 02:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:51:51 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:51 INFO - 'USER': 'cltbld', 02:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:51 INFO - 'XPC_FLAGS': '0x0', 02:51:51 INFO - 'XPC_SERVICE_NAME': '0', 02:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:51 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:54 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:51:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:51:54 INFO - Installing collected packages: mozsystemmonitor 02:51:54 INFO - Running setup.py install for mozsystemmonitor 02:51:54 INFO - Successfully installed mozsystemmonitor 02:51:54 INFO - Cleaning up... 02:51:54 INFO - Return code: 0 02:51:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:51:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:54 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:54 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 02:51:54 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 02:51:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:51:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:54 INFO - 'HOME': '/Users/cltbld', 02:51:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:54 INFO - 'LOGNAME': 'cltbld', 02:51:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:54 INFO - 'MOZ_NO_REMOTE': '1', 02:51:54 INFO - 'NO_EM_RESTART': '1', 02:51:54 INFO - 'PAGER': '/bin/cat', 02:51:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:54 INFO - 'PWD': '/builds/slave/test', 02:51:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:54 INFO - 'SHELL': '/bin/bash', 02:51:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:51:54 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:51:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:54 INFO - 'USER': 'cltbld', 02:51:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:54 INFO - 'XPC_FLAGS': '0x0', 02:51:54 INFO - 'XPC_SERVICE_NAME': '0', 02:51:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:54 INFO - Downloading/unpacking blobuploader==1.2.4 02:51:54 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 02:51:54 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 02:51:54 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 02:51:54 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 02:51:54 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 02:51:54 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 02:51:57 INFO - Downloading blobuploader-1.2.4.tar.gz 02:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:51:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:51:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:51:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:51:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:51:58 INFO - Downloading docopt-0.6.1.tar.gz 02:51:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:51:58 INFO - Installing collected packages: blobuploader, requests, docopt 02:51:58 INFO - Running setup.py install for blobuploader 02:51:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:51:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:51:58 INFO - Running setup.py install for requests 02:51:59 INFO - Running setup.py install for docopt 02:51:59 INFO - Successfully installed blobuploader requests docopt 02:51:59 INFO - Cleaning up... 02:51:59 INFO - Return code: 0 02:51:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:51:59 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')]} 02:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:59 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')]} 02:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:51:59 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:51:59 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 02:51:59 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 02:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:51:59 INFO - 'HOME': '/Users/cltbld', 02:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:51:59 INFO - 'LOGNAME': 'cltbld', 02:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:51:59 INFO - 'MOZ_NO_REMOTE': '1', 02:51:59 INFO - 'NO_EM_RESTART': '1', 02:51:59 INFO - 'PAGER': '/bin/cat', 02:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:51:59 INFO - 'PWD': '/builds/slave/test', 02:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:51:59 INFO - 'SHELL': '/bin/bash', 02:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:51:59 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:51:59 INFO - 'USER': 'cltbld', 02:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:51:59 INFO - 'XPC_FLAGS': '0x0', 02:51:59 INFO - 'XPC_SERVICE_NAME': '0', 02:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:51:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:51:59 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ArTA8S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:51:59 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-fdoEY5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:51:59 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-EyzDUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:51:59 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Gso2t7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:51:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-7phSEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-a37MYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-DBxZyh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-r9E4Bc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ifDGpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-MLigE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-yJw_pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-EPTV7S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-j6ZAMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:00 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-4PRdvl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:01 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Cf6DlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:01 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-QU049M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:01 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-0vkCOL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:52:01 INFO - Running setup.py install for manifestparser 02:52:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:52:01 INFO - Running setup.py install for mozcrash 02:52:01 INFO - Running setup.py install for mozdebug 02:52:01 INFO - Running setup.py install for mozdevice 02:52:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:52:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Running setup.py install for mozfile 02:52:02 INFO - Running setup.py install for mozhttpd 02:52:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Running setup.py install for mozinfo 02:52:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Running setup.py install for mozInstall 02:52:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Running setup.py install for mozleak 02:52:02 INFO - Running setup.py install for mozlog 02:52:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:52:02 INFO - Running setup.py install for moznetwork 02:52:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Running setup.py install for mozprocess 02:52:03 INFO - Running setup.py install for mozprofile 02:52:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Running setup.py install for mozrunner 02:52:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Running setup.py install for mozscreenshot 02:52:03 INFO - Running setup.py install for moztest 02:52:03 INFO - Running setup.py install for mozversion 02:52:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:52:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:52:03 INFO - Cleaning up... 02:52:03 INFO - Return code: 0 02:52:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:03 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:52:03 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 02:52:03 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 02:52:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:52:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:03 INFO - 'HOME': '/Users/cltbld', 02:52:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:03 INFO - 'LOGNAME': 'cltbld', 02:52:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:03 INFO - 'MOZ_NO_REMOTE': '1', 02:52:03 INFO - 'NO_EM_RESTART': '1', 02:52:03 INFO - 'PAGER': '/bin/cat', 02:52:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:03 INFO - 'PWD': '/builds/slave/test', 02:52:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:03 INFO - 'SHELL': '/bin/bash', 02:52:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:52:03 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:52:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:03 INFO - 'USER': 'cltbld', 02:52:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:03 INFO - 'XPC_FLAGS': '0x0', 02:52:03 INFO - 'XPC_SERVICE_NAME': '0', 02:52:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-lEvrng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-twoobF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-ok2DZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-jQIwH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-gymojh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-GmaJt8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Z07y_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:52:04 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)) 02:52:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:52:04 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Hp46WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-zOWmeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-BOWlxX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-8Qoyvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-yJ4ViF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-YZzyeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-WPddwk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-jruiOQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-L3ZOXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:52:05 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)) 02:52:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:52:05 INFO - Running setup.py (path:/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/pip-Cap74m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:52:06 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)) 02:52:06 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)) 02:52:06 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)) 02:52:06 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)) 02:52:06 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)) 02:52:06 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)) 02:52:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:08 INFO - Downloading blessings-1.6.tar.gz 02:52:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:52:08 INFO - Installing collected packages: blessings 02:52:08 INFO - Running setup.py install for blessings 02:52:08 INFO - Successfully installed blessings 02:52:08 INFO - Cleaning up... 02:52:08 INFO - Return code: 0 02:52:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:52:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:09 INFO - Reading from file tmpfile_stdout 02:52:09 INFO - Current package versions: 02:52:09 INFO - blessings == 1.6 02:52:09 INFO - blobuploader == 1.2.4 02:52:09 INFO - docopt == 0.6.1 02:52:09 INFO - manifestparser == 1.1 02:52:09 INFO - mozInstall == 1.12 02:52:09 INFO - mozcrash == 0.17 02:52:09 INFO - mozdebug == 0.1 02:52:09 INFO - mozdevice == 0.48 02:52:09 INFO - mozfile == 1.2 02:52:09 INFO - mozhttpd == 0.7 02:52:09 INFO - mozinfo == 0.9 02:52:09 INFO - mozleak == 0.1 02:52:09 INFO - mozlog == 3.1 02:52:09 INFO - moznetwork == 0.27 02:52:09 INFO - mozprocess == 0.22 02:52:09 INFO - mozprofile == 0.28 02:52:09 INFO - mozrunner == 6.11 02:52:09 INFO - mozscreenshot == 0.1 02:52:09 INFO - mozsystemmonitor == 0.0 02:52:09 INFO - moztest == 0.7 02:52:09 INFO - mozversion == 1.4 02:52:09 INFO - psutil == 3.1.1 02:52:09 INFO - requests == 1.2.3 02:52:09 INFO - wsgiref == 0.1.2 02:52:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:52:09 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')]} 02:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 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')]} 02:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:52:09 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 02:52:09 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 02:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:09 INFO - 'HOME': '/Users/cltbld', 02:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:09 INFO - 'LOGNAME': 'cltbld', 02:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:09 INFO - 'MOZ_NO_REMOTE': '1', 02:52:09 INFO - 'NO_EM_RESTART': '1', 02:52:09 INFO - 'PAGER': '/bin/cat', 02:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:09 INFO - 'PWD': '/builds/slave/test', 02:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:09 INFO - 'SHELL': '/bin/bash', 02:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:52:09 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:09 INFO - 'USER': 'cltbld', 02:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:09 INFO - 'XPC_FLAGS': '0x0', 02:52:09 INFO - 'XPC_SERVICE_NAME': '0', 02:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 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)) 02:52:09 INFO - Cleaning up... 02:52:09 INFO - Return code: 0 02:52:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:52:09 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')]} 02:52:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 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')]} 02:52:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:09 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:52:09 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 02:52:09 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 02:52:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:52:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:09 INFO - 'HOME': '/Users/cltbld', 02:52:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:09 INFO - 'LOGNAME': 'cltbld', 02:52:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:09 INFO - 'MOZ_NO_REMOTE': '1', 02:52:09 INFO - 'NO_EM_RESTART': '1', 02:52:09 INFO - 'PAGER': '/bin/cat', 02:52:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:09 INFO - 'PWD': '/builds/slave/test', 02:52:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:09 INFO - 'SHELL': '/bin/bash', 02:52:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:52:09 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:52:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:09 INFO - 'USER': 'cltbld', 02:52:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:09 INFO - 'XPC_FLAGS': '0x0', 02:52:09 INFO - 'XPC_SERVICE_NAME': '0', 02:52:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:09 INFO - Downloading/unpacking jsonschema 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:09 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 02:52:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:52:12 INFO - Downloading/unpacking functools32 (from jsonschema) 02:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:12 INFO - Downloading functools32-3.2.3-2.tar.gz 02:52:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:52:12 INFO - warning: no files found matching '*.txt' 02:52:12 INFO - no previously-included directories found matching 'build' 02:52:12 INFO - no previously-included directories found matching 'dist' 02:52:12 INFO - no previously-included directories found matching '.git*' 02:52:12 INFO - Installing collected packages: jsonschema, functools32 02:52:12 INFO - Running setup.py install for functools32 02:52:12 INFO - warning: no files found matching '*.txt' 02:52:12 INFO - no previously-included directories found matching 'build' 02:52:12 INFO - no previously-included directories found matching 'dist' 02:52:12 INFO - no previously-included directories found matching '.git*' 02:52:12 INFO - Successfully installed jsonschema functools32 02:52:12 INFO - Cleaning up... 02:52:12 INFO - Return code: 0 02:52:12 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:52:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:52:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:52:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:52:12 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 0x10ec80a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ec90420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe2d9dda490>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, '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.qyWFCxhidE/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.PAY2jbpTyF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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 02:52:12 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 02:52:12 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 02:52:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:52:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:12 INFO - 'HOME': '/Users/cltbld', 02:52:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:12 INFO - 'LOGNAME': 'cltbld', 02:52:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:12 INFO - 'MOZ_NO_REMOTE': '1', 02:52:12 INFO - 'NO_EM_RESTART': '1', 02:52:12 INFO - 'PAGER': '/bin/cat', 02:52:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:12 INFO - 'PWD': '/builds/slave/test', 02:52:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:12 INFO - 'SHELL': '/bin/bash', 02:52:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:52:12 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:52:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:12 INFO - 'USER': 'cltbld', 02:52:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:12 INFO - 'XPC_FLAGS': '0x0', 02:52:12 INFO - 'XPC_SERVICE_NAME': '0', 02:52:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:52:13 INFO - Downloading/unpacking flake8 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:13 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 02:52:15 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:52:15 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52:15 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:52:15 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52: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 02:52:16 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:52:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:52:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:52:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:52:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:52:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:52:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:52:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:52:16 INFO - no previously-included directories found matching 'docs/_build' 02:52:16 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:52:16 INFO - Running setup.py install for pep8 02:52:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:52:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:52:16 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:52:16 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:52:16 INFO - no previously-included directories found matching 'docs/_build' 02:52:17 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:52:17 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:52:17 INFO - Cleaning up... 02:52:17 INFO - Return code: 0 02:52:17 INFO - Running post-action listener: _resource_record_post_action 02:52:17 INFO - Running post-action listener: _start_resource_monitoring 02:52:17 INFO - Starting resource monitoring. 02:52:17 INFO - ##### 02:52:17 INFO - ##### Running install step. 02:52:17 INFO - ##### 02:52:17 INFO - Running pre-action listener: _resource_record_pre_action 02:52:17 INFO - Running main action method: install 02:52:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:52:17 INFO - Reading from file tmpfile_stdout 02:52:17 INFO - Detecting whether we're running mozinstall >=1.0... 02:52:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:52:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:52:17 INFO - Reading from file tmpfile_stdout 02:52:17 INFO - Output received: 02:52:17 INFO - Usage: mozinstall [options] installer 02:52:17 INFO - Options: 02:52:17 INFO - -h, --help show this help message and exit 02:52:17 INFO - -d DEST, --destination=DEST 02:52:17 INFO - Directory to install application into. [default: 02:52:17 INFO - "/builds/slave/test"] 02:52:17 INFO - --app=APP Application being installed. [default: firefox] 02:52:17 INFO - mkdir: /builds/slave/test/build/application 02:52:17 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'] 02:52:17 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 02:52:49 INFO - Reading from file tmpfile_stdout 02:52:49 INFO - Output received: 02:52:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:52:49 INFO - Running post-action listener: _resource_record_post_action 02:52:49 INFO - ##### 02:52:49 INFO - ##### Running run-tests step. 02:52:49 INFO - ##### 02:52:49 INFO - Running pre-action listener: _resource_record_pre_action 02:52:49 INFO - Running main action method: run_tests 02:52:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:52:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:52:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:52:49 INFO - 2016-06-02 02:52:49.407 screenresolution[1644:7462] starting screenresolution argv=screenresolution get 02:52:49 INFO - 2016-06-02 02:52:49.410 screenresolution[1644:7462] Display 0: 1600x1200x32@60 02:52:49 INFO - 2016-06-02 02:52:49.416 screenresolution[1645:7464] starting screenresolution argv=screenresolution list 02:52:49 INFO - Available Modes on Display 0 02:52:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:52:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:52:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:52:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:52:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:52:49 INFO - Intel Iris: 02:52:49 INFO - Chipset Model: Intel Iris 02:52:49 INFO - Type: GPU 02:52:49 INFO - Bus: Built-In 02:52:49 INFO - VRAM (Dynamic, Max): 1536 MB 02:52:49 INFO - Vendor: Intel (0x8086) 02:52:49 INFO - Device ID: 0x0a2e 02:52:49 INFO - Revision ID: 0x0009 02:52:49 INFO - Displays: 02:52:49 INFO - Display: 02:52:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:52:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:52:49 INFO - Display Serial Number: 1600x1200 60 02:52:49 INFO - Main Display: Yes 02:52:49 INFO - Mirror: Off 02:52:49 INFO - Online: Yes 02:52:49 INFO - Rotation: Supported 02:52:49 INFO - Return code: 0 02:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:52:49 INFO - Python 2.7.3 02:52:49 INFO - Return code: 0 02:52:49 INFO - grabbing minidump binary from tooltool 02:52:49 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')]} 02:52:49 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 0x10eb1fe00>, '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 0x10e988db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb1ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:52:49 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 02:52:49 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 02:52:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:52:49 INFO - Return code: 0 02:52:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:52:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:52:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:52:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:52:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:52:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:52:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:52:50 INFO - Return code: 0 02:52:50 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:52:50 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0048 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:52:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qyWFCxhidE/Render', 02:52:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:52:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:52:50 INFO - 'HOME': '/Users/cltbld', 02:52:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:52:50 INFO - 'LOGNAME': 'cltbld', 02:52:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:52:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:52:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:52:50 INFO - 'MOZ_NO_REMOTE': '1', 02:52:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:52:50 INFO - 'NO_EM_RESTART': '1', 02:52:50 INFO - 'PAGER': '/bin/cat', 02:52:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:52:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:52:50 INFO - 'PWD': '/builds/slave/test', 02:52:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:52:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:52:50 INFO - 'SHELL': '/bin/bash', 02:52:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PAY2jbpTyF/Listeners', 02:52:50 INFO - 'TMPDIR': '/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/', 02:52:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:52:50 INFO - 'USER': 'cltbld', 02:52:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:52:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:52:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:52:50 INFO - 'XPC_FLAGS': '0x0', 02:52:50 INFO - 'XPC_SERVICE_NAME': '0', 02:52:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:52:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0048', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:52:51 INFO - mozversion application_buildid: 20160602004012 02:52:51 INFO - mozversion application_changeset: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 02:52:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:52:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:52:51 INFO - mozversion application_name: Firefox 02:52:51 INFO - mozversion application_remotingname: firefox-dev 02:52:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:52:51 INFO - mozversion application_vendor: Mozilla 02:52:51 INFO - mozversion application_version: 48.0a2 02:52:51 INFO - mozversion platform_buildid: 20160602004012 02:52:51 INFO - mozversion platform_changeset: 2d453fe195446be7ee2a26c05bd7deca5e0cd170 02:52:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:52:51 INFO - mozversion platform_version: 48.0a2 02:52:51 INFO - using testdate: 1464861171 02:52:51 INFO - actual date: 1464861171 02:52:51 INFO - starting webserver on 'localhost:49275' 02:52:51 INFO - SUITE-START | Running 2 tests 02:52:51 INFO - TEST-START | dromaeo_css 02:52:51 INFO - operating with platform_type : mac_ 02:52:51 INFO - Initialising browser for dromaeo_css test... 02:52:51 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpnMhIxX/profile http://localhost:49275/getInfo.html) 02:52:54 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 02:52:54 INFO - PROCESS | 1663 | colorDepth:24 02:52:54 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 02:52:54 INFO - PROCESS | 1663 | __metrics 02:52:54 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 02:52:54 INFO - TEST-INFO | 1663: exit 0 02:52:54 INFO - Browser initialized. 02:52:54 INFO - Running cycle 1/1 for dromaeo_css test... 02:52:54 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpnMhIxX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:53:06 INFO - PROCESS | 1667 | RSS: Main: 221233152 02:53:06 INFO - PROCESS | 1667 | 02:53:56 INFO - PROCESS | 1667 | 1464861236547 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] 02:56:07 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 02:56:09 INFO - PROCESS | 1667 | RSS: Main: 231555072 02:56:09 INFO - PROCESS | 1667 | 02:59:11 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 02:59:11 INFO - PROCESS | 1667 | RSS: Main: 243032064 02:59:11 INFO - PROCESS | 1667 | 03:02:13 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:02:14 INFO - PROCESS | 1667 | RSS: Main: 244969472 03:02:14 INFO - PROCESS | 1667 | 03:05:16 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:05:16 INFO - PROCESS | 1667 | RSS: Main: 247492608 03:05:16 INFO - PROCESS | 1667 | 03:08:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:08:19 INFO - PROCESS | 1667 | RSS: Main: 249745408 03:08:19 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:11:22 INFO - PROCESS | 1667 | RSS: Main: 250793984 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | __start_tp_report 03:11:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 03:11:22 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 03:11:22 INFO - PROCESS | 1667 | |i|pagename|runs| 03:11:22 INFO - PROCESS | 1667 | |0;dojo.html;3435.553378060852;3518.208403109299;3547.7516573839807;3576.713862890969;3587.0852932502207;5745.396733343002;5748.338941021784;5773.769049238036;5778.075507918734;5799.565032622554;5793.449622285887;5821.18503409523;5831.4751672349485;5844.678542680162;5860.5311575074;5262.2632831403635;5301.602379821509;5320.973395133019;5374.838754837361;5425.701586412746;5210.895782084356;5328.17413300939;5342.011727830344;5342.893142137155;5366.570674346062;5164.941187056663;5279.1817268323275;5293.6294459387855;5304.310439642861;5306.071437498442;4563.7489938053395;5344;5349.358077030762;5375.569954403638;5423.674579525202;4510.436195475566;5159.948400515985;5315.548178404841;5333.253344531729;5383.784648614051;4561;5540.279763630742;5713.228714123623;5722.971385143048;5827.825165245049;6213.627182369027;6268.529860260499;6296.9370306296805;6322.367763223687;6338.080978258126;6283.403076707751;6289.622622642656;6296.1500197473215;6306.873862522724;6315.526335524854;5328.893422131536;5337.70642614656;5642.830715078554;5702.087665973424;5946.494547963385;5263.815766448156;6298.8425289367215;6350.872982540416;6368.490520758421;6382.489400847921;6233.407826256498;6242.345186055518;6258.217722784652;6258.780942667076;6295.401936815995;5610.18652295423;6189;6270.278917924497;6306.527010474141;6322.9051564226575;6255.030470277115;6282.026285925597;6299.212598425197;6303.212098487689;6326.652034138075;6210.9068363974575;6225;6279.874402512016;6281.277653069864;6330.8100756977365;4832.420109586872;4951.158303088484;4986.725730084808;5028.874278143076;5063.291139240509;4459.821607135743;4477.19410506112;4509.12072145935;4517.480489743722;4537.909241815145;5900.291964964232;5950.226470558886;5955.106733989936;5963.4931030862;5965.433283838029;6184.319724830265;6188.0717892315615;6236.501079913596;6272.247330320391;6283.120363149163;5944.791932282349;5946.70266486674;5949.702514874239;5962.34414214436;6000.039993601003;5428.475758772111;5486.835394938158;5495.835124946258;5498.835034948958;5499.230107784913;6268.49852011838;6382.234131904015;6391.296957334689;6421.165248517663;6450.741970321134;6227.782027629011;6252.781152659635;6252.843678907881;6261.186045814015;6284;6360.39576240275;6417.711202995783;6421.646809425526;6462.870742585028;6471.126397936218;6136.2636483622255;6181.1655426516845;6234.563580549318;6247.937520624736;6253.812385628438;6256.718447669775;6262.655553944577;6269.279032911274;6272.090546870587;6310.6529140895855;6076.72654730547;6086.60437071589;6224.221972253468;6235.875282494416;6245.344238854855;6126.548209435115;6217.595856269328;6269.373062693694;6269.937300627118;6292.339304372975;5940.257467816523;6023.759049637965;6119.755209791737;6122.173506576555;6133.44798968095;6024.939750602438;6150.138980542817;6228.065790131335;6235.46998505114;6242.188515492957;6109.480694141048;6111.786087486917;6112.786052488142;6126.693665316806;6155.599886007395 03:11:22 INFO - PROCESS | 1667 | |1;ext.html;495.7100096443578;524.7061645478531;585.1749033862255;589.6261710215197;612.5742608886824;28336.574951375722;38464.61535384645;38518.42222366667;38616;38683.80658096706;39796.801015994955;40261.39607905884;40382.39426408606;40419.79790101053;40531.797341013254;9364.906350936488;9442.905570944271;9450.716478505656;9460.952695236514;9568.952155239214;9401.811963760721;9482.525873706321;9524.095210954949;9554.235661147126;9624.855627165563;9588.856167157499;9595.424274543515;9696.272779541527;9698.90301096987;9702.466364349957;9577.664781732676;9583.616655333779;9642.035796420301;9694.127528522467;9729.854052189223;9471.14759671626;9477.573509192103;9605.279604029725;9615.711528654152;9638.373505722106;38893.80553097217;39230.21539569193;39393;39513.802430987664;39582.20835583301;3080.4917188663844;3110.5956225690743;3211.727003204707;3225.5000474926424;3236.660150684191;27813.304667383276;28705.85647071772;28774.280642984093;29010.709892901225;29269;2735.8358498489956;2741.6024676421807;2780.721927807203;2797.692253852075;2805.074325472589;470576;475298.99999999657;476789;477387;478059;51487.97024059498;52851.41445756634;61358;61791.6910415445;62456.375436245966;55818.72090639521;66683;69014.30985690079;70510;70732.2926770736;17314.65370692579;17599.20803563817;17755.822441775676;17819;17904.925704457775;13338.866611333957;13342.599722008355;13626.93186534061;13698.726025479435;13746.106503077468;16389.180540972902;16934;19601;19829.60340793176;19833.404997850088;6869.725210991604;8392.496450213006;8420.11588783169;8437.746867593982;8453.577321134042;917.8393781088283;1062.6599488163868;1089.2974031749477;1109.3122264196088;1165.976680466403;30104.253482186235;30392.54411183834;30423;30489.847550762104;30823.921162759194;1056.772793849326;1083.160550573312;1122.854028976228;1206.9879301206875;1217.4704003758393;15430;15464.922675386551;15466.072035677671;15484.303206355517;15790.605234869221;643549;647018;653538;656308;657538;57699.1345129815;59445.70277148586;59701.40298597045;59863.70068149718;59890.40109598936;1481.5925620454245;1485.9405623775144;1493.1265209852359;1499.1230130373635;1504.6689728259764;29725.702742972295;30043.248918777204;30245.790168393018;30364.696353036186;30789.538156927218;52497.737511312196;52727.73636131795;53092.73453632707;53414.73292633512;53539.92920141653;570938;571960;573318;574581;578003;27055.594166087132;27437.58843617387;27451.725482745715;27508.312292192855;27777.583336250376;46335.768321158175;46618.13527458864;46965.76517117392;47083.29375059308;47163.82090447766;38108.61891381162;38490.615093848704;38547.42178867263;38575.807120964215;38767.6123238764;31601.051968440985;37949.051273718374;38278.808605956794;38681.41977870278;38809.80595097006;36253.63746362608;36568.45147322739;36923.26153476969;37490;37904.81047594855;32270;33000.174995625304;33035.504467432525;33255;33540.832295838365;25286.747132529174;25384.873075634503;25480.362990925376;25814;25946.35134121662 03:11:22 INFO - PROCESS | 1667 | |2;jquery.html;401.40792331311303;401.8211895706411;406.4959450281652;410.91781643671266;412.4411422522482;15815;19847;20572.69140962885;20636.99999999998;20756.06597703103;20396.980150992465;20492.385228443134;20679.896600517015;20688.48278793031;21245.25641602544;5700.144978253254;5760.423957604248;5863.325717542494;5966.40335966402;6024.638521688713;4445.73325600465;5610.102383618621;5761.4814666679995;5825.097109947944;5941.346451890288;5749.080147176453;5891.469767720904;5918.615290006136;5936.347001829795;5962.850928726773;5219.947800521984;5823.621464604787;6118.204633397674;6161.845953851167;6182.659953702545;5749.0801471764735;5803.767849286023;5936.1392598073035;5984.401559843981;5994.07091900756;11567.421628918604;13717.314134293245;13904.304784760721;13957;13996.230207338593;2176.216562037665;2280.1221529711015;2409.3013026222548;2510.447701505666;2686.1001564475923;9050;9673.322867399285;10267.43529105899;10276.229282803819;10319.690409287734;1707.1293640243566;1731.9653606927916;1741.912904354777;1768.9469315920544;1834.3579747088545;105984.35039124083;115214.84785152209;116590;117838;121391.78608213893;10147.594096236215;10344.741381465374;11328.376939268255;11599.8260026098;12101.818472722916;13555.66110847217;14307.713845723027;14872.851271487363;15254.00848944815;15321.23393830304;8586.699465518677;8680.91319086801;8800.735977920673;8922.724705200262;8953.149450802164;8778.780530486662;8824.338174636929;8839.425437346681;8883.600237989305;8984.910150898539;3836.232753449321;4592.770361481913;4652.604528615038;4700.764961751899;4707.599854012447;1724.7499112628598;1952.6973319135557;2026.9087891044937;2038.439429156964;2069.6067747128;867.7526904832092;897.2776914583823;978.5694294510392;1021.2953062387078;1102.0742576236;20924.895375523025;22579;22596.774032259797;22825.885870570874;22915.7708422917;953.1850268020743;992.8907820139779;995.8904520502739;1031.3502493429241;1033.7415646088477;8970.327225458117;10757.677269681923;10770.946145269223;10795.730106747395;10802.783944321069;638303;643524;643846;652648;656998;134728;137061;138797.3060134693;139237.30381348231;142364.3725502902;1082.0315817343433;1083.5936523803573;1088.1729885287239;1109.972250693723;1112.866456025266;17848.91075544666;17874.5531361717;17915.835470694365;17930.5517362067;18204.180811863233;45302.54697452983;54004.72997634987;55816.720916395156;57125.143122854024;57579.42420575741;65010;79420.60289698515;79774;82345;85258.1474185275;6120.296165940974;8272.710455134042;8280.668773249003;8396.286315662992;8464.220339067697;9190.678326258549;11721.238119522204;12484.001279897768;12662.493500259887;12737.108402412114;11014.889851101707;11055.61305354341;11074;11189.272697274651;11237.38194399316;10831.891681083089;10895.836562451412;11061.723456913642;11236.719082022688;11264.549418023187;8245.340372770284;9102.499362535124;9578.760530986503;10944.562217511211;11320.09439244875;7632.084149902047;7701.998740163743;8305.709300174465;8444.324454043784;8552.016518100498;10688;10909.39998300101;11000.394978275952;11045.668629941114;11082.894250092457 03:11:22 INFO - PROCESS | 1667 | |3;mootools.html;1583.6278474558476;1600.9519714408564;1856.1832793570827;1930.3629802165278;2012.3158126237076;6638.867222655544;8323.417360784748;8500.702475413362;8675.089115642853;8710.477371357714;8446.366522510822;8662.090480499552;8737.12628737126;8738.868916966234;8758.781030474242;2405.9037638494437;2410.313060777679;2440.170342083691;2442.694663167104;2445.3519817248443;2474.9628755568683;2477.2320580619976;2528.683914510686;2531.999860055277;2540.5681034224135;2449.8530088194675;2461.8030557555444;2472.987635061822;2496.3010357100043;2499.5000999800022;2469.901203951849;2478.7025556933104;2492.264781889347;2502.5620516409554;2502.78726308264;2478.281298423455;2490.1906880263987;2517.144170981875;2534.6324782906377;2546.070684200272;8895.510746908918;8939.68711095115;8959.865602015974;8964.46213227202;8971.282297416255;5393.164059570773;5493.395726470125;5561.443855614406;5566.276384070085;5598.916016259759;3043.9239019024703;3562.1985053363046;3611.1874828163714;3616.5117709109195;3665.688416484602;1050.8791488978788;1073.061071562383;1074.8011617850646;1077.3966578715945;1078.358376765823;9332.02013788556;11226.326420414802;11385.203035787497;11533.192676512648;11549.076073914066;4791.185498465269;4882.316475693406;4920.975395123001;4924.75376231187;4948.529889660476;10414.271001029856;10460.26778125524;10489.737756556147;10493.26547141708;10517.3689578624;5078.492150784966;5198.506141916512;5412.972935135299;5455.454454554513;5543.36251331094;4470.865874023784;4472.843450479283;4913.754312284443;4925.729084900365;4985.227289770091;3593.1915319053005;4095.201435720006;4096.856610018635;4144.244077688303;4258.787060646955;1197.9401029948467;1387.7779555271109;1387.854275301099;1396.9301534923416;1401.4184113592949;922.8707980882682;937.5312343828086;949.2595775295281;950.3917492804611;954.6706386296717;5788;5794.913076303858;5795.362510123883;5886.793962211302;5980.401959803985;856.9742907712779;993.3989936088707;1008.5209525475487;1024.9897501025043;1048.6696690542608;3774.50931378919;3793.886183414502;3821.063839359375;3834.328992426314;3911.647951684362;10617.256792024638;10854.45727713627;10923.508442120125;10999.120070394349;11060.557577696802;10674.359538427563;10836;10854.620088296871;10891.747449043303;10911.454427278604;7919.722809701633;8352.081271060179;8361.456505327134;8521.57392130391;8522.659093636184;5581.944180558305;5707.514861236676;5737.282839645044;5786.710664466676;5825.3883342248455;9392.248620110511;9401.153896149379;9492.95253523728;9514.524273786144;9561.474118923526;9548.140667339694;9637.807243855224;9681.66114186;9712.514374281116;9763.902360976299;6856.965715171392;7317.780466586011;7388.224236455095;7418.96290518544;7515.624218788929;8150.144234855255;8180.345572354317;8180.918190818254;8219.917800821915;8222.712205072794;6241.563090583797;7164.534305270141;7326.853462930818;7414.814629634302;7440.665170067252;7071.222165561681;7110.608916509642;7190.712371505081;7262.01962735024;7348.1182258129365;6918.5502942308585;6952;6995.9855820906705;7005.334493223136;7019.613921234177;4745.786439610157;4747.691400058985;4755.048990202043;4835.734034627989;4945.159322915041;4218.936715949202;4887.095887260868;4968.652194346506;5035.723035233097;5106.719130447713 03:11:22 INFO - PROCESS | 1667 | |4;prototype.html;275.7837934707938;327.6674175711653;328.1517277836792;330.17291684330763;335.92609625882295;6840.58956462612;6844.4866635002445;6850.3492168244065;6859.554128981619;6866.828329291758;6849.178098628172;6854.108965834447;6859.588424694522;6864.656767161647;6878.174619045722;4792.9281060784115;4884;4998.125328067592;5015.22264049073;5024.723640199788;4005.499312585927;4984.75076246188;4999.025190087934;5010.473900240477;5044.722540260284;4940.160172770619;4947.1342515059905;4959.380077490314;4967.875803104933;4986.326845875815;4959.776810043557;4971.279164521142;4986.5761410280165;4996.775145118479;5011.699298042093;4282.015136518618;4294.914101717979;4299.376590394407;4305.806238719234;4308.289132293168;5441.700706461143;6366.0769188467875;6577.177852768404;6627.469802415795;6632.005199220107;5930.851728706773;5972.701364931736;5976.193213916109;5980.43185897339;6013.188219590343;7304.634768261566;7321.853562928712;7327.120745510573;7339.449541284372;7353.264673532604;4816.109019831342;4824.758762061883;4826.1795494765975;4842.2736589511505;4856.3201151838775;7414.406847452245;7738.148803631596;7740.535567865946;7765.728199513046;7770.805729856742;4895.412550493964;4907.9263811042865;4919.926201106986;4930.235813448921;4938.185199442052;4320.956790432118;4634.791434385461;4913.729744864067;4997.050560393586;5019.874503137377;5868.4424979626865;5930.851728706817;5945.851353716192;5955;5983.102534619842;7353.411727061929;7353.4484913631695;7372.926270737331;7380.114386273682;7412;1692.3569043763537;1714.4342367018853;1742.7385892116283;1743.1197245390997;1751.404522462369;1105.1379923659558;1108.6396921000708;1116.6761639124725;1116.6817457024708;1121.4224674292745;6815;6815.114035175396;6837.453003759687;6838.384545390939;6886.621235832077;7323.12122545298;7561.432892532972;7567.356774674105;7587.2412758723685;7597.278258565428;5490.396056433789;6918.8962165567555;6950.8609827804175;6976.825579360557;6981.406580440618;4044.312466880637;4070.5725898780793;4093.0790572121036;4098.897527561775;4101.712880098365;8085.716999904975;8087.757367278873;8087.919120808717;8106;8112.391570632226;7705.1909549496495;7816.765497035098;7982.321502672222;7992.3606111510935;7992.480488768211;6610.504212184105;8031.076426210943;8058.637361318754;8097.433179677484;8126.390520710852;7713.074431068083;7716.961415192888;7716.99679041721;7726.381889448943;7739.458237923292;7572.356349710336;7585.089789225328;7588.165301816906;7608.467407281437;7642.923570764221;6479.708413121327;6579.44709856672;7559.735409260649;8014.519128852287;8078.959605201936;3940.9950462631846;4728.314394412722;4758.095961767253;4773.737444440589;4781.9043619128115;7624.390048795972;7624.618769061413;7631.351335136354;7656.234376562299;7669.079710434784;7341.3392794648735;7376.4836461447185;7376.926230737624;7379.704811807467;7402.666879990521;6034.491377155711;7069.787906362817;7314.926850731425;7354;7364.594947277951;7111.964440177766;7188.460865435009;7190.7483238086415;7193.4604904631315;7198.49610527258;7346.632668366454;7348.889766653611;7357.1907090218965;7363.374113200223;7391.2239214881665;6979.243535582141;7145.249748776511;7188;7196.4962452627815;7201.891971620325 03:11:22 INFO - PROCESS | 1667 | |5;yui.html;640.5900223856731;713.6039498078565;743.3718507860856;762.7978585674797;763.9885401718975;14735.410583576657;15169.924150379233;15170.241487925601;15248.161351125684;16874.240659170337;15512.30194641241;15768.84231157688;15862.84137158628;15931.44239951602;16013.919930400332;726.5241266970143;748.3227678950558;905.5698543191997;924.5053896165552;943.8065196634674;930.9767255818625;935.5789894547447;938.0947385772728;938.9906100939006;939.976500587484;968.3802366485455;978.3298440568198;985.5023213277285;989.114742305636;989.5448093876826;976.3263348289657;977.3207620703614;979.750163708257;980.7057882635216;984.867042949207;724.3082855872628;724.8586525627504;729.6935287179398;732.809469537924;733.8202140475564;15235.085894846234;15243.847561524353;15443.382264709398;15458.768118478232;15540.689186216212;12779.552715655;12867.16363436383;13002.414891329912;13010.349482525931;13135.802962955564;9965.451900145488;10904;10963.342199467977;10964;11311.943440282746;3517.060891801199;3545.769524980868;3545.769524980894;3551.6448355164534;3565.0552603560077;77855;78776;79042.60478697627;79319.6034019832;79433;8307.667693292322;9596.616135354647;9643.324967252225;9907.207423406255;10347.793044139074;22468;22524.662130068064;22675.546489070126;22801.201957931393;22844.200452984067;7299.5620262784405;7342.596157211405;7375.594342311224;7418.517796343221;7485.401167906554;3928.7642741435034;3973.662238709684;3999.4000899865255;4003.719739618257;4007.4189242559664;3573.0710015396185;3577.105723569108;3603.549556305462;3626.673599376016;3646.872359467406;625.7027911741977;637.2862394118616;641.5957946493679;644.9290578036412;647.3364801078932;502.29929248697863;542.9294191755047;566.6458463460255;607.4988134788817;610.7282259394527;6737.326267373223;6878.3465570770695;7939;8283.461574997606;8316.37627177964;523.6910222968467;526.8050821196182;567.4864247855697;591.8076625096861;609.9176611157526;3786.1291902862495;3803.923921521554;3822.961770382316;3853.537575490903;4071.9796401017807;91395;92417;92625.53687231656;93460;93806;73955;74900;75913.72258832245;78360;78547.60726196447;68427.65786171037;68952;69248;69275;69372.95940560896;6473.611583305017;7600.2779735925;7831.8825217622925;7861.960690196513;7981.314381537998;39228.803855980535;49079.773005675146;49804.75097624489;49971;50703.49296507135;60847.391526084175;66100.33899660943;68192.3180768186;69287.65356173187;69686;10296.794064118525;10307.845382319421;10505.189689517229;10535.103681337432;10536.52585633633;9304.767380815534;10462.738431539272;10601.416922069051;10723.678289651323;10920.344779312949;9892.356996795184;10080.395176289447;10188.643397481053;10223.642172523925;11018.008379245906;10036.949815250877;10153;10546.57813687444;10575.62985295511;10682.519286632276;9978.950105249427;10112.84830727525;10119.848202277119;10177.592896284066;10675.306105103455;8631.654733810577;8651.437656552303;8717.288973990735;8878.245349145136;8945.776355591162;8779.253763429924;8918.554072296229;8992.640294388151;9045.819083618158;9128.406653567497 03:11:22 INFO - PROCESS | 1667 | __end_tp_report 03:11:22 INFO - PROCESS | 1667 | __start_cc_report 03:11:22 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,417 03:11:22 INFO - PROCESS | 1667 | __end_cc_report 03:11:22 INFO - PROCESS | 1667 | __startTimestamp1464862282288__endTimestamp 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | ------- Summary: start ------- 03:11:22 INFO - PROCESS | 1667 | Number of tests: 6 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5805.17 Median:6109.48 stddev:645.68 (10.6%) stddev-sans-first:620.33 03:11:22 INFO - PROCESS | 1667 | Values: 3435.6 3518.2 3547.8 3576.7 3587.1 5745.4 5748.3 5773.8 5778.1 5799.6 5793.4 5821.2 5831.5 5844.7 5860.5 5262.3 5301.6 5321.0 5374.8 5425.7 5210.9 5328.2 5342.0 5342.9 5366.6 5164.9 5279.2 5293.6 5304.3 5306.1 4563.7 5344.0 5349.4 5375.6 5423.7 4510.4 5159.9 5315.5 5333.3 5383.8 4561.0 5540.3 5713.2 5723.0 5827.8 6213.6 6268.5 6296.9 6322.4 6338.1 6283.4 6289.6 6296.2 6306.9 6315.5 5328.9 5337.7 5642.8 5702.1 5946.5 5263.8 6298.8 6350.9 6368.5 6382.5 6233.4 6242.3 6258.2 6258.8 6295.4 5610.2 6189.0 6270.3 6306.5 6322.9 6255.0 6282.0 6299.2 6303.2 6326.7 6210.9 6225.0 6279.9 6281.3 6330.8 4832.4 4951.2 4986.7 5028.9 5063.3 4459.8 4477.2 4509.1 4517.5 4537.9 5900.3 5950.2 5955.1 5963.5 5965.4 6184.3 6188.1 6236.5 6272.2 6283.1 5944.8 5946.7 5949.7 5962.3 6000.0 5428.5 5486.8 5495.8 5498.8 5499.2 6268.5 6382.2 6391.3 6421.2 6450.7 6227.8 6252.8 6252.8 6261.2 6284.0 6360.4 6417.7 6421.6 6462.9 6471.1 6136.3 6181.2 6234.6 6247.9 6253.8 6256.7 6262.7 6269.3 6272.1 6310.7 6076.7 6086.6 6224.2 6235.9 6245.3 6126.5 6217.6 6269.4 6269.9 6292.3 5940.3 6023.8 6119.8 6122.2 6133.4 6024.9 6150.1 6228.1 6235.5 6242.2 6109.5 6111.8 6112.8 6126.7 6155.6 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:70008.71 Median:38682.61 stddev:152768.40 (394.9%) stddev-sans-first:153107.81 03:11:22 INFO - PROCESS | 1667 | Values: 495.7 524.7 585.2 589.6 612.6 28336.6 38464.6 38518.4 38616.0 38683.8 39796.8 40261.4 40382.4 40419.8 40531.8 9364.9 9442.9 9450.7 9461.0 9569.0 9401.8 9482.5 9524.1 9554.2 9624.9 9588.9 9595.4 9696.3 9698.9 9702.5 9577.7 9583.6 9642.0 9694.1 9729.9 9471.1 9477.6 9605.3 9615.7 9638.4 38893.8 39230.2 39393.0 39513.8 39582.2 3080.5 3110.6 3211.7 3225.5 3236.7 27813.3 28705.9 28774.3 29010.7 29269.0 2735.8 2741.6 2780.7 2797.7 2805.1 470576.0 475299.0 476789.0 477387.0 478059.0 51488.0 52851.4 61358.0 61791.7 62456.4 55818.7 66683.0 69014.3 70510.0 70732.3 17314.7 17599.2 17755.8 17819.0 17904.9 13338.9 13342.6 13626.9 13698.7 13746.1 16389.2 16934.0 19601.0 19829.6 19833.4 6869.7 8392.5 8420.1 8437.7 8453.6 917.8 1062.7 1089.3 1109.3 1166.0 30104.3 30392.5 30423.0 30489.8 30823.9 1056.8 1083.2 1122.9 1207.0 1217.5 15430.0 15464.9 15466.1 15484.3 15790.6 643549.0 647018.0 653538.0 656308.0 657538.0 57699.1 59445.7 59701.4 59863.7 59890.4 1481.6 1485.9 1493.1 1499.1 1504.7 29725.7 30043.2 30245.8 30364.7 30789.5 52497.7 52727.7 53092.7 53414.7 53539.9 570938.0 571960.0 573318.0 574581.0 578003.0 27055.6 27437.6 27451.7 27508.3 27777.6 46335.8 46618.1 46965.8 47083.3 47163.8 38108.6 38490.6 38547.4 38575.8 38767.6 31601.1 37949.1 38278.8 38681.4 38809.8 36253.6 36568.5 36923.3 37490.0 37904.8 32270.0 33000.2 33035.5 33255.0 33540.8 25286.7 25384.9 25480.4 25814.0 25946.4 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:36246.85 Median:12438.45 stddev:107866.21 (867.2%) stddev-sans-first:108135.23 03:11:22 INFO - PROCESS | 1667 | Values: 401.4 401.8 406.5 410.9 412.4 15815.0 19847.0 20572.7 20637.0 20756.1 20397.0 20492.4 20679.9 20688.5 21245.3 5700.1 5760.4 5863.3 5966.4 6024.6 4445.7 5610.1 5761.5 5825.1 5941.3 5749.1 5891.5 5918.6 5936.3 5962.9 5219.9 5823.6 6118.2 6161.8 6182.7 5749.1 5803.8 5936.1 5984.4 5994.1 11567.4 13717.3 13904.3 13957.0 13996.2 2176.2 2280.1 2409.3 2510.4 2686.1 9050.0 9673.3 10267.4 10276.2 10319.7 1707.1 1732.0 1741.9 1768.9 1834.4 105984.4 115214.8 116590.0 117838.0 121391.8 10147.6 10344.7 11328.4 11599.8 12101.8 13555.7 14307.7 14872.9 15254.0 15321.2 8586.7 8680.9 8800.7 8922.7 8953.1 8778.8 8824.3 8839.4 8883.6 8984.9 3836.2 4592.8 4652.6 4700.8 4707.6 1724.7 1952.7 2026.9 2038.4 2069.6 867.8 897.3 978.6 1021.3 1102.1 20924.9 22579.0 22596.8 22825.9 22915.8 953.2 992.9 995.9 1031.4 1033.7 8970.3 10757.7 10770.9 10795.7 10802.8 638303.0 643524.0 643846.0 652648.0 656998.0 134728.0 137061.0 138797.3 139237.3 142364.4 1082.0 1083.6 1088.2 1110.0 1112.9 17848.9 17874.6 17915.8 17930.6 18204.2 45302.5 54004.7 55816.7 57125.1 57579.4 65010.0 79420.6 79774.0 82345.0 85258.1 6120.3 8272.7 8280.7 8396.3 8464.2 9190.7 11721.2 12484.0 12662.5 12737.1 11014.9 11055.6 11074.0 11189.3 11237.4 10831.9 10895.8 11061.7 11236.7 11264.5 8245.3 9102.5 9578.8 10944.6 11320.1 7632.1 7702.0 8305.7 8444.3 8552.0 10688.0 10909.4 11000.4 11045.7 11082.9 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5692.14 Median:4935.44 stddev:3125.96 (63.3%) stddev-sans-first:3119.48 03:11:22 INFO - PROCESS | 1667 | Values: 1583.6 1601.0 1856.2 1930.4 2012.3 6638.9 8323.4 8500.7 8675.1 8710.5 8446.4 8662.1 8737.1 8738.9 8758.8 2405.9 2410.3 2440.2 2442.7 2445.4 2475.0 2477.2 2528.7 2532.0 2540.6 2449.9 2461.8 2473.0 2496.3 2499.5 2469.9 2478.7 2492.3 2502.6 2502.8 2478.3 2490.2 2517.1 2534.6 2546.1 8895.5 8939.7 8959.9 8964.5 8971.3 5393.2 5493.4 5561.4 5566.3 5598.9 3043.9 3562.2 3611.2 3616.5 3665.7 1050.9 1073.1 1074.8 1077.4 1078.4 9332.0 11226.3 11385.2 11533.2 11549.1 4791.2 4882.3 4921.0 4924.8 4948.5 10414.3 10460.3 10489.7 10493.3 10517.4 5078.5 5198.5 5413.0 5455.5 5543.4 4470.9 4472.8 4913.8 4925.7 4985.2 3593.2 4095.2 4096.9 4144.2 4258.8 1197.9 1387.8 1387.9 1396.9 1401.4 922.9 937.5 949.3 950.4 954.7 5788.0 5794.9 5795.4 5886.8 5980.4 857.0 993.4 1008.5 1025.0 1048.7 3774.5 3793.9 3821.1 3834.3 3911.6 10617.3 10854.5 10923.5 10999.1 11060.6 10674.4 10836.0 10854.6 10891.7 10911.5 7919.7 8352.1 8361.5 8521.6 8522.7 5581.9 5707.5 5737.3 5786.7 5825.4 9392.2 9401.2 9493.0 9514.5 9561.5 9548.1 9637.8 9681.7 9712.5 9763.9 6857.0 7317.8 7388.2 7419.0 7515.6 8150.1 8180.3 8180.9 8219.9 8222.7 6241.6 7164.5 7326.9 7414.8 7440.7 7071.2 7110.6 7190.7 7262.0 7348.1 6918.6 6952.0 6996.0 7005.3 7019.6 4745.8 4747.7 4755.0 4835.7 4945.2 4218.9 4887.1 4968.7 5035.7 5106.7 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:5997.35 Median:6846.83 stddev:1933.88 (28.2%) stddev-sans-first:1891.03 03:11:22 INFO - PROCESS | 1667 | Values: 275.8 327.7 328.2 330.2 335.9 6840.6 6844.5 6850.3 6859.6 6866.8 6849.2 6854.1 6859.6 6864.7 6878.2 4792.9 4884.0 4998.1 5015.2 5024.7 4005.5 4984.8 4999.0 5010.5 5044.7 4940.2 4947.1 4959.4 4967.9 4986.3 4959.8 4971.3 4986.6 4996.8 5011.7 4282.0 4294.9 4299.4 4305.8 4308.3 5441.7 6366.1 6577.2 6627.5 6632.0 5930.9 5972.7 5976.2 5980.4 6013.2 7304.6 7321.9 7327.1 7339.4 7353.3 4816.1 4824.8 4826.2 4842.3 4856.3 7414.4 7738.1 7740.5 7765.7 7770.8 4895.4 4907.9 4919.9 4930.2 4938.2 4321.0 4634.8 4913.7 4997.1 5019.9 5868.4 5930.9 5945.9 5955.0 5983.1 7353.4 7353.4 7372.9 7380.1 7412.0 1692.4 1714.4 1742.7 1743.1 1751.4 1105.1 1108.6 1116.7 1116.7 1121.4 6815.0 6815.1 6837.5 6838.4 6886.6 7323.1 7561.4 7567.4 7587.2 7597.3 5490.4 6918.9 6950.9 6976.8 6981.4 4044.3 4070.6 4093.1 4098.9 4101.7 8085.7 8087.8 8087.9 8106.0 8112.4 7705.2 7816.8 7982.3 7992.4 7992.5 6610.5 8031.1 8058.6 8097.4 8126.4 7713.1 7717.0 7717.0 7726.4 7739.5 7572.4 7585.1 7588.2 7608.5 7642.9 6479.7 6579.4 7559.7 8014.5 8079.0 3941.0 4728.3 4758.1 4773.7 4781.9 7624.4 7624.6 7631.4 7656.2 7669.1 7341.3 7376.5 7376.9 7379.7 7402.7 6034.5 7069.8 7314.9 7354.0 7364.6 7112.0 7188.5 7190.7 7193.5 7198.5 7346.6 7348.9 7357.2 7363.4 7391.2 6979.2 7145.2 7188.0 7196.5 7201.9 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18006.92 Median:641.09 stddev:25384.41 (3959.6%) stddev-sans-first:25422.12 03:11:22 INFO - PROCESS | 1667 | Values: 640.6 713.6 743.4 762.8 764.0 14735.4 15169.9 15170.2 15248.2 16874.2 15512.3 15768.8 15862.8 15931.4 16013.9 726.5 748.3 905.6 924.5 943.8 931.0 935.6 938.1 939.0 940.0 968.4 978.3 985.5 989.1 989.5 976.3 977.3 979.8 980.7 984.9 724.3 724.9 729.7 732.8 733.8 15235.1 15243.8 15443.4 15458.8 15540.7 12779.6 12867.2 13002.4 13010.3 13135.8 9965.5 10904.0 10963.3 10964.0 11311.9 3517.1 3545.8 3545.8 3551.6 3565.1 77855.0 78776.0 79042.6 79319.6 79433.0 8307.7 9596.6 9643.3 9907.2 10347.8 22468.0 22524.7 22675.5 22801.2 22844.2 7299.6 7342.6 7375.6 7418.5 7485.4 3928.8 3973.7 3999.4 4003.7 4007.4 3573.1 3577.1 3603.5 3626.7 3646.9 625.7 637.3 641.6 644.9 647.3 502.3 542.9 566.6 607.5 610.7 6737.3 6878.3 7939.0 8283.5 8316.4 523.7 526.8 567.5 591.8 609.9 3786.1 3803.9 3823.0 3853.5 4072.0 91395.0 92417.0 92625.5 93460.0 93806.0 73955.0 74900.0 75913.7 78360.0 78547.6 68427.7 68952.0 69248.0 69275.0 69373.0 6473.6 7600.3 7831.9 7862.0 7981.3 39228.8 49079.8 49804.8 49971.0 50703.5 60847.4 66100.3 68192.3 69287.7 69686.0 10296.8 10307.8 10505.2 10535.1 10536.5 9304.8 10462.7 10601.4 10723.7 10920.3 9892.4 10080.4 10188.6 10223.6 11018.0 10036.9 10153.0 10546.6 10575.6 10682.5 9979.0 10112.8 10119.8 10177.6 10675.3 8631.7 8651.4 8717.3 8878.2 8945.8 8779.3 8918.6 8992.6 9045.8 9128.4 03:11:22 INFO - PROCESS | 1667 | -------- Summary: end -------- 03:11:22 INFO - PROCESS | 1667 | 03:11:22 INFO - TEST-INFO | 1667: exit 0 03:11:22 INFO - TEST-OK | dromaeo_css | took 1111830ms 03:11:22 INFO - TEST-START | kraken 03:11:22 INFO - operating with platform_type : mac_ 03:11:22 INFO - Initialising browser for kraken test... 03:11:22 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpMRtLZy/profile http://localhost:49275/getInfo.html) 03:11:25 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 03:11:25 INFO - PROCESS | 1679 | colorDepth:24 03:11:25 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 03:11:25 INFO - PROCESS | 1679 | __metrics 03:11:25 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 03:11:26 INFO - TEST-INFO | 1679: exit 0 03:11:26 INFO - Browser initialized. 03:11:26 INFO - Running cycle 1/1 for kraken test... 03:11:26 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/T/tmpMRtLZy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:11:38 INFO - PROCESS | 1683 | RSS: Main: 221388800 03:11:38 INFO - PROCESS | 1683 | 03:11:41 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:11:42 INFO - PROCESS | 1683 | RSS: Main: 233361408 03:11:42 INFO - PROCESS | 1683 | 03:11:45 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:11:45 INFO - PROCESS | 1683 | RSS: Main: 242200576 03:11:45 INFO - PROCESS | 1683 | 03:11:49 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:11:50 INFO - PROCESS | 1683 | RSS: Main: 244969472 03:11:50 INFO - PROCESS | 1683 | 03:11:52 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:11:52 INFO - PROCESS | 1683 | RSS: Main: 247062528 03:11:52 INFO - PROCESS | 1683 | 03:11:54 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:11:55 INFO - PROCESS | 1683 | RSS: Main: 247275520 03:11:55 INFO - PROCESS | 1683 | 03:11:58 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:11:58 INFO - PROCESS | 1683 | RSS: Main: 247746560 03:11:58 INFO - PROCESS | 1683 | 03:12:01 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:12:02 INFO - PROCESS | 1683 | RSS: Main: 248225792 03:12:02 INFO - PROCESS | 1683 | 03:12:05 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:12:05 INFO - PROCESS | 1683 | RSS: Main: 248340480 03:12:05 INFO - PROCESS | 1683 | 03:12:07 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:12:07 INFO - PROCESS | 1683 | RSS: Main: 247361536 03:12:07 INFO - PROCESS | 1683 | 03:12:09 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:12:10 INFO - PROCESS | 1683 | RSS: Main: 247443456 03:12:10 INFO - PROCESS | 1683 | 03:12:12 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:12:13 INFO - PROCESS | 1683 | RSS: Main: 247840768 03:12:13 INFO - PROCESS | 1683 | 03:12:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:12:17 INFO - PROCESS | 1683 | RSS: Main: 248197120 03:12:17 INFO - PROCESS | 1683 | 03:12:20 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:12:20 INFO - PROCESS | 1683 | RSS: Main: 247902208 03:12:20 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:12:22 INFO - PROCESS | 1683 | RSS: Main: 249868288 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | __start_tp_report 03:12:22 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 03:12:22 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 03:12:22 INFO - PROCESS | 1683 | |i|pagename|runs| 03:12:22 INFO - PROCESS | 1683 | |0;ai-astar;118;114;117;117;119;134;140;169;154;117 03:12:22 INFO - PROCESS | 1683 | |1;audio-beat-detection;157;123;148;162;121;143;142;122;178;129 03:12:22 INFO - PROCESS | 1683 | |2;audio-dft;193;198;197;235;248;248;274;226;194;194 03:12:22 INFO - PROCESS | 1683 | |3;audio-fft;65;84;110;190;57;57;56;57;127;57 03:12:22 INFO - PROCESS | 1683 | |4;audio-oscillator;80;82;134;78;77;98;118;81;80;115 03:12:22 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;97;86;89;86;90;86;89;97;118;85 03:12:22 INFO - PROCESS | 1683 | |6;imaging-darkroom;96;97;95;95;97;95;95;96;95;100 03:12:22 INFO - PROCESS | 1683 | |7;imaging-desaturate;93;99;91;91;97;91;91;98;91;97 03:12:22 INFO - PROCESS | 1683 | |8;json-parse-financial;61;65;65;65;67;65;66;80;64;62 03:12:22 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;66;87;100;109;101;101;73;67;65;86 03:12:22 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;95;106;75;107;71;101;101;79;83;108 03:12:22 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;157;152;142;141;160;141;143;129;166;165 03:12:22 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;167;177;165;180;171;171;168;217;215;186 03:12:22 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;69;72;69;78;65;71;67;70;68;68 03:12:22 INFO - PROCESS | 1683 | __end_tp_report 03:12:22 INFO - PROCESS | 1683 | __start_cc_report 03:12:22 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,1107 03:12:22 INFO - PROCESS | 1683 | __end_cc_report 03:12:22 INFO - PROCESS | 1683 | __startTimestamp1464862342617__endTimestamp 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | ------- Summary: start ------- 03:12:22 INFO - PROCESS | 1683 | Number of tests: 14 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:129.90 Median:126.50 stddev:18.98 (15.0%) stddev-sans-first:19.63 03:12:22 INFO - PROCESS | 1683 | Values: 118.0 114.0 117.0 117.0 119.0 134.0 140.0 169.0 154.0 117.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:142.50 Median:145.50 stddev:19.23 (13.2%) stddev-sans-first:19.66 03:12:22 INFO - PROCESS | 1683 | Values: 157.0 123.0 148.0 162.0 121.0 143.0 142.0 122.0 178.0 129.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:220.70 Median:230.50 stddev:29.50 (12.8%) stddev-sans-first:29.54 03:12:22 INFO - PROCESS | 1683 | Values: 193.0 198.0 197.0 235.0 248.0 248.0 274.0 226.0 194.0 194.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:86.00 Median:57.00 stddev:44.45 (78.0%) stddev-sans-first:46.49 03:12:22 INFO - PROCESS | 1683 | Values: 65.0 84.0 110.0 190.0 57.0 57.0 56.0 57.0 127.0 57.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:94.30 Median:80.00 stddev:20.77 (26.0%) stddev-sans-first:21.37 03:12:22 INFO - PROCESS | 1683 | Values: 80.0 82.0 134.0 78.0 77.0 98.0 118.0 81.0 80.0 115.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:92.30 Median:89.00 stddev:10.02 (11.3%) stddev-sans-first:10.49 03:12:22 INFO - PROCESS | 1683 | Values: 97.0 86.0 89.0 86.0 90.0 86.0 89.0 97.0 118.0 85.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:96.10 Median:95.50 stddev:1.60 (1.7%) stddev-sans-first:1.69 03:12:22 INFO - PROCESS | 1683 | Values: 96.0 97.0 95.0 95.0 97.0 95.0 95.0 96.0 95.0 100.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:93.90 Median:95.00 stddev:3.41 (3.6%) stddev-sans-first:3.61 03:12:22 INFO - PROCESS | 1683 | Values: 93.0 99.0 91.0 91.0 97.0 91.0 91.0 98.0 91.0 97.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:66.00 Median:65.00 stddev:5.23 (8.0%) stddev-sans-first:5.22 03:12:22 INFO - PROCESS | 1683 | Values: 61.0 65.0 65.0 65.0 67.0 65.0 66.0 80.0 64.0 62.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:85.50 Median:66.50 stddev:16.81 (25.3%) stddev-sans-first:16.29 03:12:22 INFO - PROCESS | 1683 | Values: 66.0 87.0 100.0 109.0 101.0 101.0 73.0 67.0 65.0 86.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:73.00 stddev:14.24 (19.5%) stddev-sans-first:15.07 03:12:22 INFO - PROCESS | 1683 | Values: 95.0 106.0 75.0 107.0 71.0 101.0 101.0 79.0 83.0 108.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:154.50 stddev:12.24 (7.9%) stddev-sans-first:12.69 03:12:22 INFO - PROCESS | 1683 | Values: 157.0 152.0 142.0 141.0 160.0 141.0 143.0 129.0 166.0 165.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.70 Median:178.50 stddev:19.18 (10.7%) stddev-sans-first:19.59 03:12:22 INFO - PROCESS | 1683 | Values: 167.0 177.0 165.0 180.0 171.0 171.0 168.0 217.0 215.0 186.0 03:12:22 INFO - PROCESS | 1683 | 03:12:22 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:69.50 stddev:3.53 (5.1%) stddev-sans-first:3.73 03:12:22 INFO - PROCESS | 1683 | Values: 69.0 72.0 69.0 78.0 65.0 71.0 67.0 70.0 68.0 68.0 03:12:22 INFO - PROCESS | 1683 | -------- Summary: end -------- 03:12:22 INFO - PROCESS | 1683 | 03:12:23 INFO - TEST-INFO | 1683: exit 0 03:12:23 INFO - TEST-OK | kraken | took 60635ms 03:12:23 INFO - SUITE-END | took 1172s 03:12:23 INFO - Completed test suite (00:19:32) 03:12:23 INFO - javascript benchmark 03:12:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3435.553378060852, 3518.208403109299, 3547.7516573839807, 3576.713862890969, 3587.0852932502207, 5745.396733343002, 5748.338941021784, 5773.769049238036, 5778.075507918734, 5799.565032622554, 5793.449622285887, 5821.18503409523, 5831.4751672349485, 5844.678542680162, 5860.5311575074, 5262.2632831403635, 5301.602379821509, 5320.973395133019, 5374.838754837361, 5425.701586412746, 5210.895782084356, 5328.17413300939, 5342.011727830344, 5342.893142137155, 5366.570674346062, 5164.941187056663, 5279.1817268323275, 5293.6294459387855, 5304.310439642861, 5306.071437498442, 4563.7489938053395, 5344.0, 5349.358077030762, 5375.569954403638, 5423.674579525202, 4510.436195475566, 5159.948400515985, 5315.548178404841, 5333.253344531729, 5383.784648614051, 4561.0, 5540.279763630742, 5713.228714123623, 5722.971385143048, 5827.825165245049, 6213.627182369027, 6268.529860260499, 6296.9370306296805, 6322.367763223687, 6338.080978258126, 6283.403076707751, 6289.622622642656, 6296.1500197473215, 6306.873862522724, 6315.526335524854, 5328.893422131536, 5337.70642614656, 5642.830715078554, 5702.087665973424, 5946.494547963385, 5263.815766448156, 6298.8425289367215, 6350.872982540416, 6368.490520758421, 6382.489400847921, 6233.407826256498, 6242.345186055518, 6258.217722784652, 6258.780942667076, 6295.401936815995, 5610.18652295423, 6189.0, 6270.278917924497, 6306.527010474141, 6322.9051564226575, 6255.030470277115, 6282.026285925597, 6299.212598425197, 6303.212098487689, 6326.652034138075, 6210.9068363974575, 6225.0, 6279.874402512016, 6281.277653069864, 6330.8100756977365, 4832.420109586872, 4951.158303088484, 4986.725730084808, 5028.874278143076, 5063.291139240509, 4459.821607135743, 4477.19410506112, 4509.12072145935, 4517.480489743722, 4537.909241815145, 5900.291964964232, 5950.226470558886, 5955.106733989936, 5963.4931030862, 5965.433283838029, 6184.319724830265, 6188.0717892315615, 6236.501079913596, 6272.247330320391, 6283.120363149163, 5944.791932282349, 5946.70266486674, 5949.702514874239, 5962.34414214436, 6000.039993601003, 5428.475758772111, 5486.835394938158, 5495.835124946258, 5498.835034948958, 5499.230107784913, 6268.49852011838, 6382.234131904015, 6391.296957334689, 6421.165248517663, 6450.741970321134, 6227.782027629011, 6252.781152659635, 6252.843678907881, 6261.186045814015, 6284.0, 6360.39576240275, 6417.711202995783, 6421.646809425526, 6462.870742585028, 6471.126397936218, 6136.2636483622255, 6181.1655426516845, 6234.563580549318, 6247.937520624736, 6253.812385628438, 6256.718447669775, 6262.655553944577, 6269.279032911274, 6272.090546870587, 6310.6529140895855, 6076.72654730547, 6086.60437071589, 6224.221972253468, 6235.875282494416, 6245.344238854855, 6126.548209435115, 6217.595856269328, 6269.373062693694, 6269.937300627118, 6292.339304372975, 5940.257467816523, 6023.759049637965, 6119.755209791737, 6122.173506576555, 6133.44798968095, 6024.939750602438, 6150.138980542817, 6228.065790131335, 6235.46998505114, 6242.188515492957, 6109.480694141048, 6111.786087486917, 6112.786052488142, 6126.693665316806, 6155.599886007395], "value": 5765.838656299696, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.7100096443578, 524.7061645478531, 585.1749033862255, 589.6261710215197, 612.5742608886824, 28336.574951375722, 38464.61535384645, 38518.42222366667, 38616.0, 38683.80658096706, 39796.801015994955, 40261.39607905884, 40382.39426408606, 40419.79790101053, 40531.797341013254, 9364.906350936488, 9442.905570944271, 9450.716478505656, 9460.952695236514, 9568.952155239214, 9401.811963760721, 9482.525873706321, 9524.095210954949, 9554.235661147126, 9624.855627165563, 9588.856167157499, 9595.424274543515, 9696.272779541527, 9698.90301096987, 9702.466364349957, 9577.664781732676, 9583.616655333779, 9642.035796420301, 9694.127528522467, 9729.854052189223, 9471.14759671626, 9477.573509192103, 9605.279604029725, 9615.711528654152, 9638.373505722106, 38893.80553097217, 39230.21539569193, 39393.0, 39513.802430987664, 39582.20835583301, 3080.4917188663844, 3110.5956225690743, 3211.727003204707, 3225.5000474926424, 3236.660150684191, 27813.304667383276, 28705.85647071772, 28774.280642984093, 29010.709892901225, 29269.0, 2735.8358498489956, 2741.6024676421807, 2780.721927807203, 2797.692253852075, 2805.074325472589, 470576.0, 475298.99999999657, 476789.0, 477387.0, 478059.0, 51487.97024059498, 52851.41445756634, 61358.0, 61791.6910415445, 62456.375436245966, 55818.72090639521, 66683.0, 69014.30985690079, 70510.0, 70732.2926770736, 17314.65370692579, 17599.20803563817, 17755.822441775676, 17819.0, 17904.925704457775, 13338.866611333957, 13342.599722008355, 13626.93186534061, 13698.726025479435, 13746.106503077468, 16389.180540972902, 16934.0, 19601.0, 19829.60340793176, 19833.404997850088, 6869.725210991604, 8392.496450213006, 8420.11588783169, 8437.746867593982, 8453.577321134042, 917.8393781088283, 1062.6599488163868, 1089.2974031749477, 1109.3122264196088, 1165.976680466403, 30104.253482186235, 30392.54411183834, 30423.0, 30489.847550762104, 30823.921162759194, 1056.772793849326, 1083.160550573312, 1122.854028976228, 1206.9879301206875, 1217.4704003758393, 15430.0, 15464.922675386551, 15466.072035677671, 15484.303206355517, 15790.605234869221, 643549.0, 647018.0, 653538.0, 656308.0, 657538.0, 57699.1345129815, 59445.70277148586, 59701.40298597045, 59863.70068149718, 59890.40109598936, 1481.5925620454245, 1485.9405623775144, 1493.1265209852359, 1499.1230130373635, 1504.6689728259764, 29725.702742972295, 30043.248918777204, 30245.790168393018, 30364.696353036186, 30789.538156927218, 52497.737511312196, 52727.73636131795, 53092.73453632707, 53414.73292633512, 53539.92920141653, 570938.0, 571960.0, 573318.0, 574581.0, 578003.0, 27055.594166087132, 27437.58843617387, 27451.725482745715, 27508.312292192855, 27777.583336250376, 46335.768321158175, 46618.13527458864, 46965.76517117392, 47083.29375059308, 47163.82090447766, 38108.61891381162, 38490.615093848704, 38547.42178867263, 38575.807120964215, 38767.6123238764, 31601.051968440985, 37949.051273718374, 38278.808605956794, 38681.41977870278, 38809.80595097006, 36253.63746362608, 36568.45147322739, 36923.26153476969, 37490.0, 37904.81047594855, 32270.0, 33000.174995625304, 33035.504467432525, 33255.0, 33540.832295838365, 25286.747132529174, 25384.873075634503, 25480.362990925376, 25814.0, 25946.35134121662], "value": 20059.374763440042, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [401.40792331311303, 401.8211895706411, 406.4959450281652, 410.91781643671266, 412.4411422522482, 15815.0, 19847.0, 20572.69140962885, 20636.99999999998, 20756.06597703103, 20396.980150992465, 20492.385228443134, 20679.896600517015, 20688.48278793031, 21245.25641602544, 5700.144978253254, 5760.423957604248, 5863.325717542494, 5966.40335966402, 6024.638521688713, 4445.73325600465, 5610.102383618621, 5761.4814666679995, 5825.097109947944, 5941.346451890288, 5749.080147176453, 5891.469767720904, 5918.615290006136, 5936.347001829795, 5962.850928726773, 5219.947800521984, 5823.621464604787, 6118.204633397674, 6161.845953851167, 6182.659953702545, 5749.0801471764735, 5803.767849286023, 5936.1392598073035, 5984.401559843981, 5994.07091900756, 11567.421628918604, 13717.314134293245, 13904.304784760721, 13957.0, 13996.230207338593, 2176.216562037665, 2280.1221529711015, 2409.3013026222548, 2510.447701505666, 2686.1001564475923, 9050.0, 9673.322867399285, 10267.43529105899, 10276.229282803819, 10319.690409287734, 1707.1293640243566, 1731.9653606927916, 1741.912904354777, 1768.9469315920544, 1834.3579747088545, 105984.35039124083, 115214.84785152209, 116590.0, 117838.0, 121391.78608213893, 10147.594096236215, 10344.741381465374, 11328.376939268255, 11599.8260026098, 12101.818472722916, 13555.66110847217, 14307.713845723027, 14872.851271487363, 15254.00848944815, 15321.23393830304, 8586.699465518677, 8680.91319086801, 8800.735977920673, 8922.724705200262, 8953.149450802164, 8778.780530486662, 8824.338174636929, 8839.425437346681, 8883.600237989305, 8984.910150898539, 3836.232753449321, 4592.770361481913, 4652.604528615038, 4700.764961751899, 4707.599854012447, 1724.7499112628598, 1952.6973319135557, 2026.9087891044937, 2038.439429156964, 2069.6067747128, 867.7526904832092, 897.2776914583823, 978.5694294510392, 1021.2953062387078, 1102.0742576236, 20924.895375523025, 22579.0, 22596.774032259797, 22825.885870570874, 22915.7708422917, 953.1850268020743, 992.8907820139779, 995.8904520502739, 1031.3502493429241, 1033.7415646088477, 8970.327225458117, 10757.677269681923, 10770.946145269223, 10795.730106747395, 10802.783944321069, 638303.0, 643524.0, 643846.0, 652648.0, 656998.0, 134728.0, 137061.0, 138797.3060134693, 139237.30381348231, 142364.3725502902, 1082.0315817343433, 1083.5936523803573, 1088.1729885287239, 1109.972250693723, 1112.866456025266, 17848.91075544666, 17874.5531361717, 17915.835470694365, 17930.5517362067, 18204.180811863233, 45302.54697452983, 54004.72997634987, 55816.720916395156, 57125.143122854024, 57579.42420575741, 65010.0, 79420.60289698515, 79774.0, 82345.0, 85258.1474185275, 6120.296165940974, 8272.710455134042, 8280.668773249003, 8396.286315662992, 8464.220339067697, 9190.678326258549, 11721.238119522204, 12484.001279897768, 12662.493500259887, 12737.108402412114, 11014.889851101707, 11055.61305354341, 11074.0, 11189.272697274651, 11237.38194399316, 10831.891681083089, 10895.836562451412, 11061.723456913642, 11236.719082022688, 11264.549418023187, 8245.340372770284, 9102.499362535124, 9578.760530986503, 10944.562217511211, 11320.09439244875, 7632.084149902047, 7701.998740163743, 8305.709300174465, 8444.324454043784, 8552.016518100498, 10688.0, 10909.39998300101, 11000.394978275952, 11045.668629941114, 11082.894250092457], "value": 9459.362765444108, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1583.6278474558476, 1600.9519714408564, 1856.1832793570827, 1930.3629802165278, 2012.3158126237076, 6638.867222655544, 8323.417360784748, 8500.702475413362, 8675.089115642853, 8710.477371357714, 8446.366522510822, 8662.090480499552, 8737.12628737126, 8738.868916966234, 8758.781030474242, 2405.9037638494437, 2410.313060777679, 2440.170342083691, 2442.694663167104, 2445.3519817248443, 2474.9628755568683, 2477.2320580619976, 2528.683914510686, 2531.999860055277, 2540.5681034224135, 2449.8530088194675, 2461.8030557555444, 2472.987635061822, 2496.3010357100043, 2499.5000999800022, 2469.901203951849, 2478.7025556933104, 2492.264781889347, 2502.5620516409554, 2502.78726308264, 2478.281298423455, 2490.1906880263987, 2517.144170981875, 2534.6324782906377, 2546.070684200272, 8895.510746908918, 8939.68711095115, 8959.865602015974, 8964.46213227202, 8971.282297416255, 5393.164059570773, 5493.395726470125, 5561.443855614406, 5566.276384070085, 5598.916016259759, 3043.9239019024703, 3562.1985053363046, 3611.1874828163714, 3616.5117709109195, 3665.688416484602, 1050.8791488978788, 1073.061071562383, 1074.8011617850646, 1077.3966578715945, 1078.358376765823, 9332.02013788556, 11226.326420414802, 11385.203035787497, 11533.192676512648, 11549.076073914066, 4791.185498465269, 4882.316475693406, 4920.975395123001, 4924.75376231187, 4948.529889660476, 10414.271001029856, 10460.26778125524, 10489.737756556147, 10493.26547141708, 10517.3689578624, 5078.492150784966, 5198.506141916512, 5412.972935135299, 5455.454454554513, 5543.36251331094, 4470.865874023784, 4472.843450479283, 4913.754312284443, 4925.729084900365, 4985.227289770091, 3593.1915319053005, 4095.201435720006, 4096.856610018635, 4144.244077688303, 4258.787060646955, 1197.9401029948467, 1387.7779555271109, 1387.854275301099, 1396.9301534923416, 1401.4184113592949, 922.8707980882682, 937.5312343828086, 949.2595775295281, 950.3917492804611, 954.6706386296717, 5788.0, 5794.913076303858, 5795.362510123883, 5886.793962211302, 5980.401959803985, 856.9742907712779, 993.3989936088707, 1008.5209525475487, 1024.9897501025043, 1048.6696690542608, 3774.50931378919, 3793.886183414502, 3821.063839359375, 3834.328992426314, 3911.647951684362, 10617.256792024638, 10854.45727713627, 10923.508442120125, 10999.120070394349, 11060.557577696802, 10674.359538427563, 10836.0, 10854.620088296871, 10891.747449043303, 10911.454427278604, 7919.722809701633, 8352.081271060179, 8361.456505327134, 8521.57392130391, 8522.659093636184, 5581.944180558305, 5707.514861236676, 5737.282839645044, 5786.710664466676, 5825.3883342248455, 9392.248620110511, 9401.153896149379, 9492.95253523728, 9514.524273786144, 9561.474118923526, 9548.140667339694, 9637.807243855224, 9681.66114186, 9712.514374281116, 9763.902360976299, 6856.965715171392, 7317.780466586011, 7388.224236455095, 7418.96290518544, 7515.624218788929, 8150.144234855255, 8180.345572354317, 8180.918190818254, 8219.917800821915, 8222.712205072794, 6241.563090583797, 7164.534305270141, 7326.853462930818, 7414.814629634302, 7440.665170067252, 7071.222165561681, 7110.608916509642, 7190.712371505081, 7262.01962735024, 7348.1182258129365, 6918.5502942308585, 6952.0, 6995.9855820906705, 7005.334493223136, 7019.613921234177, 4745.786439610157, 4747.691400058985, 4755.048990202043, 4835.734034627989, 4945.159322915041, 4218.936715949202, 4887.095887260868, 4968.652194346506, 5035.723035233097, 5106.719130447713], "value": 4632.7375750937845, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [275.7837934707938, 327.6674175711653, 328.1517277836792, 330.17291684330763, 335.92609625882295, 6840.58956462612, 6844.4866635002445, 6850.3492168244065, 6859.554128981619, 6866.828329291758, 6849.178098628172, 6854.108965834447, 6859.588424694522, 6864.656767161647, 6878.174619045722, 4792.9281060784115, 4884.0, 4998.125328067592, 5015.22264049073, 5024.723640199788, 4005.499312585927, 4984.75076246188, 4999.025190087934, 5010.473900240477, 5044.722540260284, 4940.160172770619, 4947.1342515059905, 4959.380077490314, 4967.875803104933, 4986.326845875815, 4959.776810043557, 4971.279164521142, 4986.5761410280165, 4996.775145118479, 5011.699298042093, 4282.015136518618, 4294.914101717979, 4299.376590394407, 4305.806238719234, 4308.289132293168, 5441.700706461143, 6366.0769188467875, 6577.177852768404, 6627.469802415795, 6632.005199220107, 5930.851728706773, 5972.701364931736, 5976.193213916109, 5980.43185897339, 6013.188219590343, 7304.634768261566, 7321.853562928712, 7327.120745510573, 7339.449541284372, 7353.264673532604, 4816.109019831342, 4824.758762061883, 4826.1795494765975, 4842.2736589511505, 4856.3201151838775, 7414.406847452245, 7738.148803631596, 7740.535567865946, 7765.728199513046, 7770.805729856742, 4895.412550493964, 4907.9263811042865, 4919.926201106986, 4930.235813448921, 4938.185199442052, 4320.956790432118, 4634.791434385461, 4913.729744864067, 4997.050560393586, 5019.874503137377, 5868.4424979626865, 5930.851728706817, 5945.851353716192, 5955.0, 5983.102534619842, 7353.411727061929, 7353.4484913631695, 7372.926270737331, 7380.114386273682, 7412.0, 1692.3569043763537, 1714.4342367018853, 1742.7385892116283, 1743.1197245390997, 1751.404522462369, 1105.1379923659558, 1108.6396921000708, 1116.6761639124725, 1116.6817457024708, 1121.4224674292745, 6815.0, 6815.114035175396, 6837.453003759687, 6838.384545390939, 6886.621235832077, 7323.12122545298, 7561.432892532972, 7567.356774674105, 7587.2412758723685, 7597.278258565428, 5490.396056433789, 6918.8962165567555, 6950.8609827804175, 6976.825579360557, 6981.406580440618, 4044.312466880637, 4070.5725898780793, 4093.0790572121036, 4098.897527561775, 4101.712880098365, 8085.716999904975, 8087.757367278873, 8087.919120808717, 8106.0, 8112.391570632226, 7705.1909549496495, 7816.765497035098, 7982.321502672222, 7992.3606111510935, 7992.480488768211, 6610.504212184105, 8031.076426210943, 8058.637361318754, 8097.433179677484, 8126.390520710852, 7713.074431068083, 7716.961415192888, 7716.99679041721, 7726.381889448943, 7739.458237923292, 7572.356349710336, 7585.089789225328, 7588.165301816906, 7608.467407281437, 7642.923570764221, 6479.708413121327, 6579.44709856672, 7559.735409260649, 8014.519128852287, 8078.959605201936, 3940.9950462631846, 4728.314394412722, 4758.095961767253, 4773.737444440589, 4781.9043619128115, 7624.390048795972, 7624.618769061413, 7631.351335136354, 7656.234376562299, 7669.079710434784, 7341.3392794648735, 7376.4836461447185, 7376.926230737624, 7379.704811807467, 7402.666879990521, 6034.491377155711, 7069.787906362817, 7314.926850731425, 7354.0, 7364.594947277951, 7111.964440177766, 7188.460865435009, 7190.7483238086415, 7193.4604904631315, 7198.49610527258, 7346.632668366454, 7348.889766653611, 7357.1907090218965, 7363.374113200223, 7391.2239214881665, 6979.243535582141, 7145.249748776511, 7188.0, 7196.4962452627815, 7201.891971620325], "value": 5344.4560633129, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.5900223856731, 713.6039498078565, 743.3718507860856, 762.7978585674797, 763.9885401718975, 14735.410583576657, 15169.924150379233, 15170.241487925601, 15248.161351125684, 16874.240659170337, 15512.30194641241, 15768.84231157688, 15862.84137158628, 15931.44239951602, 16013.919930400332, 726.5241266970143, 748.3227678950558, 905.5698543191997, 924.5053896165552, 943.8065196634674, 930.9767255818625, 935.5789894547447, 938.0947385772728, 938.9906100939006, 939.976500587484, 968.3802366485455, 978.3298440568198, 985.5023213277285, 989.114742305636, 989.5448093876826, 976.3263348289657, 977.3207620703614, 979.750163708257, 980.7057882635216, 984.867042949207, 724.3082855872628, 724.8586525627504, 729.6935287179398, 732.809469537924, 733.8202140475564, 15235.085894846234, 15243.847561524353, 15443.382264709398, 15458.768118478232, 15540.689186216212, 12779.552715655, 12867.16363436383, 13002.414891329912, 13010.349482525931, 13135.802962955564, 9965.451900145488, 10904.0, 10963.342199467977, 10964.0, 11311.943440282746, 3517.060891801199, 3545.769524980868, 3545.769524980894, 3551.6448355164534, 3565.0552603560077, 77855.0, 78776.0, 79042.60478697627, 79319.6034019832, 79433.0, 8307.667693292322, 9596.616135354647, 9643.324967252225, 9907.207423406255, 10347.793044139074, 22468.0, 22524.662130068064, 22675.546489070126, 22801.201957931393, 22844.200452984067, 7299.5620262784405, 7342.596157211405, 7375.594342311224, 7418.517796343221, 7485.401167906554, 3928.7642741435034, 3973.662238709684, 3999.4000899865255, 4003.719739618257, 4007.4189242559664, 3573.0710015396185, 3577.105723569108, 3603.549556305462, 3626.673599376016, 3646.872359467406, 625.7027911741977, 637.2862394118616, 641.5957946493679, 644.9290578036412, 647.3364801078932, 502.29929248697863, 542.9294191755047, 566.6458463460255, 607.4988134788817, 610.7282259394527, 6737.326267373223, 6878.3465570770695, 7939.0, 8283.461574997606, 8316.37627177964, 523.6910222968467, 526.8050821196182, 567.4864247855697, 591.8076625096861, 609.9176611157526, 3786.1291902862495, 3803.923921521554, 3822.961770382316, 3853.537575490903, 4071.9796401017807, 91395.0, 92417.0, 92625.53687231656, 93460.0, 93806.0, 73955.0, 74900.0, 75913.72258832245, 78360.0, 78547.60726196447, 68427.65786171037, 68952.0, 69248.0, 69275.0, 69372.95940560896, 6473.611583305017, 7600.2779735925, 7831.8825217622925, 7861.960690196513, 7981.314381537998, 39228.803855980535, 49079.773005675146, 49804.75097624489, 49971.0, 50703.49296507135, 60847.391526084175, 66100.33899660943, 68192.3180768186, 69287.65356173187, 69686.0, 10296.794064118525, 10307.845382319421, 10505.189689517229, 10535.103681337432, 10536.52585633633, 9304.767380815534, 10462.738431539272, 10601.416922069051, 10723.678289651323, 10920.344779312949, 9892.356996795184, 10080.395176289447, 10188.643397481053, 10223.642172523925, 11018.008379245906, 10036.949815250877, 10153.0, 10546.57813687444, 10575.62985295511, 10682.519286632276, 9978.950105249427, 10112.84830727525, 10119.848202277119, 10177.592896284066, 10675.306105103455, 8631.654733810577, 8651.437656552303, 8717.288973990735, 8878.245349145136, 8945.776355591162, 8779.253763429924, 8918.554072296229, 8992.640294388151, 9045.819083618158, 9128.406653567497], "value": 6796.162090501964, "unit": "score"}], "name": "dromaeo_css", "value": 7542.474207675396, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 114.0, 117.0, 117.0, 119.0, 134.0, 140.0, 169.0, 154.0, 117.0], "value": 129.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 148.0, 162.0, 121.0, 143.0, 142.0, 122.0, 178.0, 129.0], "value": 142.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [193.0, 198.0, 197.0, 235.0, 248.0, 248.0, 274.0, 226.0, 194.0, 194.0], "value": 220.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 84.0, 110.0, 190.0, 57.0, 57.0, 56.0, 57.0, 127.0, 57.0], "value": 86.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 82.0, 134.0, 78.0, 77.0, 98.0, 118.0, 81.0, 80.0, 115.0], "value": 94.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 86.0, 89.0, 86.0, 90.0, 86.0, 89.0, 97.0, 118.0, 85.0], "value": 92.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 95.0, 97.0, 95.0, 95.0, 96.0, 95.0, 100.0], "value": 96.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 99.0, 91.0, 91.0, 97.0, 91.0, 91.0, 98.0, 91.0, 97.0], "value": 93.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 65.0, 65.0, 67.0, 65.0, 66.0, 80.0, 64.0, 62.0], "value": 66.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 87.0, 100.0, 109.0, 101.0, 101.0, 73.0, 67.0, 65.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 106.0, 75.0, 107.0, 71.0, 101.0, 101.0, 79.0, 83.0, 108.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 152.0, 142.0, 141.0, 160.0, 141.0, 143.0, 129.0, 166.0, 165.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 177.0, 165.0, 180.0, 171.0, 171.0, 168.0, 217.0, 215.0, 186.0], "value": 181.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 69.0, 78.0, 65.0, 71.0, 67.0, 70.0, 68.0, 68.0], "value": 69.7, "unit": "score"}], "name": "kraken", "value": 1600.7999999999997, "alertThreshold": 2.0}]} 03:12:23 INFO - Return code: 0 03:12:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:12:23 ERROR - # TBPL SUCCESS # 03:12:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:12:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:12:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:12:23 INFO - 2016-06-02 03:12:23.952 screenresolution[1688:10458] starting screenresolution argv=screenresolution get 03:12:23 INFO - 2016-06-02 03:12:23.955 screenresolution[1688:10458] Display 0: 1600x1200x32@60 03:12:23 INFO - 2016-06-02 03:12:23.961 screenresolution[1689:10461] starting screenresolution argv=screenresolution list 03:12:23 INFO - Available Modes on Display 0 03:12:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:12:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:12:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:12:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:12:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:12:24 INFO - Intel Iris: 03:12:24 INFO - Chipset Model: Intel Iris 03:12:24 INFO - Type: GPU 03:12:24 INFO - Bus: Built-In 03:12:24 INFO - VRAM (Dynamic, Max): 1536 MB 03:12:24 INFO - Vendor: Intel (0x8086) 03:12:24 INFO - Device ID: 0x0a2e 03:12:24 INFO - Revision ID: 0x0009 03:12:24 INFO - Displays: 03:12:24 INFO - Display: 03:12:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:12:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:12:24 INFO - Display Serial Number: 1600x1200 60 03:12:24 INFO - Main Display: Yes 03:12:24 INFO - Mirror: Off 03:12:24 INFO - Online: Yes 03:12:24 INFO - Rotation: Supported 03:12:24 INFO - Return code: 0 03:12:24 INFO - Running post-action listener: _resource_record_post_action 03:12:24 INFO - Running post-run listener: _resource_record_post_run 03:12:25 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 38077952; Write bytes: 656025088; Read time: 384; Write time: 1801 03:12:25 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 353346048; Write bytes: 347817472; Read time: 27548; Write time: 633 03:12:25 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 36247040; Write bytes: 298463232; Read time: 363; Write time: 1135 03:12:25 INFO - Running post-run listener: _upload_blobber_files 03:12:25 INFO - Blob upload gear active. 03:12:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:12:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:12:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:12:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:12:25 INFO - (blobuploader) - INFO - Open directory for files ... 03:12:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:12:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:12:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:26 INFO - (blobuploader) - INFO - Done attempting. 03:12:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:12:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:12:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:12:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:12:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:12:27 INFO - (blobuploader) - INFO - Done attempting. 03:12:27 INFO - (blobuploader) - INFO - Iteration through files over. 03:12:27 INFO - Return code: 0 03:12:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:12:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:12:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3c70b5443e76a86354abf176e230e78d2b33296b841a0dda90e95fb6e08e4f3a68424d1d5be7a0daee25348eeb1acc8fb1d36c2f686353eafdb61121de2fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7adaf023a8a43e299149ed233fc2295893f17e50308361f1a4d63e8da79ec9e33f1f7c0a27df28cc81bdb1a05418d5f91552a7814bf203dad02bcd98ee88eed9"} 03:12:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:12:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:12:27 INFO - Contents: 03:12:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3c70b5443e76a86354abf176e230e78d2b33296b841a0dda90e95fb6e08e4f3a68424d1d5be7a0daee25348eeb1acc8fb1d36c2f686353eafdb61121de2fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7adaf023a8a43e299149ed233fc2295893f17e50308361f1a4d63e8da79ec9e33f1f7c0a27df28cc81bdb1a05418d5f91552a7814bf203dad02bcd98ee88eed9"} 03:12:27 INFO - Running post-run listener: copy_logs_to_upload_dir 03:12:27 INFO - Copying logs to upload dir... 03:12:27 INFO - mkdir: /builds/slave/test/build/upload/logs 03:12:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.874491 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-06-02 03:12:27.966100) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 03:12:27.969898) ========= 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.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3c70b5443e76a86354abf176e230e78d2b33296b841a0dda90e95fb6e08e4f3a68424d1d5be7a0daee25348eeb1acc8fb1d36c2f686353eafdb61121de2fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7adaf023a8a43e299149ed233fc2295893f17e50308361f1a4d63e8da79ec9e33f1f7c0a27df28cc81bdb1a05418d5f91552a7814bf203dad02bcd98ee88eed9"} build_url:https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010188 build_url: 'https://queue.taskcluster.net/v1/task/QIt0NV0ESjSORtFTRg2HtQ/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e3c70b5443e76a86354abf176e230e78d2b33296b841a0dda90e95fb6e08e4f3a68424d1d5be7a0daee25348eeb1acc8fb1d36c2f686353eafdb61121de2fe5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7adaf023a8a43e299149ed233fc2295893f17e50308361f1a4d63e8da79ec9e33f1f7c0a27df28cc81bdb1a05418d5f91552a7814bf203dad02bcd98ee88eed9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 03:12:28.000752) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 03:12:28.001141) ========= 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.qyWFCxhidE/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.PAY2jbpTyF/Listeners TMPDIR=/var/folders/yb/6_0vgb8j0qs3lyb34nncdvy400000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 03:12:28.049828) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 03:12:28.050274) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 03:12:28.050717) ========= ========= Total master_lag: 0.19 =========