builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0076 starttime: 1461018214.46 results: success (0) buildid: 20160418142321 builduid: f60096c778d045cfae100ba6381db718 revision: dadd3d52a252edd85975ff73ac404325d28598b2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.456155) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.456583) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.456869) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.474279) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.474546) ========= 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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-18 15:23:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-04-18 15:23:34 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073500 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.564572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.564898) ========= 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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.020635 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:34.613386) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 15:23:34.613744) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev dadd3d52a252edd85975ff73ac404325d28598b2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev dadd3d52a252edd85975ff73ac404325d28598b2 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-18 15:23:34,698 truncating revision to first 12 chars 2016-04-18 15:23:34,698 Setting DEBUG logging. 2016-04-18 15:23:34,698 attempt 1/10 2016-04-18 15:23:34,698 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/dadd3d52a252?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 15:23:35,439 unpacking tar archive at: fx-team-dadd3d52a252/testing/mozharness/ program finished with exit code 0 elapsedTime=0.989631 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-18 15:23:35.620241) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:35.620577) ========= script_repo_revision: dadd3d52a252edd85975ff73ac404325d28598b2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:35.620967) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:35.621229) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 15:23:35.635101) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-18 15:23:35.635376) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:23:35 INFO - MultiFileLogger online at 20160418 15:23:35 in /builds/slave/test 15:23:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 15:23:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:23:35 INFO - {'append_to_log': False, 15:23:35 INFO - 'base_work_dir': '/builds/slave/test', 15:23:35 INFO - 'blob_upload_branch': 'Fx-Team', 15:23:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:23:35 INFO - 'branch': 'Fx-Team', 15:23:35 INFO - 'buildbot_json_path': 'buildprops.json', 15:23:35 INFO - 'config_files': ('talos/mac_config.py',), 15:23:35 INFO - 'default_actions': ('clobber', 15:23:35 INFO - 'read-buildbot-config', 15:23:35 INFO - 'download-and-extract', 15:23:35 INFO - 'populate-webroot', 15:23:35 INFO - 'create-virtualenv', 15:23:35 INFO - 'install', 15:23:35 INFO - 'run-tests'), 15:23:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:23:35 INFO - 'download_minidump_stackwalk': True, 15:23:35 INFO - 'download_symbols': 'ondemand', 15:23:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:23:35 INFO - 'tooltool.py': '/tools/tooltool.py', 15:23:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:23:35 INFO - '/tools/misc-python/virtualenv.py')}, 15:23:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:23:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:23:35 INFO - 'installer_path': 'installer.exe', 15:23:35 INFO - 'log_level': 'info', 15:23:35 INFO - 'log_name': 'talos', 15:23:35 INFO - 'log_to_console': True, 15:23:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:23:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:23:35 INFO - 'opt_config_files': (), 15:23:35 INFO - 'pip_index': False, 15:23:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:35 INFO - 'cmd': ('bash', 15:23:35 INFO - '-c', 15:23:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:35 INFO - 'enabled': True, 15:23:35 INFO - 'halt_on_failure': False, 15:23:35 INFO - 'name': 'check_screen_resolution'},), 15:23:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:23:35 INFO - 'cmd': ('bash', 15:23:35 INFO - '-c', 15:23:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:23:35 INFO - 'enabled': True, 15:23:35 INFO - 'halt_on_failure': False, 15:23:35 INFO - 'name': 'check_screen_resolution'},), 15:23:35 INFO - 'run_cmd_checks_enabled': True, 15:23:35 INFO - 'sps_profile': False, 15:23:35 INFO - 'sps_profile_interval': 0, 15:23:35 INFO - 'suite': 'dromaeojs', 15:23:35 INFO - 'system_bits': '32', 15:23:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:23:35 INFO - 'title': 't-yosemite-r7-0076', 15:23:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:23:35 INFO - 'use_talos_json': True, 15:23:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:23:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:23:35 INFO - 'work_dir': 'build'} 15:23:35 INFO - ##### 15:23:35 INFO - ##### Running clobber step. 15:23:35 INFO - ##### 15:23:35 INFO - Running pre-action listener: _resource_record_pre_action 15:23:35 INFO - Running main action method: clobber 15:23:35 INFO - rmtree: /builds/slave/test/build 15:23:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:23:36 INFO - Running post-action listener: _resource_record_post_action 15:23:36 INFO - ##### 15:23:36 INFO - ##### Running read-buildbot-config step. 15:23:36 INFO - ##### 15:23:36 INFO - Running pre-action listener: _resource_record_pre_action 15:23:36 INFO - Running main action method: read_buildbot_config 15:23:36 INFO - Using buildbot properties: 15:23:36 INFO - { 15:23:36 INFO - "project": "", 15:23:36 INFO - "product": "firefox", 15:23:36 INFO - "script_repo_revision": "production", 15:23:36 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 15:23:36 INFO - "repository": "", 15:23:36 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 15:23:36 INFO - "buildid": "20160418142321", 15:23:36 INFO - "pgo_build": "False", 15:23:36 INFO - "basedir": "/builds/slave/test", 15:23:36 INFO - "buildnumber": 630, 15:23:36 INFO - "slavename": "t-yosemite-r7-0076", 15:23:36 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 15:23:36 INFO - "platform": "yosemite_r7", 15:23:36 INFO - "scheduler": "tests-fx-team-macosx64-talos", 15:23:36 INFO - "branch": "fx-team", 15:23:36 INFO - "repo_path": "integration/fx-team", 15:23:36 INFO - "revision": "dadd3d52a252edd85975ff73ac404325d28598b2", 15:23:36 INFO - "stage_platform": "macosx64", 15:23:36 INFO - "builduid": "f60096c778d045cfae100ba6381db718", 15:23:36 INFO - "slavebuilddir": "test" 15:23:36 INFO - } 15:23:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 15:23:36 INFO - Running post-action listener: _resource_record_post_action 15:23:36 INFO - ##### 15:23:36 INFO - ##### Running download-and-extract step. 15:23:36 INFO - ##### 15:23:36 INFO - Running pre-action listener: _resource_record_pre_action 15:23:36 INFO - Running main action method: download_and_extract 15:23:36 INFO - mkdir: /builds/slave/test/build/tests 15:23: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')]} 15:23:36 INFO - https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:23:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 15:23:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 15:23:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 15:23:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 15:23:38 INFO - Downloaded 1199 bytes. 15:23:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 15:23:38 INFO - Using the following test package requirements: 15:23:38 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 15:23:38 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 15:23:38 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 15:23:38 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'jsshell-mac.zip'], 15:23:38 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 15:23:38 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 15:23:38 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 15:23:38 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 15:23:38 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 15:23:38 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 15:23:38 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 15:23:38 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:23:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:38 INFO - https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:23:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:23:39 INFO - Downloaded 18032040 bytes. 15:23:39 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:23:39 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:23:42 INFO - Return code: 0 15:23:42 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:42 INFO - https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:23:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 15:23:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:23:44 INFO - Downloaded 18032040 bytes. 15:23:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:23:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:23:48 INFO - Return code: 0 15:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:48 INFO - https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:23:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:23:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:23:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 15:23:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:23:48 INFO - Downloaded 10845952 bytes. 15:23:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:23:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:23:49 INFO - Return code: 0 15:23: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')]} 15:23:49 INFO - https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 15:23:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:23:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:23:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 15:23:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 15:23:52 INFO - Downloaded 109962115 bytes. 15:23:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:23:52 INFO - mkdir: /builds/slave/test/properties 15:23:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:23:52 INFO - Writing to file /builds/slave/test/properties/build_url 15:23:52 INFO - Contents: 15:23:52 INFO - build_url:https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 15:23:53 INFO - Running post-action listener: _resource_record_post_action 15:23:53 INFO - Running post-action listener: set_extra_try_arguments 15:23:53 INFO - ##### 15:23:53 INFO - ##### Running populate-webroot step. 15:23:53 INFO - ##### 15:23:53 INFO - Running pre-action listener: _resource_record_pre_action 15:23:53 INFO - Running main action method: populate_webroot 15:23:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:23:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:23:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:23:53 INFO - '--tppagecycles', 15:23:53 INFO - '7'], 15:23:53 INFO - 'tests': ['tsvgm']}}, 15:23:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:23:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:23:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:23:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:23:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:23:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['damp', 'tps']}, 15:23:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'tests': ['damp', 'tps']}, 15:23:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['dromaeo_dom']}, 15:23:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:23:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'other-e10s': {'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['a11yr', 15:23:53 INFO - 'ts_paint', 15:23:53 INFO - 'tpaint', 15:23:53 INFO - 'sessionrestore', 15:23:53 INFO - 'sessionrestore_no_auto_restore', 15:23:53 INFO - 'tabpaint']}, 15:23:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['tsvgx', 15:23:53 INFO - 'tsvgr_opacity', 15:23:53 INFO - 'tart', 15:23:53 INFO - 'tscrollx', 15:23:53 INFO - 'cart']}, 15:23:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:23:53 INFO - 'tsvgr_opacity', 15:23:53 INFO - 'tart', 15:23:53 INFO - 'tscrollx', 15:23:53 INFO - 'cart']}, 15:23:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'talos_options': ['--disable-e10s'], 15:23:53 INFO - 'tests': ['tp5o']}, 15:23:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'tests': ['tp5o']}, 15:23:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'talos_options': ['--disable-e10s', 15:23:53 INFO - '--xperf_path', 15:23:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:53 INFO - 'tests': ['tp5n']}, 15:23:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:23:53 INFO - 'talos_options': ['--xperf_path', 15:23:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:23:53 INFO - 'tests': ['tp5n']}}, 15:23:53 INFO - 'talos.zip': {'path': '', 15:23:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:23:53 INFO - Running post-action listener: _resource_record_post_action 15:23:53 INFO - ##### 15:23:53 INFO - ##### Running create-virtualenv step. 15:23:53 INFO - ##### 15:23:53 INFO - Running pre-action listener: _resource_record_pre_action 15:23:53 INFO - Running main action method: create_virtualenv 15:23:53 INFO - Creating virtualenv /builds/slave/test/build/venv 15:23:53 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 15:23:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:23:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:23:53 INFO - Using real prefix '/tools/python27' 15:23:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:23:54 INFO - Installing distribute.............................................................................................................................................................................................done. 15:23:56 INFO - Installing pip.................done. 15:23:56 INFO - Return code: 0 15:23:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:56 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:23:56 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 15:23:56 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 15:23:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:23:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:56 INFO - 'HOME': '/Users/cltbld', 15:23:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:56 INFO - 'LOGNAME': 'cltbld', 15:23:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:56 INFO - 'MOZ_NO_REMOTE': '1', 15:23:56 INFO - 'NO_EM_RESTART': '1', 15:23:56 INFO - 'PAGER': '/bin/cat', 15:23:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:56 INFO - 'PWD': '/builds/slave/test', 15:23:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:56 INFO - 'SHELL': '/bin/bash', 15:23:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:23:56 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:23:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:56 INFO - 'USER': 'cltbld', 15:23:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:56 INFO - 'XPC_FLAGS': '0x0', 15:23:56 INFO - 'XPC_SERVICE_NAME': '0', 15:23:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:57 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 15:23:57 INFO - Cleaning up... 15:23:57 INFO - Return code: 0 15:23:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:23:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:23:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 15:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 15:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:23:57 INFO - 'HOME': '/Users/cltbld', 15:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:23:57 INFO - 'LOGNAME': 'cltbld', 15:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:23:57 INFO - 'MOZ_NO_REMOTE': '1', 15:23:57 INFO - 'NO_EM_RESTART': '1', 15:23:57 INFO - 'PAGER': '/bin/cat', 15:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:23:57 INFO - 'PWD': '/builds/slave/test', 15:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:23:57 INFO - 'SHELL': '/bin/bash', 15:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:23:57 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:23:57 INFO - 'USER': 'cltbld', 15:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:23:57 INFO - 'XPC_FLAGS': '0x0', 15:23:57 INFO - 'XPC_SERVICE_NAME': '0', 15:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:23:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:23:57 INFO - Downloading/unpacking psutil>=0.7.1 15:23: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 15:23: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 15:23: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 15:23: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 15:23: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 15:23: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 15:24:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:00 INFO - Installing collected packages: psutil 15:24:00 INFO - Running setup.py install for psutil 15:24:00 INFO - building 'psutil._psutil_osx' extension 15:24:00 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 15:24:00 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 15:24:00 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 15:24:00 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 15:24:00 INFO - building 'psutil._psutil_posix' extension 15:24:00 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 15:24:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:24:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:24:00 INFO - ^ 15:24:00 INFO - 1 warning generated. 15:24:00 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 15:24:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:00 INFO - Successfully installed psutil 15:24:00 INFO - Cleaning up... 15:24:01 INFO - Return code: 0 15:24:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:24:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:24:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:01 INFO - 'HOME': '/Users/cltbld', 15:24:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:01 INFO - 'LOGNAME': 'cltbld', 15:24:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:01 INFO - 'MOZ_NO_REMOTE': '1', 15:24:01 INFO - 'NO_EM_RESTART': '1', 15:24:01 INFO - 'PAGER': '/bin/cat', 15:24:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:01 INFO - 'PWD': '/builds/slave/test', 15:24:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:01 INFO - 'SHELL': '/bin/bash', 15:24:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:01 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:01 INFO - 'USER': 'cltbld', 15:24:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:01 INFO - 'XPC_FLAGS': '0x0', 15:24:01 INFO - 'XPC_SERVICE_NAME': '0', 15:24:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:03 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:24:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:24:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:24:03 INFO - Installing collected packages: mozsystemmonitor 15:24:03 INFO - Running setup.py install for mozsystemmonitor 15:24:03 INFO - Successfully installed mozsystemmonitor 15:24:03 INFO - Cleaning up... 15:24:04 INFO - Return code: 0 15:24:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:24:04 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')]} 15:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:04 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')]} 15:24:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:04 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:04 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 15:24:04 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 15:24:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:04 INFO - 'HOME': '/Users/cltbld', 15:24:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:04 INFO - 'LOGNAME': 'cltbld', 15:24:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:04 INFO - 'MOZ_NO_REMOTE': '1', 15:24:04 INFO - 'NO_EM_RESTART': '1', 15:24:04 INFO - 'PAGER': '/bin/cat', 15:24:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:04 INFO - 'PWD': '/builds/slave/test', 15:24:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:04 INFO - 'SHELL': '/bin/bash', 15:24:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:04 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:04 INFO - 'USER': 'cltbld', 15:24:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:04 INFO - 'XPC_FLAGS': '0x0', 15:24:04 INFO - 'XPC_SERVICE_NAME': '0', 15:24:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:04 INFO - Downloading/unpacking blobuploader==1.2.4 15:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:06 INFO - Downloading blobuploader-1.2.4.tar.gz 15:24:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:24:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:24:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:07 INFO - Downloading docopt-0.6.1.tar.gz 15:24:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:24:07 INFO - Installing collected packages: blobuploader, requests, docopt 15:24:07 INFO - Running setup.py install for blobuploader 15:24:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:24:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:24:08 INFO - Running setup.py install for requests 15:24:08 INFO - Running setup.py install for docopt 15:24:08 INFO - Successfully installed blobuploader requests docopt 15:24:08 INFO - Cleaning up... 15:24:08 INFO - Return code: 0 15:24:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:08 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')]} 15:24:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:08 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')]} 15:24:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:08 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:08 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 15:24:08 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 15:24:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:08 INFO - 'HOME': '/Users/cltbld', 15:24:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:08 INFO - 'LOGNAME': 'cltbld', 15:24:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:08 INFO - 'MOZ_NO_REMOTE': '1', 15:24:08 INFO - 'NO_EM_RESTART': '1', 15:24:08 INFO - 'PAGER': '/bin/cat', 15:24:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:08 INFO - 'PWD': '/builds/slave/test', 15:24:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:08 INFO - 'SHELL': '/bin/bash', 15:24:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:08 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:08 INFO - 'USER': 'cltbld', 15:24:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:08 INFO - 'XPC_FLAGS': '0x0', 15:24:08 INFO - 'XPC_SERVICE_NAME': '0', 15:24:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-iZ17sY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-umFJs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-H3TAoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-ABRp2E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-GGU4hQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-hKT5bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Rkel6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:09 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-T9wx92-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-8WPGiI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-3phTYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-ryyn9V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-bI3jtW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-8XWIh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-w5A6Fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-aTrAJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Riu9XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:10 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-Jwg4jJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:24:10 INFO - Running setup.py install for manifestparser 15:24:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:24:11 INFO - Running setup.py install for mozcrash 15:24:11 INFO - Running setup.py install for mozdebug 15:24:11 INFO - Running setup.py install for mozdevice 15:24:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:24:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:24:11 INFO - Running setup.py install for mozfile 15:24:11 INFO - Running setup.py install for mozhttpd 15:24:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:24:11 INFO - Running setup.py install for mozinfo 15:24:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Running setup.py install for mozInstall 15:24:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Running setup.py install for mozleak 15:24:12 INFO - Running setup.py install for mozlog 15:24:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Running setup.py install for moznetwork 15:24:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Running setup.py install for mozprocess 15:24:12 INFO - Running setup.py install for mozprofile 15:24:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:24:12 INFO - Running setup.py install for mozrunner 15:24:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:24:13 INFO - Running setup.py install for mozscreenshot 15:24:13 INFO - Running setup.py install for moztest 15:24:13 INFO - Running setup.py install for mozversion 15:24:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:24:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:24:13 INFO - Cleaning up... 15:24:13 INFO - Return code: 0 15:24:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:13 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:13 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 15:24:13 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 15:24:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:13 INFO - 'HOME': '/Users/cltbld', 15:24:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:13 INFO - 'LOGNAME': 'cltbld', 15:24:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:13 INFO - 'MOZ_NO_REMOTE': '1', 15:24:13 INFO - 'NO_EM_RESTART': '1', 15:24:13 INFO - 'PAGER': '/bin/cat', 15:24:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:13 INFO - 'PWD': '/builds/slave/test', 15:24:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:13 INFO - 'SHELL': '/bin/bash', 15:24:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:13 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:13 INFO - 'USER': 'cltbld', 15:24:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:13 INFO - 'XPC_FLAGS': '0x0', 15:24:13 INFO - 'XPC_SERVICE_NAME': '0', 15:24:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:24:13 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-6gIder-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:24:13 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)) 15:24:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:24:13 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-gVVPCw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-vIyWZo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-fgVfE4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-tWxJEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-0SzJ8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-XxVBr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-UZVYc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-3fAwbR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-mOaKVQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:24:14 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)) 15:24:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:24:14 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-t2uxYu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-WW5Tbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-uV6pae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-FVrvLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-kN1u3n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-xNmYDa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:24:15 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)) 15:24:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:24:15 INFO - Running setup.py (path:/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/pip-lAMjaf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:24:15 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)) 15:24:15 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)) 15:24:15 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)) 15:24:15 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)) 15:24:15 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)) 15:24:15 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)) 15:24:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24: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 15:24:18 INFO - Downloading blessings-1.6.tar.gz 15:24:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:24:18 INFO - Installing collected packages: blessings 15:24:18 INFO - Running setup.py install for blessings 15:24:18 INFO - Successfully installed blessings 15:24:18 INFO - Cleaning up... 15:24:18 INFO - Return code: 0 15:24:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:24:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:18 INFO - Reading from file tmpfile_stdout 15:24:18 INFO - Current package versions: 15:24:18 INFO - blessings == 1.6 15:24:18 INFO - blobuploader == 1.2.4 15:24:18 INFO - docopt == 0.6.1 15:24:18 INFO - manifestparser == 1.1 15:24:18 INFO - mozInstall == 1.12 15:24:18 INFO - mozcrash == 0.17 15:24:18 INFO - mozdebug == 0.1 15:24:18 INFO - mozdevice == 0.48 15:24:18 INFO - mozfile == 1.2 15:24:18 INFO - mozhttpd == 0.7 15:24:18 INFO - mozinfo == 0.9 15:24:18 INFO - mozleak == 0.1 15:24:18 INFO - mozlog == 3.1 15:24:18 INFO - moznetwork == 0.27 15:24:18 INFO - mozprocess == 0.22 15:24:18 INFO - mozprofile == 0.28 15:24:18 INFO - mozrunner == 6.11 15:24:18 INFO - mozscreenshot == 0.1 15:24:18 INFO - mozsystemmonitor == 0.0 15:24:18 INFO - moztest == 0.7 15:24:18 INFO - mozversion == 1.4 15:24:18 INFO - psutil == 3.1.1 15:24:18 INFO - requests == 1.2.3 15:24:18 INFO - wsgiref == 0.1.2 15:24:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:18 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:18 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 15:24:18 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 15:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:18 INFO - 'HOME': '/Users/cltbld', 15:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:18 INFO - 'LOGNAME': 'cltbld', 15:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:18 INFO - 'MOZ_NO_REMOTE': '1', 15:24:18 INFO - 'NO_EM_RESTART': '1', 15:24:18 INFO - 'PAGER': '/bin/cat', 15:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:18 INFO - 'PWD': '/builds/slave/test', 15:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:18 INFO - 'SHELL': '/bin/bash', 15:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:18 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:18 INFO - 'USER': 'cltbld', 15:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:18 INFO - 'XPC_FLAGS': '0x0', 15:24:18 INFO - 'XPC_SERVICE_NAME': '0', 15:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 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)) 15:24:19 INFO - Cleaning up... 15:24:19 INFO - Return code: 0 15:24:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:24:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:24:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:19 INFO - 'HOME': '/Users/cltbld', 15:24:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:19 INFO - 'LOGNAME': 'cltbld', 15:24:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:19 INFO - 'MOZ_NO_REMOTE': '1', 15:24:19 INFO - 'NO_EM_RESTART': '1', 15:24:19 INFO - 'PAGER': '/bin/cat', 15:24:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:19 INFO - 'PWD': '/builds/slave/test', 15:24:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:19 INFO - 'SHELL': '/bin/bash', 15:24:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:19 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:19 INFO - 'USER': 'cltbld', 15:24:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:19 INFO - 'XPC_FLAGS': '0x0', 15:24:19 INFO - 'XPC_SERVICE_NAME': '0', 15:24:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:19 INFO - Downloading/unpacking jsonschema 15:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:24:21 INFO - Downloading/unpacking functools32 (from jsonschema) 15:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - Downloading functools32-3.2.3-2.tar.gz 15:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:24:22 INFO - warning: no files found matching '*.txt' 15:24:22 INFO - no previously-included directories found matching 'build' 15:24:22 INFO - no previously-included directories found matching 'dist' 15:24:22 INFO - no previously-included directories found matching '.git*' 15:24:22 INFO - Installing collected packages: jsonschema, functools32 15:24:22 INFO - Running setup.py install for functools32 15:24:22 INFO - warning: no files found matching '*.txt' 15:24:22 INFO - no previously-included directories found matching 'build' 15:24:22 INFO - no previously-included directories found matching 'dist' 15:24:22 INFO - no previously-included directories found matching '.git*' 15:24:22 INFO - Successfully installed jsonschema functools32 15:24:22 INFO - Cleaning up... 15:24:22 INFO - Return code: 0 15:24:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:22 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 0x100db2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100dc2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe99957be20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, '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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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 15:24:22 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 15:24:22 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 15:24:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:24:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:22 INFO - 'HOME': '/Users/cltbld', 15:24:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:22 INFO - 'LOGNAME': 'cltbld', 15:24:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:22 INFO - 'MOZ_NO_REMOTE': '1', 15:24:22 INFO - 'NO_EM_RESTART': '1', 15:24:22 INFO - 'PAGER': '/bin/cat', 15:24:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:22 INFO - 'PWD': '/builds/slave/test', 15:24:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:22 INFO - 'SHELL': '/bin/bash', 15:24:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:24:22 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:24:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:22 INFO - 'USER': 'cltbld', 15:24:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:22 INFO - 'XPC_FLAGS': '0x0', 15:24:22 INFO - 'XPC_SERVICE_NAME': '0', 15:24:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:22 INFO - Downloading/unpacking flake8 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:24:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:24:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:25 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 15:24:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:24:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:24:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:24:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:24:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:24:26 INFO - no previously-included directories found matching 'docs/_build' 15:24:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:24:26 INFO - Running setup.py install for pep8 15:24:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:24:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:24:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:24:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:24:26 INFO - no previously-included directories found matching 'docs/_build' 15:24:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:24:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:24:26 INFO - Cleaning up... 15:24:26 INFO - Return code: 0 15:24:26 INFO - Running post-action listener: _resource_record_post_action 15:24:26 INFO - Running post-action listener: _start_resource_monitoring 15:24:26 INFO - Starting resource monitoring. 15:24:26 INFO - ##### 15:24:26 INFO - ##### Running install step. 15:24:26 INFO - ##### 15:24:26 INFO - Running pre-action listener: _resource_record_pre_action 15:24:26 INFO - Running main action method: install 15:24:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:24:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:24:27 INFO - Reading from file tmpfile_stdout 15:24:27 INFO - Detecting whether we're running mozinstall >=1.0... 15:24:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:24:27 INFO - Reading from file tmpfile_stdout 15:24:27 INFO - Output received: 15:24:27 INFO - Usage: mozinstall [options] installer 15:24:27 INFO - Options: 15:24:27 INFO - -h, --help show this help message and exit 15:24:27 INFO - -d DEST, --destination=DEST 15:24:27 INFO - Directory to install application into. [default: 15:24:27 INFO - "/builds/slave/test"] 15:24:27 INFO - --app=APP Application being installed. [default: firefox] 15:24:27 INFO - mkdir: /builds/slave/test/build/application 15:24:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:24:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:25:00 INFO - Reading from file tmpfile_stdout 15:25:00 INFO - Output received: 15:25:00 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:25:00 INFO - Running post-action listener: _resource_record_post_action 15:25:00 INFO - ##### 15:25:00 INFO - ##### Running run-tests step. 15:25:00 INFO - ##### 15:25:00 INFO - Running pre-action listener: _resource_record_pre_action 15:25:00 INFO - Running main action method: run_tests 15:25:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:25:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:25:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:25:00 INFO - 2016-04-18 15:25:00.643 screenresolution[6551:106358] starting screenresolution argv=screenresolution get 15:25:00 INFO - 2016-04-18 15:25:00.646 screenresolution[6551:106358] Display 0: 1600x1200x32@60 15:25:00 INFO - 2016-04-18 15:25:00.652 screenresolution[6552:106360] starting screenresolution argv=screenresolution list 15:25:00 INFO - Available Modes on Display 0 15:25:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:25:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:25:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:25:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:25:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:25:00 INFO - Intel Iris: 15:25:00 INFO - Chipset Model: Intel Iris 15:25:00 INFO - Type: GPU 15:25:00 INFO - Bus: Built-In 15:25:00 INFO - VRAM (Dynamic, Max): 1536 MB 15:25:00 INFO - Vendor: Intel (0x8086) 15:25:00 INFO - Device ID: 0x0a2e 15:25:00 INFO - Revision ID: 0x0009 15:25:00 INFO - Displays: 15:25:00 INFO - Display: 15:25:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:25:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:25:00 INFO - Display Serial Number: 1600x1200 60 15:25:00 INFO - Main Display: Yes 15:25:00 INFO - Mirror: Off 15:25:00 INFO - Online: Yes 15:25:00 INFO - Rotation: Supported 15:25:00 INFO - Return code: 0 15:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:25:00 INFO - Python 2.7.3 15:25:00 INFO - Return code: 0 15:25:00 INFO - grabbing minidump binary from tooltool 15:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:25:00 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 0x100c51e00>, '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 0x100a7adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100c4cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:25:00 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 15:25:00 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 15:25:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:25:00 INFO - Return code: 0 15:25:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:25:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:25:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:25:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:25:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:25:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:25:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:25:02 INFO - Return code: 0 15:25:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:25:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0076 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:25:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.JP4QOpLSod/Render', 15:25:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:02 INFO - 'HOME': '/Users/cltbld', 15:25:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:02 INFO - 'LOGNAME': 'cltbld', 15:25:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:25:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:25:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:02 INFO - 'MOZ_NO_REMOTE': '1', 15:25:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:25:02 INFO - 'NO_EM_RESTART': '1', 15:25:02 INFO - 'PAGER': '/bin/cat', 15:25:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:02 INFO - 'PWD': '/builds/slave/test', 15:25:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:25:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:02 INFO - 'SHELL': '/bin/bash', 15:25:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ey9bnmmxnL/Listeners', 15:25:02 INFO - 'TMPDIR': '/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/', 15:25:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:02 INFO - 'USER': 'cltbld', 15:25:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:02 INFO - 'XPC_FLAGS': '0x0', 15:25:02 INFO - 'XPC_SERVICE_NAME': '0', 15:25:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0076', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:25:02 INFO - mozversion application_buildid: 20160418142321 15:25:02 INFO - mozversion application_changeset: dadd3d52a252edd85975ff73ac404325d28598b2 15:25:02 INFO - mozversion application_display_name: Nightly 15:25:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:25:02 INFO - mozversion application_name: Firefox 15:25:02 INFO - mozversion application_remotingname: firefox 15:25:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 15:25:02 INFO - mozversion application_vendor: Mozilla 15:25:02 INFO - mozversion application_version: 48.0a1 15:25:02 INFO - mozversion platform_buildid: 20160418142321 15:25:02 INFO - mozversion platform_changeset: dadd3d52a252edd85975ff73ac404325d28598b2 15:25:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 15:25:02 INFO - mozversion platform_version: 48.0a1 15:25:02 INFO - using testdate: 1461018302 15:25:02 INFO - actual date: 1461018302 15:25:02 INFO - starting webserver on 'localhost:57320' 15:25:02 INFO - SUITE-START | Running 2 tests 15:25:02 INFO - TEST-START | dromaeo_css 15:25:02 INFO - operating with platform_type : mac_ 15:25:02 INFO - Initialising browser for dromaeo_css test... 15:25:02 INFO - TEST-INFO | started process 6570 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmppS_WC2/profile http://localhost:57320/getInfo.html) 15:25:04 INFO - PROCESS | 6570 | __metrics Screen width/height:1600/1200 15:25:04 INFO - PROCESS | 6570 | colorDepth:24 15:25:04 INFO - PROCESS | 6570 | Browser inner width/height: 1024/689 15:25:04 INFO - PROCESS | 6570 | __metrics 15:25:04 INFO - PROCESS | 6570 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:25:04 ERROR - PROCESS | 6570 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:25:05 INFO - TEST-INFO | 6570: exit 0 15:25:05 INFO - Browser initialized. 15:25:05 INFO - Running cycle 1/1 for dromaeo_css test... 15:25:05 INFO - TEST-INFO | started process 6572 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmppS_WC2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:17 INFO - PROCESS | 6572 | RSS: Main: 237527040 15:25:17 INFO - PROCESS | 6572 | 15:26:08 INFO - PROCESS | 6572 | 1461018368312 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] 15:28:20 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-dojo.html (next: http://localhost:57320/tests/dromaeo/cssquery-ext.html) 15:28:21 INFO - PROCESS | 6572 | RSS: Main: 347652096 15:28:21 INFO - PROCESS | 6572 | 15:31:24 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-ext.html (next: http://localhost:57320/tests/dromaeo/cssquery-jquery.html) 15:31:25 INFO - PROCESS | 6572 | RSS: Main: 383139840 15:31:25 INFO - PROCESS | 6572 | 15:34:27 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-jquery.html (next: http://localhost:57320/tests/dromaeo/cssquery-mootools.html) 15:34:27 INFO - PROCESS | 6572 | RSS: Main: 376532992 15:34:27 INFO - PROCESS | 6572 | 15:37:29 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-mootools.html (next: http://localhost:57320/tests/dromaeo/cssquery-prototype.html) 15:37:30 INFO - PROCESS | 6572 | RSS: Main: 374849536 15:37:30 INFO - PROCESS | 6572 | 15:40:33 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-prototype.html (next: http://localhost:57320/tests/dromaeo/cssquery-yui.html) 15:40:34 INFO - PROCESS | 6572 | RSS: Main: 341594112 15:40:34 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | Cycle 1(1): loaded http://localhost:57320/tests/dromaeo/cssquery-yui.html (next: http://localhost:57320/tests/dromaeo/cssquery-dojo.html) 15:43:37 INFO - PROCESS | 6572 | RSS: Main: 393187328 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | __start_tp_report 15:43:37 INFO - PROCESS | 6572 | _x_x_mozilla_page_load 15:43:37 INFO - PROCESS | 6572 | _x_x_mozilla_page_load_details 15:43:37 INFO - PROCESS | 6572 | |i|pagename|runs| 15:43:37 INFO - PROCESS | 6572 | |0;dojo.html;3144.827034513102;3401.897943061709;3428.2457859270953;3430.056734398042;3472.131967008248;5607.383187849333;5672.68800215988;5677.829665110043;5682.318121825377;5684.687342196177;5603.439656034405;5697.145428185774;5700.714964251792;5702.629329093616;5729.226554415152;5135.306733590974;5149.536541711245;5165.199394093921;5184.040952423795;5233.816816411427;5241.842744717665;5243.79024839006;5246.317978662788;5257.185136303879;5258.6318957672975;5153.871153221181;5190.169572868342;5212.582993360541;5225.529702326789;5300.09898317285;5229.163333866563;5255.737213139366;5270.578353731692;5277.340332458442;5311.389190243133;5061.6962982221185;5083.847484575432;5115.2327150927285;5164.070467315882;5191.792328306863;5574.386817450078;5614.326280846284;5623.493885550321;5628.521575666074;5651.886962260732;6246.187995560594;6281.309056003836;6283.8743225135695;6288.056791481269;6296.338884417116;5154.304168937183;6125.9693701531205;6271.184745982993;6295.2760432550385;6324.114623952651;5825.563082768809;5830.4461076197695;5856.238688970449;5858.2677165354335;5861.853453663693;6260.373962603703;6284.183056202664;6290.119383286344;6292.559520833498;6296.244450665949;6032.517398608101;6095.6801602768855;6122.020476723703;6176;6195.752169913243;6250.062490626442;6264.2796078451565;6270.745850829761;6275.246970363494;6301.086342480315;6213;6230.408111229426;6243.875122497525;6269.059641053878;6272.278687950853;6100.633961962208;6111.419415155553;6141.117811339996;6141.907871381933;6284.748610055638;4821.927671084936;4864.854054378375;4869.683470574472;4873.537013983666;4878.682885612424;4400.075984043323;4417.204903117406;4429.136318417877;4443.977780111143;4459.554044595515;5680.857978550486;5681.119426488901;5682.204491371212;5709.3434255060365;5744.540436765049;6181.412765787243;6256.249250089927;6261.530385221126;6263.937360626426;6285.748570057147;5610.326760788732;5677.063284558084;5685.687287199244;5696.487316141567;5714.7428365723645;4630.305454181899;5364.436734142937;5409.107497262908;5469.480399362054;5473.233747275384;6330.0821380899515;6351.936480635134;6357.93642063592;6378.107065010995;6387.808365749035;6107.765388667065;6277.278113016879;6300.873982520232;6314.589551679126;6317.4630156437215;6319.368063193644;6355.90466142999;6395.488360931021;6396;6396.26442959047;6166.845828854314;6175;6185.783497577563;6196.349383314868;6206.348333425106;6113.5979309659515;6178.949578571744;6252.09344645033;6274.560780745486;6280.371962803683;6153.630782153085;6160.137580738789;6164.753409863555;6188.195534580475;6194.721237544231;6072.544559157993;6144.754209831557;6161.722722477589;6173.59871608344;6177.351378105234;5855.38518455556;5957.6127551709;5973.731182096903;6014;6017.729202185825;6045.78839740607;6117.944808963622;6138.109974053648;6155.076738489083;6162.630242185483;5869.442402971711;5980.34216236205;5982;5985.311689155803;6048.485878700184 15:43:37 INFO - PROCESS | 6572 | |1;ext.html;502.87679518517945;503.74812593703126;511.7057691827199;530.2364594983226;575.5798267264896;21052.789472105273;29981.85009074957;30173.24566885829;30176.3964720706;30245;41113.58886411135;41176.79411602945;41341.96645083867;41414;41423.58576414235;8694.043655197924;9007.234385077276;9350.485723285234;9560.952195239013;9563.09150630689;8448.61981210844;8724.8691269631;9474.952625236865;9589.712308630717;9601.75995600112;11467.139964502654;11778.882211177906;11780.823287650692;11781.998530124949;11795.115366347516;11513.424328783569;11653.70865728355;11670.941645291761;11677.824832627517;11682.94158529202;8334.62494187763;11277.266977646426;11290.943545282222;11600.94199528997;11623.418829058513;38316.89257090859;38999.41500877489;39018.80490597529;39031.8048409759;39033.609663903284;3121.0480803354967;3223.564818749462;3312.8840490582716;3377.5102610121394;3574.767640103385;28032.87868485258;28223.435531289466;29123.708762912313;29861.701382986328;29861.850690746625;2723.7548620624043;2733.2620192548097;2746.217328061513;2803.355228297503;2848.5727140929025;442546;445907;448272;448573.75713121553;448716;64427;65252;65325;65361.67319163374;65432;68640.6567967157;68756.65621671859;68845.65577172182;69041.30958690449;69070.963935541;16956.49130526086;17507.94952302866;17514.737278940825;17519.64960700779;17521.211545480484;13137.080404371793;13196.60410187696;13206.07557470987;13250.072494925264;13265.734685306239;14224.85775142256;15622.90639655236;17762.06925857245;18265.726014109798;18422.35521756732;6736.562123461959;6928.688209030605;6932.79201623942;7010.579365238102;7017.193022802341;916.5279880861361;999.7300728803328;1045.811753884308;1074.9140068794477;1141.2638847943197;27460.313492162455;27498.0375686854;27707;28164.01425950123;28286;1032.1381646325253;1075.5643964194514;1103.3269705479652;1139.897409233173;1193.8746431624727;12293.597209539057;14362;14465.722289234629;14786.964912456026;15003.774943375858;586897;593206;599731;599911;602085;62857;62915.6854215726;63283;63356;63524.682376588746;1362.7342668179808;1370.56142034548;1482.7108713800706;1496.4762333183298;1505.5558610210014;22540.5491890158;28144.43711125807;29000.41999159963;29049.7095029047;29769.70230297756;51412;51923.48076519187;52122.73938630434;52166;52179.73910130425;351442;386974;388291;388560;389171;23991.400214994766;24595.631065533675;27011.864940675172;27468;27484.900603975617;46961;47034;48155.5184448165;48287.5171248283;48315;36289.818550907075;38180.42729359117;38376.42435363527;39163.804180980056;39402.60597393989;36511;37377.4393384094;37463.063423414635;38352.42471362876;38466.615333847425;36874;37265;37661.246775063795;37878.8106059468;37965.24069518649;31671.84164079165;33360.332793343514;33370.499442508866;33460.83269583636;33472.66527334793;23699.881500592386;23812.16657416982;24475.75524244806;24876.502469950137;24911.501769964136 15:43:37 INFO - PROCESS | 6572 | |2;jquery.html;382.3843611785027;394.23321639411347;394.44383419378664;394.48322697266565;408.0187149904481;17894.5526361841;18721.063946802657;18724.625507489844;19186.61626767467;21478.24826131086;19758.901205493952;20230.797692023076;20317.695234571493;20949.895250523725;21250.787492125073;5854.297484301879;5891.55813314001;5944.583879128462;5950.018246989242;5957.553183511232;5630.577706671996;5829.271341082364;5885.146653735206;5885.852853678671;5888.705564721747;5747.310322761275;5821.359650438449;5824.737886795083;5828.125781132821;5828.650280983134;5644;5652.773889044433;5827.504662103726;5864.736086876101;5876.059830427154;5817.680027598481;5835.533157347444;5835.649861008311;5849.912251316234;5856.15085812559;12149.939250303692;12989.155704879153;13102.80345794814;13263.204207747565;13586.932065339708;2208.1167532986774;2245.2141750387395;2327.988360058189;2358.1864256831536;2807.2280122966145;9264.471362225224;9962.651307204213;10228.07947284748;10841.512131954081;15176.317065732068;1686.6205103851657;1693.8136804951446;1699.864010879139;1731.5411415974777;1787.8390944815028;96306.55540166973;102478;110572.23569410748;111572;118921.40539297335;11328.773424531464;11471.655850324503;11589.76820463586;11645.417729113511;11680.357580332993;14845.777313340308;14912.254387280593;14932.850671493363;14945.252737363306;14959.401623935139;8368.24685778283;8524.27543658796;8559.443636163629;8655.788189653453;8663.610137543825;8357.498550086893;8382.580870956428;8397.538135402488;8513.914860851437;8524.957375213084;4483.55164483549;4562.422231439226;4575.153596584642;4626.444826620827;4640.95359046412;1691.6616676664717;1866.262826183677;1943.6598595246057;1946.98053019468;1970.965243247278;841.6465084664455;862.185234952976;902.566767951387;927.7958849053198;1060.867391576053;18957.104289570932;19464.124114414884;20147.8992605039;20349.186032558533;20733.27433539849;905.5381755304842;948.2556193388157;972.8080765924838;976.6484065736329;983.1790454970037;9950.751231219132;10439.37363758177;10697.358158510515;10708.892911070945;10710.08964238033;591762;614723;619733;627693;632029;126223;131605;132302;133024;134827;1005.9044390782864;1009.65671671632;1036.8030074285862;1055.1664185293685;1057.4078516031047;17432.477025689248;17637.559061023578;17795.91102044533;17821.643567128845;18109;46127.64001838261;50498.74750626223;51137.744311278206;51510.74244628753;52254.47745522496;72531.91202131867;73902.63048684722;73961.26038739544;74505.254947452;74619.62690186514;6942.305769423017;7465.104187497535;7779.72770953014;7811.726589569338;8384.287335576544;11500.654980350602;12461.439235234255;12469.688257793629;12711.745765084832;12730.23618582888;10628.574857005633;10767.569297228336;10877.45612719345;10974.725631858948;11076.723081922693;10788.946055269673;10805.945970270099;10956.34261944286;11145.609903653332;11322.32066076038;11145.72135696614;11266.718332041764;11322.37726925027;11517.25137866036;11594.130440216848;7275.672594733144;7522.3605993491165;7569.507981981374;7702.383809295355;7707;10337.9483102584;10853.02322790921;10994.28537145083;11122.499487523242;11294.209405341548 15:43:37 INFO - PROCESS | 6572 | |3;mootools.html;1350.756863764522;1530.0590137065703;1573.5200763767054;1812.1301775147929;1954.7361106250653;7823.178566250547;7997.720079797208;8949.776255593613;8960.193582577567;9003.81992360154;6952.652367381636;8805.603747831346;8809.603567839447;8821.382503224777;8828.735137945872;2271.034810205664;2305.5504176685554;2422.7577242275806;2462.4213309872166;2463.9014439422403;2416.734159242482;2463.5319289335057;2465.7534246575287;2471.5180539794746;2483.081259933827;2427.356750461129;2432.781049705526;2444.4622183119773;2459.372859920718;2466.235467005225;2402.711674599042;2408.602580574212;2431.161249368965;2434.1237154624323;2450.9142180023614;2394.2099107294553;2415.92752217432;2425.890834912433;2428.259380888828;2428.84212526187;8680.435771674822;8790.56047197644;8840.557972101433;8871.068537803567;8874.979377371556;4163.91672166558;5474.91787623182;5482.2873026506695;5484.8354549363585;5485.561155107582;3538.6815186633326;3567.500549923013;3627.1838836261895;3644.0889777555612;3646;1011.0748664971542;1027.984580231297;1029.428667089765;1036.9740756481149;1039.0648416425202;11731.7067073323;11878.109141814399;11883.88116118845;11896.643100707079;11908.225965312313;3795.6773674237998;4482.282834746425;4663.020765639254;4734.928976065362;4736.4316282046375;9319.716669416037;9324.160825525736;9325.347225694137;9339.159475647088;9340.065993400742;5286.656367336111;5297.788088476418;5303.602229832778;5314.627976041717;5327.067763141435;3697.7041836653;4092.6725861930972;4436.112777444524;4585.449746030431;4970.478099799541;3789.1285004448578;3819.7135214858995;3841.865534706272;3877.5540812806325;3891.377379619247;1193.2959553863263;1193.498730533178;1196.4855112301793;1208.359569428187;1209.8427204463364;893.1425831201989;900.9864852027225;909.6634245329138;919.4529255093208;940.8306504829196;4919.77860996256;5659.179418984224;5816.447437493432;5922.7927022554;5993.4905533029305;915.1351972386165;929.5305870535472;931.6552875436132;951.2295041016798;961.3751061809879;3302.570665813429;3629.383004889175;3796.5952597539085;3797.025896076149;3824.158685089276;10622.628208012682;11118.722031949266;11198.888011119785;11201.71995700114;11234.38210898392;10210.387376757419;10873.347599144077;10895.019448249537;11127.109831213484;11166.720831979266;6970.745265852094;8127.98400199975;8308.12764659714;8317.667293308199;8340.958295208486;5728.570357223308;5791.420857914175;5836.678982655825;6015.608985415934;6135.141080248858;9733.318667693193;9876.160526355341;9946.35348702332;10050.346727462691;10076.899231007596;9489.240860731263;10030.247731420026;10153.39079655223;10197.235207359627;10201.795964080527;6940.93059069423;7199.956006378941;7352.044234249727;7619.9238007619215;7633.503822251536;8366.665333386596;8369.581520924052;8374.790630234293;8453.535055572003;8477.364197685321;7497.662605182671;7499.212582678741;7555.433342499445;7609.315161635479;7669.8466030677955;7179.497435179696;7282.089738782653;7388.6675099621725;7417.92582074194;7475.476716629783;6327.92425287693;6437.967810160919;6817.659117044227;6868.141482314711;6896.172459304916;4686.671932964796;4825.5898248649255;4906.803727850989;4937.703737775745;4974.0797952378925;4776.665633405767;4898;4954.702717836942;4963.70217786934;5026.271190677405 15:43:37 INFO - PROCESS | 6572 | |4;prototype.html;320.61366053905044;322.5564848333541;330.46794660596436;332.7620751162918;337.1402922547503;5953.487442408978;5953.9999999999945;5965;5986.521078313735;5994.520438364932;5397.811076612319;5883.705814709258;5901;5904.232449781533;5909.793157239497;3974.4634474346026;4172.582741725826;4343.717658352213;4425.645948324126;4477.641788656916;3855.8264878080554;4191.497020357561;4221.409002739603;4278.823323586008;4449.755263460508;3702.3520883845354;4437.090396468732;4464.241079016575;4467.664925130628;4475.28395456727;3710.5361829771277;3758.913802839423;3789.431585262219;3871.915863558208;4083.571225021389;3820.732548721591;3826.766382406862;3829.6936245100324;3836.462895194675;3852.57621661617;5480.205370221335;5616.775328986836;5667.8583035424035;5684.118961560924;5750.45370689788;4334.848280310174;4460.8438704645505;4966.130927087781;5163.3545806774155;5193;5266.552343050846;6136.325004249529;6168.414000669929;6198.256209254873;6212.986701449793;4263.868065967016;4280;4302.451117597662;4310.116426132635;4311.805968731414;5227.921581176285;6149.169862068653;6178.691065446755;6359.936400635935;6401.55189136756;4331.306990881507;4345.196138714348;4355.62977146946;4358.825646974085;4362.345648152739;4412.691111622155;4415.889602759957;4418.80115394808;4426.181156485996;4453.153900758845;4882.5605695487575;5116.539511443988;5152.716600587004;5156.20078887773;5161.535461808456;4690.577847993697;5726.3700992890745;5998.640081595118;6302.527310451735;6319.33646967071;1404.3539971612968;1452.6949340638585;1491.4928924165836;1499.5426394949645;1526.2750193658146;855.8630619100913;1014.1430491235042;1022.6420752736483;1025.1235193909101;1027.383569858094;5768.307803063659;5817.894600025981;5874.125874125874;5878.794242201502;5885.61743486672;6403.231612206472;6454.9677251614385;6465.967670161714;6468.514861385415;6501.739930402826;4685.835995740133;5162.483751624883;5481.862953426196;5604.355499117653;5883.382244864228;2839.474697181028;3317.767756257087;3397.575303087114;3430.6569343065994;3431.9313613728086;5335.413104558495;6364.949783285706;6724.596524208465;6800.653774447807;6829.692663830139;5981.342052374235;6633.2040155181685;6654.534182607171;6663.433608143265;6693.598384096969;5923.259592550931;6419.743210271537;6692.96653516739;6776.627285499247;6778.661066946633;5539.806106786243;6314.905276420949;6466.191726034246;6490.643014634241;6492.220933488012;5661.320641523043;5720.742566584597;6321.322221556107;6395.360463953753;6400.39196276353;5397.8110766123;5484.369297530676;6126.356732543019;6613.603183808987;6818.897716534355;4158.589956235857;4223.5354111047145;4231;4234.174336004573;4241.660667146559;6198.504119670325;6252.531060170414;6462.579932304573;6496.642684652201;6517.674116294261;6256.593321434092;6280;6283.654399007915;6287.182666253358;6293.433590976834;5536.501714845522;5922.822315330547;5956.502840355504;6021.916055109947;6284.560080794484;6047.516198704181;6111.511079113571;6124.51956957433;6156.692165391623;6202.379762023762;6232.439080482778;6239.812805615838;6266.396821827377;6267.0286105653695;6295.622262664255;5556.944180605672;5750.827475175752;5847.707614619337;6178.351273116258;6229.750809967732 15:43:37 INFO - PROCESS | 6572 | |5;yui.html;663.3267233757736;738.5125816960807;743.8772602520581;744.028324938912;754.7320701151094;14814.407423703053;14906.254687265635;15072.623184420381;15336.386544538205;15338.003029803083;13123.737525249513;13194.406251718696;13244;13249.006324525675;13260.204387736743;720.91709453413;744.9739259125931;921.3412410126775;939.7650587353162;940.8917974432925;907.4782000349792;940.7930255343813;945.7635591102224;946.3328353510793;948.0188005414398;971.383171685981;991.3556188477511;993.3146129170885;993.7515621094726;994.4480813148699;765.2232983521702;953.4612943686794;970.5729479029239;986.0632399220733;988.5551501824136;727.4943913979786;735.4998600951351;736.4918206437541;736.7973807203063;738.3023043224156;14650;14697.485588004476;14720.852791472162;14738.778918316233;14754.188519631416;12182;12195.878041219563;12383.442745076492;12497.312647804367;12601.814350405752;9261.30540209487;9479;9816.705498835046;9850.753731156707;9855.457949812757;3400.523926650271;3477.7217822574135;3481.5032676480178;3485.4771784232316;3724.8510059597584;78102;79117;79163.20836791616;79863.20136798616;80722.59638701827;9262.95368523153;9343.085977431329;9403.247740180768;9636.614535418505;9713.36863103896;21480.14079436805;21526.56946861054;21536.892315538324;21566.460838479165;21574.568508629738;7156;7223.89164162538;7260;7261.273872612696;7464.776056718307;3880.825362858678;3951.4863067801;4010.63904248619;4034.3141665916864;4037.4751282333696;3686.7419280650633;3883.3204189266767;3888.844446222176;3899.532056153223;3928.6660633846445;657.1719633262124;660.4716227018462;669.1334721535623;689.5001124184926;691.4192078653955;444.4799584486094;578.124141924984;586.1764221269124;594.1859652276408;618.1099217127322;6382.904256436157;7428.517146385468;7641.465097443237;7755.573443460551;7804.804879878048;524.8556646922051;525.3879230696273;542.1109380615793;557.2671936403616;557.4286356484636;3530.735194860396;3611.7652352596997;3629.5281613390616;3637.890863274106;3650.543682039745;92873.53563232141;93423;95248;96262;96290;70170;71723;71900;72839.27160728326;73768;67361;70916.29083709097;70991.2900870995;71074.64462677758;71826.64086679637;7043.823904402431;7054.4003759681;7136.250693677089;7312.707491700273;7496.812579685551;37057.25885482221;39176.80411597924;41675.16649667051;42898.78550607227;43273.70178894638;41748.95627609334;53855;53868;54362.45637543732;54900;8538.359623028173;10383.792324153626;10504.159667226451;10527.894721052691;10848.728781780519;12007.279563226235;12021.210386118579;12268.079894008164;12642.178258413174;12650.620481385573;10702.839457408301;10908.181886358714;11176.99407053369;11212.719682007688;11268.042216411697;11079.446027698421;11106.278091924;11135.387553684624;11208.775824483302;11472.254303470248;11029.503672334924;11146.944265278622;11430.542778288775;11548.653540393801;11685;9426.293028023063;9650.903490965;9822.995213265678;9878.851817222603;10185.541650625888;8909.910900890907;9021.233195178484;9648.75878103053;9739.736252799205;9917.206623470249 15:43:37 INFO - PROCESS | 6572 | __end_tp_report 15:43:37 INFO - PROCESS | 6572 | __start_cc_report 15:43:37 INFO - PROCESS | 6572 | _x_x_mozilla_cycle_collect,1073 15:43:37 INFO - PROCESS | 6572 | __end_cc_report 15:43:37 INFO - PROCESS | 6572 | __startTimestamp1461019417403__endTimestamp 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | ------- Summary: start ------- 15:43:37 INFO - PROCESS | 6572 | Number of tests: 6 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#0] dojo.html Cycles:165 Average:5745.15 Median:5985.31 stddev:653.91 (10.9%) stddev-sans-first:623.28 15:43:37 INFO - PROCESS | 6572 | Values: 3144.8 3401.9 3428.2 3430.1 3472.1 5607.4 5672.7 5677.8 5682.3 5684.7 5603.4 5697.1 5700.7 5702.6 5729.2 5135.3 5149.5 5165.2 5184.0 5233.8 5241.8 5243.8 5246.3 5257.2 5258.6 5153.9 5190.2 5212.6 5225.5 5300.1 5229.2 5255.7 5270.6 5277.3 5311.4 5061.7 5083.8 5115.2 5164.1 5191.8 5574.4 5614.3 5623.5 5628.5 5651.9 6246.2 6281.3 6283.9 6288.1 6296.3 5154.3 6126.0 6271.2 6295.3 6324.1 5825.6 5830.4 5856.2 5858.3 5861.9 6260.4 6284.2 6290.1 6292.6 6296.2 6032.5 6095.7 6122.0 6176.0 6195.8 6250.1 6264.3 6270.7 6275.2 6301.1 6213.0 6230.4 6243.9 6269.1 6272.3 6100.6 6111.4 6141.1 6141.9 6284.7 4821.9 4864.9 4869.7 4873.5 4878.7 4400.1 4417.2 4429.1 4444.0 4459.6 5680.9 5681.1 5682.2 5709.3 5744.5 6181.4 6256.2 6261.5 6263.9 6285.7 5610.3 5677.1 5685.7 5696.5 5714.7 4630.3 5364.4 5409.1 5469.5 5473.2 6330.1 6351.9 6357.9 6378.1 6387.8 6107.8 6277.3 6300.9 6314.6 6317.5 6319.4 6355.9 6395.5 6396.0 6396.3 6166.8 6175.0 6185.8 6196.3 6206.3 6113.6 6178.9 6252.1 6274.6 6280.4 6153.6 6160.1 6164.8 6188.2 6194.7 6072.5 6144.8 6161.7 6173.6 6177.4 5855.4 5957.6 5973.7 6014.0 6017.7 6045.8 6117.9 6138.1 6155.1 6162.6 5869.4 5980.3 5982.0 5985.3 6048.5 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#1] ext.html Cycles:180 Average:62288.51 Median:177409.76 stddev:128766.05 (72.6%) stddev-sans-first:129043.71 15:43:37 INFO - PROCESS | 6572 | Values: 502.9 503.7 511.7 530.2 575.6 21052.8 29981.9 30173.2 30176.4 30245.0 41113.6 41176.8 41342.0 41414.0 41423.6 8694.0 9007.2 9350.5 9561.0 9563.1 8448.6 8724.9 9475.0 9589.7 9601.8 11467.1 11778.9 11780.8 11782.0 11795.1 11513.4 11653.7 11670.9 11677.8 11682.9 8334.6 11277.3 11290.9 11600.9 11623.4 38316.9 38999.4 39018.8 39031.8 39033.6 3121.0 3223.6 3312.9 3377.5 3574.8 28032.9 28223.4 29123.7 29861.7 29861.9 2723.8 2733.3 2746.2 2803.4 2848.6 442546.0 445907.0 448272.0 448573.8 448716.0 64427.0 65252.0 65325.0 65361.7 65432.0 68640.7 68756.7 68845.7 69041.3 69071.0 16956.5 17507.9 17514.7 17519.6 17521.2 13137.1 13196.6 13206.1 13250.1 13265.7 14224.9 15622.9 17762.1 18265.7 18422.4 6736.6 6928.7 6932.8 7010.6 7017.2 916.5 999.7 1045.8 1074.9 1141.3 27460.3 27498.0 27707.0 28164.0 28286.0 1032.1 1075.6 1103.3 1139.9 1193.9 12293.6 14362.0 14465.7 14787.0 15003.8 586897.0 593206.0 599731.0 599911.0 602085.0 62857.0 62915.7 63283.0 63356.0 63524.7 1362.7 1370.6 1482.7 1496.5 1505.6 22540.5 28144.4 29000.4 29049.7 29769.7 51412.0 51923.5 52122.7 52166.0 52179.7 351442.0 386974.0 388291.0 388560.0 389171.0 23991.4 24595.6 27011.9 27468.0 27484.9 46961.0 47034.0 48155.5 48287.5 48315.0 36289.8 38180.4 38376.4 39163.8 39402.6 36511.0 37377.4 37463.1 38352.4 38466.6 36874.0 37265.0 37661.2 37878.8 37965.2 31671.8 33360.3 33370.5 33460.8 33472.7 23699.9 23812.2 24475.8 24876.5 24911.5 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#2] jquery.html Cycles:180 Average:34756.34 Median:21364.52 stddev:102761.95 (481.0%) stddev-sans-first:103017.81 15:43:37 INFO - PROCESS | 6572 | Values: 382.4 394.2 394.4 394.5 408.0 17894.6 18721.1 18724.6 19186.6 21478.2 19758.9 20230.8 20317.7 20949.9 21250.8 5854.3 5891.6 5944.6 5950.0 5957.6 5630.6 5829.3 5885.1 5885.9 5888.7 5747.3 5821.4 5824.7 5828.1 5828.7 5644.0 5652.8 5827.5 5864.7 5876.1 5817.7 5835.5 5835.6 5849.9 5856.2 12149.9 12989.2 13102.8 13263.2 13586.9 2208.1 2245.2 2328.0 2358.2 2807.2 9264.5 9962.7 10228.1 10841.5 15176.3 1686.6 1693.8 1699.9 1731.5 1787.8 96306.6 102478.0 110572.2 111572.0 118921.4 11328.8 11471.7 11589.8 11645.4 11680.4 14845.8 14912.3 14932.9 14945.3 14959.4 8368.2 8524.3 8559.4 8655.8 8663.6 8357.5 8382.6 8397.5 8513.9 8525.0 4483.6 4562.4 4575.2 4626.4 4641.0 1691.7 1866.3 1943.7 1947.0 1971.0 841.6 862.2 902.6 927.8 1060.9 18957.1 19464.1 20147.9 20349.2 20733.3 905.5 948.3 972.8 976.6 983.2 9950.8 10439.4 10697.4 10708.9 10710.1 591762.0 614723.0 619733.0 627693.0 632029.0 126223.0 131605.0 132302.0 133024.0 134827.0 1005.9 1009.7 1036.8 1055.2 1057.4 17432.5 17637.6 17795.9 17821.6 18109.0 46127.6 50498.7 51137.7 51510.7 52254.5 72531.9 73902.6 73961.3 74505.3 74619.6 6942.3 7465.1 7779.7 7811.7 8384.3 11500.7 12461.4 12469.7 12711.7 12730.2 10628.6 10767.6 10877.5 10974.7 11076.7 10788.9 10805.9 10956.3 11145.6 11322.3 11145.7 11266.7 11322.4 11517.3 11594.1 7275.7 7522.4 7569.5 7702.4 7707.0 10337.9 10853.0 10994.3 11122.5 11294.2 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#3] mootools.html Cycles:180 Average:5664.52 Median:4913.29 stddev:3206.14 (65.3%) stddev-sans-first:3198.75 15:43:37 INFO - PROCESS | 6572 | Values: 1350.8 1530.1 1573.5 1812.1 1954.7 7823.2 7997.7 8949.8 8960.2 9003.8 6952.7 8805.6 8809.6 8821.4 8828.7 2271.0 2305.6 2422.8 2462.4 2463.9 2416.7 2463.5 2465.8 2471.5 2483.1 2427.4 2432.8 2444.5 2459.4 2466.2 2402.7 2408.6 2431.2 2434.1 2450.9 2394.2 2415.9 2425.9 2428.3 2428.8 8680.4 8790.6 8840.6 8871.1 8875.0 4163.9 5474.9 5482.3 5484.8 5485.6 3538.7 3567.5 3627.2 3644.1 3646.0 1011.1 1028.0 1029.4 1037.0 1039.1 11731.7 11878.1 11883.9 11896.6 11908.2 3795.7 4482.3 4663.0 4734.9 4736.4 9319.7 9324.2 9325.3 9339.2 9340.1 5286.7 5297.8 5303.6 5314.6 5327.1 3697.7 4092.7 4436.1 4585.4 4970.5 3789.1 3819.7 3841.9 3877.6 3891.4 1193.3 1193.5 1196.5 1208.4 1209.8 893.1 901.0 909.7 919.5 940.8 4919.8 5659.2 5816.4 5922.8 5993.5 915.1 929.5 931.7 951.2 961.4 3302.6 3629.4 3796.6 3797.0 3824.2 10622.6 11118.7 11198.9 11201.7 11234.4 10210.4 10873.3 10895.0 11127.1 11166.7 6970.7 8128.0 8308.1 8317.7 8341.0 5728.6 5791.4 5836.7 6015.6 6135.1 9733.3 9876.2 9946.4 10050.3 10076.9 9489.2 10030.2 10153.4 10197.2 10201.8 6940.9 7200.0 7352.0 7619.9 7633.5 8366.7 8369.6 8374.8 8453.5 8477.4 7497.7 7499.2 7555.4 7609.3 7669.8 7179.5 7282.1 7388.7 7417.9 7475.5 6327.9 6438.0 6817.7 6868.1 6896.2 4686.7 4825.6 4906.8 4937.7 4974.1 4776.7 4898.0 4954.7 4963.7 5026.3 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#4] prototype.html Cycles:180 Average:5041.79 Median:5664.59 stddev:1571.82 (27.7%) stddev-sans-first:1535.77 15:43:37 INFO - PROCESS | 6572 | Values: 320.6 322.6 330.5 332.8 337.1 5953.5 5954.0 5965.0 5986.5 5994.5 5397.8 5883.7 5901.0 5904.2 5909.8 3974.5 4172.6 4343.7 4425.6 4477.6 3855.8 4191.5 4221.4 4278.8 4449.8 3702.4 4437.1 4464.2 4467.7 4475.3 3710.5 3758.9 3789.4 3871.9 4083.6 3820.7 3826.8 3829.7 3836.5 3852.6 5480.2 5616.8 5667.9 5684.1 5750.5 4334.8 4460.8 4966.1 5163.4 5193.0 5266.6 6136.3 6168.4 6198.3 6213.0 4263.9 4280.0 4302.5 4310.1 4311.8 5227.9 6149.2 6178.7 6359.9 6401.6 4331.3 4345.2 4355.6 4358.8 4362.3 4412.7 4415.9 4418.8 4426.2 4453.2 4882.6 5116.5 5152.7 5156.2 5161.5 4690.6 5726.4 5998.6 6302.5 6319.3 1404.4 1452.7 1491.5 1499.5 1526.3 855.9 1014.1 1022.6 1025.1 1027.4 5768.3 5817.9 5874.1 5878.8 5885.6 6403.2 6455.0 6466.0 6468.5 6501.7 4685.8 5162.5 5481.9 5604.4 5883.4 2839.5 3317.8 3397.6 3430.7 3431.9 5335.4 6364.9 6724.6 6800.7 6829.7 5981.3 6633.2 6654.5 6663.4 6693.6 5923.3 6419.7 6693.0 6776.6 6778.7 5539.8 6314.9 6466.2 6490.6 6492.2 5661.3 5720.7 6321.3 6395.4 6400.4 5397.8 5484.4 6126.4 6613.6 6818.9 4158.6 4223.5 4231.0 4234.2 4241.7 6198.5 6252.5 6462.6 6496.6 6517.7 6256.6 6280.0 6283.7 6287.2 6293.4 5536.5 5922.8 5956.5 6021.9 6284.6 6047.5 6111.5 6124.5 6156.7 6202.4 6232.4 6239.8 6266.4 6267.0 6295.6 5556.9 5750.8 5847.7 6178.4 6229.8 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - PROCESS | 6572 | [#5] yui.html Cycles:180 Average:17346.99 Median:3520.04 stddev:24481.96 (695.5%) stddev-sans-first:24518.59 15:43:37 INFO - PROCESS | 6572 | Values: 663.3 738.5 743.9 744.0 754.7 14814.4 14906.3 15072.6 15336.4 15338.0 13123.7 13194.4 13244.0 13249.0 13260.2 720.9 745.0 921.3 939.8 940.9 907.5 940.8 945.8 946.3 948.0 971.4 991.4 993.3 993.8 994.4 765.2 953.5 970.6 986.1 988.6 727.5 735.5 736.5 736.8 738.3 14650.0 14697.5 14720.9 14738.8 14754.2 12182.0 12195.9 12383.4 12497.3 12601.8 9261.3 9479.0 9816.7 9850.8 9855.5 3400.5 3477.7 3481.5 3485.5 3724.9 78102.0 79117.0 79163.2 79863.2 80722.6 9263.0 9343.1 9403.2 9636.6 9713.4 21480.1 21526.6 21536.9 21566.5 21574.6 7156.0 7223.9 7260.0 7261.3 7464.8 3880.8 3951.5 4010.6 4034.3 4037.5 3686.7 3883.3 3888.8 3899.5 3928.7 657.2 660.5 669.1 689.5 691.4 444.5 578.1 586.2 594.2 618.1 6382.9 7428.5 7641.5 7755.6 7804.8 524.9 525.4 542.1 557.3 557.4 3530.7 3611.8 3629.5 3637.9 3650.5 92873.5 93423.0 95248.0 96262.0 96290.0 70170.0 71723.0 71900.0 72839.3 73768.0 67361.0 70916.3 70991.3 71074.6 71826.6 7043.8 7054.4 7136.3 7312.7 7496.8 37057.3 39176.8 41675.2 42898.8 43273.7 41749.0 53855.0 53868.0 54362.5 54900.0 8538.4 10383.8 10504.2 10527.9 10848.7 12007.3 12021.2 12268.1 12642.2 12650.6 10702.8 10908.2 11177.0 11212.7 11268.0 11079.4 11106.3 11135.4 11208.8 11472.3 11029.5 11146.9 11430.5 11548.7 11685.0 9426.3 9650.9 9823.0 9878.9 10185.5 8909.9 9021.2 9648.8 9739.7 9917.2 15:43:37 INFO - PROCESS | 6572 | -------- Summary: end -------- 15:43:37 INFO - PROCESS | 6572 | 15:43:37 INFO - TEST-INFO | 6572: exit 0 15:43:38 INFO - TEST-OK | dromaeo_css | took 1115614ms 15:43:38 INFO - TEST-START | kraken 15:43:38 INFO - operating with platform_type : mac_ 15:43:38 INFO - Initialising browser for kraken test... 15:43:38 INFO - TEST-INFO | started process 6576 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpPMEORe/profile http://localhost:57320/getInfo.html) 15:43:40 INFO - PROCESS | 6576 | __metrics Screen width/height:1600/1200 15:43:40 INFO - PROCESS | 6576 | colorDepth:24 15:43:40 INFO - PROCESS | 6576 | Browser inner width/height: 1024/689 15:43:40 INFO - PROCESS | 6576 | __metrics 15:43:40 INFO - PROCESS | 6576 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:43:40 ERROR - PROCESS | 6576 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 15:43:40 INFO - TEST-INFO | 6576: exit 0 15:43:40 INFO - Browser initialized. 15:43:40 INFO - Running cycle 1/1 for kraken test... 15:43:40 INFO - TEST-INFO | started process 6578 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/tmpPMEORe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:43:53 INFO - PROCESS | 6578 | RSS: Main: 237563904 15:43:53 INFO - PROCESS | 6578 | 15:43:55 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-beat-detection) 15:43:56 INFO - PROCESS | 6578 | RSS: Main: 285556736 15:43:56 INFO - PROCESS | 6578 | 15:43:59 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-dft) 15:44:00 INFO - PROCESS | 6578 | RSS: Main: 298696704 15:44:00 INFO - PROCESS | 6578 | 15:44:03 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-fft) 15:44:04 INFO - PROCESS | 6578 | RSS: Main: 385896448 15:44:04 INFO - PROCESS | 6578 | 15:44:06 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:57320/tests/kraken/driver.html?testName=audio-oscillator) 15:44:06 INFO - PROCESS | 6578 | RSS: Main: 285298688 15:44:06 INFO - PROCESS | 6578 | 15:44:09 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:44:09 INFO - PROCESS | 6578 | RSS: Main: 289222656 15:44:09 INFO - PROCESS | 6578 | 15:44:12 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-darkroom) 15:44:12 INFO - PROCESS | 6578 | RSS: Main: 328773632 15:44:12 INFO - PROCESS | 6578 | 15:44:15 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:57320/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:16 INFO - PROCESS | 6578 | RSS: Main: 328327168 15:44:16 INFO - PROCESS | 6578 | 15:44:19 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:57320/tests/kraken/driver.html?testName=json-parse-financial) 15:44:19 INFO - PROCESS | 6578 | RSS: Main: 328957952 15:44:19 INFO - PROCESS | 6578 | 15:44:21 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:57320/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:22 INFO - PROCESS | 6578 | RSS: Main: 319827968 15:44:22 INFO - PROCESS | 6578 | 15:44:23 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:44:24 INFO - PROCESS | 6578 | RSS: Main: 326647808 15:44:24 INFO - PROCESS | 6578 | 15:44:26 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:44:27 INFO - PROCESS | 6578 | RSS: Main: 329588736 15:44:27 INFO - PROCESS | 6578 | 15:44:30 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:44:30 INFO - PROCESS | 6578 | RSS: Main: 330969088 15:44:30 INFO - PROCESS | 6578 | 15:44:33 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:44:34 INFO - PROCESS | 6578 | RSS: Main: 326524928 15:44:34 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | Cycle 1(1): loaded http://localhost:57320/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:57320/tests/kraken/driver.html?testName=ai-astar) 15:44:36 INFO - PROCESS | 6578 | RSS: Main: 390627328 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | __start_tp_report 15:44:36 INFO - PROCESS | 6578 | _x_x_mozilla_page_load 15:44:36 INFO - PROCESS | 6578 | _x_x_mozilla_page_load_details 15:44:36 INFO - PROCESS | 6578 | |i|pagename|runs| 15:44:36 INFO - PROCESS | 6578 | |0;ai-astar;112;113;114;106;138;169;172;112;112;112 15:44:36 INFO - PROCESS | 6578 | |1;audio-beat-detection;160;122;145;182;149;161;141;123;172;119 15:44:36 INFO - PROCESS | 6578 | |2;audio-dft;187;188;188;185;184;187;187;184;188;183 15:44:36 INFO - PROCESS | 6578 | |3;audio-fft;81;74;86;92;56;57;59;78;85;56 15:44:36 INFO - PROCESS | 6578 | |4;audio-oscillator;90;97;139;80;78;81;124;77;77;128 15:44:36 INFO - PROCESS | 6578 | |5;imaging-gaussian-blur;87;84;87;85;87;84;89;84;87;83 15:44:36 INFO - PROCESS | 6578 | |6;imaging-darkroom;93;93;93;93;94;93;93;94;92;93 15:44:36 INFO - PROCESS | 6578 | |7;imaging-desaturate;86;84;84;84;84;93;132;86;85;85 15:44:36 INFO - PROCESS | 6578 | |8;json-parse-financial;60;59;59;56;59;59;57;78;57;57 15:44:36 INFO - PROCESS | 6578 | |9;json-stringify-tinderbox;63;65;73;71;76;70;71;68;68;68 15:44:36 INFO - PROCESS | 6578 | |10;stanford-crypto-aes;79;79;82;83;81;81;80;81;81;82 15:44:36 INFO - PROCESS | 6578 | |11;stanford-crypto-ccm;148;149;139;135;136;137;142;143;186;167 15:44:36 INFO - PROCESS | 6578 | |12;stanford-crypto-pbkdf2;172;170;169;167;169;171;166;166;164;164 15:44:36 INFO - PROCESS | 6578 | |13;stanford-crypto-sha256-iterative;68;70;68;70;70;92;91;93;92;67 15:44:36 INFO - PROCESS | 6578 | __end_tp_report 15:44:36 INFO - PROCESS | 6578 | __start_cc_report 15:44:36 INFO - PROCESS | 6578 | _x_x_mozilla_cycle_collect,1966 15:44:36 INFO - PROCESS | 6578 | __end_cc_report 15:44:36 INFO - PROCESS | 6578 | __startTimestamp1461019476478__endTimestamp 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | ------- Summary: start ------- 15:44:36 INFO - PROCESS | 6578 | Number of tests: 14 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#0] ai-astar Cycles:10 Average:126.00 Median:113.50 stddev:24.96 (22.0%) stddev-sans-first:25.95 15:44:36 INFO - PROCESS | 6578 | Values: 112.0 113.0 114.0 106.0 138.0 169.0 172.0 112.0 112.0 112.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:154.50 stddev:21.71 (14.1%) stddev-sans-first:22.54 15:44:36 INFO - PROCESS | 6578 | Values: 160.0 122.0 145.0 182.0 149.0 161.0 141.0 123.0 172.0 119.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#2] audio-dft Cycles:10 Average:186.10 Median:187.00 stddev:1.91 (1.0%) stddev-sans-first:2.00 15:44:36 INFO - PROCESS | 6578 | Values: 187.0 188.0 188.0 185.0 184.0 187.0 187.0 184.0 188.0 183.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#3] audio-fft Cycles:10 Average:72.40 Median:79.50 stddev:14.10 (17.7%) stddev-sans-first:14.61 15:44:36 INFO - PROCESS | 6578 | Values: 81.0 74.0 86.0 92.0 56.0 57.0 59.0 78.0 85.0 56.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#4] audio-oscillator Cycles:10 Average:97.10 Median:79.00 stddev:24.06 (30.5%) stddev-sans-first:25.38 15:44:36 INFO - PROCESS | 6578 | Values: 90.0 97.0 139.0 80.0 78.0 81.0 124.0 77.0 77.0 128.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:87.00 stddev:1.95 (2.2%) stddev-sans-first:2.01 15:44:36 INFO - PROCESS | 6578 | Values: 87.0 84.0 87.0 85.0 87.0 84.0 89.0 84.0 87.0 83.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 15:44:36 INFO - PROCESS | 6578 | Values: 93.0 93.0 93.0 93.0 94.0 93.0 93.0 94.0 92.0 93.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#7] imaging-desaturate Cycles:10 Average:90.30 Median:85.00 stddev:14.90 (17.5%) stddev-sans-first:15.72 15:44:36 INFO - PROCESS | 6578 | Values: 86.0 84.0 84.0 84.0 84.0 93.0 132.0 86.0 85.0 85.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#8] json-parse-financial Cycles:10 Average:60.10 Median:59.00 stddev:6.42 (10.9%) stddev-sans-first:6.81 15:44:36 INFO - PROCESS | 6578 | Values: 60.0 59.0 59.0 56.0 59.0 59.0 57.0 78.0 57.0 57.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:70.50 stddev:3.77 (5.4%) stddev-sans-first:3.24 15:44:36 INFO - PROCESS | 6578 | Values: 63.0 65.0 73.0 71.0 76.0 70.0 71.0 68.0 68.0 68.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.29 (1.6%) stddev-sans-first:1.17 15:44:36 INFO - PROCESS | 6578 | Values: 79.0 79.0 82.0 83.0 81.0 81.0 80.0 81.0 81.0 82.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#11] stanford-crypto-ccm Cycles:10 Average:148.20 Median:145.50 stddev:16.27 (11.2%) stddev-sans-first:17.25 15:44:36 INFO - PROCESS | 6578 | Values: 148.0 149.0 139.0 135.0 136.0 137.0 142.0 143.0 186.0 167.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:169.00 stddev:2.82 (1.7%) stddev-sans-first:2.55 15:44:36 INFO - PROCESS | 6578 | Values: 172.0 170.0 169.0 167.0 169.0 171.0 166.0 166.0 164.0 164.0 15:44:36 INFO - PROCESS | 6578 | 15:44:36 INFO - PROCESS | 6578 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.10 Median:80.50 stddev:12.01 (14.9%) stddev-sans-first:12.17 15:44:36 INFO - PROCESS | 6578 | Values: 68.0 70.0 68.0 70.0 70.0 92.0 91.0 93.0 92.0 67.0 15:44:36 INFO - PROCESS | 6578 | -------- Summary: end -------- 15:44:36 INFO - PROCESS | 6578 | 15:44:37 INFO - TEST-INFO | 6578: exit 0 15:44:37 INFO - TEST-OK | kraken | took 59233ms 15:44:37 INFO - SUITE-END | took 1174s 15:44:37 INFO - Completed test suite (00:19:35) 15:44:37 INFO - javascript benchmark 15:44:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3144.827034513102, 3401.897943061709, 3428.2457859270953, 3430.056734398042, 3472.131967008248, 5607.383187849333, 5672.68800215988, 5677.829665110043, 5682.318121825377, 5684.687342196177, 5603.439656034405, 5697.145428185774, 5700.714964251792, 5702.629329093616, 5729.226554415152, 5135.306733590974, 5149.536541711245, 5165.199394093921, 5184.040952423795, 5233.816816411427, 5241.842744717665, 5243.79024839006, 5246.317978662788, 5257.185136303879, 5258.6318957672975, 5153.871153221181, 5190.169572868342, 5212.582993360541, 5225.529702326789, 5300.09898317285, 5229.163333866563, 5255.737213139366, 5270.578353731692, 5277.340332458442, 5311.389190243133, 5061.6962982221185, 5083.847484575432, 5115.2327150927285, 5164.070467315882, 5191.792328306863, 5574.386817450078, 5614.326280846284, 5623.493885550321, 5628.521575666074, 5651.886962260732, 6246.187995560594, 6281.309056003836, 6283.8743225135695, 6288.056791481269, 6296.338884417116, 5154.304168937183, 6125.9693701531205, 6271.184745982993, 6295.2760432550385, 6324.114623952651, 5825.563082768809, 5830.4461076197695, 5856.238688970449, 5858.2677165354335, 5861.853453663693, 6260.373962603703, 6284.183056202664, 6290.119383286344, 6292.559520833498, 6296.244450665949, 6032.517398608101, 6095.6801602768855, 6122.020476723703, 6176.0, 6195.752169913243, 6250.062490626442, 6264.2796078451565, 6270.745850829761, 6275.246970363494, 6301.086342480315, 6213.0, 6230.408111229426, 6243.875122497525, 6269.059641053878, 6272.278687950853, 6100.633961962208, 6111.419415155553, 6141.117811339996, 6141.907871381933, 6284.748610055638, 4821.927671084936, 4864.854054378375, 4869.683470574472, 4873.537013983666, 4878.682885612424, 4400.075984043323, 4417.204903117406, 4429.136318417877, 4443.977780111143, 4459.554044595515, 5680.857978550486, 5681.119426488901, 5682.204491371212, 5709.3434255060365, 5744.540436765049, 6181.412765787243, 6256.249250089927, 6261.530385221126, 6263.937360626426, 6285.748570057147, 5610.326760788732, 5677.063284558084, 5685.687287199244, 5696.487316141567, 5714.7428365723645, 4630.305454181899, 5364.436734142937, 5409.107497262908, 5469.480399362054, 5473.233747275384, 6330.0821380899515, 6351.936480635134, 6357.93642063592, 6378.107065010995, 6387.808365749035, 6107.765388667065, 6277.278113016879, 6300.873982520232, 6314.589551679126, 6317.4630156437215, 6319.368063193644, 6355.90466142999, 6395.488360931021, 6396.0, 6396.26442959047, 6166.845828854314, 6175.0, 6185.783497577563, 6196.349383314868, 6206.348333425106, 6113.5979309659515, 6178.949578571744, 6252.09344645033, 6274.560780745486, 6280.371962803683, 6153.630782153085, 6160.137580738789, 6164.753409863555, 6188.195534580475, 6194.721237544231, 6072.544559157993, 6144.754209831557, 6161.722722477589, 6173.59871608344, 6177.351378105234, 5855.38518455556, 5957.6127551709, 5973.731182096903, 6014.0, 6017.729202185825, 6045.78839740607, 6117.944808963622, 6138.109974053648, 6155.076738489083, 6162.630242185483, 5869.442402971711, 5980.34216236205, 5982.0, 5985.311689155803, 6048.485878700184], "value": 5702.468284915429, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.87679518517945, 503.74812593703126, 511.7057691827199, 530.2364594983226, 575.5798267264896, 21052.789472105273, 29981.85009074957, 30173.24566885829, 30176.3964720706, 30245.0, 41113.58886411135, 41176.79411602945, 41341.96645083867, 41414.0, 41423.58576414235, 8694.043655197924, 9007.234385077276, 9350.485723285234, 9560.952195239013, 9563.09150630689, 8448.61981210844, 8724.8691269631, 9474.952625236865, 9589.712308630717, 9601.75995600112, 11467.139964502654, 11778.882211177906, 11780.823287650692, 11781.998530124949, 11795.115366347516, 11513.424328783569, 11653.70865728355, 11670.941645291761, 11677.824832627517, 11682.94158529202, 8334.62494187763, 11277.266977646426, 11290.943545282222, 11600.94199528997, 11623.418829058513, 38316.89257090859, 38999.41500877489, 39018.80490597529, 39031.8048409759, 39033.609663903284, 3121.0480803354967, 3223.564818749462, 3312.8840490582716, 3377.5102610121394, 3574.767640103385, 28032.87868485258, 28223.435531289466, 29123.708762912313, 29861.701382986328, 29861.850690746625, 2723.7548620624043, 2733.2620192548097, 2746.217328061513, 2803.355228297503, 2848.5727140929025, 442546.0, 445907.0, 448272.0, 448573.75713121553, 448716.0, 64427.0, 65252.0, 65325.0, 65361.67319163374, 65432.0, 68640.6567967157, 68756.65621671859, 68845.65577172182, 69041.30958690449, 69070.963935541, 16956.49130526086, 17507.94952302866, 17514.737278940825, 17519.64960700779, 17521.211545480484, 13137.080404371793, 13196.60410187696, 13206.07557470987, 13250.072494925264, 13265.734685306239, 14224.85775142256, 15622.90639655236, 17762.06925857245, 18265.726014109798, 18422.35521756732, 6736.562123461959, 6928.688209030605, 6932.79201623942, 7010.579365238102, 7017.193022802341, 916.5279880861361, 999.7300728803328, 1045.811753884308, 1074.9140068794477, 1141.2638847943197, 27460.313492162455, 27498.0375686854, 27707.0, 28164.01425950123, 28286.0, 1032.1381646325253, 1075.5643964194514, 1103.3269705479652, 1139.897409233173, 1193.8746431624727, 12293.597209539057, 14362.0, 14465.722289234629, 14786.964912456026, 15003.774943375858, 586897.0, 593206.0, 599731.0, 599911.0, 602085.0, 62857.0, 62915.6854215726, 63283.0, 63356.0, 63524.682376588746, 1362.7342668179808, 1370.56142034548, 1482.7108713800706, 1496.4762333183298, 1505.5558610210014, 22540.5491890158, 28144.43711125807, 29000.41999159963, 29049.7095029047, 29769.70230297756, 51412.0, 51923.48076519187, 52122.73938630434, 52166.0, 52179.73910130425, 351442.0, 386974.0, 388291.0, 388560.0, 389171.0, 23991.400214994766, 24595.631065533675, 27011.864940675172, 27468.0, 27484.900603975617, 46961.0, 47034.0, 48155.5184448165, 48287.5171248283, 48315.0, 36289.818550907075, 38180.42729359117, 38376.42435363527, 39163.804180980056, 39402.60597393989, 36511.0, 37377.4393384094, 37463.063423414635, 38352.42471362876, 38466.615333847425, 36874.0, 37265.0, 37661.246775063795, 37878.8106059468, 37965.24069518649, 31671.84164079165, 33360.332793343514, 33370.499442508866, 33460.83269583636, 33472.66527334793, 23699.881500592386, 23812.16657416982, 24475.75524244806, 24876.502469950137, 24911.501769964136], "value": 19573.681468613086, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.3843611785027, 394.23321639411347, 394.44383419378664, 394.48322697266565, 408.0187149904481, 17894.5526361841, 18721.063946802657, 18724.625507489844, 19186.61626767467, 21478.24826131086, 19758.901205493952, 20230.797692023076, 20317.695234571493, 20949.895250523725, 21250.787492125073, 5854.297484301879, 5891.55813314001, 5944.583879128462, 5950.018246989242, 5957.553183511232, 5630.577706671996, 5829.271341082364, 5885.146653735206, 5885.852853678671, 5888.705564721747, 5747.310322761275, 5821.359650438449, 5824.737886795083, 5828.125781132821, 5828.650280983134, 5644.0, 5652.773889044433, 5827.504662103726, 5864.736086876101, 5876.059830427154, 5817.680027598481, 5835.533157347444, 5835.649861008311, 5849.912251316234, 5856.15085812559, 12149.939250303692, 12989.155704879153, 13102.80345794814, 13263.204207747565, 13586.932065339708, 2208.1167532986774, 2245.2141750387395, 2327.988360058189, 2358.1864256831536, 2807.2280122966145, 9264.471362225224, 9962.651307204213, 10228.07947284748, 10841.512131954081, 15176.317065732068, 1686.6205103851657, 1693.8136804951446, 1699.864010879139, 1731.5411415974777, 1787.8390944815028, 96306.55540166973, 102478.0, 110572.23569410748, 111572.0, 118921.40539297335, 11328.773424531464, 11471.655850324503, 11589.76820463586, 11645.417729113511, 11680.357580332993, 14845.777313340308, 14912.254387280593, 14932.850671493363, 14945.252737363306, 14959.401623935139, 8368.24685778283, 8524.27543658796, 8559.443636163629, 8655.788189653453, 8663.610137543825, 8357.498550086893, 8382.580870956428, 8397.538135402488, 8513.914860851437, 8524.957375213084, 4483.55164483549, 4562.422231439226, 4575.153596584642, 4626.444826620827, 4640.95359046412, 1691.6616676664717, 1866.262826183677, 1943.6598595246057, 1946.98053019468, 1970.965243247278, 841.6465084664455, 862.185234952976, 902.566767951387, 927.7958849053198, 1060.867391576053, 18957.104289570932, 19464.124114414884, 20147.8992605039, 20349.186032558533, 20733.27433539849, 905.5381755304842, 948.2556193388157, 972.8080765924838, 976.6484065736329, 983.1790454970037, 9950.751231219132, 10439.37363758177, 10697.358158510515, 10708.892911070945, 10710.08964238033, 591762.0, 614723.0, 619733.0, 627693.0, 632029.0, 126223.0, 131605.0, 132302.0, 133024.0, 134827.0, 1005.9044390782864, 1009.65671671632, 1036.8030074285862, 1055.1664185293685, 1057.4078516031047, 17432.477025689248, 17637.559061023578, 17795.91102044533, 17821.643567128845, 18109.0, 46127.64001838261, 50498.74750626223, 51137.744311278206, 51510.74244628753, 52254.47745522496, 72531.91202131867, 73902.63048684722, 73961.26038739544, 74505.254947452, 74619.62690186514, 6942.305769423017, 7465.104187497535, 7779.72770953014, 7811.726589569338, 8384.287335576544, 11500.654980350602, 12461.439235234255, 12469.688257793629, 12711.745765084832, 12730.23618582888, 10628.574857005633, 10767.569297228336, 10877.45612719345, 10974.725631858948, 11076.723081922693, 10788.946055269673, 10805.945970270099, 10956.34261944286, 11145.609903653332, 11322.32066076038, 11145.72135696614, 11266.718332041764, 11322.37726925027, 11517.25137866036, 11594.130440216848, 7275.672594733144, 7522.3605993491165, 7569.507981981374, 7702.383809295355, 7707.0, 10337.9483102584, 10853.02322790921, 10994.28537145083, 11122.499487523242, 11294.209405341548], "value": 9318.431583349246, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1350.756863764522, 1530.0590137065703, 1573.5200763767054, 1812.1301775147929, 1954.7361106250653, 7823.178566250547, 7997.720079797208, 8949.776255593613, 8960.193582577567, 9003.81992360154, 6952.652367381636, 8805.603747831346, 8809.603567839447, 8821.382503224777, 8828.735137945872, 2271.034810205664, 2305.5504176685554, 2422.7577242275806, 2462.4213309872166, 2463.9014439422403, 2416.734159242482, 2463.5319289335057, 2465.7534246575287, 2471.5180539794746, 2483.081259933827, 2427.356750461129, 2432.781049705526, 2444.4622183119773, 2459.372859920718, 2466.235467005225, 2402.711674599042, 2408.602580574212, 2431.161249368965, 2434.1237154624323, 2450.9142180023614, 2394.2099107294553, 2415.92752217432, 2425.890834912433, 2428.259380888828, 2428.84212526187, 8680.435771674822, 8790.56047197644, 8840.557972101433, 8871.068537803567, 8874.979377371556, 4163.91672166558, 5474.91787623182, 5482.2873026506695, 5484.8354549363585, 5485.561155107582, 3538.6815186633326, 3567.500549923013, 3627.1838836261895, 3644.0889777555612, 3646.0, 1011.0748664971542, 1027.984580231297, 1029.428667089765, 1036.9740756481149, 1039.0648416425202, 11731.7067073323, 11878.109141814399, 11883.88116118845, 11896.643100707079, 11908.225965312313, 3795.6773674237998, 4482.282834746425, 4663.020765639254, 4734.928976065362, 4736.4316282046375, 9319.716669416037, 9324.160825525736, 9325.347225694137, 9339.159475647088, 9340.065993400742, 5286.656367336111, 5297.788088476418, 5303.602229832778, 5314.627976041717, 5327.067763141435, 3697.7041836653, 4092.6725861930972, 4436.112777444524, 4585.449746030431, 4970.478099799541, 3789.1285004448578, 3819.7135214858995, 3841.865534706272, 3877.5540812806325, 3891.377379619247, 1193.2959553863263, 1193.498730533178, 1196.4855112301793, 1208.359569428187, 1209.8427204463364, 893.1425831201989, 900.9864852027225, 909.6634245329138, 919.4529255093208, 940.8306504829196, 4919.77860996256, 5659.179418984224, 5816.447437493432, 5922.7927022554, 5993.4905533029305, 915.1351972386165, 929.5305870535472, 931.6552875436132, 951.2295041016798, 961.3751061809879, 3302.570665813429, 3629.383004889175, 3796.5952597539085, 3797.025896076149, 3824.158685089276, 10622.628208012682, 11118.722031949266, 11198.888011119785, 11201.71995700114, 11234.38210898392, 10210.387376757419, 10873.347599144077, 10895.019448249537, 11127.109831213484, 11166.720831979266, 6970.745265852094, 8127.98400199975, 8308.12764659714, 8317.667293308199, 8340.958295208486, 5728.570357223308, 5791.420857914175, 5836.678982655825, 6015.608985415934, 6135.141080248858, 9733.318667693193, 9876.160526355341, 9946.35348702332, 10050.346727462691, 10076.899231007596, 9489.240860731263, 10030.247731420026, 10153.39079655223, 10197.235207359627, 10201.795964080527, 6940.93059069423, 7199.956006378941, 7352.044234249727, 7619.9238007619215, 7633.503822251536, 8366.665333386596, 8369.581520924052, 8374.790630234293, 8453.535055572003, 8477.364197685321, 7497.662605182671, 7499.212582678741, 7555.433342499445, 7609.315161635479, 7669.8466030677955, 7179.497435179696, 7282.089738782653, 7388.6675099621725, 7417.92582074194, 7475.476716629783, 6327.92425287693, 6437.967810160919, 6817.659117044227, 6868.141482314711, 6896.172459304916, 4686.671932964796, 4825.5898248649255, 4906.803727850989, 4937.703737775745, 4974.0797952378925, 4776.665633405767, 4898.0, 4954.702717836942, 4963.70217786934, 5026.271190677405], "value": 4552.926506588359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.61366053905044, 322.5564848333541, 330.46794660596436, 332.7620751162918, 337.1402922547503, 5953.487442408978, 5953.9999999999945, 5965.0, 5986.521078313735, 5994.520438364932, 5397.811076612319, 5883.705814709258, 5901.0, 5904.232449781533, 5909.793157239497, 3974.4634474346026, 4172.582741725826, 4343.717658352213, 4425.645948324126, 4477.641788656916, 3855.8264878080554, 4191.497020357561, 4221.409002739603, 4278.823323586008, 4449.755263460508, 3702.3520883845354, 4437.090396468732, 4464.241079016575, 4467.664925130628, 4475.28395456727, 3710.5361829771277, 3758.913802839423, 3789.431585262219, 3871.915863558208, 4083.571225021389, 3820.732548721591, 3826.766382406862, 3829.6936245100324, 3836.462895194675, 3852.57621661617, 5480.205370221335, 5616.775328986836, 5667.8583035424035, 5684.118961560924, 5750.45370689788, 4334.848280310174, 4460.8438704645505, 4966.130927087781, 5163.3545806774155, 5193.0, 5266.552343050846, 6136.325004249529, 6168.414000669929, 6198.256209254873, 6212.986701449793, 4263.868065967016, 4280.0, 4302.451117597662, 4310.116426132635, 4311.805968731414, 5227.921581176285, 6149.169862068653, 6178.691065446755, 6359.936400635935, 6401.55189136756, 4331.306990881507, 4345.196138714348, 4355.62977146946, 4358.825646974085, 4362.345648152739, 4412.691111622155, 4415.889602759957, 4418.80115394808, 4426.181156485996, 4453.153900758845, 4882.5605695487575, 5116.539511443988, 5152.716600587004, 5156.20078887773, 5161.535461808456, 4690.577847993697, 5726.3700992890745, 5998.640081595118, 6302.527310451735, 6319.33646967071, 1404.3539971612968, 1452.6949340638585, 1491.4928924165836, 1499.5426394949645, 1526.2750193658146, 855.8630619100913, 1014.1430491235042, 1022.6420752736483, 1025.1235193909101, 1027.383569858094, 5768.307803063659, 5817.894600025981, 5874.125874125874, 5878.794242201502, 5885.61743486672, 6403.231612206472, 6454.9677251614385, 6465.967670161714, 6468.514861385415, 6501.739930402826, 4685.835995740133, 5162.483751624883, 5481.862953426196, 5604.355499117653, 5883.382244864228, 2839.474697181028, 3317.767756257087, 3397.575303087114, 3430.6569343065994, 3431.9313613728086, 5335.413104558495, 6364.949783285706, 6724.596524208465, 6800.653774447807, 6829.692663830139, 5981.342052374235, 6633.2040155181685, 6654.534182607171, 6663.433608143265, 6693.598384096969, 5923.259592550931, 6419.743210271537, 6692.96653516739, 6776.627285499247, 6778.661066946633, 5539.806106786243, 6314.905276420949, 6466.191726034246, 6490.643014634241, 6492.220933488012, 5661.320641523043, 5720.742566584597, 6321.322221556107, 6395.360463953753, 6400.39196276353, 5397.8110766123, 5484.369297530676, 6126.356732543019, 6613.603183808987, 6818.897716534355, 4158.589956235857, 4223.5354111047145, 4231.0, 4234.174336004573, 4241.660667146559, 6198.504119670325, 6252.531060170414, 6462.579932304573, 6496.642684652201, 6517.674116294261, 6256.593321434092, 6280.0, 6283.654399007915, 6287.182666253358, 6293.433590976834, 5536.501714845522, 5922.822315330547, 5956.502840355504, 6021.916055109947, 6284.560080794484, 6047.516198704181, 6111.511079113571, 6124.51956957433, 6156.692165391623, 6202.379762023762, 6232.439080482778, 6239.812805615838, 6266.396821827377, 6267.0286105653695, 6295.622262664255, 5556.944180605672, 5750.827475175752, 5847.707614619337, 6178.351273116258, 6229.750809967732], "value": 4537.451305921351, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [663.3267233757736, 738.5125816960807, 743.8772602520581, 744.028324938912, 754.7320701151094, 14814.407423703053, 14906.254687265635, 15072.623184420381, 15336.386544538205, 15338.003029803083, 13123.737525249513, 13194.406251718696, 13244.0, 13249.006324525675, 13260.204387736743, 720.91709453413, 744.9739259125931, 921.3412410126775, 939.7650587353162, 940.8917974432925, 907.4782000349792, 940.7930255343813, 945.7635591102224, 946.3328353510793, 948.0188005414398, 971.383171685981, 991.3556188477511, 993.3146129170885, 993.7515621094726, 994.4480813148699, 765.2232983521702, 953.4612943686794, 970.5729479029239, 986.0632399220733, 988.5551501824136, 727.4943913979786, 735.4998600951351, 736.4918206437541, 736.7973807203063, 738.3023043224156, 14650.0, 14697.485588004476, 14720.852791472162, 14738.778918316233, 14754.188519631416, 12182.0, 12195.878041219563, 12383.442745076492, 12497.312647804367, 12601.814350405752, 9261.30540209487, 9479.0, 9816.705498835046, 9850.753731156707, 9855.457949812757, 3400.523926650271, 3477.7217822574135, 3481.5032676480178, 3485.4771784232316, 3724.8510059597584, 78102.0, 79117.0, 79163.20836791616, 79863.20136798616, 80722.59638701827, 9262.95368523153, 9343.085977431329, 9403.247740180768, 9636.614535418505, 9713.36863103896, 21480.14079436805, 21526.56946861054, 21536.892315538324, 21566.460838479165, 21574.568508629738, 7156.0, 7223.89164162538, 7260.0, 7261.273872612696, 7464.776056718307, 3880.825362858678, 3951.4863067801, 4010.63904248619, 4034.3141665916864, 4037.4751282333696, 3686.7419280650633, 3883.3204189266767, 3888.844446222176, 3899.532056153223, 3928.6660633846445, 657.1719633262124, 660.4716227018462, 669.1334721535623, 689.5001124184926, 691.4192078653955, 444.4799584486094, 578.124141924984, 586.1764221269124, 594.1859652276408, 618.1099217127322, 6382.904256436157, 7428.517146385468, 7641.465097443237, 7755.573443460551, 7804.804879878048, 524.8556646922051, 525.3879230696273, 542.1109380615793, 557.2671936403616, 557.4286356484636, 3530.735194860396, 3611.7652352596997, 3629.5281613390616, 3637.890863274106, 3650.543682039745, 92873.53563232141, 93423.0, 95248.0, 96262.0, 96290.0, 70170.0, 71723.0, 71900.0, 72839.27160728326, 73768.0, 67361.0, 70916.29083709097, 70991.2900870995, 71074.64462677758, 71826.64086679637, 7043.823904402431, 7054.4003759681, 7136.250693677089, 7312.707491700273, 7496.812579685551, 37057.25885482221, 39176.80411597924, 41675.16649667051, 42898.78550607227, 43273.70178894638, 41748.95627609334, 53855.0, 53868.0, 54362.45637543732, 54900.0, 8538.359623028173, 10383.792324153626, 10504.159667226451, 10527.894721052691, 10848.728781780519, 12007.279563226235, 12021.210386118579, 12268.079894008164, 12642.178258413174, 12650.620481385573, 10702.839457408301, 10908.181886358714, 11176.99407053369, 11212.719682007688, 11268.042216411697, 11079.446027698421, 11106.278091924, 11135.387553684624, 11208.775824483302, 11472.254303470248, 11029.503672334924, 11146.944265278622, 11430.542778288775, 11548.653540393801, 11685.0, 9426.293028023063, 9650.903490965, 9822.995213265678, 9878.851817222603, 10185.541650625888, 8909.910900890907, 9021.233195178484, 9648.75878103053, 9739.736252799205, 9917.206623470249], "value": 6712.1271062676815, "unit": "score"}], "name": "dromaeo_css", "value": 7241.807697412217, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 114.0, 106.0, 138.0, 169.0, 172.0, 112.0, 112.0, 112.0], "value": 126.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 122.0, 145.0, 182.0, 149.0, 161.0, 141.0, 123.0, 172.0, 119.0], "value": 147.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 188.0, 185.0, 184.0, 187.0, 187.0, 184.0, 188.0, 183.0], "value": 186.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 74.0, 86.0, 92.0, 56.0, 57.0, 59.0, 78.0, 85.0, 56.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 97.0, 139.0, 80.0, 78.0, 81.0, 124.0, 77.0, 77.0, 128.0], "value": 97.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 87.0, 84.0, 89.0, 84.0, 87.0, 83.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 94.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 84.0, 93.0, 132.0, 86.0, 85.0, 85.0], "value": 90.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 56.0, 59.0, 59.0, 57.0, 78.0, 57.0, 57.0], "value": 60.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 73.0, 71.0, 76.0, 70.0, 71.0, 68.0, 68.0, 68.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 83.0, 81.0, 81.0, 80.0, 81.0, 81.0, 82.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 149.0, 139.0, 135.0, 136.0, 137.0, 142.0, 143.0, 186.0, 167.0], "value": 148.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 169.0, 167.0, 169.0, 171.0, 166.0, 166.0, 164.0, 164.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 68.0, 70.0, 70.0, 92.0, 91.0, 93.0, 92.0, 67.0], "value": 78.1, "unit": "score"}], "name": "kraken", "value": 1502.5, "alertThreshold": 2.0}]} 15:44:37 INFO - Return code: 0 15:44:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:44:37 ERROR - # TBPL SUCCESS # 15:44:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:44:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:44:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:44:37 INFO - 2016-04-18 15:44:37.813 screenresolution[6582:108493] starting screenresolution argv=screenresolution get 15:44:37 INFO - 2016-04-18 15:44:37.815 screenresolution[6582:108493] Display 0: 1600x1200x32@60 15:44:37 INFO - 2016-04-18 15:44:37.821 screenresolution[6583:108496] starting screenresolution argv=screenresolution list 15:44:37 INFO - Available Modes on Display 0 15:44:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:44:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:44:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:44:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:44:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:44:38 INFO - Intel Iris: 15:44:38 INFO - Chipset Model: Intel Iris 15:44:38 INFO - Type: GPU 15:44:38 INFO - Bus: Built-In 15:44:38 INFO - VRAM (Dynamic, Max): 1536 MB 15:44:38 INFO - Vendor: Intel (0x8086) 15:44:38 INFO - Device ID: 0x0a2e 15:44:38 INFO - Revision ID: 0x0009 15:44:38 INFO - Displays: 15:44:38 INFO - Display: 15:44:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:44:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:44:38 INFO - Display Serial Number: 1600x1200 60 15:44:38 INFO - Main Display: Yes 15:44:38 INFO - Mirror: Off 15:44:38 INFO - Online: Yes 15:44:38 INFO - Rotation: Supported 15:44:38 INFO - Return code: 0 15:44:38 INFO - Running post-action listener: _resource_record_post_action 15:44:38 INFO - Running post-run listener: _resource_record_post_run 15:44:39 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 0; Write bytes: 693576192; Read time: 0; Write time: 2009 15:44:39 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 381497856; Write bytes: 376290816; Read time: 28323; Write time: 691 15:44:39 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 0; Write bytes: 296322048; Read time: 0; Write time: 1271 15:44:39 INFO - Running post-run listener: _upload_blobber_files 15:44:39 INFO - Blob upload gear active. 15:44:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:44:39 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 15:44:39 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:44:39 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:44:39 INFO - (blobuploader) - INFO - Open directory for files ... 15:44:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:44:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:44:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:40 INFO - (blobuploader) - INFO - Done attempting. 15:44:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:44:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:44:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:44:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:44:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:44:41 INFO - (blobuploader) - INFO - Done attempting. 15:44:41 INFO - (blobuploader) - INFO - Iteration through files over. 15:44:41 INFO - Return code: 0 15:44:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:44:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:44:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53c4a62f219b14227172491ad94af63fde84d78ae6139481926a2097dd377f03c24c3a10f77c31eb906b0bbc790186e321a9b1b12f640633a4d551ab349d9f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e403b34cbbf5ecee9e4a38e4e1b1432c659364bb799f6d1ee6bc5147523ec69cdaaf7c9787e482dc61996dcc1476b5c8bc343e6b9ff59f617055aed33134c5e"} 15:44:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:44:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:44:41 INFO - Contents: 15:44:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53c4a62f219b14227172491ad94af63fde84d78ae6139481926a2097dd377f03c24c3a10f77c31eb906b0bbc790186e321a9b1b12f640633a4d551ab349d9f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e403b34cbbf5ecee9e4a38e4e1b1432c659364bb799f6d1ee6bc5147523ec69cdaaf7c9787e482dc61996dcc1476b5c8bc343e6b9ff59f617055aed33134c5e"} 15:44:41 INFO - Running post-run listener: copy_logs_to_upload_dir 15:44:41 INFO - Copying logs to upload dir... 15:44:41 INFO - mkdir: /builds/slave/test/build/upload/logs 15:44:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.114225 ========= master_lag: 3.55 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-04-18 15:44:45.295749) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 15:44:45.299590) ========= 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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53c4a62f219b14227172491ad94af63fde84d78ae6139481926a2097dd377f03c24c3a10f77c31eb906b0bbc790186e321a9b1b12f640633a4d551ab349d9f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e403b34cbbf5ecee9e4a38e4e1b1432c659364bb799f6d1ee6bc5147523ec69cdaaf7c9787e482dc61996dcc1476b5c8bc343e6b9ff59f617055aed33134c5e"} build_url:https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011112 build_url: 'https://queue.taskcluster.net/v1/task/ef7OkbCcTWKV-_mlwv0sig/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/53c4a62f219b14227172491ad94af63fde84d78ae6139481926a2097dd377f03c24c3a10f77c31eb906b0bbc790186e321a9b1b12f640633a4d551ab349d9f59", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3e403b34cbbf5ecee9e4a38e4e1b1432c659364bb799f6d1ee6bc5147523ec69cdaaf7c9787e482dc61996dcc1476b5c8bc343e6b9ff59f617055aed33134c5e"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 15:44:45.345783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:44:45.346124) ========= 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.JP4QOpLSod/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.Ey9bnmmxnL/Listeners TMPDIR=/var/folders/qf/j5tbbggj5vn1qwthzwd0p6bc00000w/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.005437 ========= master_lag: 0.35 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 15:44:45.700646) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 15:44:45.700952) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 15:44:45.702834) ========= ========= Total master_lag: 3.99 =========