builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0145 starttime: 1461097805.85 results: success (0) buildid: 20160419123832 builduid: a1783ebad6b546558fb0488a272d61ad revision: f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:05.849830) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:05.850266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:05.850539) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:05.885732) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:05.886003) ========= 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.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-19 13:30:05-- 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% 9.79M=0.001s 2016-04-19 13:30:06 (9.79 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144696 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:06.047480) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:06.047756) ========= 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.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.026646 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:06.105433) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 13:30:06.105736) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 --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 f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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-19 13:30:06,184 truncating revision to first 12 chars 2016-04-19 13:30:06,184 Setting DEBUG logging. 2016-04-19 13:30:06,184 attempt 1/10 2016-04-19 13:30:06,185 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f4ab4fd96f8e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-19 13:30:07,640 unpacking tar archive at: fx-team-f4ab4fd96f8e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.692111 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-19 13:30:07.816961) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:07.819436) ========= script_repo_revision: f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:07.819813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:07.820109) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-19 13:30:07.836694) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 13:30:07.836986) ========= /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.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 13:30:08 INFO - MultiFileLogger online at 20160419 13:30:08 in /builds/slave/test 13:30:08 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 13:30:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:30:08 INFO - {'append_to_log': False, 13:30:08 INFO - 'base_work_dir': '/builds/slave/test', 13:30:08 INFO - 'blob_upload_branch': 'Fx-Team', 13:30:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:30:08 INFO - 'branch': 'Fx-Team', 13:30:08 INFO - 'buildbot_json_path': 'buildprops.json', 13:30:08 INFO - 'config_files': ('talos/mac_config.py',), 13:30:08 INFO - 'default_actions': ('clobber', 13:30:08 INFO - 'read-buildbot-config', 13:30:08 INFO - 'download-and-extract', 13:30:08 INFO - 'populate-webroot', 13:30:08 INFO - 'create-virtualenv', 13:30:08 INFO - 'install', 13:30:08 INFO - 'run-tests'), 13:30:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:30:08 INFO - 'download_minidump_stackwalk': True, 13:30:08 INFO - 'download_symbols': 'ondemand', 13:30:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:30:08 INFO - 'tooltool.py': '/tools/tooltool.py', 13:30:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:30:08 INFO - '/tools/misc-python/virtualenv.py')}, 13:30:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:30:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:30:08 INFO - 'installer_path': 'installer.exe', 13:30:08 INFO - 'log_level': 'info', 13:30:08 INFO - 'log_name': 'talos', 13:30:08 INFO - 'log_to_console': True, 13:30:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:30:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:30:08 INFO - 'opt_config_files': (), 13:30:08 INFO - 'pip_index': False, 13:30:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:08 INFO - 'cmd': ('bash', 13:30:08 INFO - '-c', 13:30:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:08 INFO - 'enabled': True, 13:30:08 INFO - 'halt_on_failure': False, 13:30:08 INFO - 'name': 'check_screen_resolution'},), 13:30:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:30:08 INFO - 'cmd': ('bash', 13:30:08 INFO - '-c', 13:30:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:30:08 INFO - 'enabled': True, 13:30:08 INFO - 'halt_on_failure': False, 13:30:08 INFO - 'name': 'check_screen_resolution'},), 13:30:08 INFO - 'run_cmd_checks_enabled': True, 13:30:08 INFO - 'sps_profile': False, 13:30:08 INFO - 'sps_profile_interval': 0, 13:30:08 INFO - 'suite': 'dromaeojs', 13:30:08 INFO - 'system_bits': '32', 13:30:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:30:08 INFO - 'title': 't-yosemite-r7-0145', 13:30:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:30:08 INFO - 'use_talos_json': True, 13:30:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:30:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:30:08 INFO - 'work_dir': 'build'} 13:30:08 INFO - ##### 13:30:08 INFO - ##### Running clobber step. 13:30:08 INFO - ##### 13:30:08 INFO - Running pre-action listener: _resource_record_pre_action 13:30:08 INFO - Running main action method: clobber 13:30:08 INFO - rmtree: /builds/slave/test/build 13:30:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:30:09 INFO - Running post-action listener: _resource_record_post_action 13:30:09 INFO - ##### 13:30:09 INFO - ##### Running read-buildbot-config step. 13:30:09 INFO - ##### 13:30:09 INFO - Running pre-action listener: _resource_record_pre_action 13:30:09 INFO - Running main action method: read_buildbot_config 13:30:09 INFO - Using buildbot properties: 13:30:09 INFO - { 13:30:09 INFO - "project": "", 13:30:09 INFO - "product": "firefox", 13:30:09 INFO - "script_repo_revision": "production", 13:30:09 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:30:09 INFO - "repository": "", 13:30:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:30:09 INFO - "buildid": "20160419123832", 13:30:09 INFO - "pgo_build": "False", 13:30:09 INFO - "basedir": "/builds/slave/test", 13:30:09 INFO - "buildnumber": 740, 13:30:09 INFO - "slavename": "t-yosemite-r7-0145", 13:30:09 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 13:30:09 INFO - "platform": "yosemite_r7", 13:30:09 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:30:09 INFO - "branch": "fx-team", 13:30:09 INFO - "repo_path": "integration/fx-team", 13:30:09 INFO - "revision": "f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35", 13:30:09 INFO - "stage_platform": "macosx64", 13:30:09 INFO - "builduid": "a1783ebad6b546558fb0488a272d61ad", 13:30:09 INFO - "slavebuilddir": "test" 13:30:09 INFO - } 13:30:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 13:30:09 INFO - Running post-action listener: _resource_record_post_action 13:30:09 INFO - ##### 13:30:09 INFO - ##### Running download-and-extract step. 13:30:09 INFO - ##### 13:30:09 INFO - Running pre-action listener: _resource_record_pre_action 13:30:09 INFO - Running main action method: download_and_extract 13:30:09 INFO - mkdir: /builds/slave/test/build/tests 13:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:09 INFO - https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 13:30:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:30:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 13:30:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:11 INFO - Downloaded 1199 bytes. 13:30:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 13:30:11 INFO - Using the following test package requirements: 13:30:11 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:30:11 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 13:30:11 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 13:30:11 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'jsshell-mac.zip'], 13:30:11 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 13:30:11 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 13:30:11 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 13:30:11 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 13:30:11 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 13:30:11 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 13:30:11 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 13:30:11 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:30:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:11 INFO - https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:30:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:30:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:30:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:12 INFO - Downloaded 18095485 bytes. 13:30:12 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'] 13:30:12 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 13:30:16 INFO - Return code: 0 13:30:16 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 13:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:16 INFO - https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 13:30:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:30:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 13:30:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:17 INFO - Downloaded 18095485 bytes. 13:30:17 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'] 13:30:17 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 13:30:20 INFO - Return code: 0 13:30:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:20 INFO - https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 13:30:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:30:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 13:30:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:21 INFO - Downloaded 10845952 bytes. 13:30:21 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'] 13:30:21 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 13:30:22 INFO - Return code: 0 13:30: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')]} 13:30:22 INFO - https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 13:30:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:30:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:30:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 13:30:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZRVP-UwORtehXbmQYGyNhw/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 13:30:25 INFO - Downloaded 109983849 bytes. 13:30:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:30:25 INFO - mkdir: /builds/slave/test/properties 13:30:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:30:25 INFO - Writing to file /builds/slave/test/properties/build_url 13:30:25 INFO - Contents: 13:30:25 INFO - build_url:https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 13:30:26 INFO - Running post-action listener: _resource_record_post_action 13:30:26 INFO - Running post-action listener: set_extra_try_arguments 13:30:26 INFO - ##### 13:30:26 INFO - ##### Running populate-webroot step. 13:30:26 INFO - ##### 13:30:26 INFO - Running pre-action listener: _resource_record_pre_action 13:30:26 INFO - Running main action method: populate_webroot 13:30:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:30:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:30:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:30:26 INFO - '--tppagecycles', 13:30:26 INFO - '7'], 13:30:26 INFO - 'tests': ['tsvgm']}}, 13:30:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:30:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:30:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:30:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:30:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:30:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['damp', 'tps']}, 13:30:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'tests': ['damp', 'tps']}, 13:30:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['dromaeo_dom']}, 13:30:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:30:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'other-e10s': {'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['a11yr', 13:30:26 INFO - 'ts_paint', 13:30:26 INFO - 'tpaint', 13:30:26 INFO - 'sessionrestore', 13:30:26 INFO - 'sessionrestore_no_auto_restore', 13:30:26 INFO - 'tabpaint']}, 13:30:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['tsvgx', 13:30:26 INFO - 'tsvgr_opacity', 13:30:26 INFO - 'tart', 13:30:26 INFO - 'tscrollx', 13:30:26 INFO - 'cart']}, 13:30:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:30:26 INFO - 'tsvgr_opacity', 13:30:26 INFO - 'tart', 13:30:26 INFO - 'tscrollx', 13:30:26 INFO - 'cart']}, 13:30:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'talos_options': ['--disable-e10s'], 13:30:26 INFO - 'tests': ['tp5o']}, 13:30:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'tests': ['tp5o']}, 13:30:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'talos_options': ['--disable-e10s', 13:30:26 INFO - '--xperf_path', 13:30:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:26 INFO - 'tests': ['tp5n']}, 13:30:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:30:26 INFO - 'talos_options': ['--xperf_path', 13:30:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:30:26 INFO - 'tests': ['tp5n']}}, 13:30:26 INFO - 'talos.zip': {'path': '', 13:30:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:30:26 INFO - Running post-action listener: _resource_record_post_action 13:30:26 INFO - ##### 13:30:26 INFO - ##### Running create-virtualenv step. 13:30:26 INFO - ##### 13:30:26 INFO - Running pre-action listener: _resource_record_pre_action 13:30:26 INFO - Running main action method: create_virtualenv 13:30:26 INFO - Creating virtualenv /builds/slave/test/build/venv 13:30:26 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 13:30:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:30:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:30:26 INFO - Using real prefix '/tools/python27' 13:30:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:30:27 INFO - Installing distribute.............................................................................................................................................................................................done. 13:30:30 INFO - Installing pip.................done. 13:30:30 INFO - Return code: 0 13:30:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:30 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 13:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 13:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:30 INFO - 'HOME': '/Users/cltbld', 13:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:30 INFO - 'LOGNAME': 'cltbld', 13:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:30 INFO - 'MOZ_NO_REMOTE': '1', 13:30:30 INFO - 'NO_EM_RESTART': '1', 13:30:30 INFO - 'PAGER': '/bin/cat', 13:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:30 INFO - 'PWD': '/builds/slave/test', 13:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:30 INFO - 'SHELL': '/bin/bash', 13:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:30 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:30 INFO - 'USER': 'cltbld', 13:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:30 INFO - 'XPC_FLAGS': '0x0', 13:30:30 INFO - 'XPC_SERVICE_NAME': '0', 13:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:30 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 13:30:30 INFO - Cleaning up... 13:30:30 INFO - Return code: 0 13:30:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 13:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:30 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 13:30:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 13:30:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:30 INFO - 'HOME': '/Users/cltbld', 13:30:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:30 INFO - 'LOGNAME': 'cltbld', 13:30:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:30 INFO - 'MOZ_NO_REMOTE': '1', 13:30:30 INFO - 'NO_EM_RESTART': '1', 13:30:30 INFO - 'PAGER': '/bin/cat', 13:30:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:30 INFO - 'PWD': '/builds/slave/test', 13:30:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:30 INFO - 'SHELL': '/bin/bash', 13:30:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:30 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:30 INFO - 'USER': 'cltbld', 13:30:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:30 INFO - 'XPC_FLAGS': '0x0', 13:30:30 INFO - 'XPC_SERVICE_NAME': '0', 13:30:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:30 INFO - Downloading/unpacking psutil>=0.7.1 13:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:30:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:33 INFO - Installing collected packages: psutil 13:30:33 INFO - Running setup.py install for psutil 13:30:33 INFO - building 'psutil._psutil_osx' extension 13:30:33 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 13:30:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 13:30:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 13:30:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 13:30:34 INFO - building 'psutil._psutil_posix' extension 13:30:34 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 13:30:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:30:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:30:34 INFO - ^ 13:30:34 INFO - 1 warning generated. 13:30:34 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 13:30:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:30:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:30:34 INFO - Successfully installed psutil 13:30:34 INFO - Cleaning up... 13:30:34 INFO - Return code: 0 13:30:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 13:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:34 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 13:30:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 13:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:34 INFO - 'HOME': '/Users/cltbld', 13:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:34 INFO - 'LOGNAME': 'cltbld', 13:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:34 INFO - 'MOZ_NO_REMOTE': '1', 13:30:34 INFO - 'NO_EM_RESTART': '1', 13:30:34 INFO - 'PAGER': '/bin/cat', 13:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:34 INFO - 'PWD': '/builds/slave/test', 13:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:34 INFO - 'SHELL': '/bin/bash', 13:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:34 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:34 INFO - 'USER': 'cltbld', 13:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:34 INFO - 'XPC_FLAGS': '0x0', 13:30:34 INFO - 'XPC_SERVICE_NAME': '0', 13:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:35 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 13:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:37 INFO - Downloading mozsystemmonitor-0.0.tar.gz 13:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:30:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 13:30:37 INFO - Installing collected packages: mozsystemmonitor 13:30:37 INFO - Running setup.py install for mozsystemmonitor 13:30:37 INFO - Successfully installed mozsystemmonitor 13:30:37 INFO - Cleaning up... 13:30:37 INFO - Return code: 0 13:30:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:30:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:37 INFO - 'HOME': '/Users/cltbld', 13:30:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:37 INFO - 'LOGNAME': 'cltbld', 13:30:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:37 INFO - 'MOZ_NO_REMOTE': '1', 13:30:37 INFO - 'NO_EM_RESTART': '1', 13:30:37 INFO - 'PAGER': '/bin/cat', 13:30:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:37 INFO - 'PWD': '/builds/slave/test', 13:30:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:37 INFO - 'SHELL': '/bin/bash', 13:30:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:37 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:37 INFO - 'USER': 'cltbld', 13:30:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:37 INFO - 'XPC_FLAGS': '0x0', 13:30:37 INFO - 'XPC_SERVICE_NAME': '0', 13:30:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:37 INFO - Downloading/unpacking blobuploader==1.2.4 13:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:40 INFO - Downloading blobuploader-1.2.4.tar.gz 13:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:30:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:30:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:41 INFO - Downloading docopt-0.6.1.tar.gz 13:30:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:30:41 INFO - Installing collected packages: blobuploader, requests, docopt 13:30:41 INFO - Running setup.py install for blobuploader 13:30:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:30:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:30:41 INFO - Running setup.py install for requests 13:30:42 INFO - Running setup.py install for docopt 13:30:42 INFO - Successfully installed blobuploader requests docopt 13:30:42 INFO - Cleaning up... 13:30:42 INFO - Return code: 0 13:30:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30: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')]} 13:30:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30: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')]} 13:30:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:42 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:30:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:30:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:42 INFO - 'HOME': '/Users/cltbld', 13:30:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:42 INFO - 'LOGNAME': 'cltbld', 13:30:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:42 INFO - 'MOZ_NO_REMOTE': '1', 13:30:42 INFO - 'NO_EM_RESTART': '1', 13:30:42 INFO - 'PAGER': '/bin/cat', 13:30:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:42 INFO - 'PWD': '/builds/slave/test', 13:30:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:42 INFO - 'SHELL': '/bin/bash', 13:30:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:42 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:42 INFO - 'USER': 'cltbld', 13:30:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:42 INFO - 'XPC_FLAGS': '0x0', 13:30:42 INFO - 'XPC_SERVICE_NAME': '0', 13:30:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:30:42 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-QPbhVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:30:42 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-rxorqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:30:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:30:42 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-AnY_Xe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-jDC8Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-jdPXVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-VMg7cG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-FoFa2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-R2PbrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-URD6tP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-tXgYsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-cM58sG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:30:43 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-gVogdO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:30:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:30:44 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-FdRIgh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:30:44 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-E38loK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:44 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-lXpiNP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:30:44 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-cDLVsX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:30:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:30:44 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-cZQnEE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:30:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:30:44 INFO - Running setup.py install for manifestparser 13:30:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:30:44 INFO - Running setup.py install for mozcrash 13:30:44 INFO - Running setup.py install for mozdebug 13:30:44 INFO - Running setup.py install for mozdevice 13:30:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Running setup.py install for mozfile 13:30:45 INFO - Running setup.py install for mozhttpd 13:30:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Running setup.py install for mozinfo 13:30:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Running setup.py install for mozInstall 13:30:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:30:45 INFO - Running setup.py install for mozleak 13:30:45 INFO - Running setup.py install for mozlog 13:30:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Running setup.py install for moznetwork 13:30:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Running setup.py install for mozprocess 13:30:46 INFO - Running setup.py install for mozprofile 13:30:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Running setup.py install for mozrunner 13:30:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:30:46 INFO - Running setup.py install for mozscreenshot 13:30:46 INFO - Running setup.py install for moztest 13:30:46 INFO - Running setup.py install for mozversion 13:30:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:30:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:30:47 INFO - Cleaning up... 13:30:47 INFO - Return code: 0 13:30:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:47 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 13:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:30:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:47 INFO - 'HOME': '/Users/cltbld', 13:30:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:47 INFO - 'LOGNAME': 'cltbld', 13:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:47 INFO - 'MOZ_NO_REMOTE': '1', 13:30:47 INFO - 'NO_EM_RESTART': '1', 13:30:47 INFO - 'PAGER': '/bin/cat', 13:30:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:47 INFO - 'PWD': '/builds/slave/test', 13:30:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:47 INFO - 'SHELL': '/bin/bash', 13:30:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:47 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:47 INFO - 'USER': 'cltbld', 13:30:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:47 INFO - 'XPC_FLAGS': '0x0', 13:30:47 INFO - 'XPC_SERVICE_NAME': '0', 13:30:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-vwIfNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-TIXNW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-92a0fW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-C1Fris-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-mZaGHb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 13:30:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:30:47 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-scy_Cn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-t3SXly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-8fUpep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-W3IQBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-3NQKpU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-b6MZPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-z6XvAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-cyQAts-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-VWGE1z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-ctDuPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:30:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 13:30:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:30:48 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-kPs8lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 13:30:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:30:49 INFO - Running setup.py (path:/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/pip-NrpTe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:30:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:30:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:51 INFO - Downloading blessings-1.6.tar.gz 13:30:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:30:51 INFO - Installing collected packages: blessings 13:30:51 INFO - Running setup.py install for blessings 13:30:51 INFO - Successfully installed blessings 13:30:51 INFO - Cleaning up... 13:30:51 INFO - Return code: 0 13:30:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:30:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:30:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:30:52 INFO - Reading from file tmpfile_stdout 13:30:52 INFO - Current package versions: 13:30:52 INFO - blessings == 1.6 13:30:52 INFO - blobuploader == 1.2.4 13:30:52 INFO - docopt == 0.6.1 13:30:52 INFO - manifestparser == 1.1 13:30:52 INFO - mozInstall == 1.12 13:30:52 INFO - mozcrash == 0.17 13:30:52 INFO - mozdebug == 0.1 13:30:52 INFO - mozdevice == 0.48 13:30:52 INFO - mozfile == 1.2 13:30:52 INFO - mozhttpd == 0.7 13:30:52 INFO - mozinfo == 0.9 13:30:52 INFO - mozleak == 0.1 13:30:52 INFO - mozlog == 3.1 13:30:52 INFO - moznetwork == 0.27 13:30:52 INFO - mozprocess == 0.22 13:30:52 INFO - mozprofile == 0.28 13:30:52 INFO - mozrunner == 6.11 13:30:52 INFO - mozscreenshot == 0.1 13:30:52 INFO - mozsystemmonitor == 0.0 13:30:52 INFO - moztest == 0.7 13:30:52 INFO - mozversion == 1.4 13:30:52 INFO - psutil == 3.1.1 13:30:52 INFO - requests == 1.2.3 13:30:52 INFO - wsgiref == 0.1.2 13:30:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 13:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 13:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:52 INFO - 'HOME': '/Users/cltbld', 13:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:52 INFO - 'LOGNAME': 'cltbld', 13:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:52 INFO - 'MOZ_NO_REMOTE': '1', 13:30:52 INFO - 'NO_EM_RESTART': '1', 13:30:52 INFO - 'PAGER': '/bin/cat', 13:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:52 INFO - 'PWD': '/builds/slave/test', 13:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:52 INFO - 'SHELL': '/bin/bash', 13:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:52 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:52 INFO - 'USER': 'cltbld', 13:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:52 INFO - 'XPC_FLAGS': '0x0', 13:30:52 INFO - 'XPC_SERVICE_NAME': '0', 13:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 13:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 13:30:52 INFO - Cleaning up... 13:30:52 INFO - Return code: 0 13:30:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:52 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 13:30:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 13:30:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:52 INFO - 'HOME': '/Users/cltbld', 13:30:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:52 INFO - 'LOGNAME': 'cltbld', 13:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:52 INFO - 'MOZ_NO_REMOTE': '1', 13:30:52 INFO - 'NO_EM_RESTART': '1', 13:30:52 INFO - 'PAGER': '/bin/cat', 13:30:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:52 INFO - 'PWD': '/builds/slave/test', 13:30:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:52 INFO - 'SHELL': '/bin/bash', 13:30:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:52 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:52 INFO - 'USER': 'cltbld', 13:30:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:52 INFO - 'XPC_FLAGS': '0x0', 13:30:52 INFO - 'XPC_SERVICE_NAME': '0', 13:30:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:52 INFO - Downloading/unpacking jsonschema 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:30:55 INFO - Downloading/unpacking functools32 (from jsonschema) 13:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:55 INFO - Downloading functools32-3.2.3-2.tar.gz 13:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:30:55 INFO - warning: no files found matching '*.txt' 13:30:55 INFO - no previously-included directories found matching 'build' 13:30:55 INFO - no previously-included directories found matching 'dist' 13:30:55 INFO - no previously-included directories found matching '.git*' 13:30:55 INFO - Installing collected packages: jsonschema, functools32 13:30:55 INFO - Running setup.py install for functools32 13:30:55 INFO - warning: no files found matching '*.txt' 13:30:55 INFO - no previously-included directories found matching 'build' 13:30:55 INFO - no previously-included directories found matching 'dist' 13:30:55 INFO - no previously-included directories found matching '.git*' 13:30:55 INFO - Successfully installed jsonschema functools32 13:30:55 INFO - Cleaning up... 13:30:55 INFO - Return code: 0 13:30:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 13:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:30:55 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 0x10e977a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e987420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f99595c1a50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, '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.z9mxW7rq93/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.B25v2o4aH5/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 13:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 13:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 13:30:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:30:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:30:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:30:55 INFO - 'HOME': '/Users/cltbld', 13:30:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:30:55 INFO - 'LOGNAME': 'cltbld', 13:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:30:55 INFO - 'MOZ_NO_REMOTE': '1', 13:30:55 INFO - 'NO_EM_RESTART': '1', 13:30:55 INFO - 'PAGER': '/bin/cat', 13:30:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:30:55 INFO - 'PWD': '/builds/slave/test', 13:30:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:30:55 INFO - 'SHELL': '/bin/bash', 13:30:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:30:55 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:30:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:30:55 INFO - 'USER': 'cltbld', 13:30:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:30:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:30:55 INFO - 'XPC_FLAGS': '0x0', 13:30:55 INFO - 'XPC_SERVICE_NAME': '0', 13:30:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:30:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:30:56 INFO - Downloading/unpacking flake8 13:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 13:30:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 13:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:59 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 13:30:59 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 13:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 13:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:30:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:30:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 13:30:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:30:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:30:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:30:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:30:59 INFO - no previously-included directories found matching 'docs/_build' 13:30:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 13:31:00 INFO - Running setup.py install for pep8 13:31:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 13:31:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 13:31:00 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 13:31:00 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 13:31:00 INFO - no previously-included directories found matching 'docs/_build' 13:31:00 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 13:31:00 INFO - Successfully installed flake8 mccabe pyflakes pep8 13:31:00 INFO - Cleaning up... 13:31:00 INFO - Return code: 0 13:31:00 INFO - Running post-action listener: _resource_record_post_action 13:31:00 INFO - Running post-action listener: _start_resource_monitoring 13:31:00 INFO - Starting resource monitoring. 13:31:00 INFO - ##### 13:31:00 INFO - ##### Running install step. 13:31:00 INFO - ##### 13:31:00 INFO - Running pre-action listener: _resource_record_pre_action 13:31:00 INFO - Running main action method: install 13:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:31:00 INFO - Reading from file tmpfile_stdout 13:31:00 INFO - Detecting whether we're running mozinstall >=1.0... 13:31:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:31:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:31:00 INFO - Reading from file tmpfile_stdout 13:31:00 INFO - Output received: 13:31:00 INFO - Usage: mozinstall [options] installer 13:31:00 INFO - Options: 13:31:00 INFO - -h, --help show this help message and exit 13:31:00 INFO - -d DEST, --destination=DEST 13:31:00 INFO - Directory to install application into. [default: 13:31:00 INFO - "/builds/slave/test"] 13:31:00 INFO - --app=APP Application being installed. [default: firefox] 13:31:00 INFO - mkdir: /builds/slave/test/build/application 13:31:00 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'] 13:31:00 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 13:31:33 INFO - Reading from file tmpfile_stdout 13:31:33 INFO - Output received: 13:31:33 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:31:33 INFO - Running post-action listener: _resource_record_post_action 13:31:33 INFO - ##### 13:31:33 INFO - ##### Running run-tests step. 13:31:33 INFO - ##### 13:31:33 INFO - Running pre-action listener: _resource_record_pre_action 13:31:33 INFO - Running main action method: run_tests 13:31:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:31:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:31:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:31:33 INFO - 2016-04-19 13:31:33.583 screenresolution[1663:7580] starting screenresolution argv=screenresolution get 13:31:33 INFO - 2016-04-19 13:31:33.586 screenresolution[1663:7580] Display 0: 1600x1200x32@60 13:31:33 INFO - 2016-04-19 13:31:33.591 screenresolution[1664:7582] starting screenresolution argv=screenresolution list 13:31:33 INFO - Available Modes on Display 0 13:31:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:31:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:31:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:31:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:31:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:31:33 INFO - Intel Iris: 13:31:33 INFO - Chipset Model: Intel Iris 13:31:33 INFO - Type: GPU 13:31:33 INFO - Bus: Built-In 13:31:33 INFO - VRAM (Dynamic, Max): 1536 MB 13:31:33 INFO - Vendor: Intel (0x8086) 13:31:33 INFO - Device ID: 0x0a2e 13:31:33 INFO - Revision ID: 0x0009 13:31:33 INFO - Displays: 13:31:33 INFO - Display: 13:31:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:31:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:31:33 INFO - Display Serial Number: 1600x1200 60 13:31:33 INFO - Main Display: Yes 13:31:33 INFO - Mirror: Off 13:31:33 INFO - Online: Yes 13:31:33 INFO - Rotation: Supported 13:31:33 INFO - Return code: 0 13:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:31:33 INFO - Python 2.7.3 13:31:33 INFO - Return code: 0 13:31:33 INFO - grabbing minidump binary from tooltool 13:31:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 13:31:33 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 0x10e816e00>, '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 0x10e63fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e811b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:31:33 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 13:31:33 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 13:31:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:31:33 INFO - Return code: 0 13:31:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:31:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:31:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:31:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:31:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:31:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 13:31:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 13:31:35 INFO - Return code: 0 13:31:35 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/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:31:35 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/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0145 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:31:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.z9mxW7rq93/Render', 13:31:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:31:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:31:35 INFO - 'HOME': '/Users/cltbld', 13:31:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:31:35 INFO - 'LOGNAME': 'cltbld', 13:31:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:31:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:31:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:31:35 INFO - 'MOZ_NO_REMOTE': '1', 13:31:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:31:35 INFO - 'NO_EM_RESTART': '1', 13:31:35 INFO - 'PAGER': '/bin/cat', 13:31:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:31:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:31:35 INFO - 'PWD': '/builds/slave/test', 13:31:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:31:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:31:35 INFO - 'SHELL': '/bin/bash', 13:31:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.B25v2o4aH5/Listeners', 13:31:35 INFO - 'TMPDIR': '/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/', 13:31:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:31:35 INFO - 'USER': 'cltbld', 13:31:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:31:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:31:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:31:35 INFO - 'XPC_FLAGS': '0x0', 13:31:35 INFO - 'XPC_SERVICE_NAME': '0', 13:31:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:31:35 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/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0145', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:31:35 INFO - mozversion application_buildid: 20160419123832 13:31:35 INFO - mozversion application_changeset: f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 13:31:35 INFO - mozversion application_display_name: Nightly 13:31:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:31:35 INFO - mozversion application_name: Firefox 13:31:35 INFO - mozversion application_remotingname: firefox 13:31:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:31:35 INFO - mozversion application_vendor: Mozilla 13:31:35 INFO - mozversion application_version: 48.0a1 13:31:35 INFO - mozversion platform_buildid: 20160419123832 13:31:35 INFO - mozversion platform_changeset: f4ab4fd96f8ef68158a4cae1e5dae91e6b52da35 13:31:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:31:35 INFO - mozversion platform_version: 48.0a1 13:31:35 INFO - using testdate: 1461097895 13:31:35 INFO - actual date: 1461097895 13:31:35 INFO - starting webserver on 'localhost:49276' 13:31:35 INFO - SUITE-START | Running 2 tests 13:31:35 INFO - TEST-START | dromaeo_css 13:31:35 INFO - operating with platform_type : mac_ 13:31:35 INFO - Initialising browser for dromaeo_css test... 13:31:35 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpapCgtW/profile http://localhost:49276/getInfo.html) 13:31:37 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 13:31:37 INFO - PROCESS | 1682 | colorDepth:24 13:31:37 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 13:31:37 INFO - PROCESS | 1682 | __metrics 13:31:37 INFO - PROCESS | 1682 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:31:37 ERROR - PROCESS | 1682 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:31:38 INFO - TEST-INFO | 1682: exit 0 13:31:38 INFO - Browser initialized. 13:31:38 INFO - Running cycle 1/1 for dromaeo_css test... 13:31:38 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpapCgtW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:31:50 INFO - PROCESS | 1684 | RSS: Main: 237572096 13:31:50 INFO - PROCESS | 1684 | 13:32:41 INFO - PROCESS | 1684 | 1461097961730 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 13:34:51 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 13:34:53 INFO - PROCESS | 1684 | RSS: Main: 329003008 13:34:53 INFO - PROCESS | 1684 | 13:37:56 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 13:37:57 INFO - PROCESS | 1684 | RSS: Main: 378425344 13:37:57 INFO - PROCESS | 1684 | 13:40:59 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 13:40:59 INFO - PROCESS | 1684 | RSS: Main: 375578624 13:40:59 INFO - PROCESS | 1684 | 13:44:02 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 13:44:02 INFO - PROCESS | 1684 | RSS: Main: 374497280 13:44:02 INFO - PROCESS | 1684 | 13:47:06 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 13:47:06 INFO - PROCESS | 1684 | RSS: Main: 345444352 13:47:06 INFO - PROCESS | 1684 | 13:50:09 INFO - PROCESS | 1684 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 13:50:10 INFO - PROCESS | 1684 | RSS: Main: 382337024 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | __start_tp_report 13:50:10 INFO - PROCESS | 1684 | _x_x_mozilla_page_load 13:50:10 INFO - PROCESS | 1684 | _x_x_mozilla_page_load_details 13:50:10 INFO - PROCESS | 1684 | |i|pagename|runs| 13:50:10 INFO - PROCESS | 1684 | |0;dojo.html;3340.532325474434;3398.8470518826634;3431.5367425397594;3444.948325775114;3473.756837021408;5472.589555783312;5577.274954255946;5580.358258800239;5583.748731307091;5645.435456454357;5624.437556244374;5640.323161220659;5702.714864256791;5758.625689330201;5765.1928729977735;5070.923936140961;5094.847154585368;5118.257852611369;5127.769250383723;5222.112240919053;5153.9742301288625;5184.351956005499;5206.3231779868565;5215.947840521603;5244.55421289189;4244.851430199959;5035.093683137034;5065.594752419816;5108.897822043538;5153.716545589991;5128.410232823236;5137.460566640486;5139.897202055975;5152.175651895716;5221.634485586011;5090.847274581768;5131.5638170755155;5134.127198376285;5151.8454446366295;5175.508326708957;5498.80754173602;5540.501354878081;5564;5628.352739434977;5644.661320320753;6192.349803270637;6217.471514921237;6225.408586184305;6237.095621134956;6244.0321750128805;6137.162486020116;6275.709942171271;6277.560570760049;6306.842328941767;6308.684565771693;5774.076147816371;5790;5804.506616937608;5877.79427720032;5879.147523609053;6293.118963345135;6294.464970477561;6300.558960872787;6307.779227727007;6311.147995020613;5780.479756821906;5877.353491115974;6144.293406258248;6149.446549810538;6159.722812473449;6139.754409823557;6261;6276.968615156895;6280.591761535486;6298.527610429236;6144.201253836973;6145.907811382833;6178.783742568989;6199.132121502993;6224.751009959641;5981.461668449861;6068.817935461943;6203.472704820141;6236.282827474808;6241.7191226394925;4873.26900964851;4893.412790465167;4925.261210818334;4928.137575924199;4933.629977751683;4356.629686476685;4367.71609845359;4371.256886329332;4391.956080439219;4436.911261774811;5575.637583557056;5627.380988091307;5644.774209031592;5654.123610840326;5711.543076553866;5203.817866374735;6159.81520554384;6163.291221509584;6232.220972378453;6232.345603711636;5545.750441230073;5594;5626.043572592669;5643.858903527363;5678.801241956512;5314.362276526842;5338.492843179892;5339.119045357473;5339.919901201485;5348.331458567679;6163.260408750979;6212.4408803207925;6278.434940855345;6280.089387038763;6289.49684025286;6196.039613859859;6210.4100110489435;6214.005759078525;6214.2853571838;6236.501079913505;6130.459285710702;6280.371962803683;6281.842953926189;6286.654234017173;6296.118543404019;6097.2683278006925;6108.72510737009;6127.172831667846;6130.785422510191;6134.846628834315;6036.124761909587;6101.55020674385;6161.014237721943;6167.044108163242;6173.228346456693;4804.471508134033;6047.881141988746;6051.727672254847;6096.980278560325;6143.262808463013;5008.449070602158;6048.758049677964;6060.606060606046;6068.4538391544975;6072.30168530607;5920.141579471038;5929.377415371324;5931.228940237914;5932.466078052823;5945.256842894638;4962.528559786814;5987.850303742267;6120.663363515049;6121.7578389577775;6127.372786728474;5937.139114828412;5943.940560594338;5945.583809133319;5950.910736338872;5959.404059594006 13:50:10 INFO - PROCESS | 1684 | |1;ext.html;497.99253011204826;503.47638456005757;515.6493584362637;523.8219005538116;563.8787660652958;35073;37556.81221593892;38738.83783486493;38821.80589097054;38927.61072389279;40924.59075409245;41746;41815.79092104535;41833;41878.487290762496;8543.658253669846;8741.519216443125;9493.19307858831;9757.902420975806;11413.25813822103;11679.649610511698;11846.17076804624;11850.525978960832;11859.64421067365;11861;10573.418461984587;11430.199886007982;11798.764024719543;11870.406479676025;11914.940425297904;8592.011918629343;11606.535738570446;11859.110566707444;11899.345535995517;11903.464344104535;11544.59593914209;11646.767064658745;11656.883431165665;11681.299122052618;11682.766344673144;38107;38458.80770596157;38483.845484635225;38605;38614.22771544581;3336.08257729124;3365.612954510238;3703.407454807218;3752.1932784451474;3782.432635104729;28329.85835070832;29305.706942930512;29492.8525357374;29732.70267297321;29904.401911961857;2723.536998710204;2733.3986522964915;2734.576140698175;2870.454613623405;2941.4264218477406;486329;487422;488093;488189;488873.1112688863;35080;35890.64109358925;35969.10077248037;61205.69397152986;61370;34908.82545587256;35215.94352169439;35364.939051828485;35402.46896296558;35515.82242088773;13778.862211377958;14796.778048329285;14945.327460264314;14957.850421495647;15015;11293.604723834791;12694.111412201242;12990.740185196242;13014.414351354211;13015.219086854819;7966;7986.960065199753;8025.357971362277;8079.313258372987;8091.826685130623;3383.8477268522975;3439.329330780474;3443.5523381960184;3450.913727156841;3480.860765569349;1029.9751747011771;1038.6416686243233;1044.1153717539762;1087.1574529739357;1114.2645853736494;29005.854970725013;29387.265318367212;29709.554356684235;30072.097837064925;30180.84909575438;1033.7002269341806;1144.9255798373079;1166.276908316849;1179.9646010619695;1209.3832145605804;15137.470188543568;15202.46791362297;15226.46707365237;15518;15551.30019149141;637402;643589;655173;656089;657839;48895.75552122216;58842;59633;59805.700971494865;59812.700936495035;1226.5032661771997;1332.227308161264;1494.5516345096428;1503.3460444706589;1516.1888389711671;28487;28556.714432856235;28859.134225973256;29374.118776436742;29706.96025639092;45730.771346143054;46372;46559;46566.068678626914;46629;396937;413551;413688;413738;414032;22011.11955521825;23951.04195832147;26103.955841766878;26361.20916372435;26722.86638566795;41967;42088.3686744693;42463.787681061396;42680.93297667583;43128.35307470328;35979.28041439209;36720.63279367279;36816.81591592025;37291.62708372882;37735.62264377431;36235.63764362428;36282.637173627925;36688;37071.81464092662;37209.069773255884;35913.64086359103;35990.28019439649;36438.63561364352;37090.25819483541;37176.6282337184;31055.378892421573;31581.095136431835;31922.20194495156;31997.36005279928;32004.35991280208;20044;23572.764272357057;24323.27030189097;24616.384590384667;24794.628080578444 13:50:10 INFO - PROCESS | 1684 | |2;jquery.html;349.23169028138096;390.1416882857713;398.57352632683023;404.43379269023364;414.492246997428;19213.61572768544;20643.418271723094;20867.37397878064;20874.89562552187;21281.467963300925;20044.69932951007;20086.196552137895;20792.376228713125;20837.791622083776;21310.14759409622;5073.670211436263;5654.293213348321;5880.117982302646;5888.5877988540815;5890.705464726747;4079.3473044312914;4374.993751437156;4395.9889225478155;5498.900219956005;6017.669028203447;6226.221722284715;6332.715027823759;6362.427381535661;6364.936350636503;6384.233891932975;5190.792368305282;6214.596051256676;6387.073874288202;6411.519136064814;6416.4225219730215;3628.7459877808565;3841.3661745811905;5214.5046220609365;5564.554835613141;5570.693611851347;24539.754602453926;25035.998560057575;25243.495130097293;25413.364665883317;25706.614400784005;2135.8786637015537;2250.8086812620945;2277.464795772992;2341.988290058556;2730.563109902426;14259.928700356535;14549.490767823181;14834.703305933928;14952.252387380588;15231.238438078051;1669.1070277401532;1768.1159420289855;1776.2894842063279;1798.5503624093976;1802.8828126171757;115113.42443287814;120964.94775363023;121073.39463302716;121415;122307;8494.405391622651;10421.68734937953;10550.89449105499;10869.510872010778;10976;13797.655058623413;13899.79150312746;13908;13910.721785564232;13912.165270083828;8392.916070839214;8593.742187734379;8617.78455538604;8710.738677839676;8747.256483198873;8018.9174461446955;8608.182222688834;8651.091635378321;8656.956715216384;8751.387402881737;5062.519060689228;5829.766809327664;6515.804525864231;6522.119513865568;6573.605583664996;1547.4583895636438;1602.3510478256326;1736.7394890766234;1785.9821401785816;1876.8123187681122;914.2274777812737;957.8515330123842;1007.5466040281902;1009.1573536097294;1018.682457732605;20937.89531052335;21139.682904756442;21281.467963301042;21335.67996480054;21572.56854862894;798.8202654402829;865.2429124516049;1009.3590569988111;1012.2458768217725;1091.5197313182175;11120.42331400913;11302.830457543143;11348.375839328915;11370.772584548262;11421.37182454973;575663;582833;593969;594051;594150;115664;117421.41289293498;120043;122664;122787;923.9722808315761;1027.8077430180954;1060.6181774561153;1069.2408390043001;1082.7780305037447;16410.671786563962;16841.1579421026;18333;18544.165512552237;18701.25194992185;37545;44061.67814965556;44410;44664.33003505015;45309.546904530536;61932;63173.684131579044;63330;63406.36593634189;65562;6619.536632435684;6997;7996.080450748006;8064;8307.626156823078;10671.555673229093;11593.942030289794;11701.771314011907;12824.166429182073;13024.8046279304;10647.148228141576;10990.725231869268;11311.434428278388;11364.374959376983;11438;10562.788744225227;10574.682759517227;10986.560537578407;11056.180673928096;11256;10795.326724357737;11325.433728313716;11455.885441145481;11456.198066135625;11505.539778408769;7350.632468376667;7442.106947166375;7483.289087536676;7515.511491753018;7794.922050779646;8325.542095184823;10780.407077610807;11061.723456913642;11168.888311116785;11488.310701357945 13:50:10 INFO - PROCESS | 1684 | |3;mootools.html;1303.074816880015;1565.9921700391496;1579.1788270099548;1820.1263393571085;1973.190991693405;7901.802454938622;8625;8802.427842190253;8809.515476648781;8836.823263534725;8427.325813934885;8636.481811091338;8648.870266946002;8659.263962563175;8667.826643467142;2134.7011418401435;2358.103920510204;2401.6757737705407;2427.5509030829267;2432.4405386761055;2436.597961336386;2464.581021226387;2468.7778099971;2469.9012039518398;2474.1093206445667;2393.0018434607036;2451.767082127204;2453.2027091195346;2456.2262887190477;2457.1891275879007;2110.45128266651;2333.381653861711;2415.2995631266913;2415.492746523232;2422.6244932035474;2408.891599877992;2424.6363045543135;2426.029588164748;2430.8419952703016;2431.112643884987;8561.015483219448;8626.827463450758;8631.007434145027;8641.913580864237;8671.956640216822;4209.5790420957965;5141.614378921596;5250.6324557281005;5264.8683782905355;5275.050490911634;3461.152017755666;3466.4453687410146;3549;3555.8933232003083;3560.2167523144867;877.9648814047495;1037.6212682370956;1061.1086687182803;1278.3480424983325;1629.3401172525146;11243.156763242789;11366.545338186463;11368.94315528417;11374.71563210918;11387.829182562267;4338.848140315074;4346.565343465628;4550.658700597469;4564.041551274269;4617.492075871652;9040;9040.141186587263;9065.229455496225;9066.637334506679;9069.818603627891;5236.371635403776;5237.73811309433;5240.633155679066;5252.474752524717;5272.894542109137;4372.584604462571;4516.774161291923;4735.313379559944;4869.147899117711;4881.633877459205;3929.862454814068;3980.8009599520487;4059.9594004060173;4090.0388408723925;4098.938515922204;1314.6450458376185;1334.6263046347037;1335.659406851266;1341.1751772659743;1347.5042702600301;780.9531428114332;860.8149247911729;895.7088946092546;898.2589363774912;944.2681921510884;5470.124780035176;5529.170624406372;5777.855553611109;5784.1034639630925;5970.6417614942375;902.4297721963736;904.7059705595576;932.6988850553576;944.4191822029402;1007.652359935821;3635.709143268532;3726.9627303727157;3845.8269377878064;3915.236528876899;3915.941260881089;10195.592176312864;10623.681289561326;10651.733706657396;10658.307210031478;10736.248462607698;10177.745556361151;10477.738056548495;10603.469826508644;10613.097886679565;10627.84058239127;7991.280784729286;8011.879821802794;8026.317762990095;8252.587370631445;8261.008678958564;5596.153269421;5606.803761868315;5697.943020569741;5735.655860648374;5846.970765146317;9533.046695330411;9689.951550242204;9802.26483013791;9840.261980351646;9910.355826871519;10024.799504010025;10034.448105354275;10180.949095254477;10197.286189966928;10217.1826253901;6393.968030159819;6609.537332386687;6751.628660423724;6917.9308206917285;7060.823479413056;8044.074931382966;8189.262966333059;8207.876881846658;8210.712625058095;8249.958750206211;7014.579125252501;7244.746433874789;7267.818304542428;7327.230640782641;7450.552966822008;6969.860602787814;7110.822229444305;7212.675429605785;7240.746573869889;7287.198408174991;6711.966440167768;6817.147856517935;6819.113515242987;6825.488088393489;6827.419669327964;4712.151812673752;4741.312509686145;4799.472058073539;4853.6359773017875;4964.7765850537535;4835.927461088157;4876.29293752411;4879.438864530624;4931.6301277405055;5067.645264831426 13:50:10 INFO - PROCESS | 1684 | |4;prototype.html;306.722416213327;313.92151962009495;320.91014515935547;328.8783150234412;334.68372388093263;5800.274965629296;5872.941270587293;5911.261092363455;5930.673812940287;5946.791862284821;4754.7860346284415;5131.615128865341;5340.062965369041;5724.284464441945;5826.4173582641715;4141.630475737201;4161.875143745678;4330.39374487572;4333.4366532350905;4391.956080439187;3926.548446928611;3982.192554249247;4278.486581610196;4401.8430968825205;4404.20724269633;3632.6185750496084;4263.616274535291;4376.627986621141;4399.626031787302;4413.624841888443;3919.8432062717743;4239.7668128252935;4384.824607015716;4402.559744025604;4410.140022695576;3254.5931758530182;3527.629598892105;3615.566132064143;3771.3400154973046;3814.1608846053823;4595.885102872421;4614.423197100363;5559.499645031967;5597.384287728347;5600.019996500596;4919.606431485473;5059.2916991621205;5068.290439338495;5071.29001939725;5072.097963263776;5280.52475277223;6032.215811944463;6181.103739957726;6185.195924529828;6206.565540412173;3719.9070023249637;4157.688173386978;4240.363945408213;4269.807858646337;4282.036541778106;4619.930701039487;5199.298094757197;5945;6395.392437718409;6556.475481961432;3995.800209989489;4316.5467625899455;4319.719218250803;4320.416743739618;4350.1734670412525;4388.51726310112;4401;4406.339049142654;4410.823567057346;4436.201483732959;4952.62855285855;5066.138756411419;5108.6168537359845;5121.8463446096675;5124.82195067095;6263.592866463665;6267.843303917439;6278.497720182374;6304.495640348761;6323.20959880015;1345.6030471011074;1494.68611591567;1523.2459932333566;1525.123054243792;1529.6023034011014;851.7955690634327;893.8737191139081;966.6278482784038;966.970990870275;1010.131287093114;4679.298105284167;5742.16738572913;5795.13073039039;5816.534677225896;5826.563007774434;5384.380796208407;6398.488120950313;6433.5496515243485;6434.7747828826705;6438.130852334969;4758.619310455155;5621.353544342372;5781.306243250753;5801.3038435388025;5847.678377689268;2539.9238022858976;3056.7096125868006;3174.9841250793597;3306.5866766654167;3404.9489257661153;5974.731137098841;6146.969265153645;6164.4143806338325;6687.164104486939;6729.293424190488;5824.912626310609;6615.503837212228;6616.27221005689;6623.4038936495945;6632;5490.560755139659;5600;6451.612903225821;6707.429868461138;6738.629375384303;5338.492843179892;6224.502039836711;6519.380658837403;6578.440832529289;6599.307072757291;5130.615203859651;6438.195225596801;6452.709628066655;6454.128692626436;6465.903011454738;5438.755256013568;6141.877162456815;6803.557768745016;6843.589384636938;6844.946516045067;3663.798491083016;4382.5179230284;4399.516053234206;4409.096135292321;4414.602685758297;5813.476787089182;5903.848749493807;6274.372562743689;6546.083548303146;6548.246951600627;5417.566594672496;5984.8803023940145;6141.9078713818435;6336.556441049082;6355.332690067661;4696.013837094249;5515.8692468043155;5895.4399332063385;5926.733297001559;6299.433051025429;5942.702864856827;6107.6640784757265;6185.474234689922;6188.721507532082;6211.472024877936;6093.177421048279;6308.558400911892;6324.177856878577;6324.7153878076515;6368.64972426521;5581.218629391969;6071.301800292847;6166.260048794174;6210.378962103753;6223.751049957951 13:50:10 INFO - PROCESS | 1684 | |5;yui.html;651.1730102769482;714.1501613080434;739.8483310921262;744.2074190986599;754.5661244784249;15043.849561504381;15404.460843870467;15663;15682.921585392058;15692.37230510781;11612.374267602543;13033.141388428949;13878.861211387883;14155.92922035391;14156.362963666634;723.8009547374462;747.4692967992731;901.779064129286;921.8847644044495;924.066692640433;893.9106089391041;913.2511340700628;925.2597921662676;925.6898938855492;929.8884133903907;940.407543247753;977.643160246512;982.1308142294085;984.5028260728358;985.7190700650316;758.6131073152704;953.408886490377;982.3516479123815;985.9950700246453;992.9503524823803;726.2265687043282;730.8976743255948;731.6634348199834;732.6776218463859;733.581858340746;15093.622659433493;15152.090874547563;15188.924055379652;15219.771703424458;15221.543353699406;12725.427355769012;12858.549950751678;12873.485060597564;12928.418221180069;13178.275194864278;10029.648962286357;10089.142422894063;10141.594336226615;10170.44062576558;10463.947680261626;3403.4724617684296;3490.842912068963;3520.9471857922154;3694.8522059117604;3735.7571757835653;80380;81273;83848.16151838464;87193;87216;7873.921260787319;9475.526223688788;9483.241340692728;9701.272404569685;9742.366746161619;18170.818291817177;21110.894445527672;21204.681929771064;21247.78752212458;21496.032678529504;6248.656323902138;7428.702851885972;7513.737019204302;7526.360259377905;7616;4032.8991775205855;4082.591740825953;4093.5497095319492;4135.627793498599;4371.628411584988;3122.500399935999;3350.1959529712694;3494.0042088005307;3547.911302217414;3582.390993531106;596.4155127974609;612.7548980407784;645.2063961327593;672.9461643068543;693.1301216972768;470.12945880676165;533.6051322021676;537.8332716857786;540.5918531508764;561.4133230773851;7286.453515986323;7494.1381741099385;7516.04948132472;7864.606769661494;7903.723369682033;512.2802462540062;545.1305168256624;579.2903692976071;602.5179856115091;637.2926052082186;3098.3338582204483;3715.145516531213;3720.3675375186285;3736.3648179809497;3947.559140913603;98403;98798.01201987888;101175.49412252892;104379;104536.43195352076;63214.68392658007;83474;83737;84546.57726711327;84747.57626211953;59585.702071489366;60075.69962150161;61503.38496615066;61646.38353616496;63576.72846543043;7438.851222975619;7460.477766556289;7461.4030877528585;7543;7570.611945404519;35505.98199153101;46282;46504.534954651375;47455;48447.5155248443;47224.52775472201;48321.75839120782;48637;60274.39725602688;60300.492487688156;9685.225181985283;10817.296875703054;10871.021608055313;11020.834687479533;11212.943935280271;9921.751956201153;11884;11885.108616853599;11986;12896.226226426445;11200.38397888124;11286.943565282121;11607.245529040585;12027.45876435545;12385.19496232742;11416.885831141582;11700.005499532635;11723.648290551297;11826.763464730486;11915.463804128662;11348.489317980546;11359.659210223706;11574.826377604511;11714.121440891797;11738.413079345828;9576.377535460173;9646.758831029281;9808.65669701557;10054.044865737744;10130.29087963865;9549.618015279311;9690.75773105678;9693.515324233902;9789.9021009789;9869.161858639016 13:50:10 INFO - PROCESS | 1684 | __end_tp_report 13:50:10 INFO - PROCESS | 1684 | __start_cc_report 13:50:10 INFO - PROCESS | 1684 | _x_x_mozilla_cycle_collect,1806 13:50:10 INFO - PROCESS | 1684 | __end_cc_report 13:50:10 INFO - PROCESS | 1684 | __startTimestamp1461099010132__endTimestamp 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | ------- Summary: start ------- 13:50:10 INFO - PROCESS | 1684 | Number of tests: 6 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#0] dojo.html Cycles:165 Average:5675.48 Median:5929.38 stddev:648.97 (10.9%) stddev-sans-first:624.58 13:50:10 INFO - PROCESS | 1684 | Values: 3340.5 3398.8 3431.5 3444.9 3473.8 5472.6 5577.3 5580.4 5583.7 5645.4 5624.4 5640.3 5702.7 5758.6 5765.2 5070.9 5094.8 5118.3 5127.8 5222.1 5154.0 5184.4 5206.3 5215.9 5244.6 4244.9 5035.1 5065.6 5108.9 5153.7 5128.4 5137.5 5139.9 5152.2 5221.6 5090.8 5131.6 5134.1 5151.8 5175.5 5498.8 5540.5 5564.0 5628.4 5644.7 6192.3 6217.5 6225.4 6237.1 6244.0 6137.2 6275.7 6277.6 6306.8 6308.7 5774.1 5790.0 5804.5 5877.8 5879.1 6293.1 6294.5 6300.6 6307.8 6311.1 5780.5 5877.4 6144.3 6149.4 6159.7 6139.8 6261.0 6277.0 6280.6 6298.5 6144.2 6145.9 6178.8 6199.1 6224.8 5981.5 6068.8 6203.5 6236.3 6241.7 4873.3 4893.4 4925.3 4928.1 4933.6 4356.6 4367.7 4371.3 4392.0 4436.9 5575.6 5627.4 5644.8 5654.1 5711.5 5203.8 6159.8 6163.3 6232.2 6232.3 5545.8 5594.0 5626.0 5643.9 5678.8 5314.4 5338.5 5339.1 5339.9 5348.3 6163.3 6212.4 6278.4 6280.1 6289.5 6196.0 6210.4 6214.0 6214.3 6236.5 6130.5 6280.4 6281.8 6286.7 6296.1 6097.3 6108.7 6127.2 6130.8 6134.8 6036.1 6101.6 6161.0 6167.0 6173.2 4804.5 6047.9 6051.7 6097.0 6143.3 5008.4 6048.8 6060.6 6068.5 6072.3 5920.1 5929.4 5931.2 5932.5 5945.3 4962.5 5987.9 6120.7 6121.8 6127.4 5937.1 5943.9 5945.6 5950.9 5959.4 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#1] ext.html Cycles:180 Average:63673.87 Median:3465.89 stddev:140689.26 (4059.3%) stddev-sans-first:141003.97 13:50:10 INFO - PROCESS | 1684 | Values: 498.0 503.5 515.6 523.8 563.9 35073.0 37556.8 38738.8 38821.8 38927.6 40924.6 41746.0 41815.8 41833.0 41878.5 8543.7 8741.5 9493.2 9757.9 11413.3 11679.6 11846.2 11850.5 11859.6 11861.0 10573.4 11430.2 11798.8 11870.4 11914.9 8592.0 11606.5 11859.1 11899.3 11903.5 11544.6 11646.8 11656.9 11681.3 11682.8 38107.0 38458.8 38483.8 38605.0 38614.2 3336.1 3365.6 3703.4 3752.2 3782.4 28329.9 29305.7 29492.9 29732.7 29904.4 2723.5 2733.4 2734.6 2870.5 2941.4 486329.0 487422.0 488093.0 488189.0 488873.1 35080.0 35890.6 35969.1 61205.7 61370.0 34908.8 35215.9 35364.9 35402.5 35515.8 13778.9 14796.8 14945.3 14957.9 15015.0 11293.6 12694.1 12990.7 13014.4 13015.2 7966.0 7987.0 8025.4 8079.3 8091.8 3383.8 3439.3 3443.6 3450.9 3480.9 1030.0 1038.6 1044.1 1087.2 1114.3 29005.9 29387.3 29709.6 30072.1 30180.8 1033.7 1144.9 1166.3 1180.0 1209.4 15137.5 15202.5 15226.5 15518.0 15551.3 637402.0 643589.0 655173.0 656089.0 657839.0 48895.8 58842.0 59633.0 59805.7 59812.7 1226.5 1332.2 1494.6 1503.3 1516.2 28487.0 28556.7 28859.1 29374.1 29707.0 45730.8 46372.0 46559.0 46566.1 46629.0 396937.0 413551.0 413688.0 413738.0 414032.0 22011.1 23951.0 26104.0 26361.2 26722.9 41967.0 42088.4 42463.8 42680.9 43128.4 35979.3 36720.6 36816.8 37291.6 37735.6 36235.6 36282.6 36688.0 37071.8 37209.1 35913.6 35990.3 36438.6 37090.3 37176.6 31055.4 31581.1 31922.2 31997.4 32004.4 20044.0 23572.8 24323.3 24616.4 24794.6 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#2] jquery.html Cycles:180 Average:33931.10 Median:21322.91 stddev:97911.37 (459.2%) stddev-sans-first:98153.57 13:50:10 INFO - PROCESS | 1684 | Values: 349.2 390.1 398.6 404.4 414.5 19213.6 20643.4 20867.4 20874.9 21281.5 20044.7 20086.2 20792.4 20837.8 21310.1 5073.7 5654.3 5880.1 5888.6 5890.7 4079.3 4375.0 4396.0 5498.9 6017.7 6226.2 6332.7 6362.4 6364.9 6384.2 5190.8 6214.6 6387.1 6411.5 6416.4 3628.7 3841.4 5214.5 5564.6 5570.7 24539.8 25036.0 25243.5 25413.4 25706.6 2135.9 2250.8 2277.5 2342.0 2730.6 14259.9 14549.5 14834.7 14952.3 15231.2 1669.1 1768.1 1776.3 1798.6 1802.9 115113.4 120964.9 121073.4 121415.0 122307.0 8494.4 10421.7 10550.9 10869.5 10976.0 13797.7 13899.8 13908.0 13910.7 13912.2 8392.9 8593.7 8617.8 8710.7 8747.3 8018.9 8608.2 8651.1 8657.0 8751.4 5062.5 5829.8 6515.8 6522.1 6573.6 1547.5 1602.4 1736.7 1786.0 1876.8 914.2 957.9 1007.5 1009.2 1018.7 20937.9 21139.7 21281.5 21335.7 21572.6 798.8 865.2 1009.4 1012.2 1091.5 11120.4 11302.8 11348.4 11370.8 11421.4 575663.0 582833.0 593969.0 594051.0 594150.0 115664.0 117421.4 120043.0 122664.0 122787.0 924.0 1027.8 1060.6 1069.2 1082.8 16410.7 16841.2 18333.0 18544.2 18701.3 37545.0 44061.7 44410.0 44664.3 45309.5 61932.0 63173.7 63330.0 63406.4 65562.0 6619.5 6997.0 7996.1 8064.0 8307.6 10671.6 11593.9 11701.8 12824.2 13024.8 10647.1 10990.7 11311.4 11364.4 11438.0 10562.8 10574.7 10986.6 11056.2 11256.0 10795.3 11325.4 11455.9 11456.2 11505.5 7350.6 7442.1 7483.3 7515.5 7794.9 8325.5 10780.4 11061.7 11168.9 11488.3 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#3] mootools.html Cycles:180 Average:5593.82 Median:4877.87 stddev:3093.37 (63.4%) stddev-sans-first:3085.24 13:50:10 INFO - PROCESS | 1684 | Values: 1303.1 1566.0 1579.2 1820.1 1973.2 7901.8 8625.0 8802.4 8809.5 8836.8 8427.3 8636.5 8648.9 8659.3 8667.8 2134.7 2358.1 2401.7 2427.6 2432.4 2436.6 2464.6 2468.8 2469.9 2474.1 2393.0 2451.8 2453.2 2456.2 2457.2 2110.5 2333.4 2415.3 2415.5 2422.6 2408.9 2424.6 2426.0 2430.8 2431.1 8561.0 8626.8 8631.0 8641.9 8672.0 4209.6 5141.6 5250.6 5264.9 5275.1 3461.2 3466.4 3549.0 3555.9 3560.2 878.0 1037.6 1061.1 1278.3 1629.3 11243.2 11366.5 11368.9 11374.7 11387.8 4338.8 4346.6 4550.7 4564.0 4617.5 9040.0 9040.1 9065.2 9066.6 9069.8 5236.4 5237.7 5240.6 5252.5 5272.9 4372.6 4516.8 4735.3 4869.1 4881.6 3929.9 3980.8 4060.0 4090.0 4098.9 1314.6 1334.6 1335.7 1341.2 1347.5 781.0 860.8 895.7 898.3 944.3 5470.1 5529.2 5777.9 5784.1 5970.6 902.4 904.7 932.7 944.4 1007.7 3635.7 3727.0 3845.8 3915.2 3915.9 10195.6 10623.7 10651.7 10658.3 10736.2 10177.7 10477.7 10603.5 10613.1 10627.8 7991.3 8011.9 8026.3 8252.6 8261.0 5596.2 5606.8 5697.9 5735.7 5847.0 9533.0 9690.0 9802.3 9840.3 9910.4 10024.8 10034.4 10180.9 10197.3 10217.2 6394.0 6609.5 6751.6 6917.9 7060.8 8044.1 8189.3 8207.9 8210.7 8250.0 7014.6 7244.7 7267.8 7327.2 7450.6 6969.9 7110.8 7212.7 7240.7 7287.2 6712.0 6817.1 6819.1 6825.5 6827.4 4712.2 4741.3 4799.5 4853.6 4964.8 4835.9 4876.3 4879.4 4931.6 5067.6 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#4] prototype.html Cycles:180 Average:4989.28 Median:5589.30 stddev:1572.09 (28.1%) stddev-sans-first:1536.72 13:50:10 INFO - PROCESS | 1684 | Values: 306.7 313.9 320.9 328.9 334.7 5800.3 5872.9 5911.3 5930.7 5946.8 4754.8 5131.6 5340.1 5724.3 5826.4 4141.6 4161.9 4330.4 4333.4 4392.0 3926.5 3982.2 4278.5 4401.8 4404.2 3632.6 4263.6 4376.6 4399.6 4413.6 3919.8 4239.8 4384.8 4402.6 4410.1 3254.6 3527.6 3615.6 3771.3 3814.2 4595.9 4614.4 5559.5 5597.4 5600.0 4919.6 5059.3 5068.3 5071.3 5072.1 5280.5 6032.2 6181.1 6185.2 6206.6 3719.9 4157.7 4240.4 4269.8 4282.0 4619.9 5199.3 5945.0 6395.4 6556.5 3995.8 4316.5 4319.7 4320.4 4350.2 4388.5 4401.0 4406.3 4410.8 4436.2 4952.6 5066.1 5108.6 5121.8 5124.8 6263.6 6267.8 6278.5 6304.5 6323.2 1345.6 1494.7 1523.2 1525.1 1529.6 851.8 893.9 966.6 967.0 1010.1 4679.3 5742.2 5795.1 5816.5 5826.6 5384.4 6398.5 6433.5 6434.8 6438.1 4758.6 5621.4 5781.3 5801.3 5847.7 2539.9 3056.7 3175.0 3306.6 3404.9 5974.7 6147.0 6164.4 6687.2 6729.3 5824.9 6615.5 6616.3 6623.4 6632.0 5490.6 5600.0 6451.6 6707.4 6738.6 5338.5 6224.5 6519.4 6578.4 6599.3 5130.6 6438.2 6452.7 6454.1 6465.9 5438.8 6141.9 6803.6 6843.6 6844.9 3663.8 4382.5 4399.5 4409.1 4414.6 5813.5 5903.8 6274.4 6546.1 6548.2 5417.6 5984.9 6141.9 6336.6 6355.3 4696.0 5515.9 5895.4 5926.7 6299.4 5942.7 6107.7 6185.5 6188.7 6211.5 6093.2 6308.6 6324.2 6324.7 6368.6 5581.2 6071.3 6166.3 6210.4 6223.8 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - PROCESS | 1684 | [#5] yui.html Cycles:180 Average:17846.35 Median:542.86 stddev:25621.90 (4719.8%) stddev-sans-first:25661.25 13:50:10 INFO - PROCESS | 1684 | Values: 651.2 714.2 739.8 744.2 754.6 15043.8 15404.5 15663.0 15682.9 15692.4 11612.4 13033.1 13878.9 14155.9 14156.4 723.8 747.5 901.8 921.9 924.1 893.9 913.3 925.3 925.7 929.9 940.4 977.6 982.1 984.5 985.7 758.6 953.4 982.4 986.0 993.0 726.2 730.9 731.7 732.7 733.6 15093.6 15152.1 15188.9 15219.8 15221.5 12725.4 12858.5 12873.5 12928.4 13178.3 10029.6 10089.1 10141.6 10170.4 10463.9 3403.5 3490.8 3520.9 3694.9 3735.8 80380.0 81273.0 83848.2 87193.0 87216.0 7873.9 9475.5 9483.2 9701.3 9742.4 18170.8 21110.9 21204.7 21247.8 21496.0 6248.7 7428.7 7513.7 7526.4 7616.0 4032.9 4082.6 4093.5 4135.6 4371.6 3122.5 3350.2 3494.0 3547.9 3582.4 596.4 612.8 645.2 672.9 693.1 470.1 533.6 537.8 540.6 561.4 7286.5 7494.1 7516.0 7864.6 7903.7 512.3 545.1 579.3 602.5 637.3 3098.3 3715.1 3720.4 3736.4 3947.6 98403.0 98798.0 101175.5 104379.0 104536.4 63214.7 83474.0 83737.0 84546.6 84747.6 59585.7 60075.7 61503.4 61646.4 63576.7 7438.9 7460.5 7461.4 7543.0 7570.6 35506.0 46282.0 46504.5 47455.0 48447.5 47224.5 48321.8 48637.0 60274.4 60300.5 9685.2 10817.3 10871.0 11020.8 11212.9 9921.8 11884.0 11885.1 11986.0 12896.2 11200.4 11286.9 11607.2 12027.5 12385.2 11416.9 11700.0 11723.6 11826.8 11915.5 11348.5 11359.7 11574.8 11714.1 11738.4 9576.4 9646.8 9808.7 10054.0 10130.3 9549.6 9690.8 9693.5 9789.9 9869.2 13:50:10 INFO - PROCESS | 1684 | -------- Summary: end -------- 13:50:10 INFO - PROCESS | 1684 | 13:50:10 INFO - TEST-INFO | 1684: exit 0 13:50:10 INFO - TEST-OK | dromaeo_css | took 1115532ms 13:50:10 INFO - TEST-START | kraken 13:50:10 INFO - operating with platform_type : mac_ 13:50:10 INFO - Initialising browser for kraken test... 13:50:10 INFO - TEST-INFO | started process 1806 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpMKle5n/profile http://localhost:49276/getInfo.html) 13:50:12 INFO - PROCESS | 1806 | __metrics Screen width/height:1600/1200 13:50:12 INFO - PROCESS | 1806 | colorDepth:24 13:50:12 INFO - PROCESS | 1806 | Browser inner width/height: 1024/689 13:50:12 INFO - PROCESS | 1806 | __metrics 13:50:13 INFO - PROCESS | 1806 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:50:13 ERROR - PROCESS | 1806 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:50:13 INFO - TEST-INFO | 1806: exit 0 13:50:13 INFO - Browser initialized. 13:50:13 INFO - Running cycle 1/1 for kraken test... 13:50:13 INFO - TEST-INFO | started process 1808 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/T/tmpMKle5n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:50:25 INFO - PROCESS | 1808 | RSS: Main: 237768704 13:50:25 INFO - PROCESS | 1808 | 13:50:28 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 13:50:29 INFO - PROCESS | 1808 | RSS: Main: 285990912 13:50:29 INFO - PROCESS | 1808 | 13:50:31 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 13:50:32 INFO - PROCESS | 1808 | RSS: Main: 299458560 13:50:32 INFO - PROCESS | 1808 | 13:50:36 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 13:50:36 INFO - PROCESS | 1808 | RSS: Main: 387182592 13:50:36 INFO - PROCESS | 1808 | 13:50:38 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 13:50:39 INFO - PROCESS | 1808 | RSS: Main: 287711232 13:50:39 INFO - PROCESS | 1808 | 13:50:41 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:50:41 INFO - PROCESS | 1808 | RSS: Main: 291815424 13:50:41 INFO - PROCESS | 1808 | 13:50:44 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 13:50:45 INFO - PROCESS | 1808 | RSS: Main: 329703424 13:50:45 INFO - PROCESS | 1808 | 13:50:48 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 13:50:48 INFO - PROCESS | 1808 | RSS: Main: 331673600 13:50:48 INFO - PROCESS | 1808 | 13:50:51 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 13:50:52 INFO - PROCESS | 1808 | RSS: Main: 330289152 13:50:52 INFO - PROCESS | 1808 | 13:50:53 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:50:54 INFO - PROCESS | 1808 | RSS: Main: 320000000 13:50:54 INFO - PROCESS | 1808 | 13:50:56 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:50:56 INFO - PROCESS | 1808 | RSS: Main: 326836224 13:50:56 INFO - PROCESS | 1808 | 13:50:58 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:50:59 INFO - PROCESS | 1808 | RSS: Main: 330166272 13:50:59 INFO - PROCESS | 1808 | 13:51:02 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:51:03 INFO - PROCESS | 1808 | RSS: Main: 330944512 13:51:03 INFO - PROCESS | 1808 | 13:51:06 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:51:06 INFO - PROCESS | 1808 | RSS: Main: 326594560 13:51:06 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 13:51:08 INFO - PROCESS | 1808 | RSS: Main: 391532544 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | __start_tp_report 13:51:08 INFO - PROCESS | 1808 | _x_x_mozilla_page_load 13:51:08 INFO - PROCESS | 1808 | _x_x_mozilla_page_load_details 13:51:08 INFO - PROCESS | 1808 | |i|pagename|runs| 13:51:08 INFO - PROCESS | 1808 | |0;ai-astar;110;110;109;109;111;110;119;110;111;110 13:51:08 INFO - PROCESS | 1808 | |1;audio-beat-detection;157;123;146;172;120;144;141;120;170;131 13:51:08 INFO - PROCESS | 1808 | |2;audio-dft;187;184;185;186;189;186;184;181;183;181 13:51:08 INFO - PROCESS | 1808 | |3;audio-fft;77;72;86;91;56;61;60;79;88;55 13:51:08 INFO - PROCESS | 1808 | |4;audio-oscillator;89;97;133;80;78;84;122;79;76;120 13:51:08 INFO - PROCESS | 1808 | |5;imaging-gaussian-blur;97;129;87;85;86;84;86;83;87;105 13:51:08 INFO - PROCESS | 1808 | |6;imaging-darkroom;92;92;92;92;92;93;93;105;92;93 13:51:08 INFO - PROCESS | 1808 | |7;imaging-desaturate;85;85;87;81;82;84;84;84;85;85 13:51:08 INFO - PROCESS | 1808 | |8;json-parse-financial;60;61;61;61;58;58;59;75;62;59 13:51:08 INFO - PROCESS | 1808 | |9;json-stringify-tinderbox;64;63;73;74;70;68;67;67;67;69 13:51:08 INFO - PROCESS | 1808 | |10;stanford-crypto-aes;84;81;83;82;82;80;81;83;82;83 13:51:08 INFO - PROCESS | 1808 | |11;stanford-crypto-ccm;140;139;142;146;147;147;149;138;227;161 13:51:08 INFO - PROCESS | 1808 | |12;stanford-crypto-pbkdf2;169;191;222;219;171;169;169;169;169;168 13:51:08 INFO - PROCESS | 1808 | |13;stanford-crypto-sha256-iterative;69;67;70;69;71;67;69;69;71;67 13:51:08 INFO - PROCESS | 1808 | __end_tp_report 13:51:08 INFO - PROCESS | 1808 | __start_cc_report 13:51:08 INFO - PROCESS | 1808 | _x_x_mozilla_cycle_collect,1979 13:51:08 INFO - PROCESS | 1808 | __end_cc_report 13:51:08 INFO - PROCESS | 1808 | __startTimestamp1461099068936__endTimestamp 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | ------- Summary: start ------- 13:51:08 INFO - PROCESS | 1808 | Number of tests: 14 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#0] ai-astar Cycles:10 Average:110.90 Median:110.00 stddev:2.92 (2.7%) stddev-sans-first:3.08 13:51:08 INFO - PROCESS | 1808 | Values: 110.0 110.0 109.0 109.0 111.0 110.0 119.0 110.0 111.0 110.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#1] audio-beat-detection Cycles:10 Average:142.40 Median:145.00 stddev:19.37 (13.4%) stddev-sans-first:19.82 13:51:08 INFO - PROCESS | 1808 | Values: 157.0 123.0 146.0 172.0 120.0 144.0 141.0 120.0 170.0 131.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:2.55 (1.4%) stddev-sans-first:2.55 13:51:08 INFO - PROCESS | 1808 | Values: 187.0 184.0 185.0 186.0 189.0 186.0 184.0 181.0 183.0 181.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#3] audio-fft Cycles:10 Average:72.50 Median:78.00 stddev:13.72 (17.6%) stddev-sans-first:14.46 13:51:08 INFO - PROCESS | 1808 | Values: 77.0 72.0 86.0 91.0 56.0 61.0 60.0 79.0 88.0 55.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#4] audio-oscillator Cycles:10 Average:95.80 Median:79.50 stddev:21.30 (26.8%) stddev-sans-first:22.45 13:51:08 INFO - PROCESS | 1808 | Values: 89.0 97.0 133.0 80.0 78.0 84.0 122.0 79.0 76.0 120.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:86.00 stddev:14.42 (16.8%) stddev-sans-first:15.22 13:51:08 INFO - PROCESS | 1808 | Values: 97.0 129.0 87.0 85.0 86.0 84.0 86.0 83.0 87.0 105.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:92.00 stddev:4.03 (4.4%) stddev-sans-first:4.24 13:51:08 INFO - PROCESS | 1808 | Values: 92.0 92.0 92.0 92.0 92.0 93.0 93.0 105.0 92.0 93.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#7] imaging-desaturate Cycles:10 Average:84.20 Median:85.00 stddev:1.69 (2.0%) stddev-sans-first:1.76 13:51:08 INFO - PROCESS | 1808 | Values: 85.0 85.0 87.0 81.0 82.0 84.0 84.0 84.0 85.0 85.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#8] json-parse-financial Cycles:10 Average:61.40 Median:61.00 stddev:4.97 (8.1%) stddev-sans-first:5.25 13:51:08 INFO - PROCESS | 1808 | Values: 60.0 61.0 61.0 61.0 58.0 58.0 59.0 75.0 62.0 59.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:68.50 stddev:3.49 (5.1%) stddev-sans-first:3.35 13:51:08 INFO - PROCESS | 1808 | Values: 64.0 63.0 73.0 74.0 70.0 68.0 67.0 67.0 67.0 69.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#10] stanford-crypto-aes Cycles:10 Average:82.10 Median:82.50 stddev:1.20 (1.5%) stddev-sans-first:1.05 13:51:08 INFO - PROCESS | 1808 | Values: 84.0 81.0 83.0 82.0 82.0 80.0 81.0 83.0 82.0 83.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#11] stanford-crypto-ccm Cycles:10 Average:153.60 Median:147.00 stddev:26.63 (18.1%) stddev-sans-first:27.79 13:51:08 INFO - PROCESS | 1808 | Values: 140.0 139.0 142.0 146.0 147.0 147.0 149.0 138.0 227.0 161.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.60 Median:170.00 stddev:21.63 (12.7%) stddev-sans-first:22.46 13:51:08 INFO - PROCESS | 1808 | Values: 169.0 191.0 222.0 219.0 171.0 169.0 169.0 169.0 169.0 168.0 13:51:08 INFO - PROCESS | 1808 | 13:51:08 INFO - PROCESS | 1808 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.90 Median:69.00 stddev:1.52 (2.2%) stddev-sans-first:1.62 13:51:08 INFO - PROCESS | 1808 | Values: 69.0 67.0 70.0 69.0 71.0 67.0 69.0 69.0 71.0 67.0 13:51:08 INFO - PROCESS | 1808 | -------- Summary: end -------- 13:51:08 INFO - PROCESS | 1808 | 13:51:09 INFO - TEST-INFO | 1808: exit 0 13:51:09 INFO - TEST-OK | kraken | took 58864ms 13:51:09 INFO - SUITE-END | took 1174s 13:51:10 INFO - Completed test suite (00:19:34) 13:51:10 INFO - javascript benchmark 13:51:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.532325474434, 3398.8470518826634, 3431.5367425397594, 3444.948325775114, 3473.756837021408, 5472.589555783312, 5577.274954255946, 5580.358258800239, 5583.748731307091, 5645.435456454357, 5624.437556244374, 5640.323161220659, 5702.714864256791, 5758.625689330201, 5765.1928729977735, 5070.923936140961, 5094.847154585368, 5118.257852611369, 5127.769250383723, 5222.112240919053, 5153.9742301288625, 5184.351956005499, 5206.3231779868565, 5215.947840521603, 5244.55421289189, 4244.851430199959, 5035.093683137034, 5065.594752419816, 5108.897822043538, 5153.716545589991, 5128.410232823236, 5137.460566640486, 5139.897202055975, 5152.175651895716, 5221.634485586011, 5090.847274581768, 5131.5638170755155, 5134.127198376285, 5151.8454446366295, 5175.508326708957, 5498.80754173602, 5540.501354878081, 5564.0, 5628.352739434977, 5644.661320320753, 6192.349803270637, 6217.471514921237, 6225.408586184305, 6237.095621134956, 6244.0321750128805, 6137.162486020116, 6275.709942171271, 6277.560570760049, 6306.842328941767, 6308.684565771693, 5774.076147816371, 5790.0, 5804.506616937608, 5877.79427720032, 5879.147523609053, 6293.118963345135, 6294.464970477561, 6300.558960872787, 6307.779227727007, 6311.147995020613, 5780.479756821906, 5877.353491115974, 6144.293406258248, 6149.446549810538, 6159.722812473449, 6139.754409823557, 6261.0, 6276.968615156895, 6280.591761535486, 6298.527610429236, 6144.201253836973, 6145.907811382833, 6178.783742568989, 6199.132121502993, 6224.751009959641, 5981.461668449861, 6068.817935461943, 6203.472704820141, 6236.282827474808, 6241.7191226394925, 4873.26900964851, 4893.412790465167, 4925.261210818334, 4928.137575924199, 4933.629977751683, 4356.629686476685, 4367.71609845359, 4371.256886329332, 4391.956080439219, 4436.911261774811, 5575.637583557056, 5627.380988091307, 5644.774209031592, 5654.123610840326, 5711.543076553866, 5203.817866374735, 6159.81520554384, 6163.291221509584, 6232.220972378453, 6232.345603711636, 5545.750441230073, 5594.0, 5626.043572592669, 5643.858903527363, 5678.801241956512, 5314.362276526842, 5338.492843179892, 5339.119045357473, 5339.919901201485, 5348.331458567679, 6163.260408750979, 6212.4408803207925, 6278.434940855345, 6280.089387038763, 6289.49684025286, 6196.039613859859, 6210.4100110489435, 6214.005759078525, 6214.2853571838, 6236.501079913505, 6130.459285710702, 6280.371962803683, 6281.842953926189, 6286.654234017173, 6296.118543404019, 6097.2683278006925, 6108.72510737009, 6127.172831667846, 6130.785422510191, 6134.846628834315, 6036.124761909587, 6101.55020674385, 6161.014237721943, 6167.044108163242, 6173.228346456693, 4804.471508134033, 6047.881141988746, 6051.727672254847, 6096.980278560325, 6143.262808463013, 5008.449070602158, 6048.758049677964, 6060.606060606046, 6068.4538391544975, 6072.30168530607, 5920.141579471038, 5929.377415371324, 5931.228940237914, 5932.466078052823, 5945.256842894638, 4962.528559786814, 5987.850303742267, 6120.663363515049, 6121.7578389577775, 6127.372786728474, 5937.139114828412, 5943.940560594338, 5945.583809133319, 5950.910736338872, 5959.404059594006], "value": 5635.44541049412, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.99253011204826, 503.47638456005757, 515.6493584362637, 523.8219005538116, 563.8787660652958, 35073.0, 37556.81221593892, 38738.83783486493, 38821.80589097054, 38927.61072389279, 40924.59075409245, 41746.0, 41815.79092104535, 41833.0, 41878.487290762496, 8543.658253669846, 8741.519216443125, 9493.19307858831, 9757.902420975806, 11413.25813822103, 11679.649610511698, 11846.17076804624, 11850.525978960832, 11859.64421067365, 11861.0, 10573.418461984587, 11430.199886007982, 11798.764024719543, 11870.406479676025, 11914.940425297904, 8592.011918629343, 11606.535738570446, 11859.110566707444, 11899.345535995517, 11903.464344104535, 11544.59593914209, 11646.767064658745, 11656.883431165665, 11681.299122052618, 11682.766344673144, 38107.0, 38458.80770596157, 38483.845484635225, 38605.0, 38614.22771544581, 3336.08257729124, 3365.612954510238, 3703.407454807218, 3752.1932784451474, 3782.432635104729, 28329.85835070832, 29305.706942930512, 29492.8525357374, 29732.70267297321, 29904.401911961857, 2723.536998710204, 2733.3986522964915, 2734.576140698175, 2870.454613623405, 2941.4264218477406, 486329.0, 487422.0, 488093.0, 488189.0, 488873.1112688863, 35080.0, 35890.64109358925, 35969.10077248037, 61205.69397152986, 61370.0, 34908.82545587256, 35215.94352169439, 35364.939051828485, 35402.46896296558, 35515.82242088773, 13778.862211377958, 14796.778048329285, 14945.327460264314, 14957.850421495647, 15015.0, 11293.604723834791, 12694.111412201242, 12990.740185196242, 13014.414351354211, 13015.219086854819, 7966.0, 7986.960065199753, 8025.357971362277, 8079.313258372987, 8091.826685130623, 3383.8477268522975, 3439.329330780474, 3443.5523381960184, 3450.913727156841, 3480.860765569349, 1029.9751747011771, 1038.6416686243233, 1044.1153717539762, 1087.1574529739357, 1114.2645853736494, 29005.854970725013, 29387.265318367212, 29709.554356684235, 30072.097837064925, 30180.84909575438, 1033.7002269341806, 1144.9255798373079, 1166.276908316849, 1179.9646010619695, 1209.3832145605804, 15137.470188543568, 15202.46791362297, 15226.46707365237, 15518.0, 15551.30019149141, 637402.0, 643589.0, 655173.0, 656089.0, 657839.0, 48895.75552122216, 58842.0, 59633.0, 59805.700971494865, 59812.700936495035, 1226.5032661771997, 1332.227308161264, 1494.5516345096428, 1503.3460444706589, 1516.1888389711671, 28487.0, 28556.714432856235, 28859.134225973256, 29374.118776436742, 29706.96025639092, 45730.771346143054, 46372.0, 46559.0, 46566.068678626914, 46629.0, 396937.0, 413551.0, 413688.0, 413738.0, 414032.0, 22011.11955521825, 23951.04195832147, 26103.955841766878, 26361.20916372435, 26722.86638566795, 41967.0, 42088.3686744693, 42463.787681061396, 42680.93297667583, 43128.35307470328, 35979.28041439209, 36720.63279367279, 36816.81591592025, 37291.62708372882, 37735.62264377431, 36235.63764362428, 36282.637173627925, 36688.0, 37071.81464092662, 37209.069773255884, 35913.64086359103, 35990.28019439649, 36438.63561364352, 37090.25819483541, 37176.6282337184, 31055.378892421573, 31581.095136431835, 31922.20194495156, 31997.36005279928, 32004.35991280208, 20044.0, 23572.764272357057, 24323.27030189097, 24616.384590384667, 24794.628080578444], "value": 18487.820805856863, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.23169028138096, 390.1416882857713, 398.57352632683023, 404.43379269023364, 414.492246997428, 19213.61572768544, 20643.418271723094, 20867.37397878064, 20874.89562552187, 21281.467963300925, 20044.69932951007, 20086.196552137895, 20792.376228713125, 20837.791622083776, 21310.14759409622, 5073.670211436263, 5654.293213348321, 5880.117982302646, 5888.5877988540815, 5890.705464726747, 4079.3473044312914, 4374.993751437156, 4395.9889225478155, 5498.900219956005, 6017.669028203447, 6226.221722284715, 6332.715027823759, 6362.427381535661, 6364.936350636503, 6384.233891932975, 5190.792368305282, 6214.596051256676, 6387.073874288202, 6411.519136064814, 6416.4225219730215, 3628.7459877808565, 3841.3661745811905, 5214.5046220609365, 5564.554835613141, 5570.693611851347, 24539.754602453926, 25035.998560057575, 25243.495130097293, 25413.364665883317, 25706.614400784005, 2135.8786637015537, 2250.8086812620945, 2277.464795772992, 2341.988290058556, 2730.563109902426, 14259.928700356535, 14549.490767823181, 14834.703305933928, 14952.252387380588, 15231.238438078051, 1669.1070277401532, 1768.1159420289855, 1776.2894842063279, 1798.5503624093976, 1802.8828126171757, 115113.42443287814, 120964.94775363023, 121073.39463302716, 121415.0, 122307.0, 8494.405391622651, 10421.68734937953, 10550.89449105499, 10869.510872010778, 10976.0, 13797.655058623413, 13899.79150312746, 13908.0, 13910.721785564232, 13912.165270083828, 8392.916070839214, 8593.742187734379, 8617.78455538604, 8710.738677839676, 8747.256483198873, 8018.9174461446955, 8608.182222688834, 8651.091635378321, 8656.956715216384, 8751.387402881737, 5062.519060689228, 5829.766809327664, 6515.804525864231, 6522.119513865568, 6573.605583664996, 1547.4583895636438, 1602.3510478256326, 1736.7394890766234, 1785.9821401785816, 1876.8123187681122, 914.2274777812737, 957.8515330123842, 1007.5466040281902, 1009.1573536097294, 1018.682457732605, 20937.89531052335, 21139.682904756442, 21281.467963301042, 21335.67996480054, 21572.56854862894, 798.8202654402829, 865.2429124516049, 1009.3590569988111, 1012.2458768217725, 1091.5197313182175, 11120.42331400913, 11302.830457543143, 11348.375839328915, 11370.772584548262, 11421.37182454973, 575663.0, 582833.0, 593969.0, 594051.0, 594150.0, 115664.0, 117421.41289293498, 120043.0, 122664.0, 122787.0, 923.9722808315761, 1027.8077430180954, 1060.6181774561153, 1069.2408390043001, 1082.7780305037447, 16410.671786563962, 16841.1579421026, 18333.0, 18544.165512552237, 18701.25194992185, 37545.0, 44061.67814965556, 44410.0, 44664.33003505015, 45309.546904530536, 61932.0, 63173.684131579044, 63330.0, 63406.36593634189, 65562.0, 6619.536632435684, 6997.0, 7996.080450748006, 8064.0, 8307.626156823078, 10671.555673229093, 11593.942030289794, 11701.771314011907, 12824.166429182073, 13024.8046279304, 10647.148228141576, 10990.725231869268, 11311.434428278388, 11364.374959376983, 11438.0, 10562.788744225227, 10574.682759517227, 10986.560537578407, 11056.180673928096, 11256.0, 10795.326724357737, 11325.433728313716, 11455.885441145481, 11456.198066135625, 11505.539778408769, 7350.632468376667, 7442.106947166375, 7483.289087536676, 7515.511491753018, 7794.922050779646, 8325.542095184823, 10780.407077610807, 11061.723456913642, 11168.888311116785, 11488.310701357945], "value": 9462.107392966629, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.074816880015, 1565.9921700391496, 1579.1788270099548, 1820.1263393571085, 1973.190991693405, 7901.802454938622, 8625.0, 8802.427842190253, 8809.515476648781, 8836.823263534725, 8427.325813934885, 8636.481811091338, 8648.870266946002, 8659.263962563175, 8667.826643467142, 2134.7011418401435, 2358.103920510204, 2401.6757737705407, 2427.5509030829267, 2432.4405386761055, 2436.597961336386, 2464.581021226387, 2468.7778099971, 2469.9012039518398, 2474.1093206445667, 2393.0018434607036, 2451.767082127204, 2453.2027091195346, 2456.2262887190477, 2457.1891275879007, 2110.45128266651, 2333.381653861711, 2415.2995631266913, 2415.492746523232, 2422.6244932035474, 2408.891599877992, 2424.6363045543135, 2426.029588164748, 2430.8419952703016, 2431.112643884987, 8561.015483219448, 8626.827463450758, 8631.007434145027, 8641.913580864237, 8671.956640216822, 4209.5790420957965, 5141.614378921596, 5250.6324557281005, 5264.8683782905355, 5275.050490911634, 3461.152017755666, 3466.4453687410146, 3549.0, 3555.8933232003083, 3560.2167523144867, 877.9648814047495, 1037.6212682370956, 1061.1086687182803, 1278.3480424983325, 1629.3401172525146, 11243.156763242789, 11366.545338186463, 11368.94315528417, 11374.71563210918, 11387.829182562267, 4338.848140315074, 4346.565343465628, 4550.658700597469, 4564.041551274269, 4617.492075871652, 9040.0, 9040.141186587263, 9065.229455496225, 9066.637334506679, 9069.818603627891, 5236.371635403776, 5237.73811309433, 5240.633155679066, 5252.474752524717, 5272.894542109137, 4372.584604462571, 4516.774161291923, 4735.313379559944, 4869.147899117711, 4881.633877459205, 3929.862454814068, 3980.8009599520487, 4059.9594004060173, 4090.0388408723925, 4098.938515922204, 1314.6450458376185, 1334.6263046347037, 1335.659406851266, 1341.1751772659743, 1347.5042702600301, 780.9531428114332, 860.8149247911729, 895.7088946092546, 898.2589363774912, 944.2681921510884, 5470.124780035176, 5529.170624406372, 5777.855553611109, 5784.1034639630925, 5970.6417614942375, 902.4297721963736, 904.7059705595576, 932.6988850553576, 944.4191822029402, 1007.652359935821, 3635.709143268532, 3726.9627303727157, 3845.8269377878064, 3915.236528876899, 3915.941260881089, 10195.592176312864, 10623.681289561326, 10651.733706657396, 10658.307210031478, 10736.248462607698, 10177.745556361151, 10477.738056548495, 10603.469826508644, 10613.097886679565, 10627.84058239127, 7991.280784729286, 8011.879821802794, 8026.317762990095, 8252.587370631445, 8261.008678958564, 5596.153269421, 5606.803761868315, 5697.943020569741, 5735.655860648374, 5846.970765146317, 9533.046695330411, 9689.951550242204, 9802.26483013791, 9840.261980351646, 9910.355826871519, 10024.799504010025, 10034.448105354275, 10180.949095254477, 10197.286189966928, 10217.1826253901, 6393.968030159819, 6609.537332386687, 6751.628660423724, 6917.9308206917285, 7060.823479413056, 8044.074931382966, 8189.262966333059, 8207.876881846658, 8210.712625058095, 8249.958750206211, 7014.579125252501, 7244.746433874789, 7267.818304542428, 7327.230640782641, 7450.552966822008, 6969.860602787814, 7110.822229444305, 7212.675429605785, 7240.746573869889, 7287.198408174991, 6711.966440167768, 6817.147856517935, 6819.113515242987, 6825.488088393489, 6827.419669327964, 4712.151812673752, 4741.312509686145, 4799.472058073539, 4853.6359773017875, 4964.7765850537535, 4835.927461088157, 4876.29293752411, 4879.438864530624, 4931.6301277405055, 5067.645264831426], "value": 4539.34132604688, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.722416213327, 313.92151962009495, 320.91014515935547, 328.8783150234412, 334.68372388093263, 5800.274965629296, 5872.941270587293, 5911.261092363455, 5930.673812940287, 5946.791862284821, 4754.7860346284415, 5131.615128865341, 5340.062965369041, 5724.284464441945, 5826.4173582641715, 4141.630475737201, 4161.875143745678, 4330.39374487572, 4333.4366532350905, 4391.956080439187, 3926.548446928611, 3982.192554249247, 4278.486581610196, 4401.8430968825205, 4404.20724269633, 3632.6185750496084, 4263.616274535291, 4376.627986621141, 4399.626031787302, 4413.624841888443, 3919.8432062717743, 4239.7668128252935, 4384.824607015716, 4402.559744025604, 4410.140022695576, 3254.5931758530182, 3527.629598892105, 3615.566132064143, 3771.3400154973046, 3814.1608846053823, 4595.885102872421, 4614.423197100363, 5559.499645031967, 5597.384287728347, 5600.019996500596, 4919.606431485473, 5059.2916991621205, 5068.290439338495, 5071.29001939725, 5072.097963263776, 5280.52475277223, 6032.215811944463, 6181.103739957726, 6185.195924529828, 6206.565540412173, 3719.9070023249637, 4157.688173386978, 4240.363945408213, 4269.807858646337, 4282.036541778106, 4619.930701039487, 5199.298094757197, 5945.0, 6395.392437718409, 6556.475481961432, 3995.800209989489, 4316.5467625899455, 4319.719218250803, 4320.416743739618, 4350.1734670412525, 4388.51726310112, 4401.0, 4406.339049142654, 4410.823567057346, 4436.201483732959, 4952.62855285855, 5066.138756411419, 5108.6168537359845, 5121.8463446096675, 5124.82195067095, 6263.592866463665, 6267.843303917439, 6278.497720182374, 6304.495640348761, 6323.20959880015, 1345.6030471011074, 1494.68611591567, 1523.2459932333566, 1525.123054243792, 1529.6023034011014, 851.7955690634327, 893.8737191139081, 966.6278482784038, 966.970990870275, 1010.131287093114, 4679.298105284167, 5742.16738572913, 5795.13073039039, 5816.534677225896, 5826.563007774434, 5384.380796208407, 6398.488120950313, 6433.5496515243485, 6434.7747828826705, 6438.130852334969, 4758.619310455155, 5621.353544342372, 5781.306243250753, 5801.3038435388025, 5847.678377689268, 2539.9238022858976, 3056.7096125868006, 3174.9841250793597, 3306.5866766654167, 3404.9489257661153, 5974.731137098841, 6146.969265153645, 6164.4143806338325, 6687.164104486939, 6729.293424190488, 5824.912626310609, 6615.503837212228, 6616.27221005689, 6623.4038936495945, 6632.0, 5490.560755139659, 5600.0, 6451.612903225821, 6707.429868461138, 6738.629375384303, 5338.492843179892, 6224.502039836711, 6519.380658837403, 6578.440832529289, 6599.307072757291, 5130.615203859651, 6438.195225596801, 6452.709628066655, 6454.128692626436, 6465.903011454738, 5438.755256013568, 6141.877162456815, 6803.557768745016, 6843.589384636938, 6844.946516045067, 3663.798491083016, 4382.5179230284, 4399.516053234206, 4409.096135292321, 4414.602685758297, 5813.476787089182, 5903.848749493807, 6274.372562743689, 6546.083548303146, 6548.246951600627, 5417.566594672496, 5984.8803023940145, 6141.9078713818435, 6336.556441049082, 6355.332690067661, 4696.013837094249, 5515.8692468043155, 5895.4399332063385, 5926.733297001559, 6299.433051025429, 5942.702864856827, 6107.6640784757265, 6185.474234689922, 6188.721507532082, 6211.472024877936, 6093.177421048279, 6308.558400911892, 6324.177856878577, 6324.7153878076515, 6368.64972426521, 5581.218629391969, 6071.301800292847, 6166.260048794174, 6210.378962103753, 6223.751049957951], "value": 4484.858786951216, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.1730102769482, 714.1501613080434, 739.8483310921262, 744.2074190986599, 754.5661244784249, 15043.849561504381, 15404.460843870467, 15663.0, 15682.921585392058, 15692.37230510781, 11612.374267602543, 13033.141388428949, 13878.861211387883, 14155.92922035391, 14156.362963666634, 723.8009547374462, 747.4692967992731, 901.779064129286, 921.8847644044495, 924.066692640433, 893.9106089391041, 913.2511340700628, 925.2597921662676, 925.6898938855492, 929.8884133903907, 940.407543247753, 977.643160246512, 982.1308142294085, 984.5028260728358, 985.7190700650316, 758.6131073152704, 953.408886490377, 982.3516479123815, 985.9950700246453, 992.9503524823803, 726.2265687043282, 730.8976743255948, 731.6634348199834, 732.6776218463859, 733.581858340746, 15093.622659433493, 15152.090874547563, 15188.924055379652, 15219.771703424458, 15221.543353699406, 12725.427355769012, 12858.549950751678, 12873.485060597564, 12928.418221180069, 13178.275194864278, 10029.648962286357, 10089.142422894063, 10141.594336226615, 10170.44062576558, 10463.947680261626, 3403.4724617684296, 3490.842912068963, 3520.9471857922154, 3694.8522059117604, 3735.7571757835653, 80380.0, 81273.0, 83848.16151838464, 87193.0, 87216.0, 7873.921260787319, 9475.526223688788, 9483.241340692728, 9701.272404569685, 9742.366746161619, 18170.818291817177, 21110.894445527672, 21204.681929771064, 21247.78752212458, 21496.032678529504, 6248.656323902138, 7428.702851885972, 7513.737019204302, 7526.360259377905, 7616.0, 4032.8991775205855, 4082.591740825953, 4093.5497095319492, 4135.627793498599, 4371.628411584988, 3122.500399935999, 3350.1959529712694, 3494.0042088005307, 3547.911302217414, 3582.390993531106, 596.4155127974609, 612.7548980407784, 645.2063961327593, 672.9461643068543, 693.1301216972768, 470.12945880676165, 533.6051322021676, 537.8332716857786, 540.5918531508764, 561.4133230773851, 7286.453515986323, 7494.1381741099385, 7516.04948132472, 7864.606769661494, 7903.723369682033, 512.2802462540062, 545.1305168256624, 579.2903692976071, 602.5179856115091, 637.2926052082186, 3098.3338582204483, 3715.145516531213, 3720.3675375186285, 3736.3648179809497, 3947.559140913603, 98403.0, 98798.01201987888, 101175.49412252892, 104379.0, 104536.43195352076, 63214.68392658007, 83474.0, 83737.0, 84546.57726711327, 84747.57626211953, 59585.702071489366, 60075.69962150161, 61503.38496615066, 61646.38353616496, 63576.72846543043, 7438.851222975619, 7460.477766556289, 7461.4030877528585, 7543.0, 7570.611945404519, 35505.98199153101, 46282.0, 46504.534954651375, 47455.0, 48447.5155248443, 47224.52775472201, 48321.75839120782, 48637.0, 60274.39725602688, 60300.492487688156, 9685.225181985283, 10817.296875703054, 10871.021608055313, 11020.834687479533, 11212.943935280271, 9921.751956201153, 11884.0, 11885.108616853599, 11986.0, 12896.226226426445, 11200.38397888124, 11286.943565282121, 11607.245529040585, 12027.45876435545, 12385.19496232742, 11416.885831141582, 11700.005499532635, 11723.648290551297, 11826.763464730486, 11915.463804128662, 11348.489317980546, 11359.659210223706, 11574.826377604511, 11714.121440891797, 11738.413079345828, 9576.377535460173, 9646.758831029281, 9808.65669701557, 10054.044865737744, 10130.29087963865, 9549.618015279311, 9690.75773105678, 9693.515324233902, 9789.9021009789, 9869.161858639016], "value": 6778.987098146169, "unit": "score"}], "name": "dromaeo_css", "value": 7171.741991390627, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 109.0, 109.0, 111.0, 110.0, 119.0, 110.0, 111.0, 110.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 123.0, 146.0, 172.0, 120.0, 144.0, 141.0, 120.0, 170.0, 131.0], "value": 142.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 186.0, 189.0, 186.0, 184.0, 181.0, 183.0, 181.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 72.0, 86.0, 91.0, 56.0, 61.0, 60.0, 79.0, 88.0, 55.0], "value": 72.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 97.0, 133.0, 80.0, 78.0, 84.0, 122.0, 79.0, 76.0, 120.0], "value": 95.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 129.0, 87.0, 85.0, 86.0, 84.0, 86.0, 83.0, 87.0, 105.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 93.0, 93.0, 105.0, 92.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 87.0, 81.0, 82.0, 84.0, 84.0, 84.0, 85.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 61.0, 61.0, 58.0, 58.0, 59.0, 75.0, 62.0, 59.0], "value": 61.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 73.0, 74.0, 70.0, 68.0, 67.0, 67.0, 67.0, 69.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 83.0, 82.0, 82.0, 80.0, 81.0, 83.0, 82.0, 83.0], "value": 82.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 139.0, 142.0, 146.0, 147.0, 147.0, 149.0, 138.0, 227.0, 161.0], "value": 153.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 191.0, 222.0, 219.0, 171.0, 169.0, 169.0, 169.0, 169.0, 168.0], "value": 181.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 70.0, 69.0, 71.0, 67.0, 69.0, 69.0, 71.0, 67.0], "value": 68.9, "unit": "score"}], "name": "kraken", "value": 1492.6999999999998, "alertThreshold": 2.0}]} 13:51:10 INFO - Return code: 0 13:51:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:51:10 ERROR - # TBPL SUCCESS # 13:51:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:51:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:51:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:51:10 INFO - 2016-04-19 13:51:10.258 screenresolution[1818:10058] starting screenresolution argv=screenresolution get 13:51:10 INFO - 2016-04-19 13:51:10.261 screenresolution[1818:10058] Display 0: 1600x1200x32@60 13:51:10 INFO - 2016-04-19 13:51:10.267 screenresolution[1819:10061] starting screenresolution argv=screenresolution list 13:51:10 INFO - Available Modes on Display 0 13:51:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:51:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:51:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:51:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:51:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:51:10 INFO - Intel Iris: 13:51:10 INFO - Chipset Model: Intel Iris 13:51:10 INFO - Type: GPU 13:51:10 INFO - Bus: Built-In 13:51:10 INFO - VRAM (Dynamic, Max): 1536 MB 13:51:10 INFO - Vendor: Intel (0x8086) 13:51:10 INFO - Device ID: 0x0a2e 13:51:10 INFO - Revision ID: 0x0009 13:51:10 INFO - Displays: 13:51:10 INFO - Display: 13:51:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:51:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:51:10 INFO - Display Serial Number: 1600x1200 60 13:51:10 INFO - Main Display: Yes 13:51:10 INFO - Mirror: Off 13:51:10 INFO - Online: Yes 13:51:10 INFO - Rotation: Supported 13:51:10 INFO - Return code: 0 13:51:10 INFO - Running post-action listener: _resource_record_post_action 13:51:10 INFO - Running post-run listener: _resource_record_post_run 13:51:11 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 37717504; Write bytes: 696271360; Read time: 395; Write time: 1866 13:51:11 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 390277632; Write bytes: 384235008; Read time: 28511; Write time: 705 13:51:11 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 35878400; Write bytes: 298597376; Read time: 373; Write time: 1127 13:51:11 INFO - Running post-run listener: _upload_blobber_files 13:51:11 INFO - Blob upload gear active. 13:51:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:51:11 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 13:51:11 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'] 13:51:11 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 13:51:11 INFO - (blobuploader) - INFO - Open directory for files ... 13:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:51:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:13 INFO - (blobuploader) - INFO - Done attempting. 13:51:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:51:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:51:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:51:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:51:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:51:14 INFO - (blobuploader) - INFO - Done attempting. 13:51:14 INFO - (blobuploader) - INFO - Iteration through files over. 13:51:14 INFO - Return code: 0 13:51:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:51:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:51:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbe5bce68f0f4ac4baec0dc40ba1d2e3bde9fafb5119400bcc8bae399f05a15a3316c34839a92f1068491d5cbd4afdaee8174935c25d14e796dfe8edacf7d07b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9593e994d48b5bcb015b2aa8de8bce4fed8b3ae89b9ee3d8ac8df69df302ad1c4411acb416a135d3b36772ea617bbf68e29c46e4d42d3ca31d39cb5827c1292e"} 13:51:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:51:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:51:14 INFO - Contents: 13:51:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fbe5bce68f0f4ac4baec0dc40ba1d2e3bde9fafb5119400bcc8bae399f05a15a3316c34839a92f1068491d5cbd4afdaee8174935c25d14e796dfe8edacf7d07b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9593e994d48b5bcb015b2aa8de8bce4fed8b3ae89b9ee3d8ac8df69df302ad1c4411acb416a135d3b36772ea617bbf68e29c46e4d42d3ca31d39cb5827c1292e"} 13:51:14 INFO - Running post-run listener: copy_logs_to_upload_dir 13:51:14 INFO - Copying logs to upload dir... 13:51:14 INFO - mkdir: /builds/slave/test/build/upload/logs 13:51:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.368949 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-19 13:51:14.257010) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 13:51:14.263473) ========= 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.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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/fbe5bce68f0f4ac4baec0dc40ba1d2e3bde9fafb5119400bcc8bae399f05a15a3316c34839a92f1068491d5cbd4afdaee8174935c25d14e796dfe8edacf7d07b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9593e994d48b5bcb015b2aa8de8bce4fed8b3ae89b9ee3d8ac8df69df302ad1c4411acb416a135d3b36772ea617bbf68e29c46e4d42d3ca31d39cb5827c1292e"} build_url:https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/ZRVP-UwORtehXbmQYGyNhw/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/fbe5bce68f0f4ac4baec0dc40ba1d2e3bde9fafb5119400bcc8bae399f05a15a3316c34839a92f1068491d5cbd4afdaee8174935c25d14e796dfe8edacf7d07b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9593e994d48b5bcb015b2aa8de8bce4fed8b3ae89b9ee3d8ac8df69df302ad1c4411acb416a135d3b36772ea617bbf68e29c46e4d42d3ca31d39cb5827c1292e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-19 13:51:14.291300) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:51:14.291629) ========= 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.z9mxW7rq93/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.B25v2o4aH5/Listeners TMPDIR=/var/folders/5n/qj9h8_p94j3fbvp8w7v03z9m00000w/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.005207 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-19 13:51:14.342989) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 13:51:14.346322) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-19 13:51:14.346720) ========= ========= Total master_lag: 0.18 =========