builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0390 starttime: 1464106513.26 results: success (0) buildid: 20160524071211 builduid: 13f6ce33e14c4948a727ad7859e38d94 revision: bcac6066d9267ca2ea7b281cfcf2223450435a5e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.258804) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.259266) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.259549) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.280966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.281351) ========= 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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-05-24 09:15:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 38.1M=0s 2016-05-24 09:15:12 (38.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.092176 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.392547) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.392859) ========= 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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.025228 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.441672) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:13.442032) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bcac6066d9267ca2ea7b281cfcf2223450435a5e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bcac6066d9267ca2ea7b281cfcf2223450435a5e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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-05-24 09:15:12,960 truncating revision to first 12 chars 2016-05-24 09:15:12,960 Setting DEBUG logging. 2016-05-24 09:15:12,960 attempt 1/10 2016-05-24 09:15:12,960 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bcac6066d926?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-24 09:15:13,603 unpacking tar archive at: mozilla-aurora-bcac6066d926/testing/mozharness/ program finished with exit code 0 elapsedTime=0.899690 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:14.359704) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:14.360395) ========= script_repo_revision: bcac6066d9267ca2ea7b281cfcf2223450435a5e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:14.360767) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:14.361048) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-24 09:15:14.377567) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-24 09:15:14.377953) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:13 INFO - MultiFileLogger online at 20160524 09:15:13 in /builds/slave/test 09:15:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:15:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:15:13 INFO - {'append_to_log': False, 09:15:13 INFO - 'base_work_dir': '/builds/slave/test', 09:15:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:15:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:15:14 INFO - 'branch': 'Mozilla-Aurora', 09:15:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:15:14 INFO - 'config_files': ('talos/mac_config.py',), 09:15:14 INFO - 'default_actions': ('clobber', 09:15:14 INFO - 'read-buildbot-config', 09:15:14 INFO - 'download-and-extract', 09:15:14 INFO - 'populate-webroot', 09:15:14 INFO - 'create-virtualenv', 09:15:14 INFO - 'install', 09:15:14 INFO - 'run-tests'), 09:15:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:15:14 INFO - 'download_minidump_stackwalk': True, 09:15:14 INFO - 'download_symbols': 'ondemand', 09:15:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:15:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:15:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:15:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:15:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:15:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:15:14 INFO - 'installer_path': 'installer.exe', 09:15:14 INFO - 'log_level': 'info', 09:15:14 INFO - 'log_name': 'talos', 09:15:14 INFO - 'log_to_console': True, 09:15:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:15:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:15:14 INFO - 'opt_config_files': (), 09:15:14 INFO - 'pip_index': False, 09:15:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:14 INFO - 'cmd': ('bash', 09:15:14 INFO - '-c', 09:15:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:14 INFO - 'enabled': True, 09:15:14 INFO - 'halt_on_failure': False, 09:15:14 INFO - 'name': 'check_screen_resolution'},), 09:15:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:15:14 INFO - 'cmd': ('bash', 09:15:14 INFO - '-c', 09:15:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:15:14 INFO - 'enabled': True, 09:15:14 INFO - 'halt_on_failure': False, 09:15:14 INFO - 'name': 'check_screen_resolution'},), 09:15:14 INFO - 'run_cmd_checks_enabled': True, 09:15:14 INFO - 'sps_profile': False, 09:15:14 INFO - 'sps_profile_interval': 0, 09:15:14 INFO - 'suite': 'dromaeojs', 09:15:14 INFO - 'system_bits': '32', 09:15:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:15:14 INFO - 'title': 't-yosemite-r7-0390', 09:15:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:15:14 INFO - 'use_talos_json': True, 09:15:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:15:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:15:14 INFO - 'work_dir': 'build'} 09:15:14 INFO - ##### 09:15:14 INFO - ##### Running clobber step. 09:15:14 INFO - ##### 09:15:14 INFO - Running pre-action listener: _resource_record_pre_action 09:15:14 INFO - Running main action method: clobber 09:15:14 INFO - rmtree: /builds/slave/test/build 09:15:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:15:15 INFO - Running post-action listener: _resource_record_post_action 09:15:15 INFO - ##### 09:15:15 INFO - ##### Running read-buildbot-config step. 09:15:15 INFO - ##### 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: read_buildbot_config 09:15:15 INFO - Using buildbot properties: 09:15:15 INFO - { 09:15:15 INFO - "project": "", 09:15:15 INFO - "product": "firefox", 09:15:15 INFO - "script_repo_revision": "production", 09:15:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:15:15 INFO - "repository": "", 09:15:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:15:15 INFO - "buildid": "20160524071211", 09:15:15 INFO - "pgo_build": "False", 09:15:15 INFO - "basedir": "/builds/slave/test", 09:15:15 INFO - "buildnumber": 10, 09:15:15 INFO - "slavename": "t-yosemite-r7-0390", 09:15:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:15:15 INFO - "platform": "yosemite_r7", 09:15:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:15:15 INFO - "branch": "mozilla-aurora", 09:15:15 INFO - "repo_path": "releases/mozilla-aurora", 09:15:15 INFO - "revision": "bcac6066d9267ca2ea7b281cfcf2223450435a5e", 09:15:15 INFO - "stage_platform": "macosx64", 09:15:15 INFO - "builduid": "13f6ce33e14c4948a727ad7859e38d94", 09:15:15 INFO - "slavebuilddir": "test" 09:15:15 INFO - } 09:15:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:15:15 INFO - Running post-action listener: _resource_record_post_action 09:15:15 INFO - ##### 09:15:15 INFO - ##### Running download-and-extract step. 09:15:15 INFO - ##### 09:15:15 INFO - Running pre-action listener: _resource_record_pre_action 09:15:15 INFO - Running main action method: download_and_extract 09:15:15 INFO - mkdir: /builds/slave/test/build/tests 09:15:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:15 INFO - https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:15:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:15:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:15:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:15:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:15:17 INFO - Downloaded 1199 bytes. 09:15:17 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:15:17 INFO - Using the following test package requirements: 09:15:17 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:15:17 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:15:17 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:15:17 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'jsshell-mac.zip'], 09:15:17 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:15:17 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:15:17 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:15:17 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:15:17 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:15:17 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:15:17 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:15:17 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:15:17 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')]} 09:15:17 INFO - https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:15:18 INFO - Downloaded 18425426 bytes. 09:15:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:15:21 INFO - Return code: 0 09:15:21 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:15: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')]} 09:15:21 INFO - https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:15:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:15:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:15:22 INFO - Downloaded 18425426 bytes. 09:15:22 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:22 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:15:26 INFO - Return code: 0 09:15: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')]} 09:15:26 INFO - https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:15:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:15:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:15:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:15:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:15:27 INFO - Downloaded 10840145 bytes. 09:15:27 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:15:27 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:15:27 INFO - Return code: 0 09:15:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:27 INFO - https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:15:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:15:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:15:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:15:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:15:31 INFO - Downloaded 114136383 bytes. 09:15:31 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:15:31 INFO - mkdir: /builds/slave/test/properties 09:15:31 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:15:31 INFO - Writing to file /builds/slave/test/properties/build_url 09:15:31 INFO - Contents: 09:15:31 INFO - build_url:https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:15:32 INFO - Running post-action listener: _resource_record_post_action 09:15:32 INFO - Running post-action listener: set_extra_try_arguments 09:15:32 INFO - ##### 09:15:32 INFO - ##### Running populate-webroot step. 09:15:32 INFO - ##### 09:15:32 INFO - Running pre-action listener: _resource_record_pre_action 09:15:32 INFO - Running main action method: populate_webroot 09:15:32 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:15:32 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:15:32 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:15:32 INFO - '--tppagecycles', 09:15:32 INFO - '7'], 09:15:32 INFO - 'tests': ['tsvgm']}}, 09:15:32 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:15:32 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:15:32 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:15:32 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:15:32 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:32 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:15:32 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['damp', 'tps']}, 09:15:32 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'tests': ['damp', 'tps']}, 09:15:32 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['dromaeo_dom']}, 09:15:32 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:15:32 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'other-e10s': {'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['a11yr', 09:15:32 INFO - 'ts_paint', 09:15:32 INFO - 'tpaint', 09:15:32 INFO - 'sessionrestore', 09:15:32 INFO - 'sessionrestore_no_auto_restore', 09:15:32 INFO - 'tabpaint']}, 09:15:32 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['tsvgx', 09:15:32 INFO - 'tsvgr_opacity', 09:15:32 INFO - 'tart', 09:15:32 INFO - 'tscrollx', 09:15:32 INFO - 'cart']}, 09:15:32 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:15:32 INFO - 'tsvgr_opacity', 09:15:32 INFO - 'tart', 09:15:32 INFO - 'tscrollx', 09:15:32 INFO - 'cart']}, 09:15:32 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'talos_options': ['--disable-e10s'], 09:15:32 INFO - 'tests': ['tp5o']}, 09:15:32 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'tests': ['tp5o']}, 09:15:32 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'talos_options': ['--disable-e10s', 09:15:32 INFO - '--xperf_path', 09:15:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:32 INFO - 'tests': ['tp5n']}, 09:15:32 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:15:32 INFO - 'talos_options': ['--xperf_path', 09:15:32 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:15:32 INFO - 'tests': ['tp5n']}}, 09:15:32 INFO - 'talos.zip': {'path': '', 09:15:32 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:15:32 INFO - Running post-action listener: _resource_record_post_action 09:15:32 INFO - ##### 09:15:32 INFO - ##### Running create-virtualenv step. 09:15:32 INFO - ##### 09:15:32 INFO - Running pre-action listener: _resource_record_pre_action 09:15:32 INFO - Running main action method: create_virtualenv 09:15:32 INFO - Creating virtualenv /builds/slave/test/build/venv 09:15:32 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 09:15:32 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:15:32 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:15:32 INFO - Using real prefix '/tools/python27' 09:15:32 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:15:33 INFO - Installing distribute.............................................................................................................................................................................................done. 09:15:36 INFO - Installing pip.................done. 09:15:36 INFO - Return code: 0 09:15:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:36 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:36 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 09:15:36 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 09:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:36 INFO - 'HOME': '/Users/cltbld', 09:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:36 INFO - 'LOGNAME': 'cltbld', 09:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:36 INFO - 'MOZ_NO_REMOTE': '1', 09:15:36 INFO - 'NO_EM_RESTART': '1', 09:15:36 INFO - 'PAGER': '/bin/cat', 09:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:36 INFO - 'PWD': '/builds/slave/test', 09:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:36 INFO - 'SHELL': '/bin/bash', 09:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:36 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:36 INFO - 'USER': 'cltbld', 09:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:36 INFO - 'XPC_FLAGS': '0x0', 09:15:36 INFO - 'XPC_SERVICE_NAME': '0', 09:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:36 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 09:15:36 INFO - Cleaning up... 09:15:36 INFO - Return code: 0 09:15:36 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:15: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')]} 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15: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')]} 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:36 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:36 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 09:15:36 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 09:15:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:36 INFO - 'HOME': '/Users/cltbld', 09:15:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:36 INFO - 'LOGNAME': 'cltbld', 09:15:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:36 INFO - 'MOZ_NO_REMOTE': '1', 09:15:36 INFO - 'NO_EM_RESTART': '1', 09:15:36 INFO - 'PAGER': '/bin/cat', 09:15:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:36 INFO - 'PWD': '/builds/slave/test', 09:15:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:36 INFO - 'SHELL': '/bin/bash', 09:15:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:36 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:36 INFO - 'USER': 'cltbld', 09:15:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:36 INFO - 'XPC_FLAGS': '0x0', 09:15:36 INFO - 'XPC_SERVICE_NAME': '0', 09:15:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:36 INFO - Downloading/unpacking psutil>=0.7.1 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:15:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:39 INFO - Installing collected packages: psutil 09:15:39 INFO - Running setup.py install for psutil 09:15:39 INFO - building 'psutil._psutil_osx' extension 09:15:39 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 09:15:40 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 09:15:40 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 09:15:40 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 09:15:40 INFO - building 'psutil._psutil_posix' extension 09:15:40 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 09:15:40 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:15:40 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:15:40 INFO - ^ 09:15:40 INFO - 1 warning generated. 09:15:40 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 09:15:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:15:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:15:40 INFO - Successfully installed psutil 09:15:40 INFO - Cleaning up... 09:15:40 INFO - Return code: 0 09:15:40 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:15:40 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')]} 09:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:40 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')]} 09:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:40 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:40 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 09:15:40 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 09:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:40 INFO - 'HOME': '/Users/cltbld', 09:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:40 INFO - 'LOGNAME': 'cltbld', 09:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:40 INFO - 'MOZ_NO_REMOTE': '1', 09:15:40 INFO - 'NO_EM_RESTART': '1', 09:15:40 INFO - 'PAGER': '/bin/cat', 09:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:40 INFO - 'PWD': '/builds/slave/test', 09:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:40 INFO - 'SHELL': '/bin/bash', 09:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:40 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:40 INFO - 'USER': 'cltbld', 09:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:40 INFO - 'XPC_FLAGS': '0x0', 09:15:40 INFO - 'XPC_SERVICE_NAME': '0', 09:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:43 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:15:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:15:43 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:15:43 INFO - Installing collected packages: mozsystemmonitor 09:15:43 INFO - Running setup.py install for mozsystemmonitor 09:15:43 INFO - Successfully installed mozsystemmonitor 09:15:43 INFO - Cleaning up... 09:15:43 INFO - Return code: 0 09:15:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:15:43 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')]} 09:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:43 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')]} 09:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:43 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:43 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 09:15:43 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 09:15:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:43 INFO - 'HOME': '/Users/cltbld', 09:15:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:43 INFO - 'LOGNAME': 'cltbld', 09:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:43 INFO - 'MOZ_NO_REMOTE': '1', 09:15:43 INFO - 'NO_EM_RESTART': '1', 09:15:43 INFO - 'PAGER': '/bin/cat', 09:15:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:43 INFO - 'PWD': '/builds/slave/test', 09:15:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:43 INFO - 'SHELL': '/bin/bash', 09:15:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:43 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:43 INFO - 'USER': 'cltbld', 09:15:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:43 INFO - 'XPC_FLAGS': '0x0', 09:15:43 INFO - 'XPC_SERVICE_NAME': '0', 09:15:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:44 INFO - Downloading/unpacking blobuploader==1.2.4 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:46 INFO - Downloading blobuploader-1.2.4.tar.gz 09:15:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:15:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:15:46 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 09:15:46 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 09:15:46 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 09:15:46 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 09:15:46 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 09:15:46 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 09:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:15:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15: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 09:15:47 INFO - Downloading docopt-0.6.1.tar.gz 09:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:15:47 INFO - Installing collected packages: blobuploader, requests, docopt 09:15:47 INFO - Running setup.py install for blobuploader 09:15:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:15:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:15:47 INFO - Running setup.py install for requests 09:15:48 INFO - Running setup.py install for docopt 09:15:48 INFO - Successfully installed blobuploader requests docopt 09:15:48 INFO - Cleaning up... 09:15:48 INFO - Return code: 0 09:15:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:15:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:48 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:48 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 09:15:48 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 09:15:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:48 INFO - 'HOME': '/Users/cltbld', 09:15:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:48 INFO - 'LOGNAME': 'cltbld', 09:15:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:48 INFO - 'MOZ_NO_REMOTE': '1', 09:15:48 INFO - 'NO_EM_RESTART': '1', 09:15:48 INFO - 'PAGER': '/bin/cat', 09:15:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:48 INFO - 'PWD': '/builds/slave/test', 09:15:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:48 INFO - 'SHELL': '/bin/bash', 09:15:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:48 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:48 INFO - 'USER': 'cltbld', 09:15:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:48 INFO - 'XPC_FLAGS': '0x0', 09:15:48 INFO - 'XPC_SERVICE_NAME': '0', 09:15:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:48 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-mLC11s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:48 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-wakH7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-hHrZ2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-7N4Ys5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-NSofui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-qrfI44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-GnXWDU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-dXy8sC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-VvbdDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-F346TF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-6lHJ_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:49 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-YtPjbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-MMaqog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-SGBGtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-HsdVkN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-djGl8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:50 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-aw4Zst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:15:50 INFO - Running setup.py install for manifestparser 09:15:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:15:50 INFO - Running setup.py install for mozcrash 09:15:50 INFO - Running setup.py install for mozdebug 09:15:51 INFO - Running setup.py install for mozdevice 09:15:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Running setup.py install for mozfile 09:15:51 INFO - Running setup.py install for mozhttpd 09:15:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Running setup.py install for mozinfo 09:15:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Running setup.py install for mozInstall 09:15:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:15:51 INFO - Running setup.py install for mozleak 09:15:51 INFO - Running setup.py install for mozlog 09:15:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Running setup.py install for moznetwork 09:15:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Running setup.py install for mozprocess 09:15:52 INFO - Running setup.py install for mozprofile 09:15:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Running setup.py install for mozrunner 09:15:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:15:52 INFO - Running setup.py install for mozscreenshot 09:15:52 INFO - Running setup.py install for moztest 09:15:53 INFO - Running setup.py install for mozversion 09:15:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:15:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:15:53 INFO - Cleaning up... 09:15:53 INFO - Return code: 0 09:15:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:53 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')]} 09:15:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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')]} 09:15:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:53 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:53 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 09:15:53 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 09:15:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:53 INFO - 'HOME': '/Users/cltbld', 09:15:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:53 INFO - 'LOGNAME': 'cltbld', 09:15:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:53 INFO - 'MOZ_NO_REMOTE': '1', 09:15:53 INFO - 'NO_EM_RESTART': '1', 09:15:53 INFO - 'PAGER': '/bin/cat', 09:15:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:53 INFO - 'PWD': '/builds/slave/test', 09:15:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:53 INFO - 'SHELL': '/bin/bash', 09:15:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:53 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:53 INFO - 'USER': 'cltbld', 09:15:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:53 INFO - 'XPC_FLAGS': '0x0', 09:15:53 INFO - 'XPC_SERVICE_NAME': '0', 09:15:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:15:53 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-cfmxzs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:15:53 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)) 09:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:15:53 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-sNJuux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:15:53 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)) 09:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:15:53 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-svW2r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:15:53 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)) 09:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:15:53 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-TGeSHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:15:53 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)) 09:15:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:15:53 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Yu1Iea-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-b18U16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-BfXcsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Ttdv3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Bos9Rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-Qib07f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-XC5FpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-DZF3vS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-uS1JXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:15:54 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-shKydd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:15:54 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)) 09:15:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:55 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-U8EFRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:15:55 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)) 09:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:15:55 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-cJz0_E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:15:55 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)) 09:15:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:15:55 INFO - Running setup.py (path:/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/pip-l4T14d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:15:55 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)) 09:15:55 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)) 09:15:55 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)) 09:15:55 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)) 09:15:55 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)) 09:15:55 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)) 09:15:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:57 INFO - Downloading blessings-1.6.tar.gz 09:15:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:15:57 INFO - Installing collected packages: blessings 09:15:57 INFO - Running setup.py install for blessings 09:15:57 INFO - Successfully installed blessings 09:15:57 INFO - Cleaning up... 09:15:58 INFO - Return code: 0 09:15:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:15:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:15:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:15:58 INFO - Reading from file tmpfile_stdout 09:15:58 INFO - Current package versions: 09:15:58 INFO - blessings == 1.6 09:15:58 INFO - blobuploader == 1.2.4 09:15:58 INFO - docopt == 0.6.1 09:15:58 INFO - manifestparser == 1.1 09:15:58 INFO - mozInstall == 1.12 09:15:58 INFO - mozcrash == 0.17 09:15:58 INFO - mozdebug == 0.1 09:15:58 INFO - mozdevice == 0.48 09:15:58 INFO - mozfile == 1.2 09:15:58 INFO - mozhttpd == 0.7 09:15:58 INFO - mozinfo == 0.9 09:15:58 INFO - mozleak == 0.1 09:15:58 INFO - mozlog == 3.1 09:15:58 INFO - moznetwork == 0.27 09:15:58 INFO - mozprocess == 0.22 09:15:58 INFO - mozprofile == 0.28 09:15:58 INFO - mozrunner == 6.11 09:15:58 INFO - mozscreenshot == 0.1 09:15:58 INFO - mozsystemmonitor == 0.0 09:15:58 INFO - moztest == 0.7 09:15:58 INFO - mozversion == 1.4 09:15:58 INFO - psutil == 3.1.1 09:15:58 INFO - requests == 1.2.3 09:15:58 INFO - wsgiref == 0.1.2 09:15:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:15:58 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')]} 09:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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')]} 09:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:58 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 09:15:58 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 09:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:58 INFO - 'HOME': '/Users/cltbld', 09:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:58 INFO - 'LOGNAME': 'cltbld', 09:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:58 INFO - 'MOZ_NO_REMOTE': '1', 09:15:58 INFO - 'NO_EM_RESTART': '1', 09:15:58 INFO - 'PAGER': '/bin/cat', 09:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:58 INFO - 'PWD': '/builds/slave/test', 09:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:58 INFO - 'SHELL': '/bin/bash', 09:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:58 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:58 INFO - 'USER': 'cltbld', 09:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:58 INFO - 'XPC_FLAGS': '0x0', 09:15:58 INFO - 'XPC_SERVICE_NAME': '0', 09:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 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)) 09:15:58 INFO - Cleaning up... 09:15:58 INFO - Return code: 0 09:15:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:15:58 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')]} 09:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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')]} 09:15:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:15:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:15:58 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:15:58 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 09:15:58 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 09:15:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:15:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:15:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:15:58 INFO - 'HOME': '/Users/cltbld', 09:15:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:15:58 INFO - 'LOGNAME': 'cltbld', 09:15:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:15:58 INFO - 'MOZ_NO_REMOTE': '1', 09:15:58 INFO - 'NO_EM_RESTART': '1', 09:15:58 INFO - 'PAGER': '/bin/cat', 09:15:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:15:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:15:58 INFO - 'PWD': '/builds/slave/test', 09:15:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:15:58 INFO - 'SHELL': '/bin/bash', 09:15:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:15:58 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:15:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:15:58 INFO - 'USER': 'cltbld', 09:15:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:15:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:15:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:15:58 INFO - 'XPC_FLAGS': '0x0', 09:15:58 INFO - 'XPC_SERVICE_NAME': '0', 09:15:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:15:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:15:58 INFO - Downloading/unpacking jsonschema 09:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:15:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:15:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:15:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:16:01 INFO - Downloading/unpacking functools32 (from jsonschema) 09:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:16:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:16:01 INFO - warning: no files found matching '*.txt' 09:16:01 INFO - no previously-included directories found matching 'build' 09:16:01 INFO - no previously-included directories found matching 'dist' 09:16:01 INFO - no previously-included directories found matching '.git*' 09:16:01 INFO - Installing collected packages: jsonschema, functools32 09:16:01 INFO - Running setup.py install for functools32 09:16:02 INFO - warning: no files found matching '*.txt' 09:16:02 INFO - no previously-included directories found matching 'build' 09:16:02 INFO - no previously-included directories found matching 'dist' 09:16:02 INFO - no previously-included directories found matching '.git*' 09:16:02 INFO - Successfully installed jsonschema functools32 09:16:02 INFO - Cleaning up... 09:16:02 INFO - Return code: 0 09:16:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:16:02 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')]} 09:16:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:16:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:02 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')]} 09:16:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:16:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:16:02 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 0x103ab0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ac0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd151cce860>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, '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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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 09:16:02 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 09:16:02 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 09:16:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:16:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:02 INFO - 'HOME': '/Users/cltbld', 09:16:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:02 INFO - 'LOGNAME': 'cltbld', 09:16:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:02 INFO - 'MOZ_NO_REMOTE': '1', 09:16:02 INFO - 'NO_EM_RESTART': '1', 09:16:02 INFO - 'PAGER': '/bin/cat', 09:16:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:02 INFO - 'PWD': '/builds/slave/test', 09:16:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:02 INFO - 'SHELL': '/bin/bash', 09:16:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:16:02 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:16:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:02 INFO - 'USER': 'cltbld', 09:16:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:02 INFO - 'XPC_FLAGS': '0x0', 09:16:02 INFO - 'XPC_SERVICE_NAME': '0', 09:16:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:16:02 INFO - Downloading/unpacking flake8 09:16:02 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 09:16:02 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 09:16:02 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 09:16:02 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 09:16:02 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 09:16:02 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 09:16:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:16:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:16:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:16:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:16:05 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:16:05 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:05 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 09:16:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:16:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:06 INFO - no previously-included directories found matching 'docs/_build' 09:16:06 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:16:06 INFO - Running setup.py install for pep8 09:16:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:16:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:16:06 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:16:06 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:16:06 INFO - no previously-included directories found matching 'docs/_build' 09:16:06 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:16:06 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:16:06 INFO - Cleaning up... 09:16:06 INFO - Return code: 0 09:16:06 INFO - Running post-action listener: _resource_record_post_action 09:16:06 INFO - Running post-action listener: _start_resource_monitoring 09:16:06 INFO - Starting resource monitoring. 09:16:06 INFO - ##### 09:16:06 INFO - ##### Running install step. 09:16:06 INFO - ##### 09:16:06 INFO - Running pre-action listener: _resource_record_pre_action 09:16:06 INFO - Running main action method: install 09:16:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:16:06 INFO - Reading from file tmpfile_stdout 09:16:06 INFO - Detecting whether we're running mozinstall >=1.0... 09:16:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:16:06 INFO - Reading from file tmpfile_stdout 09:16:06 INFO - Output received: 09:16:06 INFO - Usage: mozinstall [options] installer 09:16:06 INFO - Options: 09:16:06 INFO - -h, --help show this help message and exit 09:16:06 INFO - -d DEST, --destination=DEST 09:16:06 INFO - Directory to install application into. [default: 09:16:06 INFO - "/builds/slave/test"] 09:16:06 INFO - --app=APP Application being installed. [default: firefox] 09:16:06 INFO - mkdir: /builds/slave/test/build/application 09:16:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:16:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:16:38 INFO - Reading from file tmpfile_stdout 09:16:38 INFO - Output received: 09:16:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:16:38 INFO - Running post-action listener: _resource_record_post_action 09:16:38 INFO - ##### 09:16:38 INFO - ##### Running run-tests step. 09:16:38 INFO - ##### 09:16:38 INFO - Running pre-action listener: _resource_record_pre_action 09:16:38 INFO - Running main action method: run_tests 09:16:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:16:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:16:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:16:38 INFO - 2016-05-24 09:16:38.883 screenresolution[1652:7467] starting screenresolution argv=screenresolution get 09:16:38 INFO - 2016-05-24 09:16:38.886 screenresolution[1652:7467] Display 0: 1600x1200x32@60 09:16:38 INFO - 2016-05-24 09:16:38.892 screenresolution[1653:7471] starting screenresolution argv=screenresolution list 09:16:38 INFO - Available Modes on Display 0 09:16:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:16:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:16:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:16:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:16:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:16:39 INFO - Intel Iris: 09:16:39 INFO - Chipset Model: Intel Iris 09:16:39 INFO - Type: GPU 09:16:39 INFO - Bus: Built-In 09:16:39 INFO - VRAM (Dynamic, Max): 1536 MB 09:16:39 INFO - Vendor: Intel (0x8086) 09:16:39 INFO - Device ID: 0x0a2e 09:16:39 INFO - Revision ID: 0x0009 09:16:39 INFO - Displays: 09:16:39 INFO - Display: 09:16:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:16:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:16:39 INFO - Display Serial Number: 1600x1200 60 09:16:39 INFO - Main Display: Yes 09:16:39 INFO - Mirror: Off 09:16:39 INFO - Online: Yes 09:16:39 INFO - Rotation: Supported 09:16:39 INFO - Return code: 0 09:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:16:39 INFO - Python 2.7.3 09:16:39 INFO - Return code: 0 09:16:39 INFO - grabbing minidump binary from tooltool 09:16:39 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')]} 09:16:39 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 0x10394fe00>, '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 0x1037b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10394ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:16:39 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 09:16:39 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 09:16:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:16:39 INFO - Return code: 0 09:16:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:16:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:16:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:16:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:16:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:16:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:16:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:16:40 INFO - Return code: 0 09:16:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 09:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0390 --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 09:16:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Ox0CMDfnGy/Render', 09:16:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:16:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:16:40 INFO - 'HOME': '/Users/cltbld', 09:16:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:16:40 INFO - 'LOGNAME': 'cltbld', 09:16:40 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:16:40 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:16:40 INFO - 'MOZ_NO_REMOTE': '1', 09:16:40 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:16:40 INFO - 'NO_EM_RESTART': '1', 09:16:40 INFO - 'PAGER': '/bin/cat', 09:16:40 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:16:40 INFO - 'PWD': '/builds/slave/test', 09:16:40 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:16:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:16:40 INFO - 'SHELL': '/bin/bash', 09:16:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.98TG3ItX0L/Listeners', 09:16:40 INFO - 'TMPDIR': '/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/', 09:16:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:16:40 INFO - 'USER': 'cltbld', 09:16:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:16:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:16:40 INFO - 'XPC_FLAGS': '0x0', 09:16:40 INFO - 'XPC_SERVICE_NAME': '0', 09:16:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:16:40 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0390', '--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 09:16:40 INFO - mozversion application_buildid: 20160524071211 09:16:40 INFO - mozversion application_changeset: bcac6066d9267ca2ea7b281cfcf2223450435a5e 09:16:40 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:16:40 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:16:40 INFO - mozversion application_name: Firefox 09:16:40 INFO - mozversion application_remotingname: firefox-dev 09:16:40 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:40 INFO - mozversion application_vendor: Mozilla 09:16:40 INFO - mozversion application_version: 48.0a2 09:16:40 INFO - mozversion platform_buildid: 20160524071211 09:16:40 INFO - mozversion platform_changeset: bcac6066d9267ca2ea7b281cfcf2223450435a5e 09:16:40 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:16:40 INFO - mozversion platform_version: 48.0a2 09:16:40 INFO - using testdate: 1464106600 09:16:40 INFO - actual date: 1464106600 09:16:40 INFO - starting webserver on 'localhost:49270' 09:16:40 INFO - SUITE-START | Running 2 tests 09:16:40 INFO - TEST-START | dromaeo_css 09:16:40 INFO - operating with platform_type : mac_ 09:16:40 INFO - Initialising browser for dromaeo_css test... 09:16:40 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpQWTWXT/profile http://localhost:49270/getInfo.html) 09:16:42 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 09:16:42 INFO - PROCESS | 1671 | colorDepth:24 09:16:42 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 09:16:42 INFO - PROCESS | 1671 | __metrics 09:16:43 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" 09:16:43 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:16:43 INFO - TEST-INFO | 1671: exit 0 09:16:43 INFO - Browser initialized. 09:16:43 INFO - Running cycle 1/1 for dromaeo_css test... 09:16:43 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmpQWTWXT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:16:55 INFO - PROCESS | 1673 | RSS: Main: 211173376 09:16:55 INFO - PROCESS | 1673 | 09:17:47 INFO - PROCESS | 1673 | 1464106667187 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] 09:19:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 09:19:59 INFO - PROCESS | 1673 | RSS: Main: 337518592 09:19:59 INFO - PROCESS | 1673 | 09:23:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 09:23:02 INFO - PROCESS | 1673 | RSS: Main: 357863424 09:23:02 INFO - PROCESS | 1673 | 09:26:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 09:26:05 INFO - PROCESS | 1673 | RSS: Main: 361467904 09:26:05 INFO - PROCESS | 1673 | 09:29:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 09:29:08 INFO - PROCESS | 1673 | RSS: Main: 360960000 09:29:08 INFO - PROCESS | 1673 | 09:32:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 09:32:12 INFO - PROCESS | 1673 | RSS: Main: 330346496 09:32:12 INFO - PROCESS | 1673 | 09:35:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 09:35:15 INFO - PROCESS | 1673 | RSS: Main: 380391424 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | __start_tp_report 09:35:15 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 09:35:15 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 09:35:15 INFO - PROCESS | 1673 | |i|pagename|runs| 09:35:15 INFO - PROCESS | 1673 | |0;dojo.html;3147.748180145589;3406.9318613627743;3451.2579795344004;3465.7573969822092;3483.1292176955762;4658.30125481178;5597.552195824334;5617.691026993513;5627.043402621554;5658.179563963223;5658.7170641467965;5677.40387259337;5693.373728889819;5706.9727449059155;5743.425657434254;5067.594592432597;5097.5157360050825;5100.2183960081775;5193.948060519403;5216.217567364888;5157.329547158864;5178.275041494194;5181.9740901295445;5183.844484665466;5183.870403239912;5148.74256287186;5168.715720635363;5174.689518628876;5192.40287366952;5207.765650545734;5176.689398636075;5204.141316682742;5207.505286997729;5219.243209734605;5235.110031294651;5052.974735126338;5060.139776238011;5075.467075957045;5142.1772516397195;5162.303089082963;5527.944720552783;5563.860903477405;5567.944320556783;5592;5613.4386561343945;6189.597676151081;6223.719932602999;6229.563930524865;6235.00239961604;6239.344868788802;6096.963516202256;6227.065940108974;6249.0626406039;6281.340459251758;6326.905096423558;4794.256890182028;5755.827325180251;5800.6809625471005;5800.854978625526;5832.066869300934;6303.653299068504;6306.24325080984;6309.14826498426;6310.684465776692;6318.62088274705;6015.849603759853;6067.969660151759;6108.358622344679;6126.785562505291;6135.202423684892;6232.688365581703;6233.34549872266;6243.8126856194385;6248.250209974832;6266.749330026839;6132.50939924805;6195.96902015487;6198.349173336825;6199.287081985539;6233.3143354231;6044.758209671564;6128.258691089271;6133.1510485532735;6232.532560058014;6239.407256310643;4831.227003679394;4862.80548778052;4872.001239745844;4882.292067650171;4883.4628190898975;4419.226635338803;4419.668524860648;4421.38100665907;4435.778211089432;4481.327800829837;5650.604457687922;5658.405867383948;5667.4615911488345;5685.459881311269;5693.4875861172695;5876.853078673067;6134.681512609229;6229.283632382243;6273.12176295319;6286.779962701284;5581.3302403711;5655.7172141393585;5662.660240385508;5682.971585142048;5686.943130568724;4516.480604730498;5297.231901374279;5335.413104558495;5350.919736203882;5373.973130134403;6156.415140561639;6290.748370065146;6310.7475700971445;6330.4619107375465;6364.809055728336;6253.656048917172;6273.498120150286;6290.308066112815;6299.30707622152;6300.149479820349;6301.684915754102;6311.147995020613;6318.304986451395;6327.430531252209;6355.90466142999;5221.556167725786;6099.146119543356;6130.080487926846;6135.416406698409;6172.783952561639;5150.768215430389;6263;6277.874442511215;6302.054691796267;6304.085907543289;5818.389069147678;6016.939830601638;6038.094285857156;6091.269047714302;6137.294211165773;5847.795327163529;5923.674197919156;5960;6038.064100064497;6073.817785466443;5881.5588830838715;5886.705664716661;5892.204552385545;5894.911576326444;5906.940930590811;6038.3357830637715;6059.6364218147055;6062.333143354139;6088.208532890696;6114.0828875669;5785.566082543742;5919.674417906885;5938.435848594377;5999.640021598718;6022.247219097613 09:35:15 INFO - PROCESS | 1673 | |1;ext.html;529.9655522391045;535.5180337696073;537.9515843574079;550.4742970463205;592.4135106244813;30981.22546936325;31643.683563164363;31684;31760.523592146135;31806.522902156485;32078.03765887027;32585.022449326498;32648.510272345935;40053.39919901197;40937.7953110234;9486.810263794738;9759.316847820655;9763.463009534473;9763.804723905554;9769.560369783378;9432.19826314764;9617.759556011086;9625.37435066722;9632.181264592518;9668;8676.956615216946;8873.378863479558;9588.424694518351;11459.942700286487;11704.882951170506;11343.602973895915;11446.255993360446;11469.770604587862;11480;11503.309801411942;8578.056413794478;11344.773104537946;11475.311481311148;11480.598179063776;11481.253718508353;40562.79718601418;41143.38284925699;41163.794181028905;41164.1767164658;41309.1738165238;3246.3345014272254;3307.702306792376;3321.717653999413;3452.671996160361;3714.1086139326367;27398.315042123908;27694.861525692446;27776.861115694493;27909.441811163866;28335.149945501667;2286.325533967484;2722;2729.3586007288272;2756.627855239537;2798.468291024699;299792;300975;301625;301723;302470;36182;36830.63169368326;36876.07809804723;36894;58556.70721646365;35612.46581301282;35632.93101206968;35848.462273065925;35855.82072089623;35887.82056089703;14925.477608283658;15095.39618415251;15099.622509437133;15119.622009449631;15154.848451515565;13134.671633209055;13197.736045279233;13205.141665791885;13207.40566674502;13263.270520121487;7642.579658118746;7840.215978402228;8218.096009438957;8223.301019413417;8312.501249925024;3386.4581666933664;3441.625278557787;3451.2752322012157;3452.551168348149;3486.16332080304;961.4327546747453;1021.6168936648756;1048.2543104513904;1119.2333251722596;1128.7686024364984;29982.884316944823;30258.24354391114;30316.54525182124;30697.386052279275;30797.230069248;783.4124406694979;1027.1885210683477;1085.4225571802676;1106.5186643809807;1130.4008875296106;14861;14897.627559311104;15022.023568468014;15310.770338444932;15337.386504539916;628390;732400;734328.3283583547;734893;737291;61369.386306136366;61467.38532614616;62377.37622623716;62682.68658656678;62727.6863615679;1358.3276278242333;1385.0443194196173;1521.2773932382252;1538.5768913548864;1543.2592355669117;29118.417631646826;30406;30664.56202046061;31142.532862006636;31953.68046319507;45323;55052.449475506335;56121;56182.71908640431;56242.43757562536;572320;588629;588671;588862;590637;29233.561496577993;34593.13478795984;36159.45760813643;43216.35175472433;43575;43365.91585210269;44074.898127547065;44794.88012799706;44933.775331123135;45183;38240;38886.4167037489;39328.2134357317;39771.60228397679;39855.80432587027;38379.424308634836;38747.806260968515;39152.608473916036;39200.2159956805;39657.80171099126;37133.628663714095;38177.618223818514;38200.61799381971;38735.41896871493;39795.80102099471;30998.925349261128;31416.37167256688;31746.682533175295;33502;34286.82856585701;21447.678284826052;24050;24338.148164814145;24856;24881.87559062193 09:35:15 INFO - PROCESS | 1673 | |2;jquery.html;404.19766762975496;404.76725882617495;406.88200421877656;407.6310938600566;419.737663960025;19682;19930.800691993074;20353.18587256512;20378;21215.151393944263;20571.588568228628;20948.581028379424;21225.36323910285;21680.566388672218;21721.3483595492;5585.3297604287545;5637.577181711357;5896.086106653475;5898.085796701518;5937.88124237515;5701.600887937846;5794.159846822208;5803.158542011406;5841.591088623777;5859.824205273849;5307.628466007381;5880.794701986755;6053.727582258809;6064.666443345614;6064.9393506065035;5739.713014349266;6004.939950600482;6021.548383871205;6023.728932198062;6026.879462410771;5766.682832444214;5828.708564571797;5838.649681019152;5845.06478963368;5845.210896528999;13019.544315948991;13644.454221831114;13662.658433539142;13670.589882303548;13967.231802250872;2080.5942841145984;2123.766385697572;2166.664167054109;2242.1703969531377;2397.5854245994947;9769.951150244275;10079.44563049032;13478.460861565525;13754.555771643933;13794.793078103736;1522.4975757999835;1696.4401747423444;1723.6638855423198;1729.0922265810425;1825.3884948542254;96379;112744;119051;120949.39525302318;122500;9454.669086581936;11457.255278406878;11877.643670689893;11883.821742673867;11948.342841143647;15060.87043471744;15199;15210.923945380202;15212.011219270711;15216.923915380574;7454.40364770817;8802.25180859634;8803;8896.110388961182;9001.859144793338;8887.15572020657;8966.86549701755;9044;9072.727818165466;9134.086591340812;4660.813567457264;4711.104890070876;4731.29030645406;4742.95257047432;4818.204996175662;1933.265359163509;1983.5537004174234;1996.5008747813047;2007.6988451732066;2035.7862424445516;888.2627419242014;908.8503043649788;930.6789157740569;969.147150507563;1022.9335093218917;19286.06347210995;20263.39209823678;20404.48988775263;21031.68452473214;21586.78413215848;958.6357184269933;974.7660561465198;1012.8959434216739;1014.4572653630272;1056.1392465140887;9926.851097233548;10952.069074128789;10958.01377875995;10958.397288149068;10989.120870330355;511812;590884;601686;611491;619601;129133.35433322773;130871;131449;131869;138144.30927845498;1017.5471914997903;1020.6121673764071;1036.3471013261596;1038.1694644284541;1038.9896101039044;17788.46634600964;17885.19516621729;18096.00471974054;18112.818871811112;18495.890246585248;50524.49475505198;52814.73592632166;54039.29642393603;55925;56644.433555665564;77530.22469775456;79687;80726.59636701779;81238.96902577246;82204.76692849492;7524.360429363565;7937.523748575104;8006.719764808437;8326.375521836007;8377.45546539473;10475.58097676115;12581.685457863627;12671.113022088366;12723.300218487702;13078.542251021168;10920.781584368428;11155.553777848796;11320.433978301216;11341.376224307742;11374.488148033524;10475.94762026185;10627;10941.726456838644;11091.112710982941;11300.434978251218;11205.439728013404;11236.988671019648;11353.54585816558;11496.82754758696;11662.475188616363;8627.525486098073;11191.384473854016;11741.882581174079;12173.756524869275;12549.184303020274;11321.150913681342;11548.826767598324;11717.06263498901;11830.290182589073;11884.702882428008 09:35:15 INFO - PROCESS | 1673 | |3;mootools.html;1418.47516418925;1568.2237292540194;1576.9290381932813;1828.679981003324;1973.3192048743178;7731.0336207974005;8459.746207613774;8643.611037503311;8654.528730115895;8670.913290867098;8536.74389768308;8569.65721371146;8707.216350528452;8718.433301835375;8723.171298726627;2138.230237114645;2366.645003249509;2436.634504824273;2457.041753716052;2458.7172475165316;2437.9878100609562;2438.1100898172126;2442.853428794278;2444.559979203743;2445.2664200739796;2346.1788374069024;2382.9991403610525;2391.426057746146;2415.649730789043;2415.9637605435933;2117.862338947979;2350.2831636350907;2401.411654144737;2409.325388891113;2421.7336093029753;2371.584972629783;2413.1674572272536;2415.0339864054345;2420.3586049696846;2430.2466235467064;8535.615897284637;8589.398742088057;8619.698310559163;8627.051024387312;8630.65477380904;5210.6613070150315;5320;5325.281087053237;5353.250544923714;5356.54469370104;2877.2375320540073;3267.6895694908944;3335.199552107477;3396.847141878621;3435.604905435857;1058.2327812336073;1058.4231593781408;1063.7500187455942;1066.9413182274973;1072.7050061233144;11733.882661173364;11751.236169648946;11770;11770.234934729215;11771.882281177164;4163.687723420756;4563.520830312836;4770;4788.808447662124;4824.517548245218;9912.207023438108;9916.652917147865;9954.601815927283;9958.850617240747;9966.2525310602;5320.494553017457;5355.410904800468;5363.704996225167;5367.275417818622;5382.434844341366;4389.736615802998;4474.865754027384;4480.238359478832;4488.304312831517;4891.192953162759;3698.2973235085246;3947.684185265172;4022.1699126897192;4035.576264492186;4040.5555388907196;1283.7175821319295;1331.760283149023;1332.573576455525;1341.7115320206203;1341.7584834729842;788.891607291756;857.691231156783;889.5063239901916;906.1889608800087;965.5220665770488;4820.517948205152;5584.692841893735;5760.3951585083805;5852.473277405054;6075.210222671025;1014.9949250253701;1027.897210278981;1055.1664185293532;1057.4501259345252;1058.2486434631344;3733.626637336299;3738.5513738351574;3797.867074652374;3900.6294402031763;3908.7654740715648;10251.026152515498;10407.271490995709;10718.571257149782;10777.676669699922;10811.83782243267;10358.896411035945;10659.4137322448;10671.733206669895;10689.572417103383;10698.78602427947;6598.967005165039;8063.354931605458;8065.677372905018;8225.95887020561;8380.287675547646;5729.541636668973;5755.8561035974435;5804.1874137619925;5847;5938.377589670384;9322.720318390458;9366.625334986524;9497.002814704356;9526.618935242514;9755.951220243853;9702.6118955241;9859.654912078044;9903.950480247553;9966.850497242402;10018.849717254392;7015.824604384931;7410.888836667346;7412.703491860265;7413;7426.257374262746;7405.481616287024;7550.622468876424;7551.848963020819;7564.016677831846;7566.167721550515;6856;7477.775666730006;7546.547207167588;7590.734324298623;7667.578283194255;6276.497880169667;7419.443541734284;7428.405727541892;7570.810729731801;7666.9616651916385;6792.592444453348;6813.557118787263;6862;6869.519133660796;6880.415164711056;4872.123017856819;4937.530934561211;4954.777035033504;5006.098902197639;5038.319826823332;5044.319016932667;5056.191009438619;5124.077666020003;5180.041692286939;5298.682079075268 09:35:15 INFO - PROCESS | 1673 | |4;prototype.html;312.31446973892315;317.81725507832994;326.1324875830292;326.9231730543321;331.97842140260883;6513.934860651392;6527.445167160791;6549.999999999994;6561.179852518435;6569.605823650584;6227.999999999989;6444.355564443554;6450;6455.451286640641;6497.772577959772;3882.3400021996326;4691.225947718623;4741.454732705749;4744.691595046328;4843.709377437365;4748.240281554952;4750.762461876892;4778.426588809348;4787.1143838389835;4833.661643684944;4685.1566717990745;4769.332293478916;4773.403324584427;4780.569748722614;4784.497627749089;4561.7262964222255;4640.257558790578;4685.8594242172785;4762.952370476303;4775.856724298276;4007.2786898358286;4036.858709945167;4064.695147863922;4067.064575147703;4103.753774773493;5208.374995000624;6146.907796383058;6207.565470417073;6236.781712640036;6236.781712640081;5642.463965923231;5700.287464066992;5701.372848986608;5722.113072473773;5724;6917.550359226634;6957.130358705162;6965.721371145148;6967;6987.1266091738535;4516.841910533149;4663.533646635343;4671.369365135697;4682.7658617069355;4702.670813043088;7268.927310726878;7276.927230727678;7357.632118394059;7368.594727289997;7406.444516661218;4812.470628230892;4814.831480898222;4828.734419606955;4831.0096430231715;4836.984233311044;4887.34020907173;4895.388076490439;4898.510148985144;4928.778204980785;4929.457759646436;5777.24895763548;5781.53747700183;5788.82633520995;5789.218455508537;5802.33273173582;6841.247462779091;6924.965375173092;6949.652517374111;7034.859302813915;7059.258777828357;1561.7345051341072;1677.8909370890854;1700.9029176181293;1702.174445393983;1703.3271857616173;1073.4739977411011;1077.8383242513528;1100.3287994323457;1101.3446999035561;1108.8503052088026;6296.779612713624;6474.482041436674;6498.837529061811;6511.576747511492;6532.771353002622;7108;7209.315115064165;7258.129024517092;7309.89035164473;7318.926810731825;5252.97566974444;5308.362996440452;5492.47821456961;6553.099601115623;6573.83565410859;3793.8103094845146;3848.692104631679;3863.8840834775;3872;3876.7673939563715;7350.117985841734;7660.272274134059;7675.577843218676;7679;7683.193264707125;7550.169481357047;7555.584442855696;7572.734954276574;7573.962130189314;7577.810554736176;7482.176960534337;7616.124145723202;7629.847403052019;7639;7640.541567505968;6750.1562304711915;7194.028806111317;7327.74352897646;7346.5592064476305;7350;6940.583564986117;7168.569885806868;7222.422206223595;7239.167495738057;7240.239774823568;6038.275406951194;7575;7577.393808495417;7614.048243969504;7617.542947423172;4784.808607655794;4802.8799280018275;4821.758912054313;4825.879353016203;4831.154547954024;5896.587238893237;6585.604863708193;7218.89171662436;7249.565026098451;7274.854502910018;6675.399214070757;7040.964795175992;7046.753363632249;7046.964765176142;7058.223595404604;6820.113385259884;6867.141607299088;6887.827804304932;6891.827704307432;6934.653267336714;6735.383507958153;6815.795526134224;6816.625085620339;6824.897626535705;6833.624150671761;7015.473839461959;7023.192332881784;7030.753923612649;7036.577805331492;7060.223375428802;6640.0411878496925;6884.277150899284;6887.276835932155;6887.345702158287;6923.169219693669 09:35:15 INFO - PROCESS | 1673 | |5;yui.html;637.9043143528468;680;740.3965767899161;747.8990336304604;755.848830233953;14836;15130.470433534829;15160.469383571564;15235.46675866345;16888.408905688302;15868.127253001108;15924.601884952883;15950.840491595081;15996.360145594192;15998.440054598062;727.3817255332978;908.2960705453261;941.0730430525927;944.1078181118845;945.1588086602928;738.8743913534685;906.7098528470892;936.1434287626847;937.1331518345537;937.1612406895824;975.639013564982;979.7746518300795;982.1504398695113;984.1142971325828;984.8227319082562;759.111839148198;949.9952500237524;975.3416443900375;977.0571398600349;977.1205914676776;723.8045727653504;723.8552289542113;724.3299947548496;725.3508110241357;725.640807800137;15090.54728358151;15190.696186076328;15211.543653690407;15220.619484512865;15226.695466090727;12429.31638759868;12469.501219951191;12589.496420143183;12619.936900315532;12788.104832661717;10083.243756718202;10178.33840800353;10252.589896404135;10467.05796478313;10563.996420340056;3476.148343655795;3490.092575930251;3500.194955160302;3533.805640689761;3734.4958430612064;71827.2817271831;79504.60247698783;83608.74586881201;84064.31871362538;84097.15902841015;9268.72193834186;9408.153266206074;9464.526773661428;9523.90476095244;9583.952080239693;21383.46541336479;21583.46041348979;21623.24318648871;21636;21684.132634694437;7510.436717246228;7598;7608.125065617414;7872.606369681493;7899.486533375312;4152.91694166115;4153.563875793058;4156.771377574262;4166.8749937501925;4245.172191422643;3605.0446631642635;3641.235340578455;3659.2315613721416;3664.95548768605;3695.0392897846755;631.311870061635;642.5470043619238;643.0932385336746;653.3401264722569;660.8843452395812;546.0635010956149;614.9815505534841;616.6454288783967;620.2928661326092;629.4901130084646;7748.690052397841;8003.279704826594;8243.83512329762;8264.21489958453;8321.334293256525;580.4427749360576;614.4132353602299;614.7756069034859;618.848382146377;621.4313902778997;3728.496652951871;3736.252749450121;3854.9807250964127;3905.3165696003084;3920.7843568604;90078.54960725154;90129;91395.5430222858;92036;93266.53366733123;76170.23829761743;78500.60749696329;79534;80464.59767701125;81074.59462702766;67776.322236777;67938.66030669815;68677;69488.3051169492;71184.64407678031;7577.810554736176;7580.620968951641;7741.961290193513;7785.688572457038;7994.92005079965;48657.02685946332;48780.78048048827;50002.49997500124;50499;50796.23805642845;61560.69219653873;63992;64981.35018649753;67853.3214667847;70143.64928175327;10699.465026748787;10815.188860835311;10916.181286403393;11076.280041797258;11440.94279528597;11785.175037747618;11837.112216583619;11915;12071.155019148575;12476;11239.831402528805;11519;11529.769404612029;11564.306141631529;11927.821082683593;11317;11407.030402415887;11431.371274579978;11742;12058.75882482363;10865.674029779118;10917.672469825919;11023.393713345522;11287;11581.363025033705;9185.697624577577;9507.66723164686;9666.903330966601;9799.804003919739;9861.633965745115;9389.248860091313;9407.106324899123;9683.979115141467;9717.6598819041;9875 09:35:15 INFO - PROCESS | 1673 | __end_tp_report 09:35:15 INFO - PROCESS | 1673 | __start_cc_report 09:35:15 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1353 09:35:15 INFO - PROCESS | 1673 | __end_cc_report 09:35:15 INFO - PROCESS | 1673 | __startTimestamp1464107715113__endTimestamp 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | ------- Summary: start ------- 09:35:15 INFO - PROCESS | 1673 | Number of tests: 6 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#0] dojo.html Cycles:165 Average:5687.76 Median:5886.71 stddev:654.30 (11.1%) stddev-sans-first:625.23 09:35:15 INFO - PROCESS | 1673 | Values: 3147.7 3406.9 3451.3 3465.8 3483.1 4658.3 5597.6 5617.7 5627.0 5658.2 5658.7 5677.4 5693.4 5707.0 5743.4 5067.6 5097.5 5100.2 5193.9 5216.2 5157.3 5178.3 5182.0 5183.8 5183.9 5148.7 5168.7 5174.7 5192.4 5207.8 5176.7 5204.1 5207.5 5219.2 5235.1 5053.0 5060.1 5075.5 5142.2 5162.3 5527.9 5563.9 5567.9 5592.0 5613.4 6189.6 6223.7 6229.6 6235.0 6239.3 6097.0 6227.1 6249.1 6281.3 6326.9 4794.3 5755.8 5800.7 5800.9 5832.1 6303.7 6306.2 6309.1 6310.7 6318.6 6015.8 6068.0 6108.4 6126.8 6135.2 6232.7 6233.3 6243.8 6248.3 6266.7 6132.5 6196.0 6198.3 6199.3 6233.3 6044.8 6128.3 6133.2 6232.5 6239.4 4831.2 4862.8 4872.0 4882.3 4883.5 4419.2 4419.7 4421.4 4435.8 4481.3 5650.6 5658.4 5667.5 5685.5 5693.5 5876.9 6134.7 6229.3 6273.1 6286.8 5581.3 5655.7 5662.7 5683.0 5686.9 4516.5 5297.2 5335.4 5350.9 5374.0 6156.4 6290.7 6310.7 6330.5 6364.8 6253.7 6273.5 6290.3 6299.3 6300.1 6301.7 6311.1 6318.3 6327.4 6355.9 5221.6 6099.1 6130.1 6135.4 6172.8 5150.8 6263.0 6277.9 6302.1 6304.1 5818.4 6016.9 6038.1 6091.3 6137.3 5847.8 5923.7 5960.0 6038.1 6073.8 5881.6 5886.7 5892.2 5894.9 5906.9 6038.3 6059.6 6062.3 6088.2 6114.1 5785.6 5919.7 5938.4 5999.6 6022.2 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#1] ext.html Cycles:180 Average:65612.07 Median:19022.90 stddev:151080.49 (794.2%) stddev-sans-first:151425.28 09:35:15 INFO - PROCESS | 1673 | Values: 530.0 535.5 538.0 550.5 592.4 30981.2 31643.7 31684.0 31760.5 31806.5 32078.0 32585.0 32648.5 40053.4 40937.8 9486.8 9759.3 9763.5 9763.8 9769.6 9432.2 9617.8 9625.4 9632.2 9668.0 8677.0 8873.4 9588.4 11459.9 11704.9 11343.6 11446.3 11469.8 11480.0 11503.3 8578.1 11344.8 11475.3 11480.6 11481.3 40562.8 41143.4 41163.8 41164.2 41309.2 3246.3 3307.7 3321.7 3452.7 3714.1 27398.3 27694.9 27776.9 27909.4 28335.1 2286.3 2722.0 2729.4 2756.6 2798.5 299792.0 300975.0 301625.0 301723.0 302470.0 36182.0 36830.6 36876.1 36894.0 58556.7 35612.5 35632.9 35848.5 35855.8 35887.8 14925.5 15095.4 15099.6 15119.6 15154.8 13134.7 13197.7 13205.1 13207.4 13263.3 7642.6 7840.2 8218.1 8223.3 8312.5 3386.5 3441.6 3451.3 3452.6 3486.2 961.4 1021.6 1048.3 1119.2 1128.8 29982.9 30258.2 30316.5 30697.4 30797.2 783.4 1027.2 1085.4 1106.5 1130.4 14861.0 14897.6 15022.0 15310.8 15337.4 628390.0 732400.0 734328.3 734893.0 737291.0 61369.4 61467.4 62377.4 62682.7 62727.7 1358.3 1385.0 1521.3 1538.6 1543.3 29118.4 30406.0 30664.6 31142.5 31953.7 45323.0 55052.4 56121.0 56182.7 56242.4 572320.0 588629.0 588671.0 588862.0 590637.0 29233.6 34593.1 36159.5 43216.4 43575.0 43365.9 44074.9 44794.9 44933.8 45183.0 38240.0 38886.4 39328.2 39771.6 39855.8 38379.4 38747.8 39152.6 39200.2 39657.8 37133.6 38177.6 38200.6 38735.4 39795.8 30998.9 31416.4 31746.7 33502.0 34286.8 21447.7 24050.0 24338.1 24856.0 24881.9 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#2] jquery.html Cycles:180 Average:34688.27 Median:20488.04 stddev:98388.56 (480.2%) stddev-sans-first:98630.89 09:35:15 INFO - PROCESS | 1673 | Values: 404.2 404.8 406.9 407.6 419.7 19682.0 19930.8 20353.2 20378.0 21215.2 20571.6 20948.6 21225.4 21680.6 21721.3 5585.3 5637.6 5896.1 5898.1 5937.9 5701.6 5794.2 5803.2 5841.6 5859.8 5307.6 5880.8 6053.7 6064.7 6064.9 5739.7 6004.9 6021.5 6023.7 6026.9 5766.7 5828.7 5838.6 5845.1 5845.2 13019.5 13644.5 13662.7 13670.6 13967.2 2080.6 2123.8 2166.7 2242.2 2397.6 9770.0 10079.4 13478.5 13754.6 13794.8 1522.5 1696.4 1723.7 1729.1 1825.4 96379.0 112744.0 119051.0 120949.4 122500.0 9454.7 11457.3 11877.6 11883.8 11948.3 15060.9 15199.0 15210.9 15212.0 15216.9 7454.4 8802.3 8803.0 8896.1 9001.9 8887.2 8966.9 9044.0 9072.7 9134.1 4660.8 4711.1 4731.3 4743.0 4818.2 1933.3 1983.6 1996.5 2007.7 2035.8 888.3 908.9 930.7 969.1 1022.9 19286.1 20263.4 20404.5 21031.7 21586.8 958.6 974.8 1012.9 1014.5 1056.1 9926.9 10952.1 10958.0 10958.4 10989.1 511812.0 590884.0 601686.0 611491.0 619601.0 129133.4 130871.0 131449.0 131869.0 138144.3 1017.5 1020.6 1036.3 1038.2 1039.0 17788.5 17885.2 18096.0 18112.8 18495.9 50524.5 52814.7 54039.3 55925.0 56644.4 77530.2 79687.0 80726.6 81239.0 82204.8 7524.4 7937.5 8006.7 8326.4 8377.5 10475.6 12581.7 12671.1 12723.3 13078.5 10920.8 11155.6 11320.4 11341.4 11374.5 10475.9 10627.0 10941.7 11091.1 11300.4 11205.4 11237.0 11353.5 11496.8 11662.5 8627.5 11191.4 11741.9 12173.8 12549.2 11321.2 11548.8 11717.1 11830.3 11884.7 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#3] mootools.html Cycles:180 Average:5627.63 Median:5022.21 stddev:3130.34 (62.3%) stddev-sans-first:3123.14 09:35:15 INFO - PROCESS | 1673 | Values: 1418.5 1568.2 1576.9 1828.7 1973.3 7731.0 8459.7 8643.6 8654.5 8670.9 8536.7 8569.7 8707.2 8718.4 8723.2 2138.2 2366.6 2436.6 2457.0 2458.7 2438.0 2438.1 2442.9 2444.6 2445.3 2346.2 2383.0 2391.4 2415.6 2416.0 2117.9 2350.3 2401.4 2409.3 2421.7 2371.6 2413.2 2415.0 2420.4 2430.2 8535.6 8589.4 8619.7 8627.1 8630.7 5210.7 5320.0 5325.3 5353.3 5356.5 2877.2 3267.7 3335.2 3396.8 3435.6 1058.2 1058.4 1063.8 1066.9 1072.7 11733.9 11751.2 11770.0 11770.2 11771.9 4163.7 4563.5 4770.0 4788.8 4824.5 9912.2 9916.7 9954.6 9958.9 9966.3 5320.5 5355.4 5363.7 5367.3 5382.4 4389.7 4474.9 4480.2 4488.3 4891.2 3698.3 3947.7 4022.2 4035.6 4040.6 1283.7 1331.8 1332.6 1341.7 1341.8 788.9 857.7 889.5 906.2 965.5 4820.5 5584.7 5760.4 5852.5 6075.2 1015.0 1027.9 1055.2 1057.5 1058.2 3733.6 3738.6 3797.9 3900.6 3908.8 10251.0 10407.3 10718.6 10777.7 10811.8 10358.9 10659.4 10671.7 10689.6 10698.8 6599.0 8063.4 8065.7 8226.0 8380.3 5729.5 5755.9 5804.2 5847.0 5938.4 9322.7 9366.6 9497.0 9526.6 9756.0 9702.6 9859.7 9904.0 9966.9 10018.8 7015.8 7410.9 7412.7 7413.0 7426.3 7405.5 7550.6 7551.8 7564.0 7566.2 6856.0 7477.8 7546.5 7590.7 7667.6 6276.5 7419.4 7428.4 7570.8 7667.0 6792.6 6813.6 6862.0 6869.5 6880.4 4872.1 4937.5 4954.8 5006.1 5038.3 5044.3 5056.2 5124.1 5180.0 5298.7 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#4] prototype.html Cycles:180 Average:5723.28 Median:6486.13 stddev:1813.26 (28.0%) stddev-sans-first:1772.28 09:35:15 INFO - PROCESS | 1673 | Values: 312.3 317.8 326.1 326.9 332.0 6513.9 6527.4 6550.0 6561.2 6569.6 6228.0 6444.4 6450.0 6455.5 6497.8 3882.3 4691.2 4741.5 4744.7 4843.7 4748.2 4750.8 4778.4 4787.1 4833.7 4685.2 4769.3 4773.4 4780.6 4784.5 4561.7 4640.3 4685.9 4763.0 4775.9 4007.3 4036.9 4064.7 4067.1 4103.8 5208.4 6146.9 6207.6 6236.8 6236.8 5642.5 5700.3 5701.4 5722.1 5724.0 6917.6 6957.1 6965.7 6967.0 6987.1 4516.8 4663.5 4671.4 4682.8 4702.7 7268.9 7276.9 7357.6 7368.6 7406.4 4812.5 4814.8 4828.7 4831.0 4837.0 4887.3 4895.4 4898.5 4928.8 4929.5 5777.2 5781.5 5788.8 5789.2 5802.3 6841.2 6925.0 6949.7 7034.9 7059.3 1561.7 1677.9 1700.9 1702.2 1703.3 1073.5 1077.8 1100.3 1101.3 1108.9 6296.8 6474.5 6498.8 6511.6 6532.8 7108.0 7209.3 7258.1 7309.9 7318.9 5253.0 5308.4 5492.5 6553.1 6573.8 3793.8 3848.7 3863.9 3872.0 3876.8 7350.1 7660.3 7675.6 7679.0 7683.2 7550.2 7555.6 7572.7 7574.0 7577.8 7482.2 7616.1 7629.8 7639.0 7640.5 6750.2 7194.0 7327.7 7346.6 7350.0 6940.6 7168.6 7222.4 7239.2 7240.2 6038.3 7575.0 7577.4 7614.0 7617.5 4784.8 4802.9 4821.8 4825.9 4831.2 5896.6 6585.6 7218.9 7249.6 7274.9 6675.4 7041.0 7046.8 7047.0 7058.2 6820.1 6867.1 6887.8 6891.8 6934.7 6735.4 6815.8 6816.6 6824.9 6833.6 7015.5 7023.2 7030.8 7036.6 7060.2 6640.0 6884.3 6887.3 6887.3 6923.2 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - PROCESS | 1673 | [#5] yui.html Cycles:180 Average:18288.26 Median:630.40 stddev:25512.54 (4047.0%) stddev-sans-first:25549.68 09:35:15 INFO - PROCESS | 1673 | Values: 637.9 680.0 740.4 747.9 755.8 14836.0 15130.5 15160.5 15235.5 16888.4 15868.1 15924.6 15950.8 15996.4 15998.4 727.4 908.3 941.1 944.1 945.2 738.9 906.7 936.1 937.1 937.2 975.6 979.8 982.2 984.1 984.8 759.1 950.0 975.3 977.1 977.1 723.8 723.9 724.3 725.4 725.6 15090.5 15190.7 15211.5 15220.6 15226.7 12429.3 12469.5 12589.5 12619.9 12788.1 10083.2 10178.3 10252.6 10467.1 10564.0 3476.1 3490.1 3500.2 3533.8 3734.5 71827.3 79504.6 83608.7 84064.3 84097.2 9268.7 9408.2 9464.5 9523.9 9584.0 21383.5 21583.5 21623.2 21636.0 21684.1 7510.4 7598.0 7608.1 7872.6 7899.5 4152.9 4153.6 4156.8 4166.9 4245.2 3605.0 3641.2 3659.2 3665.0 3695.0 631.3 642.5 643.1 653.3 660.9 546.1 615.0 616.6 620.3 629.5 7748.7 8003.3 8243.8 8264.2 8321.3 580.4 614.4 614.8 618.8 621.4 3728.5 3736.3 3855.0 3905.3 3920.8 90078.5 90129.0 91395.5 92036.0 93266.5 76170.2 78500.6 79534.0 80464.6 81074.6 67776.3 67938.7 68677.0 69488.3 71184.6 7577.8 7580.6 7742.0 7785.7 7994.9 48657.0 48780.8 50002.5 50499.0 50796.2 61560.7 63992.0 64981.4 67853.3 70143.6 10699.5 10815.2 10916.2 11076.3 11440.9 11785.2 11837.1 11915.0 12071.2 12476.0 11239.8 11519.0 11529.8 11564.3 11927.8 11317.0 11407.0 11431.4 11742.0 12058.8 10865.7 10917.7 11023.4 11287.0 11581.4 9185.7 9507.7 9666.9 9799.8 9861.6 9389.2 9407.1 9684.0 9717.7 9875.0 09:35:15 INFO - PROCESS | 1673 | -------- Summary: end -------- 09:35:15 INFO - PROCESS | 1673 | 09:35:15 INFO - TEST-INFO | 1673: exit 0 09:35:15 INFO - TEST-OK | dromaeo_css | took 1115090ms 09:35:15 INFO - TEST-START | kraken 09:35:15 INFO - operating with platform_type : mac_ 09:35:15 INFO - Initialising browser for kraken test... 09:35:15 INFO - TEST-INFO | started process 1684 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmppgTeNZ/profile http://localhost:49270/getInfo.html) 09:35:17 INFO - PROCESS | 1684 | __metrics Screen width/height:1600/1200 09:35:17 INFO - PROCESS | 1684 | colorDepth:24 09:35:17 INFO - PROCESS | 1684 | Browser inner width/height: 1024/689 09:35:17 INFO - PROCESS | 1684 | __metrics 09:35:17 INFO - PROCESS | 1684 | 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" 09:35:17 ERROR - PROCESS | 1684 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:35:18 INFO - TEST-INFO | 1684: exit 0 09:35:18 INFO - Browser initialized. 09:35:18 INFO - Running cycle 1/1 for kraken test... 09:35:18 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/tmppgTeNZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:35:30 INFO - PROCESS | 1686 | RSS: Main: 211111936 09:35:30 INFO - PROCESS | 1686 | 09:35:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 09:35:34 INFO - PROCESS | 1686 | RSS: Main: 271187968 09:35:34 INFO - PROCESS | 1686 | 09:35:36 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 09:35:37 INFO - PROCESS | 1686 | RSS: Main: 288174080 09:35:37 INFO - PROCESS | 1686 | 09:35:40 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 09:35:41 INFO - PROCESS | 1686 | RSS: Main: 374972416 09:35:41 INFO - PROCESS | 1686 | 09:35:43 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 09:35:43 INFO - PROCESS | 1686 | RSS: Main: 279388160 09:35:43 INFO - PROCESS | 1686 | 09:35:46 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:35:46 INFO - PROCESS | 1686 | RSS: Main: 279900160 09:35:46 INFO - PROCESS | 1686 | 09:35:49 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 09:35:50 INFO - PROCESS | 1686 | RSS: Main: 318271488 09:35:50 INFO - PROCESS | 1686 | 09:35:52 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 09:35:53 INFO - PROCESS | 1686 | RSS: Main: 318566400 09:35:53 INFO - PROCESS | 1686 | 09:35:56 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 09:35:56 INFO - PROCESS | 1686 | RSS: Main: 316485632 09:35:56 INFO - PROCESS | 1686 | 09:35:58 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:35:59 INFO - PROCESS | 1686 | RSS: Main: 308711424 09:35:59 INFO - PROCESS | 1686 | 09:36:00 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:36:01 INFO - PROCESS | 1686 | RSS: Main: 316141568 09:36:01 INFO - PROCESS | 1686 | 09:36:03 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:36:04 INFO - PROCESS | 1686 | RSS: Main: 318119936 09:36:04 INFO - PROCESS | 1686 | 09:36:07 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:36:08 INFO - PROCESS | 1686 | RSS: Main: 323465216 09:36:08 INFO - PROCESS | 1686 | 09:36:10 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:36:11 INFO - PROCESS | 1686 | RSS: Main: 321531904 09:36:11 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 09:36:13 INFO - PROCESS | 1686 | RSS: Main: 386465792 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | __start_tp_report 09:36:13 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 09:36:13 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 09:36:13 INFO - PROCESS | 1686 | |i|pagename|runs| 09:36:13 INFO - PROCESS | 1686 | |0;ai-astar;115;114;118;115;114;112;114;114;113;136 09:36:13 INFO - PROCESS | 1686 | |1;audio-beat-detection;156;122;148;196;151;134;125;177;188;137 09:36:13 INFO - PROCESS | 1686 | |2;audio-dft;183;185;184;186;184;183;182;186;183;184 09:36:13 INFO - PROCESS | 1686 | |3;audio-fft;70;78;66;148;56;56;61;64;132;61 09:36:13 INFO - PROCESS | 1686 | |4;audio-oscillator;95;94;180;114;117;88;124;79;77;126 09:36:13 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;90;87;90;86;90;86;90;88;90;86 09:36:13 INFO - PROCESS | 1686 | |6;imaging-darkroom;125;95;95;95;92;94;95;94;94;94 09:36:13 INFO - PROCESS | 1686 | |7;imaging-desaturate;86;90;127;86;86;83;86;85;85;85 09:36:13 INFO - PROCESS | 1686 | |8;json-parse-financial;61;65;64;64;64;65;66;80;61;58 09:36:13 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;66;69;70;73;78;74;71;68;68;69 09:36:13 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;90;103;105;104;80;80;81;80;81;84 09:36:13 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;129;138;147;147;116;139;150;145;164;159 09:36:13 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;165;159;164;166;161;151;142;189;171;167 09:36:13 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;66;67;67;68;68;65;71;69;68;65 09:36:13 INFO - PROCESS | 1686 | __end_tp_report 09:36:13 INFO - PROCESS | 1686 | __start_cc_report 09:36:13 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,2060 09:36:13 INFO - PROCESS | 1686 | __end_cc_report 09:36:13 INFO - PROCESS | 1686 | __startTimestamp1464107773375__endTimestamp 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | ------- Summary: start ------- 09:36:13 INFO - PROCESS | 1686 | Number of tests: 14 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.50 stddev:7.03 (6.1%) stddev-sans-first:7.43 09:36:13 INFO - PROCESS | 1686 | Values: 115.0 114.0 118.0 115.0 114.0 112.0 114.0 114.0 113.0 136.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:153.50 stddev:25.92 (16.9%) stddev-sans-first:27.48 09:36:13 INFO - PROCESS | 1686 | Values: 156.0 122.0 148.0 196.0 151.0 134.0 125.0 177.0 188.0 137.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:184.00 Median:184.00 stddev:1.33 (0.7%) stddev-sans-first:1.36 09:36:13 INFO - PROCESS | 1686 | Values: 183.0 185.0 184.0 186.0 184.0 183.0 182.0 186.0 183.0 184.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:79.20 Median:62.50 stddev:32.92 (52.7%) stddev-sans-first:34.74 09:36:13 INFO - PROCESS | 1686 | Values: 70.0 78.0 66.0 148.0 56.0 56.0 61.0 64.0 132.0 61.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:109.40 Median:78.00 stddev:30.60 (39.2%) stddev-sans-first:32.01 09:36:13 INFO - PROCESS | 1686 | Values: 95.0 94.0 180.0 114.0 117.0 88.0 124.0 79.0 77.0 126.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:90.00 stddev:1.89 (2.1%) stddev-sans-first:1.90 09:36:13 INFO - PROCESS | 1686 | Values: 90.0 87.0 90.0 86.0 90.0 86.0 90.0 88.0 90.0 86.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:97.30 Median:94.50 stddev:9.78 (10.3%) stddev-sans-first:0.97 09:36:13 INFO - PROCESS | 1686 | Values: 125.0 95.0 95.0 95.0 92.0 94.0 95.0 94.0 94.0 94.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:89.90 Median:86.00 stddev:13.15 (15.3%) stddev-sans-first:13.87 09:36:13 INFO - PROCESS | 1686 | Values: 86.0 90.0 127.0 86.0 86.0 83.0 86.0 85.0 85.0 85.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:64.80 Median:64.50 stddev:5.87 (9.1%) stddev-sans-first:6.06 09:36:13 INFO - PROCESS | 1686 | Values: 61.0 65.0 64.0 64.0 64.0 65.0 66.0 80.0 61.0 58.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:70.50 stddev:3.53 (5.0%) stddev-sans-first:3.33 09:36:13 INFO - PROCESS | 1686 | Values: 66.0 69.0 70.0 73.0 78.0 74.0 71.0 68.0 68.0 69.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:80.50 stddev:10.92 (13.6%) stddev-sans-first:11.58 09:36:13 INFO - PROCESS | 1686 | Values: 90.0 103.0 105.0 104.0 80.0 80.0 81.0 80.0 81.0 84.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:143.40 Median:147.00 stddev:13.93 (9.5%) stddev-sans-first:13.77 09:36:13 INFO - PROCESS | 1686 | Values: 129.0 138.0 147.0 147.0 116.0 139.0 150.0 145.0 164.0 159.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.50 Median:165.50 stddev:12.35 (7.5%) stddev-sans-first:13.09 09:36:13 INFO - PROCESS | 1686 | Values: 165.0 159.0 164.0 166.0 161.0 151.0 142.0 189.0 171.0 167.0 09:36:13 INFO - PROCESS | 1686 | 09:36:13 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.84 (2.7%) stddev-sans-first:1.88 09:36:13 INFO - PROCESS | 1686 | Values: 66.0 67.0 67.0 68.0 68.0 65.0 71.0 69.0 68.0 65.0 09:36:13 INFO - PROCESS | 1686 | -------- Summary: end -------- 09:36:13 INFO - PROCESS | 1686 | 09:36:14 INFO - TEST-INFO | 1686: exit 0 09:36:14 INFO - TEST-OK | kraken | took 58455ms 09:36:14 INFO - SUITE-END | took 1173s 09:36:14 INFO - Completed test suite (00:19:33) 09:36:14 INFO - javascript benchmark 09:36:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.748180145589, 3406.9318613627743, 3451.2579795344004, 3465.7573969822092, 3483.1292176955762, 4658.30125481178, 5597.552195824334, 5617.691026993513, 5627.043402621554, 5658.179563963223, 5658.7170641467965, 5677.40387259337, 5693.373728889819, 5706.9727449059155, 5743.425657434254, 5067.594592432597, 5097.5157360050825, 5100.2183960081775, 5193.948060519403, 5216.217567364888, 5157.329547158864, 5178.275041494194, 5181.9740901295445, 5183.844484665466, 5183.870403239912, 5148.74256287186, 5168.715720635363, 5174.689518628876, 5192.40287366952, 5207.765650545734, 5176.689398636075, 5204.141316682742, 5207.505286997729, 5219.243209734605, 5235.110031294651, 5052.974735126338, 5060.139776238011, 5075.467075957045, 5142.1772516397195, 5162.303089082963, 5527.944720552783, 5563.860903477405, 5567.944320556783, 5592.0, 5613.4386561343945, 6189.597676151081, 6223.719932602999, 6229.563930524865, 6235.00239961604, 6239.344868788802, 6096.963516202256, 6227.065940108974, 6249.0626406039, 6281.340459251758, 6326.905096423558, 4794.256890182028, 5755.827325180251, 5800.6809625471005, 5800.854978625526, 5832.066869300934, 6303.653299068504, 6306.24325080984, 6309.14826498426, 6310.684465776692, 6318.62088274705, 6015.849603759853, 6067.969660151759, 6108.358622344679, 6126.785562505291, 6135.202423684892, 6232.688365581703, 6233.34549872266, 6243.8126856194385, 6248.250209974832, 6266.749330026839, 6132.50939924805, 6195.96902015487, 6198.349173336825, 6199.287081985539, 6233.3143354231, 6044.758209671564, 6128.258691089271, 6133.1510485532735, 6232.532560058014, 6239.407256310643, 4831.227003679394, 4862.80548778052, 4872.001239745844, 4882.292067650171, 4883.4628190898975, 4419.226635338803, 4419.668524860648, 4421.38100665907, 4435.778211089432, 4481.327800829837, 5650.604457687922, 5658.405867383948, 5667.4615911488345, 5685.459881311269, 5693.4875861172695, 5876.853078673067, 6134.681512609229, 6229.283632382243, 6273.12176295319, 6286.779962701284, 5581.3302403711, 5655.7172141393585, 5662.660240385508, 5682.971585142048, 5686.943130568724, 4516.480604730498, 5297.231901374279, 5335.413104558495, 5350.919736203882, 5373.973130134403, 6156.415140561639, 6290.748370065146, 6310.7475700971445, 6330.4619107375465, 6364.809055728336, 6253.656048917172, 6273.498120150286, 6290.308066112815, 6299.30707622152, 6300.149479820349, 6301.684915754102, 6311.147995020613, 6318.304986451395, 6327.430531252209, 6355.90466142999, 5221.556167725786, 6099.146119543356, 6130.080487926846, 6135.416406698409, 6172.783952561639, 5150.768215430389, 6263.0, 6277.874442511215, 6302.054691796267, 6304.085907543289, 5818.389069147678, 6016.939830601638, 6038.094285857156, 6091.269047714302, 6137.294211165773, 5847.795327163529, 5923.674197919156, 5960.0, 6038.064100064497, 6073.817785466443, 5881.5588830838715, 5886.705664716661, 5892.204552385545, 5894.911576326444, 5906.940930590811, 6038.3357830637715, 6059.6364218147055, 6062.333143354139, 6088.208532890696, 6114.0828875669, 5785.566082543742, 5919.674417906885, 5938.435848594377, 5999.640021598718, 6022.247219097613], "value": 5646.664276989403, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.9655522391045, 535.5180337696073, 537.9515843574079, 550.4742970463205, 592.4135106244813, 30981.22546936325, 31643.683563164363, 31684.0, 31760.523592146135, 31806.522902156485, 32078.03765887027, 32585.022449326498, 32648.510272345935, 40053.39919901197, 40937.7953110234, 9486.810263794738, 9759.316847820655, 9763.463009534473, 9763.804723905554, 9769.560369783378, 9432.19826314764, 9617.759556011086, 9625.37435066722, 9632.181264592518, 9668.0, 8676.956615216946, 8873.378863479558, 9588.424694518351, 11459.942700286487, 11704.882951170506, 11343.602973895915, 11446.255993360446, 11469.770604587862, 11480.0, 11503.309801411942, 8578.056413794478, 11344.773104537946, 11475.311481311148, 11480.598179063776, 11481.253718508353, 40562.79718601418, 41143.38284925699, 41163.794181028905, 41164.1767164658, 41309.1738165238, 3246.3345014272254, 3307.702306792376, 3321.717653999413, 3452.671996160361, 3714.1086139326367, 27398.315042123908, 27694.861525692446, 27776.861115694493, 27909.441811163866, 28335.149945501667, 2286.325533967484, 2722.0, 2729.3586007288272, 2756.627855239537, 2798.468291024699, 299792.0, 300975.0, 301625.0, 301723.0, 302470.0, 36182.0, 36830.63169368326, 36876.07809804723, 36894.0, 58556.70721646365, 35612.46581301282, 35632.93101206968, 35848.462273065925, 35855.82072089623, 35887.82056089703, 14925.477608283658, 15095.39618415251, 15099.622509437133, 15119.622009449631, 15154.848451515565, 13134.671633209055, 13197.736045279233, 13205.141665791885, 13207.40566674502, 13263.270520121487, 7642.579658118746, 7840.215978402228, 8218.096009438957, 8223.301019413417, 8312.501249925024, 3386.4581666933664, 3441.625278557787, 3451.2752322012157, 3452.551168348149, 3486.16332080304, 961.4327546747453, 1021.6168936648756, 1048.2543104513904, 1119.2333251722596, 1128.7686024364984, 29982.884316944823, 30258.24354391114, 30316.54525182124, 30697.386052279275, 30797.230069248, 783.4124406694979, 1027.1885210683477, 1085.4225571802676, 1106.5186643809807, 1130.4008875296106, 14861.0, 14897.627559311104, 15022.023568468014, 15310.770338444932, 15337.386504539916, 628390.0, 732400.0, 734328.3283583547, 734893.0, 737291.0, 61369.386306136366, 61467.38532614616, 62377.37622623716, 62682.68658656678, 62727.6863615679, 1358.3276278242333, 1385.0443194196173, 1521.2773932382252, 1538.5768913548864, 1543.2592355669117, 29118.417631646826, 30406.0, 30664.56202046061, 31142.532862006636, 31953.68046319507, 45323.0, 55052.449475506335, 56121.0, 56182.71908640431, 56242.43757562536, 572320.0, 588629.0, 588671.0, 588862.0, 590637.0, 29233.561496577993, 34593.13478795984, 36159.45760813643, 43216.35175472433, 43575.0, 43365.91585210269, 44074.898127547065, 44794.88012799706, 44933.775331123135, 45183.0, 38240.0, 38886.4167037489, 39328.2134357317, 39771.60228397679, 39855.80432587027, 38379.424308634836, 38747.806260968515, 39152.608473916036, 39200.2159956805, 39657.80171099126, 37133.628663714095, 38177.618223818514, 38200.61799381971, 38735.41896871493, 39795.80102099471, 30998.925349261128, 31416.37167256688, 31746.682533175295, 33502.0, 34286.82856585701, 21447.678284826052, 24050.0, 24338.148164814145, 24856.0, 24881.87559062193], "value": 18522.442404252768, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [404.19766762975496, 404.76725882617495, 406.88200421877656, 407.6310938600566, 419.737663960025, 19682.0, 19930.800691993074, 20353.18587256512, 20378.0, 21215.151393944263, 20571.588568228628, 20948.581028379424, 21225.36323910285, 21680.566388672218, 21721.3483595492, 5585.3297604287545, 5637.577181711357, 5896.086106653475, 5898.085796701518, 5937.88124237515, 5701.600887937846, 5794.159846822208, 5803.158542011406, 5841.591088623777, 5859.824205273849, 5307.628466007381, 5880.794701986755, 6053.727582258809, 6064.666443345614, 6064.9393506065035, 5739.713014349266, 6004.939950600482, 6021.548383871205, 6023.728932198062, 6026.879462410771, 5766.682832444214, 5828.708564571797, 5838.649681019152, 5845.06478963368, 5845.210896528999, 13019.544315948991, 13644.454221831114, 13662.658433539142, 13670.589882303548, 13967.231802250872, 2080.5942841145984, 2123.766385697572, 2166.664167054109, 2242.1703969531377, 2397.5854245994947, 9769.951150244275, 10079.44563049032, 13478.460861565525, 13754.555771643933, 13794.793078103736, 1522.4975757999835, 1696.4401747423444, 1723.6638855423198, 1729.0922265810425, 1825.3884948542254, 96379.0, 112744.0, 119051.0, 120949.39525302318, 122500.0, 9454.669086581936, 11457.255278406878, 11877.643670689893, 11883.821742673867, 11948.342841143647, 15060.87043471744, 15199.0, 15210.923945380202, 15212.011219270711, 15216.923915380574, 7454.40364770817, 8802.25180859634, 8803.0, 8896.110388961182, 9001.859144793338, 8887.15572020657, 8966.86549701755, 9044.0, 9072.727818165466, 9134.086591340812, 4660.813567457264, 4711.104890070876, 4731.29030645406, 4742.95257047432, 4818.204996175662, 1933.265359163509, 1983.5537004174234, 1996.5008747813047, 2007.6988451732066, 2035.7862424445516, 888.2627419242014, 908.8503043649788, 930.6789157740569, 969.147150507563, 1022.9335093218917, 19286.06347210995, 20263.39209823678, 20404.48988775263, 21031.68452473214, 21586.78413215848, 958.6357184269933, 974.7660561465198, 1012.8959434216739, 1014.4572653630272, 1056.1392465140887, 9926.851097233548, 10952.069074128789, 10958.01377875995, 10958.397288149068, 10989.120870330355, 511812.0, 590884.0, 601686.0, 611491.0, 619601.0, 129133.35433322773, 130871.0, 131449.0, 131869.0, 138144.30927845498, 1017.5471914997903, 1020.6121673764071, 1036.3471013261596, 1038.1694644284541, 1038.9896101039044, 17788.46634600964, 17885.19516621729, 18096.00471974054, 18112.818871811112, 18495.890246585248, 50524.49475505198, 52814.73592632166, 54039.29642393603, 55925.0, 56644.433555665564, 77530.22469775456, 79687.0, 80726.59636701779, 81238.96902577246, 82204.76692849492, 7524.360429363565, 7937.523748575104, 8006.719764808437, 8326.375521836007, 8377.45546539473, 10475.58097676115, 12581.685457863627, 12671.113022088366, 12723.300218487702, 13078.542251021168, 10920.781584368428, 11155.553777848796, 11320.433978301216, 11341.376224307742, 11374.488148033524, 10475.94762026185, 10627.0, 10941.726456838644, 11091.112710982941, 11300.434978251218, 11205.439728013404, 11236.988671019648, 11353.54585816558, 11496.82754758696, 11662.475188616363, 8627.525486098073, 11191.384473854016, 11741.882581174079, 12173.756524869275, 12549.184303020274, 11321.150913681342, 11548.826767598324, 11717.06263498901, 11830.290182589073, 11884.702882428008], "value": 9628.210843067429, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.47516418925, 1568.2237292540194, 1576.9290381932813, 1828.679981003324, 1973.3192048743178, 7731.0336207974005, 8459.746207613774, 8643.611037503311, 8654.528730115895, 8670.913290867098, 8536.74389768308, 8569.65721371146, 8707.216350528452, 8718.433301835375, 8723.171298726627, 2138.230237114645, 2366.645003249509, 2436.634504824273, 2457.041753716052, 2458.7172475165316, 2437.9878100609562, 2438.1100898172126, 2442.853428794278, 2444.559979203743, 2445.2664200739796, 2346.1788374069024, 2382.9991403610525, 2391.426057746146, 2415.649730789043, 2415.9637605435933, 2117.862338947979, 2350.2831636350907, 2401.411654144737, 2409.325388891113, 2421.7336093029753, 2371.584972629783, 2413.1674572272536, 2415.0339864054345, 2420.3586049696846, 2430.2466235467064, 8535.615897284637, 8589.398742088057, 8619.698310559163, 8627.051024387312, 8630.65477380904, 5210.6613070150315, 5320.0, 5325.281087053237, 5353.250544923714, 5356.54469370104, 2877.2375320540073, 3267.6895694908944, 3335.199552107477, 3396.847141878621, 3435.604905435857, 1058.2327812336073, 1058.4231593781408, 1063.7500187455942, 1066.9413182274973, 1072.7050061233144, 11733.882661173364, 11751.236169648946, 11770.0, 11770.234934729215, 11771.882281177164, 4163.687723420756, 4563.520830312836, 4770.0, 4788.808447662124, 4824.517548245218, 9912.207023438108, 9916.652917147865, 9954.601815927283, 9958.850617240747, 9966.2525310602, 5320.494553017457, 5355.410904800468, 5363.704996225167, 5367.275417818622, 5382.434844341366, 4389.736615802998, 4474.865754027384, 4480.238359478832, 4488.304312831517, 4891.192953162759, 3698.2973235085246, 3947.684185265172, 4022.1699126897192, 4035.576264492186, 4040.5555388907196, 1283.7175821319295, 1331.760283149023, 1332.573576455525, 1341.7115320206203, 1341.7584834729842, 788.891607291756, 857.691231156783, 889.5063239901916, 906.1889608800087, 965.5220665770488, 4820.517948205152, 5584.692841893735, 5760.3951585083805, 5852.473277405054, 6075.210222671025, 1014.9949250253701, 1027.897210278981, 1055.1664185293532, 1057.4501259345252, 1058.2486434631344, 3733.626637336299, 3738.5513738351574, 3797.867074652374, 3900.6294402031763, 3908.7654740715648, 10251.026152515498, 10407.271490995709, 10718.571257149782, 10777.676669699922, 10811.83782243267, 10358.896411035945, 10659.4137322448, 10671.733206669895, 10689.572417103383, 10698.78602427947, 6598.967005165039, 8063.354931605458, 8065.677372905018, 8225.95887020561, 8380.287675547646, 5729.541636668973, 5755.8561035974435, 5804.1874137619925, 5847.0, 5938.377589670384, 9322.720318390458, 9366.625334986524, 9497.002814704356, 9526.618935242514, 9755.951220243853, 9702.6118955241, 9859.654912078044, 9903.950480247553, 9966.850497242402, 10018.849717254392, 7015.824604384931, 7410.888836667346, 7412.703491860265, 7413.0, 7426.257374262746, 7405.481616287024, 7550.622468876424, 7551.848963020819, 7564.016677831846, 7566.167721550515, 6856.0, 7477.775666730006, 7546.547207167588, 7590.734324298623, 7667.578283194255, 6276.497880169667, 7419.443541734284, 7428.405727541892, 7570.810729731801, 7666.9616651916385, 6792.592444453348, 6813.557118787263, 6862.0, 6869.519133660796, 6880.415164711056, 4872.123017856819, 4937.530934561211, 4954.777035033504, 5006.098902197639, 5038.319826823332, 5044.319016932667, 5056.191009438619, 5124.077666020003, 5180.041692286939, 5298.682079075268], "value": 4557.474377498328, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.31446973892315, 317.81725507832994, 326.1324875830292, 326.9231730543321, 331.97842140260883, 6513.934860651392, 6527.445167160791, 6549.999999999994, 6561.179852518435, 6569.605823650584, 6227.999999999989, 6444.355564443554, 6450.0, 6455.451286640641, 6497.772577959772, 3882.3400021996326, 4691.225947718623, 4741.454732705749, 4744.691595046328, 4843.709377437365, 4748.240281554952, 4750.762461876892, 4778.426588809348, 4787.1143838389835, 4833.661643684944, 4685.1566717990745, 4769.332293478916, 4773.403324584427, 4780.569748722614, 4784.497627749089, 4561.7262964222255, 4640.257558790578, 4685.8594242172785, 4762.952370476303, 4775.856724298276, 4007.2786898358286, 4036.858709945167, 4064.695147863922, 4067.064575147703, 4103.753774773493, 5208.374995000624, 6146.907796383058, 6207.565470417073, 6236.781712640036, 6236.781712640081, 5642.463965923231, 5700.287464066992, 5701.372848986608, 5722.113072473773, 5724.0, 6917.550359226634, 6957.130358705162, 6965.721371145148, 6967.0, 6987.1266091738535, 4516.841910533149, 4663.533646635343, 4671.369365135697, 4682.7658617069355, 4702.670813043088, 7268.927310726878, 7276.927230727678, 7357.632118394059, 7368.594727289997, 7406.444516661218, 4812.470628230892, 4814.831480898222, 4828.734419606955, 4831.0096430231715, 4836.984233311044, 4887.34020907173, 4895.388076490439, 4898.510148985144, 4928.778204980785, 4929.457759646436, 5777.24895763548, 5781.53747700183, 5788.82633520995, 5789.218455508537, 5802.33273173582, 6841.247462779091, 6924.965375173092, 6949.652517374111, 7034.859302813915, 7059.258777828357, 1561.7345051341072, 1677.8909370890854, 1700.9029176181293, 1702.174445393983, 1703.3271857616173, 1073.4739977411011, 1077.8383242513528, 1100.3287994323457, 1101.3446999035561, 1108.8503052088026, 6296.779612713624, 6474.482041436674, 6498.837529061811, 6511.576747511492, 6532.771353002622, 7108.0, 7209.315115064165, 7258.129024517092, 7309.89035164473, 7318.926810731825, 5252.97566974444, 5308.362996440452, 5492.47821456961, 6553.099601115623, 6573.83565410859, 3793.8103094845146, 3848.692104631679, 3863.8840834775, 3872.0, 3876.7673939563715, 7350.117985841734, 7660.272274134059, 7675.577843218676, 7679.0, 7683.193264707125, 7550.169481357047, 7555.584442855696, 7572.734954276574, 7573.962130189314, 7577.810554736176, 7482.176960534337, 7616.124145723202, 7629.847403052019, 7639.0, 7640.541567505968, 6750.1562304711915, 7194.028806111317, 7327.74352897646, 7346.5592064476305, 7350.0, 6940.583564986117, 7168.569885806868, 7222.422206223595, 7239.167495738057, 7240.239774823568, 6038.275406951194, 7575.0, 7577.393808495417, 7614.048243969504, 7617.542947423172, 4784.808607655794, 4802.8799280018275, 4821.758912054313, 4825.879353016203, 4831.154547954024, 5896.587238893237, 6585.604863708193, 7218.89171662436, 7249.565026098451, 7274.854502910018, 6675.399214070757, 7040.964795175992, 7046.753363632249, 7046.964765176142, 7058.223595404604, 6820.113385259884, 6867.141607299088, 6887.827804304932, 6891.827704307432, 6934.653267336714, 6735.383507958153, 6815.795526134224, 6816.625085620339, 6824.897626535705, 6833.624150671761, 7015.473839461959, 7023.192332881784, 7030.753923612649, 7036.577805331492, 7060.223375428802, 6640.0411878496925, 6884.277150899284, 6887.276835932155, 6887.345702158287, 6923.169219693669], "value": 5119.473874342457, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [637.9043143528468, 680.0, 740.3965767899161, 747.8990336304604, 755.848830233953, 14836.0, 15130.470433534829, 15160.469383571564, 15235.46675866345, 16888.408905688302, 15868.127253001108, 15924.601884952883, 15950.840491595081, 15996.360145594192, 15998.440054598062, 727.3817255332978, 908.2960705453261, 941.0730430525927, 944.1078181118845, 945.1588086602928, 738.8743913534685, 906.7098528470892, 936.1434287626847, 937.1331518345537, 937.1612406895824, 975.639013564982, 979.7746518300795, 982.1504398695113, 984.1142971325828, 984.8227319082562, 759.111839148198, 949.9952500237524, 975.3416443900375, 977.0571398600349, 977.1205914676776, 723.8045727653504, 723.8552289542113, 724.3299947548496, 725.3508110241357, 725.640807800137, 15090.54728358151, 15190.696186076328, 15211.543653690407, 15220.619484512865, 15226.695466090727, 12429.31638759868, 12469.501219951191, 12589.496420143183, 12619.936900315532, 12788.104832661717, 10083.243756718202, 10178.33840800353, 10252.589896404135, 10467.05796478313, 10563.996420340056, 3476.148343655795, 3490.092575930251, 3500.194955160302, 3533.805640689761, 3734.4958430612064, 71827.2817271831, 79504.60247698783, 83608.74586881201, 84064.31871362538, 84097.15902841015, 9268.72193834186, 9408.153266206074, 9464.526773661428, 9523.90476095244, 9583.952080239693, 21383.46541336479, 21583.46041348979, 21623.24318648871, 21636.0, 21684.132634694437, 7510.436717246228, 7598.0, 7608.125065617414, 7872.606369681493, 7899.486533375312, 4152.91694166115, 4153.563875793058, 4156.771377574262, 4166.8749937501925, 4245.172191422643, 3605.0446631642635, 3641.235340578455, 3659.2315613721416, 3664.95548768605, 3695.0392897846755, 631.311870061635, 642.5470043619238, 643.0932385336746, 653.3401264722569, 660.8843452395812, 546.0635010956149, 614.9815505534841, 616.6454288783967, 620.2928661326092, 629.4901130084646, 7748.690052397841, 8003.279704826594, 8243.83512329762, 8264.21489958453, 8321.334293256525, 580.4427749360576, 614.4132353602299, 614.7756069034859, 618.848382146377, 621.4313902778997, 3728.496652951871, 3736.252749450121, 3854.9807250964127, 3905.3165696003084, 3920.7843568604, 90078.54960725154, 90129.0, 91395.5430222858, 92036.0, 93266.53366733123, 76170.23829761743, 78500.60749696329, 79534.0, 80464.59767701125, 81074.59462702766, 67776.322236777, 67938.66030669815, 68677.0, 69488.3051169492, 71184.64407678031, 7577.810554736176, 7580.620968951641, 7741.961290193513, 7785.688572457038, 7994.92005079965, 48657.02685946332, 48780.78048048827, 50002.49997500124, 50499.0, 50796.23805642845, 61560.69219653873, 63992.0, 64981.35018649753, 67853.3214667847, 70143.64928175327, 10699.465026748787, 10815.188860835311, 10916.181286403393, 11076.280041797258, 11440.94279528597, 11785.175037747618, 11837.112216583619, 11915.0, 12071.155019148575, 12476.0, 11239.831402528805, 11519.0, 11529.769404612029, 11564.306141631529, 11927.821082683593, 11317.0, 11407.030402415887, 11431.371274579978, 11742.0, 12058.75882482363, 10865.674029779118, 10917.672469825919, 11023.393713345522, 11287.0, 11581.363025033705, 9185.697624577577, 9507.66723164686, 9666.903330966601, 9799.804003919739, 9861.633965745115, 9389.248860091313, 9407.106324899123, 9683.979115141467, 9717.6598819041, 9875.0], "value": 6958.168392694452, "unit": "score"}], "name": "dromaeo_css", "value": 7394.678910601002, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 118.0, 115.0, 114.0, 112.0, 114.0, 114.0, 113.0, 136.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 148.0, 196.0, 151.0, 134.0, 125.0, 177.0, 188.0, 137.0], "value": 153.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 184.0, 186.0, 184.0, 183.0, 182.0, 186.0, 183.0, 184.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 66.0, 148.0, 56.0, 56.0, 61.0, 64.0, 132.0, 61.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 180.0, 114.0, 117.0, 88.0, 124.0, 79.0, 77.0, 126.0], "value": 109.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 86.0, 90.0, 86.0, 90.0, 88.0, 90.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [125.0, 95.0, 95.0, 95.0, 92.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 97.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 127.0, 86.0, 86.0, 83.0, 86.0, 85.0, 85.0, 85.0], "value": 89.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 64.0, 64.0, 65.0, 66.0, 80.0, 61.0, 58.0], "value": 64.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 70.0, 73.0, 78.0, 74.0, 71.0, 68.0, 68.0, 69.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 103.0, 105.0, 104.0, 80.0, 80.0, 81.0, 80.0, 81.0, 84.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 138.0, 147.0, 147.0, 116.0, 139.0, 150.0, 145.0, 164.0, 159.0], "value": 143.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 159.0, 164.0, 166.0, 161.0, 151.0, 142.0, 189.0, 171.0, 167.0], "value": 163.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 68.0, 68.0, 65.0, 71.0, 69.0, 68.0, 65.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1516.5, "alertThreshold": 2.0}]} 09:36:14 INFO - Return code: 0 09:36:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 09:36:14 ERROR - # TBPL SUCCESS # 09:36:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:14 INFO - 2016-05-24 09:36:14.712 screenresolution[1690:9739] starting screenresolution argv=screenresolution get 09:36:14 INFO - 2016-05-24 09:36:14.714 screenresolution[1690:9739] Display 0: 1600x1200x32@60 09:36:14 INFO - 2016-05-24 09:36:14.720 screenresolution[1691:9742] starting screenresolution argv=screenresolution list 09:36:14 INFO - Available Modes on Display 0 09:36:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:14 INFO - Intel Iris: 09:36:14 INFO - Chipset Model: Intel Iris 09:36:14 INFO - Type: GPU 09:36:14 INFO - Bus: Built-In 09:36:14 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:14 INFO - Vendor: Intel (0x8086) 09:36:14 INFO - Device ID: 0x0a2e 09:36:14 INFO - Revision ID: 0x0009 09:36:14 INFO - Displays: 09:36:14 INFO - Display: 09:36:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:14 INFO - Display Serial Number: 1600x1200 60 09:36:14 INFO - Main Display: Yes 09:36:14 INFO - Mirror: Off 09:36:14 INFO - Online: Yes 09:36:14 INFO - Rotation: Supported 09:36:14 INFO - Return code: 0 09:36:14 INFO - Running post-action listener: _resource_record_post_action 09:36:14 INFO - Running post-run listener: _resource_record_post_run 09:36:16 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 36435456; Write bytes: 652179968; Read time: 236; Write time: 1838 09:36:16 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 351105536; Write bytes: 346171392; Read time: 27577; Write time: 729 09:36:16 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34620928; Write bytes: 295215616; Read time: 220; Write time: 1073 09:36:16 INFO - Running post-run listener: _upload_blobber_files 09:36:16 INFO - Blob upload gear active. 09:36:16 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:36:16 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 09:36:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:36:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:36:16 INFO - (blobuploader) - INFO - Open directory for files ... 09:36:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:36:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:36:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:17 INFO - (blobuploader) - INFO - Done attempting. 09:36:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:36:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:36:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:36:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:36:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:36:18 INFO - (blobuploader) - INFO - Done attempting. 09:36:18 INFO - (blobuploader) - INFO - Iteration through files over. 09:36:18 INFO - Return code: 0 09:36:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:36:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:36:18 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cce9e5662bcde15188c5025783a9a93c5f4bc67104e666826185ed0b79a6c5dd4104a79ec22b9be28267f1404e449b7d896b65fb61aef55a82ec78eed5e408f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70c0f12aab420e8180d6ebc37825f62fd65dac64dbacc7a3bb1648f43993f703f07feeea136a8c8fc3ac2feb1b2b5a0eeff1f63f86fbe794f3508c7f81e40214"} 09:36:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:36:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:36:18 INFO - Contents: 09:36:18 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cce9e5662bcde15188c5025783a9a93c5f4bc67104e666826185ed0b79a6c5dd4104a79ec22b9be28267f1404e449b7d896b65fb61aef55a82ec78eed5e408f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70c0f12aab420e8180d6ebc37825f62fd65dac64dbacc7a3bb1648f43993f703f07feeea136a8c8fc3ac2feb1b2b5a0eeff1f63f86fbe794f3508c7f81e40214"} 09:36:18 INFO - Running post-run listener: copy_logs_to_upload_dir 09:36:18 INFO - Copying logs to upload dir... 09:36:18 INFO - mkdir: /builds/slave/test/build/upload/logs 09:36:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.887837 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-24 09:36:19.311454) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 09:36:19.315348) ========= 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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cce9e5662bcde15188c5025783a9a93c5f4bc67104e666826185ed0b79a6c5dd4104a79ec22b9be28267f1404e449b7d896b65fb61aef55a82ec78eed5e408f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70c0f12aab420e8180d6ebc37825f62fd65dac64dbacc7a3bb1648f43993f703f07feeea136a8c8fc3ac2feb1b2b5a0eeff1f63f86fbe794f3508c7f81e40214"} build_url:https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/UptUVdsnRKy8F5iDZqTtMA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5cce9e5662bcde15188c5025783a9a93c5f4bc67104e666826185ed0b79a6c5dd4104a79ec22b9be28267f1404e449b7d896b65fb61aef55a82ec78eed5e408f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/70c0f12aab420e8180d6ebc37825f62fd65dac64dbacc7a3bb1648f43993f703f07feeea136a8c8fc3ac2feb1b2b5a0eeff1f63f86fbe794f3508c7f81e40214"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-24 09:36:19.344729) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:36:19.345067) ========= 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.Ox0CMDfnGy/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.98TG3ItX0L/Listeners TMPDIR=/var/folders/vc/_7zfnphs2611zn3vnbmsqk3c00000w/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.005133 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-24 09:36:19.392689) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 09:36:19.393018) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-24 09:36:19.393347) ========= ========= Total master_lag: 0.17 =========