builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0250 starttime: 1468475776.18 results: success (0) buildid: 20160713154642 builduid: 0219f93640ac4a39933847396c8d0f21 revision: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.179479) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.180070) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.180418) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.254597) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.254970) ========= 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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-07-13 22:56:16-- 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% 19.2M=0.001s 2016-07-13 22:56:16 (19.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.507930 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.796884) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.797237) ========= 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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.026116 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:16.846864) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-13 22:56:16.847282) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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-07-13 22:56:16,928 truncating revision to first 12 chars 2016-07-13 22:56:16,928 Setting DEBUG logging. 2016-07-13 22:56:16,928 attempt 1/10 2016-07-13 22:56:16,928 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/84c59d136a9e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-13 22:56:18,790 unpacking tar archive at: mozilla-beta-84c59d136a9e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.130729 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-13 22:56:19.000288) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:19.000606) ========= script_repo_revision: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:19.004050) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:19.004340) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-13 22:56:19.022588) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-07-13 22:56:19.022961) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:56:19 INFO - MultiFileLogger online at 20160713 22:56:19 in /builds/slave/test 22:56:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:56:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:56:19 INFO - {'append_to_log': False, 22:56:19 INFO - 'base_work_dir': '/builds/slave/test', 22:56:19 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:56:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:56:19 INFO - 'branch': 'Mozilla-Beta', 22:56:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:56:19 INFO - 'config_files': ('talos/mac_config.py',), 22:56:19 INFO - 'default_actions': ('clobber', 22:56:19 INFO - 'read-buildbot-config', 22:56:19 INFO - 'download-and-extract', 22:56:19 INFO - 'populate-webroot', 22:56:19 INFO - 'create-virtualenv', 22:56:19 INFO - 'install', 22:56:19 INFO - 'run-tests'), 22:56:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:56:19 INFO - 'download_minidump_stackwalk': True, 22:56:19 INFO - 'download_symbols': 'ondemand', 22:56:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:56:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:56:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:56:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:56:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:56:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:56:19 INFO - 'installer_path': 'installer.exe', 22:56:19 INFO - 'log_level': 'info', 22:56:19 INFO - 'log_name': 'talos', 22:56:19 INFO - 'log_to_console': True, 22:56:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:56:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:56:19 INFO - 'opt_config_files': (), 22:56:19 INFO - 'pip_index': False, 22:56:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:56:19 INFO - 'cmd': ('bash', 22:56:19 INFO - '-c', 22:56:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:56:19 INFO - 'enabled': True, 22:56:19 INFO - 'halt_on_failure': False, 22:56:19 INFO - 'name': 'check_screen_resolution'},), 22:56:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:56:19 INFO - 'cmd': ('bash', 22:56:19 INFO - '-c', 22:56:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:56:19 INFO - 'enabled': True, 22:56:19 INFO - 'halt_on_failure': False, 22:56:19 INFO - 'name': 'check_screen_resolution'},), 22:56:19 INFO - 'run_cmd_checks_enabled': True, 22:56:19 INFO - 'sps_profile': False, 22:56:19 INFO - 'sps_profile_interval': 0, 22:56:19 INFO - 'suite': 'dromaeojs', 22:56:19 INFO - 'system_bits': '32', 22:56:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:56:19 INFO - 'title': 't-yosemite-r7-0250', 22:56:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:56:19 INFO - 'use_talos_json': True, 22:56:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:56:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:56:19 INFO - 'work_dir': 'build'} 22:56:19 INFO - ##### 22:56:19 INFO - ##### Running clobber step. 22:56:19 INFO - ##### 22:56:19 INFO - Running pre-action listener: _resource_record_pre_action 22:56:19 INFO - Running main action method: clobber 22:56:19 INFO - rmtree: /builds/slave/test/build 22:56:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:56:21 INFO - Running post-action listener: _resource_record_post_action 22:56:21 INFO - ##### 22:56:21 INFO - ##### Running read-buildbot-config step. 22:56:21 INFO - ##### 22:56:21 INFO - Running pre-action listener: _resource_record_pre_action 22:56:21 INFO - Running main action method: read_buildbot_config 22:56:21 INFO - Using buildbot properties: 22:56:21 INFO - { 22:56:21 INFO - "project": "", 22:56:21 INFO - "product": "firefox", 22:56:21 INFO - "script_repo_revision": "production", 22:56:21 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 22:56:21 INFO - "repository": "", 22:56:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 22:56:21 INFO - "buildid": "20160713154642", 22:56:21 INFO - "pgo_build": "False", 22:56:21 INFO - "basedir": "/builds/slave/test", 22:56:21 INFO - "buildnumber": 39, 22:56:21 INFO - "slavename": "t-yosemite-r7-0250", 22:56:21 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 22:56:21 INFO - "platform": "yosemite_r7", 22:56:21 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:56:21 INFO - "branch": "mozilla-beta", 22:56:21 INFO - "repo_path": "releases/mozilla-beta", 22:56:21 INFO - "revision": "84c59d136a9e54be69571b75a19e8a8ccff7b8a1", 22:56:21 INFO - "stage_platform": "macosx64", 22:56:21 INFO - "builduid": "0219f93640ac4a39933847396c8d0f21", 22:56:21 INFO - "slavebuilddir": "test" 22:56:21 INFO - } 22:56:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 22:56:21 INFO - Running post-action listener: _resource_record_post_action 22:56:21 INFO - ##### 22:56:21 INFO - ##### Running download-and-extract step. 22:56:21 INFO - ##### 22:56:21 INFO - Running pre-action listener: _resource_record_pre_action 22:56:21 INFO - Running main action method: download_and_extract 22:56:21 INFO - mkdir: /builds/slave/test/build/tests 22:56:21 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')]} 22:56:21 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 22:56:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:56:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 22:56:45 INFO - Downloaded 1165 bytes. 22:56:45 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 22:56:45 INFO - Using the following test package requirements: 22:56:45 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:56:45 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 22:56:45 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 22:56:45 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:56:45 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 22:56:45 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 22:56:45 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 22:56:45 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 22:56:45 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 22:56:45 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 22:56:45 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 22:56:45 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 22:56:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:56:45 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:56:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:56:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:57:02 INFO - Downloaded 18314911 bytes. 22:57:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:57:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:57:05 INFO - Return code: 0 22:57:05 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:57:05 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')]} 22:57:05 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 22:57:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:57:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 22:57:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 22:57:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 22:57:35 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip: timed out 22:57:35 INFO - retry: attempt #1 caught exception: timed out 22:57:35 INFO - retry: Failed, sleeping 30 seconds before retrying 22:58:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #2 22:58:06 INFO - Downloaded 18314911 bytes. 22:58:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:58:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 22:58:10 INFO - Return code: 0 22:58:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:10 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 22:58:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:58:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 22:58:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 22:58:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 22:58:11 INFO - Downloaded 10840145 bytes. 22:58:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:58:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 22:58:11 INFO - Return code: 0 22:58: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')]} 22:58:11 INFO - https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 22:58:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:58:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:58:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 22:58:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 22:58:14 INFO - Downloaded 86190055 bytes. 22:58:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:58:14 INFO - mkdir: /builds/slave/test/properties 22:58:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:58:14 INFO - Writing to file /builds/slave/test/properties/build_url 22:58:14 INFO - Contents: 22:58:14 INFO - build_url:https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 22:58:15 INFO - Running post-action listener: _resource_record_post_action 22:58:15 INFO - Running post-action listener: set_extra_try_arguments 22:58:15 INFO - ##### 22:58:15 INFO - ##### Running populate-webroot step. 22:58:15 INFO - ##### 22:58:15 INFO - Running pre-action listener: _resource_record_pre_action 22:58:15 INFO - Running main action method: populate_webroot 22:58:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:58:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:58:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:58:15 INFO - '--tppagecycles', 22:58:15 INFO - '7'], 22:58:15 INFO - 'tests': ['tsvgm']}}, 22:58:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:58:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:58:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:58:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:58:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['damp', 'tps']}, 22:58:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'tests': ['damp', 'tps']}, 22:58:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['dromaeo_dom']}, 22:58:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:58:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'other-e10s': {'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['a11yr', 22:58:15 INFO - 'ts_paint', 22:58:15 INFO - 'tpaint', 22:58:15 INFO - 'sessionrestore', 22:58:15 INFO - 'sessionrestore_no_auto_restore', 22:58:15 INFO - 'tabpaint']}, 22:58:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['tsvgx', 22:58:15 INFO - 'tsvgr_opacity', 22:58:15 INFO - 'tart', 22:58:15 INFO - 'tscrollx', 22:58:15 INFO - 'cart']}, 22:58:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:58:15 INFO - 'tsvgr_opacity', 22:58:15 INFO - 'tart', 22:58:15 INFO - 'tscrollx', 22:58:15 INFO - 'cart']}, 22:58:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'talos_options': ['--disable-e10s'], 22:58:15 INFO - 'tests': ['tp5o']}, 22:58:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'tests': ['tp5o']}, 22:58:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'talos_options': ['--disable-e10s', 22:58:15 INFO - '--xperf_path', 22:58:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:15 INFO - 'tests': ['tp5n']}, 22:58:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:15 INFO - 'talos_options': ['--xperf_path', 22:58:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:15 INFO - 'tests': ['tp5n']}}, 22:58:15 INFO - 'talos.zip': {'path': '', 22:58:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:58:15 INFO - Running post-action listener: _resource_record_post_action 22:58:15 INFO - ##### 22:58:15 INFO - ##### Running create-virtualenv step. 22:58:15 INFO - ##### 22:58:15 INFO - Running pre-action listener: _resource_record_pre_action 22:58:15 INFO - Running main action method: create_virtualenv 22:58:15 INFO - Creating virtualenv /builds/slave/test/build/venv 22:58:15 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 22:58:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:58:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:58:15 INFO - Using real prefix '/tools/python27' 22:58:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:58:15 INFO - Installing distribute.............................................................................................................................................................................................done. 22:58:18 INFO - Installing pip.................done. 22:58:18 INFO - Return code: 0 22:58:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:58:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:18 INFO - 'HOME': '/Users/cltbld', 22:58:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:18 INFO - 'LOGNAME': 'cltbld', 22:58:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:18 INFO - 'MOZ_NO_REMOTE': '1', 22:58:18 INFO - 'NO_EM_RESTART': '1', 22:58:18 INFO - 'PAGER': '/bin/cat', 22:58:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:18 INFO - 'PWD': '/builds/slave/test', 22:58:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:18 INFO - 'SHELL': '/bin/bash', 22:58:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:18 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:18 INFO - 'USER': 'cltbld', 22:58:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:18 INFO - 'XPC_FLAGS': '0x0', 22:58:18 INFO - 'XPC_SERVICE_NAME': '0', 22:58:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:18 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 22:58:18 INFO - Cleaning up... 22:58:18 INFO - Return code: 0 22:58:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:58:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:58:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:18 INFO - 'HOME': '/Users/cltbld', 22:58:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:19 INFO - 'LOGNAME': 'cltbld', 22:58:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:19 INFO - 'MOZ_NO_REMOTE': '1', 22:58:19 INFO - 'NO_EM_RESTART': '1', 22:58:19 INFO - 'PAGER': '/bin/cat', 22:58:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:19 INFO - 'PWD': '/builds/slave/test', 22:58:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:19 INFO - 'SHELL': '/bin/bash', 22:58:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:19 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:19 INFO - 'USER': 'cltbld', 22:58:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:19 INFO - 'XPC_FLAGS': '0x0', 22:58:19 INFO - 'XPC_SERVICE_NAME': '0', 22:58:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:19 INFO - Downloading/unpacking psutil>=0.7.1 22:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:58:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:22 INFO - Installing collected packages: psutil 22:58:22 INFO - Running setup.py install for psutil 22:58:22 INFO - building 'psutil._psutil_osx' extension 22:58:22 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 22:58:23 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 22:58:23 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 22:58:23 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 22:58:23 INFO - building 'psutil._psutil_posix' extension 22:58:23 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 22:58:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:58:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:58:23 INFO - ^ 22:58:23 INFO - 1 warning generated. 22:58:23 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 22:58:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:23 INFO - Successfully installed psutil 22:58:23 INFO - Cleaning up... 22:58:23 INFO - Return code: 0 22:58:23 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:58:23 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')]} 22:58:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:23 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')]} 22:58:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:23 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:23 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 22:58:23 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 22:58:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:23 INFO - 'HOME': '/Users/cltbld', 22:58:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:23 INFO - 'LOGNAME': 'cltbld', 22:58:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:23 INFO - 'MOZ_NO_REMOTE': '1', 22:58:23 INFO - 'NO_EM_RESTART': '1', 22:58:23 INFO - 'PAGER': '/bin/cat', 22:58:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:23 INFO - 'PWD': '/builds/slave/test', 22:58:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:23 INFO - 'SHELL': '/bin/bash', 22:58:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:23 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:23 INFO - 'USER': 'cltbld', 22:58:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:23 INFO - 'XPC_FLAGS': '0x0', 22:58:23 INFO - 'XPC_SERVICE_NAME': '0', 22:58:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:23 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:58:23 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 22:58:23 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 22:58:23 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 22:58:23 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 22:58:23 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 22:58:23 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 22:58:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:58:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:58:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:58:26 INFO - Installing collected packages: mozsystemmonitor 22:58:26 INFO - Running setup.py install for mozsystemmonitor 22:58:26 INFO - Successfully installed mozsystemmonitor 22:58:26 INFO - Cleaning up... 22:58:26 INFO - Return code: 0 22:58:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:26 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:26 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 22:58:26 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 22:58:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:26 INFO - 'HOME': '/Users/cltbld', 22:58:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:26 INFO - 'LOGNAME': 'cltbld', 22:58:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:26 INFO - 'MOZ_NO_REMOTE': '1', 22:58:26 INFO - 'NO_EM_RESTART': '1', 22:58:26 INFO - 'PAGER': '/bin/cat', 22:58:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:26 INFO - 'PWD': '/builds/slave/test', 22:58:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:26 INFO - 'SHELL': '/bin/bash', 22:58:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:26 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:26 INFO - 'USER': 'cltbld', 22:58:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:26 INFO - 'XPC_FLAGS': '0x0', 22:58:26 INFO - 'XPC_SERVICE_NAME': '0', 22:58:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:26 INFO - Downloading/unpacking blobuploader==1.2.4 22:58:26 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 22:58:26 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 22:58:26 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 22:58:26 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 22:58:26 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 22:58:26 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 22:58:29 INFO - Downloading blobuploader-1.2.4.tar.gz 22:58:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:58:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:58:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:30 INFO - Downloading docopt-0.6.1.tar.gz 22:58:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:58:30 INFO - Installing collected packages: blobuploader, requests, docopt 22:58:30 INFO - Running setup.py install for blobuploader 22:58:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:58:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:58:30 INFO - Running setup.py install for requests 22:58:31 INFO - Running setup.py install for docopt 22:58:31 INFO - Successfully installed blobuploader requests docopt 22:58:31 INFO - Cleaning up... 22:58:31 INFO - Return code: 0 22:58:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:31 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:31 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 22:58:31 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 22:58:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:31 INFO - 'HOME': '/Users/cltbld', 22:58:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:31 INFO - 'LOGNAME': 'cltbld', 22:58:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:31 INFO - 'MOZ_NO_REMOTE': '1', 22:58:31 INFO - 'NO_EM_RESTART': '1', 22:58:31 INFO - 'PAGER': '/bin/cat', 22:58:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:31 INFO - 'PWD': '/builds/slave/test', 22:58:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:31 INFO - 'SHELL': '/bin/bash', 22:58:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:31 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:31 INFO - 'USER': 'cltbld', 22:58:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:31 INFO - 'XPC_FLAGS': '0x0', 22:58:31 INFO - 'XPC_SERVICE_NAME': '0', 22:58:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:31 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-SEO7eI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:31 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-wl7UPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-F8cNIn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-CaONYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-aXsBzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-RJmiex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-pBQOnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ESCjaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-U8bT4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-TZokze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-JgSX5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:32 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-pInRiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:33 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-WhBSJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:33 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-o3ICGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:33 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-yYchjz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:33 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Iqp9Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:33 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-y7pTcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:58:33 INFO - Running setup.py install for manifestparser 22:58:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:58:33 INFO - Running setup.py install for mozcrash 22:58:33 INFO - Running setup.py install for mozdebug 22:58:33 INFO - Running setup.py install for mozdevice 22:58:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Running setup.py install for mozfile 22:58:34 INFO - Running setup.py install for mozhttpd 22:58:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Running setup.py install for mozinfo 22:58:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Running setup.py install for mozInstall 22:58:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:58:34 INFO - Running setup.py install for mozleak 22:58:34 INFO - Running setup.py install for mozlog 22:58:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Running setup.py install for moznetwork 22:58:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Running setup.py install for mozprocess 22:58:35 INFO - Running setup.py install for mozprofile 22:58:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Running setup.py install for mozrunner 22:58:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:58:35 INFO - Running setup.py install for mozscreenshot 22:58:35 INFO - Running setup.py install for moztest 22:58:35 INFO - Running setup.py install for mozversion 22:58:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:58:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:58:36 INFO - Cleaning up... 22:58:36 INFO - Return code: 0 22:58:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:36 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:36 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 22:58:36 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 22:58:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:36 INFO - 'HOME': '/Users/cltbld', 22:58:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:36 INFO - 'LOGNAME': 'cltbld', 22:58:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:36 INFO - 'MOZ_NO_REMOTE': '1', 22:58:36 INFO - 'NO_EM_RESTART': '1', 22:58:36 INFO - 'PAGER': '/bin/cat', 22:58:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:36 INFO - 'PWD': '/builds/slave/test', 22:58:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:36 INFO - 'SHELL': '/bin/bash', 22:58:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:36 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:36 INFO - 'USER': 'cltbld', 22:58:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:36 INFO - 'XPC_FLAGS': '0x0', 22:58:36 INFO - 'XPC_SERVICE_NAME': '0', 22:58:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-p6QVjq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:36 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)) 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-jADFer-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:36 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)) 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-gm7uTR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:36 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)) 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Aepri3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:36 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)) 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-nn5lGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:36 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)) 22:58:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:36 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-aUg35R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-MbCdaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-1YqqXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-CXP6qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Zl_lv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ORbsks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-YtoohU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-m0SxPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-vm5fe3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-JB0_YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:37 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)) 22:58:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:37 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ipxXjD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:38 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)) 22:58:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:38 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-bAkE7O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:38 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)) 22:58:38 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)) 22:58:38 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)) 22:58:38 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)) 22:58:38 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)) 22:58:38 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)) 22:58:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:40 INFO - Downloading blessings-1.6.tar.gz 22:58:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:40 INFO - Installing collected packages: blessings 22:58:40 INFO - Running setup.py install for blessings 22:58:40 INFO - Successfully installed blessings 22:58:40 INFO - Cleaning up... 22:58:41 INFO - Return code: 0 22:58:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:58:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:41 INFO - Reading from file tmpfile_stdout 22:58:41 INFO - Current package versions: 22:58:41 INFO - blessings == 1.6 22:58:41 INFO - blobuploader == 1.2.4 22:58:41 INFO - docopt == 0.6.1 22:58:41 INFO - manifestparser == 1.1 22:58:41 INFO - mozInstall == 1.12 22:58:41 INFO - mozcrash == 0.17 22:58:41 INFO - mozdebug == 0.1 22:58:41 INFO - mozdevice == 0.48 22:58:41 INFO - mozfile == 1.2 22:58:41 INFO - mozhttpd == 0.7 22:58:41 INFO - mozinfo == 0.9 22:58:41 INFO - mozleak == 0.1 22:58:41 INFO - mozlog == 3.1 22:58:41 INFO - moznetwork == 0.27 22:58:41 INFO - mozprocess == 0.22 22:58:41 INFO - mozprofile == 0.28 22:58:41 INFO - mozrunner == 6.11 22:58:41 INFO - mozscreenshot == 0.1 22:58:41 INFO - mozsystemmonitor == 0.0 22:58:41 INFO - moztest == 0.7 22:58:41 INFO - mozversion == 1.4 22:58:41 INFO - psutil == 3.1.1 22:58:41 INFO - requests == 1.2.3 22:58:41 INFO - wsgiref == 0.1.2 22:58:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:41 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:41 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 22:58:41 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 22:58:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:41 INFO - 'HOME': '/Users/cltbld', 22:58:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:41 INFO - 'LOGNAME': 'cltbld', 22:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:41 INFO - 'MOZ_NO_REMOTE': '1', 22:58:41 INFO - 'NO_EM_RESTART': '1', 22:58:41 INFO - 'PAGER': '/bin/cat', 22:58:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:41 INFO - 'PWD': '/builds/slave/test', 22:58:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:41 INFO - 'SHELL': '/bin/bash', 22:58:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:41 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:41 INFO - 'USER': 'cltbld', 22:58:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:41 INFO - 'XPC_FLAGS': '0x0', 22:58:41 INFO - 'XPC_SERVICE_NAME': '0', 22:58:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 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)) 22:58:41 INFO - Cleaning up... 22:58:41 INFO - Return code: 0 22:58:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:41 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 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:41 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 22:58:41 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 22:58:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:41 INFO - 'HOME': '/Users/cltbld', 22:58:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:41 INFO - 'LOGNAME': 'cltbld', 22:58:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:41 INFO - 'MOZ_NO_REMOTE': '1', 22:58:41 INFO - 'NO_EM_RESTART': '1', 22:58:41 INFO - 'PAGER': '/bin/cat', 22:58:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:41 INFO - 'PWD': '/builds/slave/test', 22:58:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:41 INFO - 'SHELL': '/bin/bash', 22:58:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:41 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:41 INFO - 'USER': 'cltbld', 22:58:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:41 INFO - 'XPC_FLAGS': '0x0', 22:58:41 INFO - 'XPC_SERVICE_NAME': '0', 22:58:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:41 INFO - Downloading/unpacking jsonschema 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58: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 22:58:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:44 INFO - Downloading/unpacking functools32 (from jsonschema) 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:44 INFO - warning: no files found matching '*.txt' 22:58:44 INFO - no previously-included directories found matching 'build' 22:58:44 INFO - no previously-included directories found matching 'dist' 22:58:44 INFO - no previously-included directories found matching '.git*' 22:58:44 INFO - Installing collected packages: jsonschema, functools32 22:58:44 INFO - Running setup.py install for functools32 22:58:44 INFO - warning: no files found matching '*.txt' 22:58:44 INFO - no previously-included directories found matching 'build' 22:58:44 INFO - no previously-included directories found matching 'dist' 22:58:44 INFO - no previously-included directories found matching '.git*' 22:58:45 INFO - Successfully installed jsonschema functools32 22:58:45 INFO - Cleaning up... 22:58:45 INFO - Return code: 0 22:58:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105b06a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105b16420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffdb2ca2870>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, '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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 22:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:58:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:58:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:58:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:58:45 INFO - 'HOME': '/Users/cltbld', 22:58:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:58:45 INFO - 'LOGNAME': 'cltbld', 22:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:45 INFO - 'MOZ_NO_REMOTE': '1', 22:58:45 INFO - 'NO_EM_RESTART': '1', 22:58:45 INFO - 'PAGER': '/bin/cat', 22:58:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:45 INFO - 'PWD': '/builds/slave/test', 22:58:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:58:45 INFO - 'SHELL': '/bin/bash', 22:58:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:58:45 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:58:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:58:45 INFO - 'USER': 'cltbld', 22:58:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:58:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:45 INFO - 'XPC_FLAGS': '0x0', 22:58:45 INFO - 'XPC_SERVICE_NAME': '0', 22:58:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:58:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:45 INFO - Downloading/unpacking flake8 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:58:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:58:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:58:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:58:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:58:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:49 INFO - no previously-included directories found matching 'docs/_build' 22:58:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:58:49 INFO - Running setup.py install for pep8 22:58:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:49 INFO - no previously-included directories found matching 'docs/_build' 22:58:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:58:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:58:49 INFO - Cleaning up... 22:58:49 INFO - Return code: 0 22:58:49 INFO - Running post-action listener: _resource_record_post_action 22:58:49 INFO - Running post-action listener: _start_resource_monitoring 22:58:49 INFO - Starting resource monitoring. 22:58:49 INFO - ##### 22:58:49 INFO - ##### Running install step. 22:58:49 INFO - ##### 22:58:49 INFO - Running pre-action listener: _resource_record_pre_action 22:58:49 INFO - Running main action method: install 22:58:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:49 INFO - Reading from file tmpfile_stdout 22:58:49 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:58:49 INFO - Reading from file tmpfile_stdout 22:58:49 INFO - Output received: 22:58:49 INFO - Usage: mozinstall [options] installer 22:58:49 INFO - Options: 22:58:49 INFO - -h, --help show this help message and exit 22:58:49 INFO - -d DEST, --destination=DEST 22:58:49 INFO - Directory to install application into. [default: 22:58:49 INFO - "/builds/slave/test"] 22:58:49 INFO - --app=APP Application being installed. [default: firefox] 22:58:49 INFO - mkdir: /builds/slave/test/build/application 22:58:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:58:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:59:11 INFO - Reading from file tmpfile_stdout 22:59:11 INFO - Output received: 22:59:11 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:59:11 INFO - Running post-action listener: _resource_record_post_action 22:59:11 INFO - ##### 22:59:11 INFO - ##### Running run-tests step. 22:59:11 INFO - ##### 22:59:11 INFO - Running pre-action listener: _resource_record_pre_action 22:59:11 INFO - Running main action method: run_tests 22:59:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:59:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:59:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:59:11 INFO - 2016-07-13 22:59:11.612 screenresolution[1642:8235] starting screenresolution argv=screenresolution get 22:59:11 INFO - 2016-07-13 22:59:11.615 screenresolution[1642:8235] Display 0: 1600x1200x32@60 22:59:11 INFO - 2016-07-13 22:59:11.620 screenresolution[1643:8238] starting screenresolution argv=screenresolution list 22:59:11 INFO - Available Modes on Display 0 22:59:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:59:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:59:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:59:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:59:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:59:11 INFO - Intel Iris: 22:59:11 INFO - Chipset Model: Intel Iris 22:59:11 INFO - Type: GPU 22:59:11 INFO - Bus: Built-In 22:59:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:59:11 INFO - Vendor: Intel (0x8086) 22:59:11 INFO - Device ID: 0x0a2e 22:59:11 INFO - Revision ID: 0x0009 22:59:11 INFO - Displays: 22:59:11 INFO - Display: 22:59:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:59:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:59:11 INFO - Display Serial Number: 1600x1200 60 22:59:11 INFO - Main Display: Yes 22:59:11 INFO - Mirror: Off 22:59:11 INFO - Online: Yes 22:59:11 INFO - Rotation: Supported 22:59:11 INFO - Return code: 0 22:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:59:11 INFO - Python 2.7.3 22:59:11 INFO - Return code: 0 22:59:11 INFO - grabbing minidump binary from tooltool 22:59: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')]} 22:59:11 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 0x1059a5e00>, '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 0x10580edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1059a0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:59:11 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 22:59:11 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 22:59:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:59:11 INFO - Return code: 0 22:59:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:59:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:59:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:59:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:59:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:59:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:59:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:59:13 INFO - Return code: 0 22:59:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 22:59:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0250 --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 22:59:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aIyMQYE6JP/Render', 22:59:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:59:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:59:13 INFO - 'HOME': '/Users/cltbld', 22:59:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:59:13 INFO - 'LOGNAME': 'cltbld', 22:59:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:59:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:59:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:13 INFO - 'MOZ_NO_REMOTE': '1', 22:59:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:59:13 INFO - 'NO_EM_RESTART': '1', 22:59:13 INFO - 'PAGER': '/bin/cat', 22:59:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:59:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:13 INFO - 'PWD': '/builds/slave/test', 22:59:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:59:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:59:13 INFO - 'SHELL': '/bin/bash', 22:59:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AIwaa8z7kU/Listeners', 22:59:13 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 22:59:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:59:13 INFO - 'USER': 'cltbld', 22:59:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:59:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:59:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:13 INFO - 'XPC_FLAGS': '0x0', 22:59:13 INFO - 'XPC_SERVICE_NAME': '0', 22:59:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:59:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 22:59:13 INFO - mozversion application_buildid: 20160713154642 22:59:13 INFO - mozversion application_changeset: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 22:59:13 INFO - mozversion application_display_name: Firefox 22:59:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:59:13 INFO - mozversion application_name: Firefox 22:59:13 INFO - mozversion application_remotingname: firefox 22:59:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:59:13 INFO - mozversion application_vendor: Mozilla 22:59:13 INFO - mozversion application_version: 48.0 22:59:13 INFO - mozversion platform_buildid: 20160713154642 22:59:13 INFO - mozversion platform_changeset: 84c59d136a9e54be69571b75a19e8a8ccff7b8a1 22:59:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:59:13 INFO - mozversion platform_version: 48.0 22:59:13 INFO - using testdate: 1468475953 22:59:13 INFO - actual date: 1468475953 22:59:13 INFO - starting webserver on 'localhost:49275' 22:59:13 INFO - SUITE-START | Running 2 tests 22:59:13 INFO - TEST-START | dromaeo_css 22:59:13 INFO - operating with platform_type : mac_ 22:59:13 INFO - Initialising browser for dromaeo_css test... 22:59:13 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpcUWv8a/profile http://localhost:49275/getInfo.html) 22:59:15 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 22:59:15 INFO - PROCESS | 1661 | colorDepth:24 22:59:15 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 22:59:15 INFO - PROCESS | 1661 | __metrics 22:59:15 INFO - PROCESS | 1661 | 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" 22:59:15 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:59:16 INFO - TEST-INFO | 1661: exit 0 22:59:16 INFO - Browser initialized. 22:59:16 INFO - Running cycle 1/1 for dromaeo_css test... 22:59:16 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpcUWv8a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:28 INFO - PROCESS | 1665 | RSS: Main: 160739328 22:59:28 INFO - PROCESS | 1665 | 23:00:19 INFO - PROCESS | 1665 | 1468476019411 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] 23:02:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 23:02:30 INFO - PROCESS | 1665 | RSS: Main: 287633408 23:02:30 INFO - PROCESS | 1665 | 23:05:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 23:05:34 INFO - PROCESS | 1665 | RSS: Main: 306941952 23:05:34 INFO - PROCESS | 1665 | 23:08:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 23:08:36 INFO - PROCESS | 1665 | RSS: Main: 310157312 23:08:36 INFO - PROCESS | 1665 | 23:11:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 23:11:39 INFO - PROCESS | 1665 | RSS: Main: 308637696 23:11:39 INFO - PROCESS | 1665 | 23:14:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 23:14:43 INFO - PROCESS | 1665 | RSS: Main: 276443136 23:14:43 INFO - PROCESS | 1665 | 23:17:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 23:17:46 INFO - PROCESS | 1665 | RSS: Main: 316628992 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | __start_tp_report 23:17:46 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 23:17:46 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 23:17:46 INFO - PROCESS | 1665 | |i|pagename|runs| 23:17:46 INFO - PROCESS | 1665 | |0;dojo.html;3568.9286214275717;3641.4719865619477;3669;3669.981650091749;3739;4935.284383764352;5999.61002534836;6001.309849367319;6023.849403764908;6029.306629737576;6022.6687532185715;6059.363766804478;6061.060535616975;6064.969675151629;6077.270727512694;5312.096943519611;5326;5398.217258497515;5416.756245968941;5436.864078398052;5314.9468505314835;5332.573394128461;5333.386660534049;5342.973285133569;5349.170878513836;5345.118055520835;5407.215953686713;5451.618386712932;5453.972730136344;5473.959947609961;5415.1335786274;5469.097598896177;5486.231927530149;5488.286522752017;5512.503874651301;5240.292560504321;5365.382980957201;5365.704886231256;5403.972980135113;5424.5660347172525;5769.336526299488;5836.97081514594;5837.270341207349;5866.472017518401;5899.793507227269;6271.655058971754;6283.96858015707;6290.056491526262;6298.086777417248;6307.117003619497;6249.000159974427;6276.309605943343;6319.968400158015;6327.588706734094;6329.746810127589;6034.728437220336;6076.240469941257;6100.908486372708;6105.8168254952425;6128.38716128388;6395.680215989182;6429.485641148697;6432.06735023419;6435.292117867031;6447.580907241015;6175.44421002112;6254.874902502015;6330.366963303725;6337.746490140343;6339.936600633934;6340.556161068681;6346.555741098079;6351.015592583157;6353.809385718436;6367.7452901884335;6255.46828519581;6313.747450101944;6322.36776322364;6332.968335158387;6348.238211414568;6275.560710750204;6291.653959032205;6307.306196318401;6342.080398342215;6350.206224221973;5005.34930459038;5011.423686276052;5038.571721403649;5072.087024335581;5121.487851214849;4577.679562430664;4591.816327346935;4600.263957766741;4606.654500912445;4627.6066534344955;6110.29731580865;6136.539759518054;6138.662373569497;6142.846428839315;6148.661823599656;6249.9062514062325;6320.431161195423;6364.809055728336;6366.076918846741;6596.8350791230605;6081.635101893812;6092.208012958376;6101.359357267512;6102.7558897644485;6119.847003824851;5493.752781124859;5547.1679248113105;5579.246801681721;5590.804321848797;5603.887922241533;6434.7104380303;6439.967800161063;6507.511936604868;6531.314212007861;6543.574667646588;6315.368463153648;6333.619982801047;6349.8730025400155;6354.841128971812;6356.55504114726;6384.297727250092;6524.902126468011;6548.410643041959;6551.279359270572;6551.44312733423;5208.401033881152;6241.095041219089;6330.746770129143;6344.460720838781;6364.268109167505;6240.750369985331;6335.746570137328;6343.111964324906;6344.365563443619;6346.524010699124;6331.525135614939;6334.208223972004;6338.271098823694;6349;6374;5610.5231055360755;6172.290186628596;6213.2233470816145;6256.436920677161;6340.968295158495;6115.1438798569125;6148.692565371803;6209.937900620936;6222.751089956351;6257.155284036597;6289.874202516015;6317.778877739257;6327.936720632735;6328.6519241442165;6347.555671103163;6027.78902738402;6134.4978165938865;6156.26124865019;6165.383461653799;6273.52948528853 23:17:46 INFO - PROCESS | 1665 | |1;ext.html;533.3466503533173;553.4078535966518;571.9113537401702;599.6701814002298;620.6276234259445;32137.517937230932;38487;39509.80245098771;39559.01102472436;39593.81218563441;42968;43367.566324336745;43448.348274775904;43555.56444435555;43651.78174109133;9071.773205669844;9756.414615123098;9979.251556133282;9981.151602113829;9986.251031172655;11990.700232494213;12302.077344199177;12311.876881231208;12315.753684926342;12319.5072197112;11874.821877671842;12021.759564808699;12037.45831437583;12046.759064818698;12049.192621106817;11957;11971.401429928555;12010.399480026052;12115.878841211652;12117.33354665493;9972.95013524935;11645.010174135208;11754.706132346675;11921.225120367148;11934.522619095225;36552.45171322432;41213.38179927303;41783.79108104471;41908.952276193035;41916.580834191576;3323.335332933423;3350.463925771889;3398;3431.1936694876686;3459.7924124552605;28973.56539651907;29349.706502935125;30165.849170754005;30874.382512349628;31257.374852502824;2792.176307989148;2817.421137141084;2827.5758636204528;2870.8421036842965;2889.9133026009044;456701;458400;459183.7040814808;461113;469258;36704;37697.984161267035;37815.05462363463;63667.68166159232;64230.67884660547;36651.633483665355;36851.89444316675;36908.63091369106;36978.815105924834;37030.62969370326;15934.28295726672;16086;16088.75866861998;16098.75851862223;16107;12668.303243321521;12754.68113297175;13417.396217170251;13480.662983425296;13738.313084345742;8430.873536896952;8473.279771219391;8474.74575762728;8477.279431248413;8573.699920502877;3221.291315910496;3238.3037646906027;3485.1809824691177;3489.668481494254;3512.297540491912;973.9026097390205;991.9931269761198;993.339429279521;1036.9429681367596;1094.3269889018197;30720.53919191214;31344.686553134634;31412;31464.685353146633;32174.678253217637;1127.560251501915;1146.954121835134;1148.0700632487712;1169.082270417718;1191.7854786138578;14717.779233311509;14834.703305933821;14873.181974991263;15156.621084472976;15212.619684507754;463222;466240;472377;474113;474177;57377.13934290989;60964.08553871696;61098.38901611016;61269;61293.69353153206;1424.8005279260913;1531.76257680062;1550.2636247782214;1572.3553343129263;1576.3064251729202;28916.132516024554;30158.69841301559;30195.396092077597;30406.84796576003;30905.536416953313;49012.03951842029;52741;53521.73239133779;53720;53851.19223211733;483157;485982;486519;487506;487537;24284.757152428956;24577.631335529622;27094;27598.172054838386;27877.721222787513;42447.787761060994;43253.13493730045;43351.78324108359;43366.56633433751;43859.561404385546;34505;35086.12284692903;36421.27157456889;36978.815105924295;36988.890333290045;35179.64820351764;35434.822825885705;35460.82269588635;36203.81898090493;36278;37514.68698595536;39322;39940.8002959995;40724.778256652346;40839.59160408358;30688.84655576797;32616.34767304688;32678;32844.34311313808;32938.71796666418;20841.583168336245;24325.878370608032;25067.87466062658;25136.74863251344;26135.346616334744 23:17:46 INFO - PROCESS | 1665 | |2;jquery.html;359.54697081677097;411.21458015190984;412.16330848377805;416.4315709057138;424.22790521251335;19260.90369548154;20001.499962500944;20639.38081857545;20654.48363790906;20965.47586310343;20453.97730113492;20726.27458038969;20976.26583069589;21317.68023479649;21368;5932.050871860503;6024.0963855421705;6203.91431499488;6205.875882482348;6242.906356404657;6122.540809439288;6159;6210.596311239755;6217.129601855744;6220.1913751212505;5974.820755377345;6106.114613381057;6172.043333283348;6180.567360284782;6206.627602343874;6059.272887253535;6111.449969502743;6113.969430152865;6137.41694539018;6161.322254552018;4551.385562948993;5293.947060529384;5367.0339338918975;5371.060064488738;5380.704061276629;25882.6117608236;26276.343091422677;26803.06189283385;27013.72986270132;27019.729802702113;2231.141010710872;2284.5088306014286;2601.427685909097;2686.5029969455713;2996.4007198560157;15275.541733748005;15505.224738763016;15559.066456012675;15564.299606517734;15717.978331408422;1789.4721057288132;1847.630473905211;1866.803985581508;1869;1910.0258867977293;84327.57836210841;106833;107036;109254.45372773086;110608.4469577647;11632.709182270512;11800.521890403112;11927.821082683766;11942.940285298519;11963.700907477209;12391.814122788166;15223.847761522244;15247;15266.847331526764;15312.770308445382;7371.8157046074275;9052.592633331516;9103.271738261055;9109.544522773835;9224.53877306132;7756.728514501965;9254;9276.443413395218;9327.673531426368;9364.672236471692;4727.456342520654;4855.368802055781;4863.221884498464;4864.73243971585;4892.877678058077;1582.3354191239707;1599.472174182517;1978.960420791605;2011.5272910865936;2026.0781344488223;939.0609390609391;958.3483231402713;964.1515466389673;1007.9236772249191;1063.7978784031009;16721.83278167227;20201;20452;20558.903022638973;21543.78456215449;957.760559860035;1004.2217281606668;1054.514923135351;1082.09644946469;1110.6723516562472;10998.34009959389;11230.94384528072;11287.604933827277;11319.717007074889;11362.829557556643;563306;644561;648229;659685;661866;108621;131575.34212328878;137223;138197;138888;937.2548823685187;940.8212439636447;1050.9369437833755;1053.4732633683159;1066.919981001428;15390.84609153894;18620.720689189402;18997.810021899604;19082.618347633248;19478.707819382438;46456;51160;55796.163057553356;56192.43807562035;56547.71726141343;61808.38191618026;66108.66945665241;80439.59780201061;81657;81929.18070819216;8039.5176289422825;8309.089371944649;8555.529445880537;8843.027267000753;9156.175944165057;8576.927884014498;10565.784934732605;13223.669408264486;13595.86404135946;13638.249896255802;11525.90503902128;11619.535218591162;11747.823782643096;11774.646760597196;11996.820047699466;9488.923425967074;11457.083433325148;11574.479148438171;11761.176717629683;11884.702882428008;8082.474639148437;10494.52774625159;11353.37556434371;11716.9454749073;11871.465784039567;8499.532525711145;8568.657253709782;8622.396432249683;8786.384953053224;8970.641174353214;11551.133664974992;11626.128040397174;11857.703557410789;11861.991730702986;12031.819522706992 23:17:46 INFO - PROCESS | 1665 | |3;mootools.html;1495.4018392642947;1597.888147829652;1608.437047033538;1883.237288897996;2011.115109351886;8468.068512463631;9136.086391360861;9299.721008369743;9306.813863722722;9325.766855828608;9029.187373136416;9286.39638423502;9298.209652179556;9305;9324.627014919395;2558.0535201975295;2580.2388295452793;2588.7022992355933;2590.0287392227915;2592.8055395845295;2495.6131799571003;2516.6476693262957;2523.1169090818157;2529.291798296471;2529.380301826046;2134.231676596424;2510.083819405922;2518.26970178648;2577.1495406515805;2580.238829545289;2539.860307683086;2561.039610146195;2570.5244529761962;2583.9612405813928;2589.7798687111617;2519.546481633305;2559.3089865736147;2562.3466016165758;2570.0876188953025;2578.7808036316746;9058.320625953014;9312.208462280714;9357.157855793012;9438.952805235998;9454.33819632626;4765.21373973294;5954.136650185742;5961.672108034057;6026.728797204137;6029.155918171459;3660.3228402745576;3709.7217708671687;3779.3386157422615;3780.9810950945343;3806.467094606757;1042.213129087534;1062.3201151263197;1065.1053115383115;1082.5561519776854;1097.05653138301;12655.050871184605;12679.23924564529;12723.872761272361;12731.745365092645;12740.617781466479;4790.353302304145;4839.451375559857;4848.272759086095;4924.8768780780765;4938.111139994764;9814.411135331902;9842.803143937224;9848.704538863845;9852.901470985342;9872;5708.257926469532;5710.057840456361;5711.314642242957;5716;5719.3422756382715;4656.4412270527755;4699.718016918996;4810.6632535722165;4861;5305.336832895888;4033.233685599727;4049.5950404959267;4133.731307465006;4163.12574359388;4164.12553363797;1085.3921803789904;1285.4279845468864;1287.2083668543778;1298.1302527306539;1299.616613099138;952.6284748947916;970.4080510888352;973.7030205787303;999.4053538144792;999.4802702594756;6151.507879369639;6175.320714721377;6213.2233470816145;6338.619682818953;6354.555181137276;481.6002717744264;488.88022434503773;495.94296655885034;504.10018117660536;505.14378129071605;4010.6590939770444;4079.3880917861966;4102.384642303678;4127.9587204128175;4192.517860446088;11580.768384632429;11831.290122592673;11991.700207494883;12007.099467539969;12009.219400738924;11468.36923969173;11648.94175529117;11781.587644432575;11805.940970295094;11819.231749936227;7629.771106866803;8979.057198994273;8980.191782739585;9004.684836030707;9082.545872706338;5847;6011.068284415923;6022.427869352405;6183.165272688309;6238.968805155945;10246.6413675521;10393.896061039293;10539.947300263757;10599.15206783471;10610.575576976835;10219.284650074424;10354.5340459678;10405.427701476492;10568.735781605521;10613;7175.641217939186;7800.609969501389;7907;7914.0503139623615;7931;8091;8122.187781221831;8140.959295203486;8155.796105097183;8673.306135509018;7736.071671399468;7859.017622797151;7877.291043806085;8018.238267364591;8019.117897031439;7703.922960770545;7705.922940770521;7726.729564465217;7822.843543129219;7842;7200.423966082805;7210.495265331377;7257.637118143966;7264.89102663471;7276.563406195433;5028.245763135559;5135.743212839268;5173.456787037307;5229.424763276113;5276.894462110813;4987.850364489072;5018.648694591489;5181.637285389987;5206.8958620828125;5272.367315922113 23:17:46 INFO - PROCESS | 1665 | |4;prototype.html;314.18312387791735;328.80107534941345;342.92455659754853;347.74267042388607;348.09321716927406;6545.345465453458;6652.368025037626;6658.101156343892;6695.397414232731;6719.294474080212;6388.904166437507;6418.967905160468;6584.275729669733;6590.308017658149;6644.202695676526;3974.6621537169376;4924.162892308316;4930.728809915453;4950.183219768734;4954.3063971044085;4884.853454396356;4918.114739346916;4956.083124621957;4958.603311735071;4971.328870602459;4747.738874361926;4758.191107511731;4870.537298956593;4892;4912.70523768575;4839.37088178538;4848.466668666444;4849.175640141185;4856.39295088114;4871.2206047032305;3368.6799754023327;4127.504699436057;4128.277551428488;4153.792310384469;4159.667226621893;6284.402981716729;6344.809655710336;6348.17473728417;6357.777477788301;6370.4266616004315;5724.513416359573;5726.942730572724;5732.942670573282;5733.397993210736;5741.770329186827;6794.626295553742;7033.261507541737;7033.613151276678;7039.049728286667;7101.467389945723;3887.844486220548;3963.4253033310297;4595.172868883599;4691;4706.152892479352;7400.481966262418;7402.481826272163;7424.665890034961;7431.665575049082;7440.404767618577;4726.220173671306;4731.597814185833;4748.216544270157;4749.786259618257;4749.857504274877;4811;4813.78337974792;4822.855314340575;4828.947697228664;4830.396200474941;5509.917351239735;5724.79963201286;5754.510866576305;5771.787924535811;5780;5775.971120144456;7107.253738357502;7138.571685698771;7142.4286057115305;7197.172325182566;1640.9507714768577;1676.740105283683;1677.270387381493;1680.0759582229725;1680.8235135310617;992.8907820139779;1003.4681618742078;1008.702432782331;1010.4695035106481;1020.3673722292226;6708.664566771642;6891.414229790424;6896.724131034803;6897.206821215625;6929.930700693029;6108.633481991094;7449.590272534954;7465.104187497535;7465.626718664045;7478.9252107479315;6852;6867.862642747117;6868.244493105754;6872.862542749117;6876.630451078935;3797.3164830330807;3895.844166233319;3942.7634341939574;3955.7033222508426;3956.9802150989062;7727.304542591194;7792.922070779333;7803.297703206738;7814.33578145864;7830;6152.876942461216;7622.504537205064;7641.885371719318;7657.731979380695;7660.770176894591;7738.916551682769;7765.417593680497;7767.53394796314;7783.805404864924;7798.33714134305;7065.964670176616;7416.480846340704;7437.628118593941;7456.32893039629;7471.738489152854;7253.202147763636;7312.96343518279;7314.597697126709;7343.1555371133;7350.411967042731;7748.7675369739;7757.340626046849;7764.340031097194;7776.805579860549;7800.531968081933;4728.503507131702;4744.264638980963;4753.334533165429;4761;4764.809407623657;5959;6514.925037368781;7129.607871566906;7402.444816638881;7420.628968551443;6778.694958726967;7123.358897699232;7169.211386747558;7185.353318201387;7189.82025449347;7132.536385134949;7143.928560714534;7150.463715221484;7150.856982860209;7195.928040719735;6976.372126508436;6998.6150761708595;7000.894986575104;7010.053642758162;7013.333733295329;6916.827079323057;7009;7146.106736657918;7195.172555156015;7209.855802884018;5560.443955604407;6848.828779280558;6939.618320992192;6957.930420695729;6987.056747339044 23:17:46 INFO - PROCESS | 1665 | |5;yui.html;697.3653974882858;707.5330282013872;736.1865139021381;743.0897150990036;815.1929589706191;15177.772333414994;15478;15500.14749188797;15500.53498395047;15519.379224831022;12858.549950751702;13066.608001759938;13071.150375225603;13085.47658093675;13134.21194728317;775.864223760841;788.9684412623488;957.6935380678151;981.0385821894548;984.5963155106408;980.0934135924277;984.6307634637011;985.571276494727;986.2504496582577;993.135971704618;823.1192623892438;1040.287403128859;1048.6172547020321;1049.9107575856062;1051.5898799468214;819.9385046121565;828.6271177969908;832.5504227717025;1007.9899201007969;1039.9168066554732;778.1440415542914;781.8788087846336;783.4046124945025;784.2353705137502;786.2137862137862;15169.544913652608;15174.317155728018;15252.0086194397;15409.152496612682;15425.53723388289;12862.421191046331;12927.48290068396;13100.410481528354;13169.934150329187;13214.405351759195;8342.040665323504;8599.699010534601;8615.664571991352;8634.568271586459;8779.429337093068;3591.44332628443;3605.3330133925306;3609.4405367168133;3634.7819130852236;3790.5261842269715;77794;78056;78509.60745196295;79705;79826.20173798304;9828.017198280115;9846.163076138608;9948.80102397948;10092.142167915663;10388.844167337496;21587.35237942833;21594.02826872763;21595.568088638138;21601.02795374212;21641.458963526038;7558.168601453835;7739.5743234122665;7794.41541884361;7856.33221176195;7950.0857401398425;4215.768132752667;4247.086876321606;4301.698881078294;4330.415393921843;4463.129689710475;3707.8146092695256;3767.830447629863;3768.9823747588;3770.8868733938025;3773.8301776420126;622.9190205273286;661.22966743743;680.7072958627838;686.605202008847;711.7081996381459;515.3249243491039;524.9343832020998;561;599.484443378694;624.7563450254404;8035.517868927766;8282.917170828336;8336.083030866599;8342.29090527312;8520.573668287749;509.8674344670412;529.9761510732027;560.5907687388229;565.5984251181707;591.8017464149472;3816.656500914931;3910.980445097813;3950.4271880577157;4003.619656132663;4077;88141.55929220314;89109;89265.55367223122;89364.553177235;89388;75906;77299;77812.2218777805;77915;81447;68100.3189968094;69533.2616684587;70405;71419.92870106953;71901.64049179721;7748.041253597589;7819.882701759592;7899.368050556057;7925.643346049557;7972.880406794019;47541.04917901692;47890.52109478861;48425.51574484351;50399.74800125976;50531.494685052676;63665;65227.369315767486;65850;66289.33710662831;67448.77412649979;9910.47282942845;9992.20062394992;10013.549390277309;10098.444585547866;10133.922545878224;10796.730081748019;11244.437778111225;11337.26307789991;11355.659330220107;11400.144989125683;10545.209109316986;10609.89390106089;10817.350958942488;10848;10854.240203185702;10301.690949271533;10304.381737095498;10470.947645261725;10496;10933.507992140214;10403.635872744419;10572.84140737905;10580.682579522627;10621.575136994434;11170.050545703707;8752.343574232085;8993.711939380892;9097.454152750855;9208.12522810332;9241.21449676785;8372.451096547158;8930.482614430986;9074.567409436077;9090.500022498825;9200.861987070333 23:17:46 INFO - PROCESS | 1665 | __end_tp_report 23:17:46 INFO - PROCESS | 1665 | __start_cc_report 23:17:46 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1092 23:17:46 INFO - PROCESS | 1665 | __end_cc_report 23:17:46 INFO - PROCESS | 1665 | __startTimestamp1468477066072__endTimestamp 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | ------- Summary: start ------- 23:17:46 INFO - PROCESS | 1665 | Number of tests: 6 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5921.98 Median:6165.38 stddev:623.12 (10.1%) stddev-sans-first:597.07 23:17:46 INFO - PROCESS | 1665 | Values: 3568.9 3641.5 3669.0 3670.0 3739.0 4935.3 5999.6 6001.3 6023.8 6029.3 6022.7 6059.4 6061.1 6065.0 6077.3 5312.1 5326.0 5398.2 5416.8 5436.9 5314.9 5332.6 5333.4 5343.0 5349.2 5345.1 5407.2 5451.6 5454.0 5474.0 5415.1 5469.1 5486.2 5488.3 5512.5 5240.3 5365.4 5365.7 5404.0 5424.6 5769.3 5837.0 5837.3 5866.5 5899.8 6271.7 6284.0 6290.1 6298.1 6307.1 6249.0 6276.3 6320.0 6327.6 6329.7 6034.7 6076.2 6100.9 6105.8 6128.4 6395.7 6429.5 6432.1 6435.3 6447.6 6175.4 6254.9 6330.4 6337.7 6339.9 6340.6 6346.6 6351.0 6353.8 6367.7 6255.5 6313.7 6322.4 6333.0 6348.2 6275.6 6291.7 6307.3 6342.1 6350.2 5005.3 5011.4 5038.6 5072.1 5121.5 4577.7 4591.8 4600.3 4606.7 4627.6 6110.3 6136.5 6138.7 6142.8 6148.7 6249.9 6320.4 6364.8 6366.1 6596.8 6081.6 6092.2 6101.4 6102.8 6119.8 5493.8 5547.2 5579.2 5590.8 5603.9 6434.7 6440.0 6507.5 6531.3 6543.6 6315.4 6333.6 6349.9 6354.8 6356.6 6384.3 6524.9 6548.4 6551.3 6551.4 5208.4 6241.1 6330.7 6344.5 6364.3 6240.8 6335.7 6343.1 6344.4 6346.5 6331.5 6334.2 6338.3 6349.0 6374.0 5610.5 6172.3 6213.2 6256.4 6341.0 6115.1 6148.7 6209.9 6222.8 6257.2 6289.9 6317.8 6327.9 6328.7 6347.6 6027.8 6134.5 6156.3 6165.4 6273.5 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:60840.38 Median:3487.42 stddev:125551.78 (3600.1%) stddev-sans-first:125822.34 23:17:46 INFO - PROCESS | 1665 | Values: 533.3 553.4 571.9 599.7 620.6 32137.5 38487.0 39509.8 39559.0 39593.8 42968.0 43367.6 43448.3 43555.6 43651.8 9071.8 9756.4 9979.3 9981.2 9986.3 11990.7 12302.1 12311.9 12315.8 12319.5 11874.8 12021.8 12037.5 12046.8 12049.2 11957.0 11971.4 12010.4 12115.9 12117.3 9973.0 11645.0 11754.7 11921.2 11934.5 36552.5 41213.4 41783.8 41909.0 41916.6 3323.3 3350.5 3398.0 3431.2 3459.8 28973.6 29349.7 30165.8 30874.4 31257.4 2792.2 2817.4 2827.6 2870.8 2889.9 456701.0 458400.0 459183.7 461113.0 469258.0 36704.0 37698.0 37815.1 63667.7 64230.7 36651.6 36851.9 36908.6 36978.8 37030.6 15934.3 16086.0 16088.8 16098.8 16107.0 12668.3 12754.7 13417.4 13480.7 13738.3 8430.9 8473.3 8474.7 8477.3 8573.7 3221.3 3238.3 3485.2 3489.7 3512.3 973.9 992.0 993.3 1036.9 1094.3 30720.5 31344.7 31412.0 31464.7 32174.7 1127.6 1147.0 1148.1 1169.1 1191.8 14717.8 14834.7 14873.2 15156.6 15212.6 463222.0 466240.0 472377.0 474113.0 474177.0 57377.1 60964.1 61098.4 61269.0 61293.7 1424.8 1531.8 1550.3 1572.4 1576.3 28916.1 30158.7 30195.4 30406.8 30905.5 49012.0 52741.0 53521.7 53720.0 53851.2 483157.0 485982.0 486519.0 487506.0 487537.0 24284.8 24577.6 27094.0 27598.2 27877.7 42447.8 43253.1 43351.8 43366.6 43859.6 34505.0 35086.1 36421.3 36978.8 36988.9 35179.6 35434.8 35460.8 36203.8 36278.0 37514.7 39322.0 39940.8 40724.8 40839.6 30688.8 32616.3 32678.0 32844.3 32938.7 20841.6 24325.9 25067.9 25136.7 26135.3 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:35937.46 Median:2257.82 stddev:105639.54 (4678.8%) stddev-sans-first:105902.11 23:17:46 INFO - PROCESS | 1665 | Values: 359.5 411.2 412.2 416.4 424.2 19260.9 20001.5 20639.4 20654.5 20965.5 20454.0 20726.3 20976.3 21317.7 21368.0 5932.1 6024.1 6203.9 6205.9 6242.9 6122.5 6159.0 6210.6 6217.1 6220.2 5974.8 6106.1 6172.0 6180.6 6206.6 6059.3 6111.4 6114.0 6137.4 6161.3 4551.4 5293.9 5367.0 5371.1 5380.7 25882.6 26276.3 26803.1 27013.7 27019.7 2231.1 2284.5 2601.4 2686.5 2996.4 15275.5 15505.2 15559.1 15564.3 15718.0 1789.5 1847.6 1866.8 1869.0 1910.0 84327.6 106833.0 107036.0 109254.5 110608.4 11632.7 11800.5 11927.8 11942.9 11963.7 12391.8 15223.8 15247.0 15266.8 15312.8 7371.8 9052.6 9103.3 9109.5 9224.5 7756.7 9254.0 9276.4 9327.7 9364.7 4727.5 4855.4 4863.2 4864.7 4892.9 1582.3 1599.5 1979.0 2011.5 2026.1 939.1 958.3 964.2 1007.9 1063.8 16721.8 20201.0 20452.0 20558.9 21543.8 957.8 1004.2 1054.5 1082.1 1110.7 10998.3 11230.9 11287.6 11319.7 11362.8 563306.0 644561.0 648229.0 659685.0 661866.0 108621.0 131575.3 137223.0 138197.0 138888.0 937.3 940.8 1050.9 1053.5 1066.9 15390.8 18620.7 18997.8 19082.6 19478.7 46456.0 51160.0 55796.2 56192.4 56547.7 61808.4 66108.7 80439.6 81657.0 81929.2 8039.5 8309.1 8555.5 8843.0 9156.2 8576.9 10565.8 13223.7 13595.9 13638.2 11525.9 11619.5 11747.8 11774.6 11996.8 9488.9 11457.1 11574.5 11761.2 11884.7 8082.5 10494.5 11353.4 11716.9 11871.5 8499.5 8568.7 8622.4 8786.4 8970.6 11551.1 11626.1 11857.7 11862.0 12031.8 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:6008.97 Median:4931.49 stddev:3424.66 (69.4%) stddev-sans-first:3417.47 23:17:46 INFO - PROCESS | 1665 | Values: 1495.4 1597.9 1608.4 1883.2 2011.1 8468.1 9136.1 9299.7 9306.8 9325.8 9029.2 9286.4 9298.2 9305.0 9324.6 2558.1 2580.2 2588.7 2590.0 2592.8 2495.6 2516.6 2523.1 2529.3 2529.4 2134.2 2510.1 2518.3 2577.1 2580.2 2539.9 2561.0 2570.5 2584.0 2589.8 2519.5 2559.3 2562.3 2570.1 2578.8 9058.3 9312.2 9357.2 9439.0 9454.3 4765.2 5954.1 5961.7 6026.7 6029.2 3660.3 3709.7 3779.3 3781.0 3806.5 1042.2 1062.3 1065.1 1082.6 1097.1 12655.1 12679.2 12723.9 12731.7 12740.6 4790.4 4839.5 4848.3 4924.9 4938.1 9814.4 9842.8 9848.7 9852.9 9872.0 5708.3 5710.1 5711.3 5716.0 5719.3 4656.4 4699.7 4810.7 4861.0 5305.3 4033.2 4049.6 4133.7 4163.1 4164.1 1085.4 1285.4 1287.2 1298.1 1299.6 952.6 970.4 973.7 999.4 999.5 6151.5 6175.3 6213.2 6338.6 6354.6 481.6 488.9 495.9 504.1 505.1 4010.7 4079.4 4102.4 4128.0 4192.5 11580.8 11831.3 11991.7 12007.1 12009.2 11468.4 11648.9 11781.6 11805.9 11819.2 7629.8 8979.1 8980.2 9004.7 9082.5 5847.0 6011.1 6022.4 6183.2 6239.0 10246.6 10393.9 10539.9 10599.2 10610.6 10219.3 10354.5 10405.4 10568.7 10613.0 7175.6 7800.6 7907.0 7914.1 7931.0 8091.0 8122.2 8141.0 8155.8 8673.3 7736.1 7859.0 7877.3 8018.2 8019.1 7703.9 7705.9 7726.7 7822.8 7842.0 7200.4 7210.5 7257.6 7264.9 7276.6 5028.2 5135.7 5173.5 5229.4 5276.9 4987.9 5018.6 5181.6 5206.9 5272.4 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5816.54 Median:6648.29 stddev:1876.88 (28.2%) stddev-sans-first:1836.15 23:17:46 INFO - PROCESS | 1665 | Values: 314.2 328.8 342.9 347.7 348.1 6545.3 6652.4 6658.1 6695.4 6719.3 6388.9 6419.0 6584.3 6590.3 6644.2 3974.7 4924.2 4930.7 4950.2 4954.3 4884.9 4918.1 4956.1 4958.6 4971.3 4747.7 4758.2 4870.5 4892.0 4912.7 4839.4 4848.5 4849.2 4856.4 4871.2 3368.7 4127.5 4128.3 4153.8 4159.7 6284.4 6344.8 6348.2 6357.8 6370.4 5724.5 5726.9 5732.9 5733.4 5741.8 6794.6 7033.3 7033.6 7039.0 7101.5 3887.8 3963.4 4595.2 4691.0 4706.2 7400.5 7402.5 7424.7 7431.7 7440.4 4726.2 4731.6 4748.2 4749.8 4749.9 4811.0 4813.8 4822.9 4828.9 4830.4 5509.9 5724.8 5754.5 5771.8 5780.0 5776.0 7107.3 7138.6 7142.4 7197.2 1641.0 1676.7 1677.3 1680.1 1680.8 992.9 1003.5 1008.7 1010.5 1020.4 6708.7 6891.4 6896.7 6897.2 6929.9 6108.6 7449.6 7465.1 7465.6 7478.9 6852.0 6867.9 6868.2 6872.9 6876.6 3797.3 3895.8 3942.8 3955.7 3957.0 7727.3 7792.9 7803.3 7814.3 7830.0 6152.9 7622.5 7641.9 7657.7 7660.8 7738.9 7765.4 7767.5 7783.8 7798.3 7066.0 7416.5 7437.6 7456.3 7471.7 7253.2 7313.0 7314.6 7343.2 7350.4 7748.8 7757.3 7764.3 7776.8 7800.5 4728.5 4744.3 4753.3 4761.0 4764.8 5959.0 6514.9 7129.6 7402.4 7420.6 6778.7 7123.4 7169.2 7185.4 7189.8 7132.5 7143.9 7150.5 7150.9 7195.9 6976.4 6998.6 7000.9 7010.1 7013.3 6916.8 7009.0 7146.1 7195.2 7209.9 5560.4 6848.8 6939.6 6957.9 6987.1 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17937.63 Median:623.84 stddev:25208.71 (4040.9%) stddev-sans-first:25246.19 23:17:46 INFO - PROCESS | 1665 | Values: 697.4 707.5 736.2 743.1 815.2 15177.8 15478.0 15500.1 15500.5 15519.4 12858.5 13066.6 13071.2 13085.5 13134.2 775.9 789.0 957.7 981.0 984.6 980.1 984.6 985.6 986.3 993.1 823.1 1040.3 1048.6 1049.9 1051.6 819.9 828.6 832.6 1008.0 1039.9 778.1 781.9 783.4 784.2 786.2 15169.5 15174.3 15252.0 15409.2 15425.5 12862.4 12927.5 13100.4 13169.9 13214.4 8342.0 8599.7 8615.7 8634.6 8779.4 3591.4 3605.3 3609.4 3634.8 3790.5 77794.0 78056.0 78509.6 79705.0 79826.2 9828.0 9846.2 9948.8 10092.1 10388.8 21587.4 21594.0 21595.6 21601.0 21641.5 7558.2 7739.6 7794.4 7856.3 7950.1 4215.8 4247.1 4301.7 4330.4 4463.1 3707.8 3767.8 3769.0 3770.9 3773.8 622.9 661.2 680.7 686.6 711.7 515.3 524.9 561.0 599.5 624.8 8035.5 8282.9 8336.1 8342.3 8520.6 509.9 530.0 560.6 565.6 591.8 3816.7 3911.0 3950.4 4003.6 4077.0 88141.6 89109.0 89265.6 89364.6 89388.0 75906.0 77299.0 77812.2 77915.0 81447.0 68100.3 69533.3 70405.0 71419.9 71901.6 7748.0 7819.9 7899.4 7925.6 7972.9 47541.0 47890.5 48425.5 50399.7 50531.5 63665.0 65227.4 65850.0 66289.3 67448.8 9910.5 9992.2 10013.5 10098.4 10133.9 10796.7 11244.4 11337.3 11355.7 11400.1 10545.2 10609.9 10817.4 10848.0 10854.2 10301.7 10304.4 10470.9 10496.0 10933.5 10403.6 10572.8 10580.7 10621.6 11170.1 8752.3 8993.7 9097.5 9208.1 9241.2 8372.5 8930.5 9074.6 9090.5 9200.9 23:17:46 INFO - PROCESS | 1665 | -------- Summary: end -------- 23:17:46 INFO - PROCESS | 1665 | 23:17:46 INFO - TEST-INFO | 1665: exit 0 23:17:46 INFO - TEST-OK | dromaeo_css | took 1113346ms 23:17:46 INFO - TEST-START | kraken 23:17:46 INFO - operating with platform_type : mac_ 23:17:46 INFO - Initialising browser for kraken test... 23:17:46 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmptEUz5C/profile http://localhost:49275/getInfo.html) 23:17:48 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 23:17:48 INFO - PROCESS | 1671 | colorDepth:24 23:17:48 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 23:17:48 INFO - PROCESS | 1671 | __metrics 23:17:48 INFO - PROCESS | 1671 | 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" 23:17:48 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:17:49 INFO - TEST-INFO | 1671: exit 0 23:17:49 INFO - Browser initialized. 23:17:49 INFO - Running cycle 1/1 for kraken test... 23:17:49 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmptEUz5C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:01 INFO - PROCESS | 1674 | RSS: Main: 160784384 23:18:01 INFO - PROCESS | 1674 | 23:18:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:04 INFO - PROCESS | 1674 | RSS: Main: 225562624 23:18:04 INFO - PROCESS | 1674 | 23:18:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 23:18:08 INFO - PROCESS | 1674 | RSS: Main: 241319936 23:18:08 INFO - PROCESS | 1674 | 23:18:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 23:18:11 INFO - PROCESS | 1674 | RSS: Main: 325419008 23:18:11 INFO - PROCESS | 1674 | 23:18:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 23:18:14 INFO - PROCESS | 1674 | RSS: Main: 231374848 23:18:14 INFO - PROCESS | 1674 | 23:18:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:18:17 INFO - PROCESS | 1674 | RSS: Main: 231530496 23:18:17 INFO - PROCESS | 1674 | 23:18:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 23:18:20 INFO - PROCESS | 1674 | RSS: Main: 271179776 23:18:20 INFO - PROCESS | 1674 | 23:18:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 23:18:24 INFO - PROCESS | 1674 | RSS: Main: 271200256 23:18:24 INFO - PROCESS | 1674 | 23:18:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 23:18:27 INFO - PROCESS | 1674 | RSS: Main: 269266944 23:18:27 INFO - PROCESS | 1674 | 23:18:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:18:29 INFO - PROCESS | 1674 | RSS: Main: 260296704 23:18:29 INFO - PROCESS | 1674 | 23:18:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:18:32 INFO - PROCESS | 1674 | RSS: Main: 267309056 23:18:32 INFO - PROCESS | 1674 | 23:18:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:18:34 INFO - PROCESS | 1674 | RSS: Main: 270790656 23:18:34 INFO - PROCESS | 1674 | 23:18:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:18:38 INFO - PROCESS | 1674 | RSS: Main: 277745664 23:18:38 INFO - PROCESS | 1674 | 23:18:40 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:18:41 INFO - PROCESS | 1674 | RSS: Main: 275021824 23:18:41 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 23:18:43 INFO - PROCESS | 1674 | RSS: Main: 335880192 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | __start_tp_report 23:18:43 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 23:18:43 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 23:18:43 INFO - PROCESS | 1674 | |i|pagename|runs| 23:18:43 INFO - PROCESS | 1674 | |0;ai-astar;112;122;180;154;112;111;114;113;113;133 23:18:43 INFO - PROCESS | 1674 | |1;audio-beat-detection;157;118;143;162;116;138;118;152;153;117 23:18:43 INFO - PROCESS | 1674 | |2;audio-dft;184;187;185;184;185;184;187;182;185;182 23:18:43 INFO - PROCESS | 1674 | |3;audio-fft;71;79;72;151;56;55;57;64;129;56 23:18:43 INFO - PROCESS | 1674 | |4;audio-oscillator;91;89;136;78;79;95;117;78;75;124 23:18:43 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;85;89;86;90;86;89;86;135;125 23:18:43 INFO - PROCESS | 1674 | |6;imaging-darkroom;93;94;95;94;93;93;94;103;149;125 23:18:43 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;87;90;88;89;89;90;91;87;88 23:18:43 INFO - PROCESS | 1674 | |8;json-parse-financial;59;62;65;62;61;62;62;80;70;82 23:18:43 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;61;65;65;71;74;76;68;71;66;65 23:18:43 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;80;78;79;79;79;76;79;78;79;82 23:18:43 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;125;121;124;118;125;124;123;123;150;143 23:18:43 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;161;164;166;162;160;163;160;163;163;160 23:18:43 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;65;68;66;68;65;65;66;73;81 23:18:43 INFO - PROCESS | 1674 | __end_tp_report 23:18:43 INFO - PROCESS | 1674 | __start_cc_report 23:18:43 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1894 23:18:43 INFO - PROCESS | 1674 | __end_cc_report 23:18:43 INFO - PROCESS | 1674 | __startTimestamp1468477123493__endTimestamp 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | ------- Summary: start ------- 23:18:43 INFO - PROCESS | 1674 | Number of tests: 14 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:126.40 Median:118.00 stddev:23.24 (19.7%) stddev-sans-first:24.06 23:18:43 INFO - PROCESS | 1674 | Values: 112.0 122.0 180.0 154.0 112.0 111.0 114.0 113.0 113.0 133.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:147.50 stddev:18.57 (12.6%) stddev-sans-first:18.29 23:18:43 INFO - PROCESS | 1674 | Values: 157.0 118.0 143.0 162.0 116.0 138.0 118.0 152.0 153.0 117.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:184.50 Median:185.00 stddev:1.72 (0.9%) stddev-sans-first:1.81 23:18:43 INFO - PROCESS | 1674 | Values: 184.0 187.0 185.0 184.0 185.0 184.0 187.0 182.0 185.0 182.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:79.00 Median:60.50 stddev:33.57 (55.5%) stddev-sans-first:35.48 23:18:43 INFO - PROCESS | 1674 | Values: 71.0 79.0 72.0 151.0 56.0 55.0 57.0 64.0 129.0 56.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:96.20 Median:78.50 stddev:21.80 (27.8%) stddev-sans-first:23.04 23:18:43 INFO - PROCESS | 1674 | Values: 91.0 89.0 136.0 78.0 79.0 95.0 117.0 78.0 75.0 124.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:96.00 Median:87.50 stddev:18.15 (20.7%) stddev-sans-first:19.08 23:18:43 INFO - PROCESS | 1674 | Values: 89.0 85.0 89.0 86.0 90.0 86.0 89.0 86.0 135.0 125.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:103.30 Median:93.50 stddev:18.87 (20.2%) stddev-sans-first:19.65 23:18:43 INFO - PROCESS | 1674 | Values: 93.0 94.0 95.0 94.0 93.0 93.0 94.0 103.0 149.0 125.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:88.50 Median:89.00 stddev:1.58 (1.8%) stddev-sans-first:1.39 23:18:43 INFO - PROCESS | 1674 | Values: 86.0 87.0 90.0 88.0 89.0 89.0 90.0 91.0 87.0 88.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:66.50 Median:63.50 stddev:8.20 (12.9%) stddev-sans-first:8.23 23:18:43 INFO - PROCESS | 1674 | Values: 59.0 62.0 65.0 62.0 61.0 62.0 62.0 80.0 70.0 82.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:68.20 Median:69.50 stddev:4.69 (6.7%) stddev-sans-first:4.18 23:18:43 INFO - PROCESS | 1674 | Values: 61.0 65.0 65.0 71.0 74.0 76.0 68.0 71.0 66.0 65.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:78.90 Median:79.00 stddev:1.52 (1.9%) stddev-sans-first:1.56 23:18:43 INFO - PROCESS | 1674 | Values: 80.0 78.0 79.0 79.0 79.0 76.0 79.0 78.0 79.0 82.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:127.60 Median:124.50 stddev:10.31 (8.3%) stddev-sans-first:10.89 23:18:43 INFO - PROCESS | 1674 | Values: 125.0 121.0 124.0 118.0 125.0 124.0 123.0 123.0 150.0 143.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:162.20 Median:163.00 stddev:1.99 (1.2%) stddev-sans-first:2.06 23:18:43 INFO - PROCESS | 1674 | Values: 161.0 164.0 166.0 162.0 160.0 163.0 160.0 163.0 163.0 160.0 23:18:43 INFO - PROCESS | 1674 | 23:18:43 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:67.00 stddev:5.08 (7.6%) stddev-sans-first:5.32 23:18:43 INFO - PROCESS | 1674 | Values: 66.0 65.0 68.0 66.0 68.0 65.0 65.0 66.0 73.0 81.0 23:18:43 INFO - PROCESS | 1674 | -------- Summary: end -------- 23:18:43 INFO - PROCESS | 1674 | 23:18:44 INFO - TEST-INFO | 1674: exit 0 23:18:44 INFO - TEST-OK | kraken | took 57716ms 23:18:44 INFO - SUITE-END | took 1171s 23:18:44 INFO - Completed test suite (00:19:31) 23:18:44 INFO - javascript benchmark 23:18:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3568.9286214275717, 3641.4719865619477, 3669.0, 3669.981650091749, 3739.0, 4935.284383764352, 5999.61002534836, 6001.309849367319, 6023.849403764908, 6029.306629737576, 6022.6687532185715, 6059.363766804478, 6061.060535616975, 6064.969675151629, 6077.270727512694, 5312.096943519611, 5326.0, 5398.217258497515, 5416.756245968941, 5436.864078398052, 5314.9468505314835, 5332.573394128461, 5333.386660534049, 5342.973285133569, 5349.170878513836, 5345.118055520835, 5407.215953686713, 5451.618386712932, 5453.972730136344, 5473.959947609961, 5415.1335786274, 5469.097598896177, 5486.231927530149, 5488.286522752017, 5512.503874651301, 5240.292560504321, 5365.382980957201, 5365.704886231256, 5403.972980135113, 5424.5660347172525, 5769.336526299488, 5836.97081514594, 5837.270341207349, 5866.472017518401, 5899.793507227269, 6271.655058971754, 6283.96858015707, 6290.056491526262, 6298.086777417248, 6307.117003619497, 6249.000159974427, 6276.309605943343, 6319.968400158015, 6327.588706734094, 6329.746810127589, 6034.728437220336, 6076.240469941257, 6100.908486372708, 6105.8168254952425, 6128.38716128388, 6395.680215989182, 6429.485641148697, 6432.06735023419, 6435.292117867031, 6447.580907241015, 6175.44421002112, 6254.874902502015, 6330.366963303725, 6337.746490140343, 6339.936600633934, 6340.556161068681, 6346.555741098079, 6351.015592583157, 6353.809385718436, 6367.7452901884335, 6255.46828519581, 6313.747450101944, 6322.36776322364, 6332.968335158387, 6348.238211414568, 6275.560710750204, 6291.653959032205, 6307.306196318401, 6342.080398342215, 6350.206224221973, 5005.34930459038, 5011.423686276052, 5038.571721403649, 5072.087024335581, 5121.487851214849, 4577.679562430664, 4591.816327346935, 4600.263957766741, 4606.654500912445, 4627.6066534344955, 6110.29731580865, 6136.539759518054, 6138.662373569497, 6142.846428839315, 6148.661823599656, 6249.9062514062325, 6320.431161195423, 6364.809055728336, 6366.076918846741, 6596.8350791230605, 6081.635101893812, 6092.208012958376, 6101.359357267512, 6102.7558897644485, 6119.847003824851, 5493.752781124859, 5547.1679248113105, 5579.246801681721, 5590.804321848797, 5603.887922241533, 6434.7104380303, 6439.967800161063, 6507.511936604868, 6531.314212007861, 6543.574667646588, 6315.368463153648, 6333.619982801047, 6349.8730025400155, 6354.841128971812, 6356.55504114726, 6384.297727250092, 6524.902126468011, 6548.410643041959, 6551.279359270572, 6551.44312733423, 5208.401033881152, 6241.095041219089, 6330.746770129143, 6344.460720838781, 6364.268109167505, 6240.750369985331, 6335.746570137328, 6343.111964324906, 6344.365563443619, 6346.524010699124, 6331.525135614939, 6334.208223972004, 6338.271098823694, 6349.0, 6374.0, 5610.5231055360755, 6172.290186628596, 6213.2233470816145, 6256.436920677161, 6340.968295158495, 6115.1438798569125, 6148.692565371803, 6209.937900620936, 6222.751089956351, 6257.155284036597, 6289.874202516015, 6317.778877739257, 6327.936720632735, 6328.6519241442165, 6347.555671103163, 6027.78902738402, 6134.4978165938865, 6156.26124865019, 6165.383461653799, 6273.52948528853], "value": 5885.192808789377, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [533.3466503533173, 553.4078535966518, 571.9113537401702, 599.6701814002298, 620.6276234259445, 32137.517937230932, 38487.0, 39509.80245098771, 39559.01102472436, 39593.81218563441, 42968.0, 43367.566324336745, 43448.348274775904, 43555.56444435555, 43651.78174109133, 9071.773205669844, 9756.414615123098, 9979.251556133282, 9981.151602113829, 9986.251031172655, 11990.700232494213, 12302.077344199177, 12311.876881231208, 12315.753684926342, 12319.5072197112, 11874.821877671842, 12021.759564808699, 12037.45831437583, 12046.759064818698, 12049.192621106817, 11957.0, 11971.401429928555, 12010.399480026052, 12115.878841211652, 12117.33354665493, 9972.95013524935, 11645.010174135208, 11754.706132346675, 11921.225120367148, 11934.522619095225, 36552.45171322432, 41213.38179927303, 41783.79108104471, 41908.952276193035, 41916.580834191576, 3323.335332933423, 3350.463925771889, 3398.0, 3431.1936694876686, 3459.7924124552605, 28973.56539651907, 29349.706502935125, 30165.849170754005, 30874.382512349628, 31257.374852502824, 2792.176307989148, 2817.421137141084, 2827.5758636204528, 2870.8421036842965, 2889.9133026009044, 456701.0, 458400.0, 459183.7040814808, 461113.0, 469258.0, 36704.0, 37697.984161267035, 37815.05462363463, 63667.68166159232, 64230.67884660547, 36651.633483665355, 36851.89444316675, 36908.63091369106, 36978.815105924834, 37030.62969370326, 15934.28295726672, 16086.0, 16088.75866861998, 16098.75851862223, 16107.0, 12668.303243321521, 12754.68113297175, 13417.396217170251, 13480.662983425296, 13738.313084345742, 8430.873536896952, 8473.279771219391, 8474.74575762728, 8477.279431248413, 8573.699920502877, 3221.291315910496, 3238.3037646906027, 3485.1809824691177, 3489.668481494254, 3512.297540491912, 973.9026097390205, 991.9931269761198, 993.339429279521, 1036.9429681367596, 1094.3269889018197, 30720.53919191214, 31344.686553134634, 31412.0, 31464.685353146633, 32174.678253217637, 1127.560251501915, 1146.954121835134, 1148.0700632487712, 1169.082270417718, 1191.7854786138578, 14717.779233311509, 14834.703305933821, 14873.181974991263, 15156.621084472976, 15212.619684507754, 463222.0, 466240.0, 472377.0, 474113.0, 474177.0, 57377.13934290989, 60964.08553871696, 61098.38901611016, 61269.0, 61293.69353153206, 1424.8005279260913, 1531.76257680062, 1550.2636247782214, 1572.3553343129263, 1576.3064251729202, 28916.132516024554, 30158.69841301559, 30195.396092077597, 30406.84796576003, 30905.536416953313, 49012.03951842029, 52741.0, 53521.73239133779, 53720.0, 53851.19223211733, 483157.0, 485982.0, 486519.0, 487506.0, 487537.0, 24284.757152428956, 24577.631335529622, 27094.0, 27598.172054838386, 27877.721222787513, 42447.787761060994, 43253.13493730045, 43351.78324108359, 43366.56633433751, 43859.561404385546, 34505.0, 35086.12284692903, 36421.27157456889, 36978.815105924295, 36988.890333290045, 35179.64820351764, 35434.822825885705, 35460.82269588635, 36203.81898090493, 36278.0, 37514.68698595536, 39322.0, 39940.8002959995, 40724.778256652346, 40839.59160408358, 30688.84655576797, 32616.34767304688, 32678.0, 32844.34311313808, 32938.71796666418, 20841.583168336245, 24325.878370608032, 25067.87466062658, 25136.74863251344, 26135.346616334744], "value": 18920.557694642146, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.54697081677097, 411.21458015190984, 412.16330848377805, 416.4315709057138, 424.22790521251335, 19260.90369548154, 20001.499962500944, 20639.38081857545, 20654.48363790906, 20965.47586310343, 20453.97730113492, 20726.27458038969, 20976.26583069589, 21317.68023479649, 21368.0, 5932.050871860503, 6024.0963855421705, 6203.91431499488, 6205.875882482348, 6242.906356404657, 6122.540809439288, 6159.0, 6210.596311239755, 6217.129601855744, 6220.1913751212505, 5974.820755377345, 6106.114613381057, 6172.043333283348, 6180.567360284782, 6206.627602343874, 6059.272887253535, 6111.449969502743, 6113.969430152865, 6137.41694539018, 6161.322254552018, 4551.385562948993, 5293.947060529384, 5367.0339338918975, 5371.060064488738, 5380.704061276629, 25882.6117608236, 26276.343091422677, 26803.06189283385, 27013.72986270132, 27019.729802702113, 2231.141010710872, 2284.5088306014286, 2601.427685909097, 2686.5029969455713, 2996.4007198560157, 15275.541733748005, 15505.224738763016, 15559.066456012675, 15564.299606517734, 15717.978331408422, 1789.4721057288132, 1847.630473905211, 1866.803985581508, 1869.0, 1910.0258867977293, 84327.57836210841, 106833.0, 107036.0, 109254.45372773086, 110608.4469577647, 11632.709182270512, 11800.521890403112, 11927.821082683766, 11942.940285298519, 11963.700907477209, 12391.814122788166, 15223.847761522244, 15247.0, 15266.847331526764, 15312.770308445382, 7371.8157046074275, 9052.592633331516, 9103.271738261055, 9109.544522773835, 9224.53877306132, 7756.728514501965, 9254.0, 9276.443413395218, 9327.673531426368, 9364.672236471692, 4727.456342520654, 4855.368802055781, 4863.221884498464, 4864.73243971585, 4892.877678058077, 1582.3354191239707, 1599.472174182517, 1978.960420791605, 2011.5272910865936, 2026.0781344488223, 939.0609390609391, 958.3483231402713, 964.1515466389673, 1007.9236772249191, 1063.7978784031009, 16721.83278167227, 20201.0, 20452.0, 20558.903022638973, 21543.78456215449, 957.760559860035, 1004.2217281606668, 1054.514923135351, 1082.09644946469, 1110.6723516562472, 10998.34009959389, 11230.94384528072, 11287.604933827277, 11319.717007074889, 11362.829557556643, 563306.0, 644561.0, 648229.0, 659685.0, 661866.0, 108621.0, 131575.34212328878, 137223.0, 138197.0, 138888.0, 937.2548823685187, 940.8212439636447, 1050.9369437833755, 1053.4732633683159, 1066.919981001428, 15390.84609153894, 18620.720689189402, 18997.810021899604, 19082.618347633248, 19478.707819382438, 46456.0, 51160.0, 55796.163057553356, 56192.43807562035, 56547.71726141343, 61808.38191618026, 66108.66945665241, 80439.59780201061, 81657.0, 81929.18070819216, 8039.5176289422825, 8309.089371944649, 8555.529445880537, 8843.027267000753, 9156.175944165057, 8576.927884014498, 10565.784934732605, 13223.669408264486, 13595.86404135946, 13638.249896255802, 11525.90503902128, 11619.535218591162, 11747.823782643096, 11774.646760597196, 11996.820047699466, 9488.923425967074, 11457.083433325148, 11574.479148438171, 11761.176717629683, 11884.702882428008, 8082.474639148437, 10494.52774625159, 11353.37556434371, 11716.9454749073, 11871.465784039567, 8499.532525711145, 8568.657253709782, 8622.396432249683, 8786.384953053224, 8970.641174353214, 11551.133664974992, 11626.128040397174, 11857.703557410789, 11861.991730702986, 12031.819522706992], "value": 9884.34512574564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.4018392642947, 1597.888147829652, 1608.437047033538, 1883.237288897996, 2011.115109351886, 8468.068512463631, 9136.086391360861, 9299.721008369743, 9306.813863722722, 9325.766855828608, 9029.187373136416, 9286.39638423502, 9298.209652179556, 9305.0, 9324.627014919395, 2558.0535201975295, 2580.2388295452793, 2588.7022992355933, 2590.0287392227915, 2592.8055395845295, 2495.6131799571003, 2516.6476693262957, 2523.1169090818157, 2529.291798296471, 2529.380301826046, 2134.231676596424, 2510.083819405922, 2518.26970178648, 2577.1495406515805, 2580.238829545289, 2539.860307683086, 2561.039610146195, 2570.5244529761962, 2583.9612405813928, 2589.7798687111617, 2519.546481633305, 2559.3089865736147, 2562.3466016165758, 2570.0876188953025, 2578.7808036316746, 9058.320625953014, 9312.208462280714, 9357.157855793012, 9438.952805235998, 9454.33819632626, 4765.21373973294, 5954.136650185742, 5961.672108034057, 6026.728797204137, 6029.155918171459, 3660.3228402745576, 3709.7217708671687, 3779.3386157422615, 3780.9810950945343, 3806.467094606757, 1042.213129087534, 1062.3201151263197, 1065.1053115383115, 1082.5561519776854, 1097.05653138301, 12655.050871184605, 12679.23924564529, 12723.872761272361, 12731.745365092645, 12740.617781466479, 4790.353302304145, 4839.451375559857, 4848.272759086095, 4924.8768780780765, 4938.111139994764, 9814.411135331902, 9842.803143937224, 9848.704538863845, 9852.901470985342, 9872.0, 5708.257926469532, 5710.057840456361, 5711.314642242957, 5716.0, 5719.3422756382715, 4656.4412270527755, 4699.718016918996, 4810.6632535722165, 4861.0, 5305.336832895888, 4033.233685599727, 4049.5950404959267, 4133.731307465006, 4163.12574359388, 4164.12553363797, 1085.3921803789904, 1285.4279845468864, 1287.2083668543778, 1298.1302527306539, 1299.616613099138, 952.6284748947916, 970.4080510888352, 973.7030205787303, 999.4053538144792, 999.4802702594756, 6151.507879369639, 6175.320714721377, 6213.2233470816145, 6338.619682818953, 6354.555181137276, 481.6002717744264, 488.88022434503773, 495.94296655885034, 504.10018117660536, 505.14378129071605, 4010.6590939770444, 4079.3880917861966, 4102.384642303678, 4127.9587204128175, 4192.517860446088, 11580.768384632429, 11831.290122592673, 11991.700207494883, 12007.099467539969, 12009.219400738924, 11468.36923969173, 11648.94175529117, 11781.587644432575, 11805.940970295094, 11819.231749936227, 7629.771106866803, 8979.057198994273, 8980.191782739585, 9004.684836030707, 9082.545872706338, 5847.0, 6011.068284415923, 6022.427869352405, 6183.165272688309, 6238.968805155945, 10246.6413675521, 10393.896061039293, 10539.947300263757, 10599.15206783471, 10610.575576976835, 10219.284650074424, 10354.5340459678, 10405.427701476492, 10568.735781605521, 10613.0, 7175.641217939186, 7800.609969501389, 7907.0, 7914.0503139623615, 7931.0, 8091.0, 8122.187781221831, 8140.959295203486, 8155.796105097183, 8673.306135509018, 7736.071671399468, 7859.017622797151, 7877.291043806085, 8018.238267364591, 8019.117897031439, 7703.922960770545, 7705.922940770521, 7726.729564465217, 7822.843543129219, 7842.0, 7200.423966082805, 7210.495265331377, 7257.637118143966, 7264.89102663471, 7276.563406195433, 5028.245763135559, 5135.743212839268, 5173.456787037307, 5229.424763276113, 5276.894462110813, 4987.850364489072, 5018.648694591489, 5181.637285389987, 5206.8958620828125, 5272.367315922113], "value": 4741.455060487722, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.18312387791735, 328.80107534941345, 342.92455659754853, 347.74267042388607, 348.09321716927406, 6545.345465453458, 6652.368025037626, 6658.101156343892, 6695.397414232731, 6719.294474080212, 6388.904166437507, 6418.967905160468, 6584.275729669733, 6590.308017658149, 6644.202695676526, 3974.6621537169376, 4924.162892308316, 4930.728809915453, 4950.183219768734, 4954.3063971044085, 4884.853454396356, 4918.114739346916, 4956.083124621957, 4958.603311735071, 4971.328870602459, 4747.738874361926, 4758.191107511731, 4870.537298956593, 4892.0, 4912.70523768575, 4839.37088178538, 4848.466668666444, 4849.175640141185, 4856.39295088114, 4871.2206047032305, 3368.6799754023327, 4127.504699436057, 4128.277551428488, 4153.792310384469, 4159.667226621893, 6284.402981716729, 6344.809655710336, 6348.17473728417, 6357.777477788301, 6370.4266616004315, 5724.513416359573, 5726.942730572724, 5732.942670573282, 5733.397993210736, 5741.770329186827, 6794.626295553742, 7033.261507541737, 7033.613151276678, 7039.049728286667, 7101.467389945723, 3887.844486220548, 3963.4253033310297, 4595.172868883599, 4691.0, 4706.152892479352, 7400.481966262418, 7402.481826272163, 7424.665890034961, 7431.665575049082, 7440.404767618577, 4726.220173671306, 4731.597814185833, 4748.216544270157, 4749.786259618257, 4749.857504274877, 4811.0, 4813.78337974792, 4822.855314340575, 4828.947697228664, 4830.396200474941, 5509.917351239735, 5724.79963201286, 5754.510866576305, 5771.787924535811, 5780.0, 5775.971120144456, 7107.253738357502, 7138.571685698771, 7142.4286057115305, 7197.172325182566, 1640.9507714768577, 1676.740105283683, 1677.270387381493, 1680.0759582229725, 1680.8235135310617, 992.8907820139779, 1003.4681618742078, 1008.702432782331, 1010.4695035106481, 1020.3673722292226, 6708.664566771642, 6891.414229790424, 6896.724131034803, 6897.206821215625, 6929.930700693029, 6108.633481991094, 7449.590272534954, 7465.104187497535, 7465.626718664045, 7478.9252107479315, 6852.0, 6867.862642747117, 6868.244493105754, 6872.862542749117, 6876.630451078935, 3797.3164830330807, 3895.844166233319, 3942.7634341939574, 3955.7033222508426, 3956.9802150989062, 7727.304542591194, 7792.922070779333, 7803.297703206738, 7814.33578145864, 7830.0, 6152.876942461216, 7622.504537205064, 7641.885371719318, 7657.731979380695, 7660.770176894591, 7738.916551682769, 7765.417593680497, 7767.53394796314, 7783.805404864924, 7798.33714134305, 7065.964670176616, 7416.480846340704, 7437.628118593941, 7456.32893039629, 7471.738489152854, 7253.202147763636, 7312.96343518279, 7314.597697126709, 7343.1555371133, 7350.411967042731, 7748.7675369739, 7757.340626046849, 7764.340031097194, 7776.805579860549, 7800.531968081933, 4728.503507131702, 4744.264638980963, 4753.334533165429, 4761.0, 4764.809407623657, 5959.0, 6514.925037368781, 7129.607871566906, 7402.444816638881, 7420.628968551443, 6778.694958726967, 7123.358897699232, 7169.211386747558, 7185.353318201387, 7189.82025449347, 7132.536385134949, 7143.928560714534, 7150.463715221484, 7150.856982860209, 7195.928040719735, 6976.372126508436, 6998.6150761708595, 7000.894986575104, 7010.053642758162, 7013.333733295329, 6916.827079323057, 7009.0, 7146.106736657918, 7195.172555156015, 7209.855802884018, 5560.443955604407, 6848.828779280558, 6939.618320992192, 6957.930420695729, 6987.056747339044], "value": 5186.115651434783, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.3653974882858, 707.5330282013872, 736.1865139021381, 743.0897150990036, 815.1929589706191, 15177.772333414994, 15478.0, 15500.14749188797, 15500.53498395047, 15519.379224831022, 12858.549950751702, 13066.608001759938, 13071.150375225603, 13085.47658093675, 13134.21194728317, 775.864223760841, 788.9684412623488, 957.6935380678151, 981.0385821894548, 984.5963155106408, 980.0934135924277, 984.6307634637011, 985.571276494727, 986.2504496582577, 993.135971704618, 823.1192623892438, 1040.287403128859, 1048.6172547020321, 1049.9107575856062, 1051.5898799468214, 819.9385046121565, 828.6271177969908, 832.5504227717025, 1007.9899201007969, 1039.9168066554732, 778.1440415542914, 781.8788087846336, 783.4046124945025, 784.2353705137502, 786.2137862137862, 15169.544913652608, 15174.317155728018, 15252.0086194397, 15409.152496612682, 15425.53723388289, 12862.421191046331, 12927.48290068396, 13100.410481528354, 13169.934150329187, 13214.405351759195, 8342.040665323504, 8599.699010534601, 8615.664571991352, 8634.568271586459, 8779.429337093068, 3591.44332628443, 3605.3330133925306, 3609.4405367168133, 3634.7819130852236, 3790.5261842269715, 77794.0, 78056.0, 78509.60745196295, 79705.0, 79826.20173798304, 9828.017198280115, 9846.163076138608, 9948.80102397948, 10092.142167915663, 10388.844167337496, 21587.35237942833, 21594.02826872763, 21595.568088638138, 21601.02795374212, 21641.458963526038, 7558.168601453835, 7739.5743234122665, 7794.41541884361, 7856.33221176195, 7950.0857401398425, 4215.768132752667, 4247.086876321606, 4301.698881078294, 4330.415393921843, 4463.129689710475, 3707.8146092695256, 3767.830447629863, 3768.9823747588, 3770.8868733938025, 3773.8301776420126, 622.9190205273286, 661.22966743743, 680.7072958627838, 686.605202008847, 711.7081996381459, 515.3249243491039, 524.9343832020998, 561.0, 599.484443378694, 624.7563450254404, 8035.517868927766, 8282.917170828336, 8336.083030866599, 8342.29090527312, 8520.573668287749, 509.8674344670412, 529.9761510732027, 560.5907687388229, 565.5984251181707, 591.8017464149472, 3816.656500914931, 3910.980445097813, 3950.4271880577157, 4003.619656132663, 4077.0, 88141.55929220314, 89109.0, 89265.55367223122, 89364.553177235, 89388.0, 75906.0, 77299.0, 77812.2218777805, 77915.0, 81447.0, 68100.3189968094, 69533.2616684587, 70405.0, 71419.92870106953, 71901.64049179721, 7748.041253597589, 7819.882701759592, 7899.368050556057, 7925.643346049557, 7972.880406794019, 47541.04917901692, 47890.52109478861, 48425.51574484351, 50399.74800125976, 50531.494685052676, 63665.0, 65227.369315767486, 65850.0, 66289.33710662831, 67448.77412649979, 9910.47282942845, 9992.20062394992, 10013.549390277309, 10098.444585547866, 10133.922545878224, 10796.730081748019, 11244.437778111225, 11337.26307789991, 11355.659330220107, 11400.144989125683, 10545.209109316986, 10609.89390106089, 10817.350958942488, 10848.0, 10854.240203185702, 10301.690949271533, 10304.381737095498, 10470.947645261725, 10496.0, 10933.507992140214, 10403.635872744419, 10572.84140737905, 10580.682579522627, 10621.575136994434, 11170.050545703707, 8752.343574232085, 8993.711939380892, 9097.454152750855, 9208.12522810332, 9241.21449676785, 8372.451096547158, 8930.482614430986, 9074.567409436077, 9090.500022498825, 9200.861987070333], "value": 6845.3709082975665, "unit": "score"}], "name": "dromaeo_css", "value": 7550.422986544343, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 122.0, 180.0, 154.0, 112.0, 111.0, 114.0, 113.0, 113.0, 133.0], "value": 126.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 118.0, 143.0, 162.0, 116.0, 138.0, 118.0, 152.0, 153.0, 117.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 187.0, 185.0, 184.0, 185.0, 184.0, 187.0, 182.0, 185.0, 182.0], "value": 184.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 79.0, 72.0, 151.0, 56.0, 55.0, 57.0, 64.0, 129.0, 56.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 136.0, 78.0, 79.0, 95.0, 117.0, 78.0, 75.0, 124.0], "value": 96.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 90.0, 86.0, 89.0, 86.0, 135.0, 125.0], "value": 96.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 94.0, 93.0, 93.0, 94.0, 103.0, 149.0, 125.0], "value": 103.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 90.0, 88.0, 89.0, 89.0, 90.0, 91.0, 87.0, 88.0], "value": 88.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 65.0, 62.0, 61.0, 62.0, 62.0, 80.0, 70.0, 82.0], "value": 66.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 65.0, 71.0, 74.0, 76.0, 68.0, 71.0, 66.0, 65.0], "value": 68.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 79.0, 79.0, 76.0, 79.0, 78.0, 79.0, 82.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 121.0, 124.0, 118.0, 125.0, 124.0, 123.0, 123.0, 150.0, 143.0], "value": 127.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 166.0, 162.0, 160.0, 163.0, 160.0, 163.0, 163.0, 160.0], "value": 162.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 68.0, 66.0, 68.0, 65.0, 65.0, 66.0, 73.0, 81.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1483.0, "alertThreshold": 2.0}]} 23:18:44 INFO - Return code: 0 23:18:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:18:44 ERROR - # TBPL SUCCESS # 23:18:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:18:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:18:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:18:44 INFO - 2016-07-13 23:18:44.773 screenresolution[1678:10512] starting screenresolution argv=screenresolution get 23:18:44 INFO - 2016-07-13 23:18:44.775 screenresolution[1678:10512] Display 0: 1600x1200x32@60 23:18:44 INFO - 2016-07-13 23:18:44.781 screenresolution[1679:10515] starting screenresolution argv=screenresolution list 23:18:44 INFO - Available Modes on Display 0 23:18:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:18:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:18:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:18:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:18:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:18:45 INFO - Intel Iris: 23:18:45 INFO - Chipset Model: Intel Iris 23:18:45 INFO - Type: GPU 23:18:45 INFO - Bus: Built-In 23:18:45 INFO - VRAM (Dynamic, Max): 1536 MB 23:18:45 INFO - Vendor: Intel (0x8086) 23:18:45 INFO - Device ID: 0x0a2e 23:18:45 INFO - Revision ID: 0x0009 23:18:45 INFO - Displays: 23:18:45 INFO - Display: 23:18:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:18:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:18:45 INFO - Display Serial Number: 1600x1200 60 23:18:45 INFO - Main Display: Yes 23:18:45 INFO - Mirror: Off 23:18:45 INFO - Online: Yes 23:18:45 INFO - Rotation: Supported 23:18:45 INFO - Return code: 0 23:18:45 INFO - Running post-action listener: _resource_record_post_action 23:18:45 INFO - Running post-run listener: _resource_record_post_run 23:18:46 INFO - Total resource usage - Wall time: 1195s; CPU: 52.0%; Read bytes: 31690240; Write bytes: 495395840; Read time: 200; Write time: 1559 23:18:46 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 194791936; Write bytes: 190596608; Read time: 17570; Write time: 433 23:18:46 INFO - run-tests - Wall time: 1173s; CPU: 52.0%; Read bytes: 29908480; Write bytes: 298200576; Read time: 185; Write time: 1100 23:18:46 INFO - Running post-run listener: _upload_blobber_files 23:18:46 INFO - Blob upload gear active. 23:18:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:18:46 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 23:18:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:18:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:18:46 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:18:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:18:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:47 INFO - (blobuploader) - INFO - Done attempting. 23:18:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:18:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:18:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:48 INFO - (blobuploader) - INFO - Done attempting. 23:18:48 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:48 INFO - Return code: 0 23:18:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:18:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:48 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28d0817f8bfdd96b98d84e2803196ef2d4647fe5f8d2672c0f175dd8df44b339125d7dc2119e723275fdfd204d02fcf54ed6350a6ec7cad2cc8947ca47b65555", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aafb8a57a4d1554151e8a7df20d79bf4eac1ab6b19134b31ceb9bb273ee3c11870317f385d8835058db4913d3169975751d9db4afba159273cfd68e6193489a6"} 23:18:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:18:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:18:48 INFO - Contents: 23:18:48 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28d0817f8bfdd96b98d84e2803196ef2d4647fe5f8d2672c0f175dd8df44b339125d7dc2119e723275fdfd204d02fcf54ed6350a6ec7cad2cc8947ca47b65555", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aafb8a57a4d1554151e8a7df20d79bf4eac1ab6b19134b31ceb9bb273ee3c11870317f385d8835058db4913d3169975751d9db4afba159273cfd68e6193489a6"} 23:18:48 INFO - Running post-run listener: copy_logs_to_upload_dir 23:18:48 INFO - Copying logs to upload dir... 23:18:48 INFO - mkdir: /builds/slave/test/build/upload/logs 23:18:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1349.093484 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-07-13 23:18:48.172577) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 23:18:48.176458) ========= 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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28d0817f8bfdd96b98d84e2803196ef2d4647fe5f8d2672c0f175dd8df44b339125d7dc2119e723275fdfd204d02fcf54ed6350a6ec7cad2cc8947ca47b65555", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aafb8a57a4d1554151e8a7df20d79bf4eac1ab6b19134b31ceb9bb273ee3c11870317f385d8835058db4913d3169975751d9db4afba159273cfd68e6193489a6"} build_url:https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010491 build_url: 'https://queue.taskcluster.net/v1/task/V_8pXVBJQB6mWuAnjEt6SA/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/28d0817f8bfdd96b98d84e2803196ef2d4647fe5f8d2672c0f175dd8df44b339125d7dc2119e723275fdfd204d02fcf54ed6350a6ec7cad2cc8947ca47b65555", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/aafb8a57a4d1554151e8a7df20d79bf4eac1ab6b19134b31ceb9bb273ee3c11870317f385d8835058db4913d3169975751d9db4afba159273cfd68e6193489a6"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-13 23:18:48.216019) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 23:18:48.216565) ========= 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.aIyMQYE6JP/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.AIwaa8z7kU/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.005173 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-13 23:18:48.266705) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 23:18:48.267036) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-13 23:18:48.267382) ========= ========= Total master_lag: 0.21 =========