builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0353 starttime: 1463391992.48 results: success (0) buildid: 20160516004009 builduid: fd5caa0c704f4344a63b32d981388128 revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.482548) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.482969) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.483239) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.502291) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.502600) ========= 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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-16 02:46:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-05-16 02:46:32 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.120900 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.640390) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.640711) ========= 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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.024820 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.689120) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:32.689505) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --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 f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-16 02:46:32,768 truncating revision to first 12 chars 2016-05-16 02:46:32,768 Setting DEBUG logging. 2016-05-16 02:46:32,768 attempt 1/10 2016-05-16 02:46:32,768 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f00f9e754ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-16 02:46:33,437 unpacking tar archive at: mozilla-aurora-f00f9e754ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.912955 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:33.618778) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:33.619096) ========= script_repo_revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:33.619485) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:33.619772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-16 02:46:33.633428) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-16 02:46:33.633708) ========= /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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:46:33 INFO - MultiFileLogger online at 20160516 02:46:33 in /builds/slave/test 02:46:33 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 02:46:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:46:33 INFO - {'append_to_log': False, 02:46:33 INFO - 'base_work_dir': '/builds/slave/test', 02:46:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:46:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:46:33 INFO - 'branch': 'Mozilla-Aurora', 02:46:33 INFO - 'buildbot_json_path': 'buildprops.json', 02:46:33 INFO - 'config_files': ('talos/mac_config.py',), 02:46:33 INFO - 'default_actions': ('clobber', 02:46:33 INFO - 'read-buildbot-config', 02:46:33 INFO - 'download-and-extract', 02:46:33 INFO - 'populate-webroot', 02:46:33 INFO - 'create-virtualenv', 02:46:33 INFO - 'install', 02:46:33 INFO - 'run-tests'), 02:46:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:46:33 INFO - 'download_minidump_stackwalk': True, 02:46:33 INFO - 'download_symbols': 'ondemand', 02:46:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:46:33 INFO - 'tooltool.py': '/tools/tooltool.py', 02:46:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:46:33 INFO - '/tools/misc-python/virtualenv.py')}, 02:46:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:46:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:46:33 INFO - 'installer_path': 'installer.exe', 02:46:33 INFO - 'log_level': 'info', 02:46:33 INFO - 'log_name': 'talos', 02:46:33 INFO - 'log_to_console': True, 02:46:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:46:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:46:33 INFO - 'opt_config_files': (), 02:46:33 INFO - 'pip_index': False, 02:46:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:33 INFO - 'cmd': ('bash', 02:46:33 INFO - '-c', 02:46:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:33 INFO - 'enabled': True, 02:46:33 INFO - 'halt_on_failure': False, 02:46:33 INFO - 'name': 'check_screen_resolution'},), 02:46:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:33 INFO - 'cmd': ('bash', 02:46:33 INFO - '-c', 02:46:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:33 INFO - 'enabled': True, 02:46:33 INFO - 'halt_on_failure': False, 02:46:33 INFO - 'name': 'check_screen_resolution'},), 02:46:33 INFO - 'run_cmd_checks_enabled': True, 02:46:33 INFO - 'sps_profile': False, 02:46:33 INFO - 'sps_profile_interval': 0, 02:46:33 INFO - 'suite': 'dromaeojs', 02:46:33 INFO - 'system_bits': '32', 02:46:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:46:33 INFO - 'title': 't-yosemite-r7-0353', 02:46:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:46:33 INFO - 'use_talos_json': True, 02:46:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:46:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:46:33 INFO - 'work_dir': 'build'} 02:46:33 INFO - ##### 02:46:33 INFO - ##### Running clobber step. 02:46:33 INFO - ##### 02:46:33 INFO - Running pre-action listener: _resource_record_pre_action 02:46:33 INFO - Running main action method: clobber 02:46:33 INFO - rmtree: /builds/slave/test/build 02:46:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:46:35 INFO - Running post-action listener: _resource_record_post_action 02:46:35 INFO - ##### 02:46:35 INFO - ##### Running read-buildbot-config step. 02:46:35 INFO - ##### 02:46:35 INFO - Running pre-action listener: _resource_record_pre_action 02:46:35 INFO - Running main action method: read_buildbot_config 02:46:35 INFO - Using buildbot properties: 02:46:35 INFO - { 02:46:35 INFO - "project": "", 02:46:35 INFO - "product": "firefox", 02:46:35 INFO - "script_repo_revision": "production", 02:46:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:46:35 INFO - "repository": "", 02:46:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:46:35 INFO - "buildid": "20160516004009", 02:46:35 INFO - "stage_platform": "macosx64", 02:46:35 INFO - "basedir": "/builds/slave/test", 02:46:35 INFO - "nightly_build": "True", 02:46:35 INFO - "buildnumber": 234, 02:46:35 INFO - "slavename": "t-yosemite-r7-0353", 02:46:35 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 02:46:35 INFO - "platform": "yosemite_r7", 02:46:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:46:35 INFO - "branch": "mozilla-aurora", 02:46:35 INFO - "repo_path": "releases/mozilla-aurora", 02:46:35 INFO - "revision": "f00f9e754ce71fc5efa3e4334afea5cf6ad6c215", 02:46:35 INFO - "pgo_build": "False", 02:46:35 INFO - "builduid": "fd5caa0c704f4344a63b32d981388128", 02:46:35 INFO - "slavebuilddir": "test" 02:46:35 INFO - } 02:46:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:46:35 INFO - Running post-action listener: _resource_record_post_action 02:46:35 INFO - ##### 02:46:35 INFO - ##### Running download-and-extract step. 02:46:35 INFO - ##### 02:46:35 INFO - Running pre-action listener: _resource_record_pre_action 02:46:35 INFO - Running main action method: download_and_extract 02:46:35 INFO - mkdir: /builds/slave/test/build/tests 02:46:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:35 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:46:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:46:54 INFO - Downloaded 1199 bytes. 02:46:54 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:54 INFO - Using the following test package requirements: 02:46:54 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:54 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:46:54 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:46:54 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'jsshell-mac.zip'], 02:46:54 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:46:54 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:54 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:46:54 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:46:54 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:46:54 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:54 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:46:54 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:54 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:46:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:46:57 INFO - Downloaded 18102284 bytes. 02:46:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:46:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:00 INFO - Return code: 0 02:47:00 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:47:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:00 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:47:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:47:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:47:02 INFO - Downloaded 18102284 bytes. 02:47:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:47:05 INFO - Return code: 0 02:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:05 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:47:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:47:07 INFO - Downloaded 10840145 bytes. 02:47:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:47:07 INFO - Return code: 0 02:47:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:07 INFO - https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:47:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:47:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:47:13 INFO - Downloaded 114148849 bytes. 02:47:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:13 INFO - mkdir: /builds/slave/test/properties 02:47:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:47:13 INFO - Writing to file /builds/slave/test/properties/build_url 02:47:13 INFO - Contents: 02:47:13 INFO - build_url:https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:14 INFO - Running post-action listener: _resource_record_post_action 02:47:14 INFO - Running post-action listener: set_extra_try_arguments 02:47:14 INFO - ##### 02:47:14 INFO - ##### Running populate-webroot step. 02:47:14 INFO - ##### 02:47:14 INFO - Running pre-action listener: _resource_record_pre_action 02:47:14 INFO - Running main action method: populate_webroot 02:47:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:47:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:47:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:47:14 INFO - '--tppagecycles', 02:47:14 INFO - '7'], 02:47:14 INFO - 'tests': ['tsvgm']}}, 02:47:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:47:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:47:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:47:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:47:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['damp', 'tps']}, 02:47:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'tests': ['damp', 'tps']}, 02:47:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['dromaeo_dom']}, 02:47:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:47:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'other-e10s': {'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['a11yr', 02:47:14 INFO - 'ts_paint', 02:47:14 INFO - 'tpaint', 02:47:14 INFO - 'sessionrestore', 02:47:14 INFO - 'sessionrestore_no_auto_restore', 02:47:14 INFO - 'tabpaint']}, 02:47:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['tsvgx', 02:47:14 INFO - 'tsvgr_opacity', 02:47:14 INFO - 'tart', 02:47:14 INFO - 'tscrollx', 02:47:14 INFO - 'cart']}, 02:47:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:47:14 INFO - 'tsvgr_opacity', 02:47:14 INFO - 'tart', 02:47:14 INFO - 'tscrollx', 02:47:14 INFO - 'cart']}, 02:47:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'talos_options': ['--disable-e10s'], 02:47:14 INFO - 'tests': ['tp5o']}, 02:47:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'tests': ['tp5o']}, 02:47:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'talos_options': ['--disable-e10s', 02:47:14 INFO - '--xperf_path', 02:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:14 INFO - 'tests': ['tp5n']}, 02:47:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:14 INFO - 'talos_options': ['--xperf_path', 02:47:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:14 INFO - 'tests': ['tp5n']}}, 02:47:14 INFO - 'talos.zip': {'path': '', 02:47:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:47:14 INFO - Running post-action listener: _resource_record_post_action 02:47:14 INFO - ##### 02:47:14 INFO - ##### Running create-virtualenv step. 02:47:14 INFO - ##### 02:47:14 INFO - Running pre-action listener: _resource_record_pre_action 02:47:14 INFO - Running main action method: create_virtualenv 02:47:14 INFO - Creating virtualenv /builds/slave/test/build/venv 02:47:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:47:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:47:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:47:14 INFO - Using real prefix '/tools/python27' 02:47:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:47:15 INFO - Installing distribute.............................................................................................................................................................................................done. 02:47:17 INFO - Installing pip.................done. 02:47:17 INFO - Return code: 0 02:47:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:47: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')]} 02:47:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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')]} 02:47:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:17 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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:47:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:47:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:17 INFO - 'HOME': '/Users/cltbld', 02:47:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:17 INFO - 'LOGNAME': 'cltbld', 02:47:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:17 INFO - 'MOZ_NO_REMOTE': '1', 02:47:17 INFO - 'NO_EM_RESTART': '1', 02:47:17 INFO - 'PAGER': '/bin/cat', 02:47:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:17 INFO - 'PWD': '/builds/slave/test', 02:47:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:17 INFO - 'SHELL': '/bin/bash', 02:47:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:17 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:17 INFO - 'USER': 'cltbld', 02:47:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:17 INFO - 'XPC_FLAGS': '0x0', 02:47:17 INFO - 'XPC_SERVICE_NAME': '0', 02:47:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:18 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:47:18 INFO - Cleaning up... 02:47:18 INFO - Return code: 0 02:47:18 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:47:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:47:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:18 INFO - 'HOME': '/Users/cltbld', 02:47:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:18 INFO - 'LOGNAME': 'cltbld', 02:47:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:18 INFO - 'MOZ_NO_REMOTE': '1', 02:47:18 INFO - 'NO_EM_RESTART': '1', 02:47:18 INFO - 'PAGER': '/bin/cat', 02:47:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:18 INFO - 'PWD': '/builds/slave/test', 02:47:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:18 INFO - 'SHELL': '/bin/bash', 02:47:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:18 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:18 INFO - 'USER': 'cltbld', 02:47:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:18 INFO - 'XPC_FLAGS': '0x0', 02:47:18 INFO - 'XPC_SERVICE_NAME': '0', 02:47:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:18 INFO - Downloading/unpacking psutil>=0.7.1 02:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:47:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:21 INFO - Installing collected packages: psutil 02:47:21 INFO - Running setup.py install for psutil 02:47:21 INFO - building 'psutil._psutil_osx' extension 02:47:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:47:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:47:22 INFO - building 'psutil._psutil_posix' extension 02:47:22 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:47:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:47:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:47:22 INFO - ^ 02:47:22 INFO - 1 warning generated. 02:47:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:47:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:22 INFO - Successfully installed psutil 02:47:22 INFO - Cleaning up... 02:47:22 INFO - Return code: 0 02:47:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:47:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:47:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:22 INFO - 'HOME': '/Users/cltbld', 02:47:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:22 INFO - 'LOGNAME': 'cltbld', 02:47:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:22 INFO - 'MOZ_NO_REMOTE': '1', 02:47:22 INFO - 'NO_EM_RESTART': '1', 02:47:22 INFO - 'PAGER': '/bin/cat', 02:47:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:22 INFO - 'PWD': '/builds/slave/test', 02:47:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:22 INFO - 'SHELL': '/bin/bash', 02:47:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:22 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:22 INFO - 'USER': 'cltbld', 02:47:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:22 INFO - 'XPC_FLAGS': '0x0', 02:47:22 INFO - 'XPC_SERVICE_NAME': '0', 02:47:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:25 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:47:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:47:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:47:25 INFO - Installing collected packages: mozsystemmonitor 02:47:25 INFO - Running setup.py install for mozsystemmonitor 02:47:25 INFO - Successfully installed mozsystemmonitor 02:47:25 INFO - Cleaning up... 02:47:25 INFO - Return code: 0 02:47:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:25 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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:25 INFO - 'HOME': '/Users/cltbld', 02:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:25 INFO - 'LOGNAME': 'cltbld', 02:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:25 INFO - 'MOZ_NO_REMOTE': '1', 02:47:25 INFO - 'NO_EM_RESTART': '1', 02:47:25 INFO - 'PAGER': '/bin/cat', 02:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:25 INFO - 'PWD': '/builds/slave/test', 02:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:25 INFO - 'SHELL': '/bin/bash', 02:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:25 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:25 INFO - 'USER': 'cltbld', 02:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:25 INFO - 'XPC_FLAGS': '0x0', 02:47:25 INFO - 'XPC_SERVICE_NAME': '0', 02:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:25 INFO - Downloading/unpacking blobuploader==1.2.4 02:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:28 INFO - Downloading blobuploader-1.2.4.tar.gz 02:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:47:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:47:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:29 INFO - Downloading docopt-0.6.1.tar.gz 02:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:47:29 INFO - Installing collected packages: blobuploader, requests, docopt 02:47:29 INFO - Running setup.py install for blobuploader 02:47:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:47:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:47:29 INFO - Running setup.py install for requests 02:47:30 INFO - Running setup.py install for docopt 02:47:30 INFO - Successfully installed blobuploader requests docopt 02:47:30 INFO - Cleaning up... 02:47:30 INFO - Return code: 0 02:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:30 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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:30 INFO - 'HOME': '/Users/cltbld', 02:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:30 INFO - 'LOGNAME': 'cltbld', 02:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:30 INFO - 'MOZ_NO_REMOTE': '1', 02:47:30 INFO - 'NO_EM_RESTART': '1', 02:47:30 INFO - 'PAGER': '/bin/cat', 02:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:30 INFO - 'PWD': '/builds/slave/test', 02:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:30 INFO - 'SHELL': '/bin/bash', 02:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:30 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:30 INFO - 'USER': 'cltbld', 02:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:30 INFO - 'XPC_FLAGS': '0x0', 02:47:30 INFO - 'XPC_SERVICE_NAME': '0', 02:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-EQYHAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-wF_w7K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-rDfm3t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-TfJYc0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-QB1dWM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-u1Smqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-orOZ6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-4h8Emf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:31 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-oiruki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-6UJmfB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-MnaLPq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-wc25sL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-94sXaB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-f9K5La-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SP5z8J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SYfOIF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:32 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-fwuwOJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:47:32 INFO - Running setup.py install for manifestparser 02:47:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:47:32 INFO - Running setup.py install for mozcrash 02:47:33 INFO - Running setup.py install for mozdebug 02:47:33 INFO - Running setup.py install for mozdevice 02:47:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Running setup.py install for mozfile 02:47:33 INFO - Running setup.py install for mozhttpd 02:47:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Running setup.py install for mozinfo 02:47:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Running setup.py install for mozInstall 02:47:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:47:33 INFO - Running setup.py install for mozleak 02:47:34 INFO - Running setup.py install for mozlog 02:47:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for moznetwork 02:47:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for mozprocess 02:47:34 INFO - Running setup.py install for mozprofile 02:47:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for mozrunner 02:47:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:47:34 INFO - Running setup.py install for mozscreenshot 02:47:35 INFO - Running setup.py install for moztest 02:47:35 INFO - Running setup.py install for mozversion 02:47:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:47:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:47:35 INFO - Cleaning up... 02:47:35 INFO - Return code: 0 02:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:35 INFO - 'HOME': '/Users/cltbld', 02:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:35 INFO - 'LOGNAME': 'cltbld', 02:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:35 INFO - 'MOZ_NO_REMOTE': '1', 02:47:35 INFO - 'NO_EM_RESTART': '1', 02:47:35 INFO - 'PAGER': '/bin/cat', 02:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:35 INFO - 'PWD': '/builds/slave/test', 02:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:35 INFO - 'SHELL': '/bin/bash', 02:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:35 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:35 INFO - 'USER': 'cltbld', 02:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:35 INFO - 'XPC_FLAGS': '0x0', 02:47:35 INFO - 'XPC_SERVICE_NAME': '0', 02:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-uJYmlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ReVehh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-aDciYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-iss6Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-vdrj9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jUxG6b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ePfrJM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-FQU7wL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:36 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-9rJaTO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Cem6j7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-jUZJUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-JuBJHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SwnmEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ZagQzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Ae3Nmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-p4eFmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-SfkLYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:41 INFO - Downloading blessings-1.6.tar.gz 02:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:47:41 INFO - Installing collected packages: blessings 02:47:41 INFO - Running setup.py install for blessings 02:47:41 INFO - Successfully installed blessings 02:47:41 INFO - Cleaning up... 02:47:41 INFO - Return code: 0 02:47:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:41 INFO - Reading from file tmpfile_stdout 02:47:41 INFO - Current package versions: 02:47:41 INFO - blessings == 1.6 02:47:41 INFO - blobuploader == 1.2.4 02:47:41 INFO - docopt == 0.6.1 02:47:41 INFO - manifestparser == 1.1 02:47:41 INFO - mozInstall == 1.12 02:47:41 INFO - mozcrash == 0.17 02:47:41 INFO - mozdebug == 0.1 02:47:41 INFO - mozdevice == 0.48 02:47:41 INFO - mozfile == 1.2 02:47:41 INFO - mozhttpd == 0.7 02:47:41 INFO - mozinfo == 0.9 02:47:41 INFO - mozleak == 0.1 02:47:41 INFO - mozlog == 3.1 02:47:41 INFO - moznetwork == 0.27 02:47:41 INFO - mozprocess == 0.22 02:47:41 INFO - mozprofile == 0.28 02:47:41 INFO - mozrunner == 6.11 02:47:41 INFO - mozscreenshot == 0.1 02:47:41 INFO - mozsystemmonitor == 0.0 02:47:41 INFO - moztest == 0.7 02:47:41 INFO - mozversion == 1.4 02:47:41 INFO - psutil == 3.1.1 02:47:41 INFO - requests == 1.2.3 02:47:41 INFO - wsgiref == 0.1.2 02:47:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:41 INFO - 'HOME': '/Users/cltbld', 02:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:41 INFO - 'LOGNAME': 'cltbld', 02:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:41 INFO - 'MOZ_NO_REMOTE': '1', 02:47:41 INFO - 'NO_EM_RESTART': '1', 02:47:41 INFO - 'PAGER': '/bin/cat', 02:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:41 INFO - 'PWD': '/builds/slave/test', 02:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:41 INFO - 'SHELL': '/bin/bash', 02:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:41 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:41 INFO - 'USER': 'cltbld', 02:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:41 INFO - 'XPC_FLAGS': '0x0', 02:47:41 INFO - 'XPC_SERVICE_NAME': '0', 02:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:47:42 INFO - Cleaning up... 02:47:42 INFO - Return code: 0 02:47:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:42 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 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:42 INFO - 'HOME': '/Users/cltbld', 02:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:42 INFO - 'LOGNAME': 'cltbld', 02:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:42 INFO - 'MOZ_NO_REMOTE': '1', 02:47:42 INFO - 'NO_EM_RESTART': '1', 02:47:42 INFO - 'PAGER': '/bin/cat', 02:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:42 INFO - 'PWD': '/builds/slave/test', 02:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:42 INFO - 'SHELL': '/bin/bash', 02:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:42 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:42 INFO - 'USER': 'cltbld', 02:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:42 INFO - 'XPC_FLAGS': '0x0', 02:47:42 INFO - 'XPC_SERVICE_NAME': '0', 02:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:42 INFO - Downloading/unpacking jsonschema 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:47:44 INFO - Downloading/unpacking functools32 (from jsonschema) 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47:45 INFO - Downloading functools32-3.2.3-2.tar.gz 02:47:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:47:45 INFO - warning: no files found matching '*.txt' 02:47:45 INFO - no previously-included directories found matching 'build' 02:47:45 INFO - no previously-included directories found matching 'dist' 02:47:45 INFO - no previously-included directories found matching '.git*' 02:47:45 INFO - Installing collected packages: jsonschema, functools32 02:47:45 INFO - Running setup.py install for functools32 02:47:45 INFO - warning: no files found matching '*.txt' 02:47:45 INFO - no previously-included directories found matching 'build' 02:47:45 INFO - no previously-included directories found matching 'dist' 02:47:45 INFO - no previously-included directories found matching '.git*' 02:47:45 INFO - Successfully installed jsonschema functools32 02:47:45 INFO - Cleaning up... 02:47:45 INFO - Return code: 0 02:47:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9cca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9dc420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9090d6b1c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, '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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:47:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:47:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:47:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:45 INFO - 'HOME': '/Users/cltbld', 02:47:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:45 INFO - 'LOGNAME': 'cltbld', 02:47:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:45 INFO - 'MOZ_NO_REMOTE': '1', 02:47:45 INFO - 'NO_EM_RESTART': '1', 02:47:45 INFO - 'PAGER': '/bin/cat', 02:47:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:45 INFO - 'PWD': '/builds/slave/test', 02:47:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:45 INFO - 'SHELL': '/bin/bash', 02:47:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:47:45 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:47:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:45 INFO - 'USER': 'cltbld', 02:47:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:45 INFO - 'XPC_FLAGS': '0x0', 02:47:45 INFO - 'XPC_SERVICE_NAME': '0', 02:47:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:45 INFO - Downloading/unpacking flake8 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:47:48 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:47:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:49 INFO - no previously-included directories found matching 'docs/_build' 02:47:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:47:49 INFO - Running setup.py install for pep8 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:49 INFO - no previously-included directories found matching 'docs/_build' 02:47:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:47:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:47:49 INFO - Cleaning up... 02:47:49 INFO - Return code: 0 02:47:49 INFO - Running post-action listener: _resource_record_post_action 02:47:49 INFO - Running post-action listener: _start_resource_monitoring 02:47:49 INFO - Starting resource monitoring. 02:47:49 INFO - ##### 02:47:49 INFO - ##### Running install step. 02:47:49 INFO - ##### 02:47:49 INFO - Running pre-action listener: _resource_record_pre_action 02:47:49 INFO - Running main action method: install 02:47:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:50 INFO - Reading from file tmpfile_stdout 02:47:50 INFO - Detecting whether we're running mozinstall >=1.0... 02:47:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:47:50 INFO - Reading from file tmpfile_stdout 02:47:50 INFO - Output received: 02:47:50 INFO - Usage: mozinstall [options] installer 02:47:50 INFO - Options: 02:47:50 INFO - -h, --help show this help message and exit 02:47:50 INFO - -d DEST, --destination=DEST 02:47:50 INFO - Directory to install application into. [default: 02:47:50 INFO - "/builds/slave/test"] 02:47:50 INFO - --app=APP Application being installed. [default: firefox] 02:47:50 INFO - mkdir: /builds/slave/test/build/application 02:47:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:47:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:48:21 INFO - Reading from file tmpfile_stdout 02:48:21 INFO - Output received: 02:48:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:48:21 INFO - Running post-action listener: _resource_record_post_action 02:48:21 INFO - ##### 02:48:21 INFO - ##### Running run-tests step. 02:48:21 INFO - ##### 02:48:21 INFO - Running pre-action listener: _resource_record_pre_action 02:48:21 INFO - Running main action method: run_tests 02:48:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:21 INFO - 2016-05-16 02:48:21.975 screenresolution[1642:7707] starting screenresolution argv=screenresolution get 02:48:21 INFO - 2016-05-16 02:48:21.977 screenresolution[1642:7707] Display 0: 1600x1200x32@60 02:48:21 INFO - 2016-05-16 02:48:21.983 screenresolution[1643:7709] starting screenresolution argv=screenresolution list 02:48:21 INFO - Available Modes on Display 0 02:48:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:22 INFO - Intel Iris: 02:48:22 INFO - Chipset Model: Intel Iris 02:48:22 INFO - Type: GPU 02:48:22 INFO - Bus: Built-In 02:48:22 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:22 INFO - Vendor: Intel (0x8086) 02:48:22 INFO - Device ID: 0x0a2e 02:48:22 INFO - Revision ID: 0x0009 02:48:22 INFO - Displays: 02:48:22 INFO - Display: 02:48:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:22 INFO - Display Serial Number: 1600x1200 60 02:48:22 INFO - Main Display: Yes 02:48:22 INFO - Mirror: Off 02:48:22 INFO - Online: Yes 02:48:22 INFO - Rotation: Supported 02:48:22 INFO - Return code: 0 02:48:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:48:22 INFO - Python 2.7.3 02:48:22 INFO - Return code: 0 02:48:22 INFO - grabbing minidump binary from tooltool 02:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:22 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 0x10d86be00>, '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 0x10d6d4db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d866b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:48:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:48:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:48:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:48:22 INFO - Return code: 0 02:48:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:48:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:48:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:48:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:48:23 INFO - Return code: 0 02:48:23 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/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 02:48:23 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/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --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 02:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5zXPTncoKY/Render', 02:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:23 INFO - 'HOME': '/Users/cltbld', 02:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:23 INFO - 'LOGNAME': 'cltbld', 02:48:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:23 INFO - 'MOZ_NO_REMOTE': '1', 02:48:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:23 INFO - 'NO_EM_RESTART': '1', 02:48:23 INFO - 'PAGER': '/bin/cat', 02:48:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:23 INFO - 'PWD': '/builds/slave/test', 02:48:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:23 INFO - 'SHELL': '/bin/bash', 02:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.RyyxRmr8Mk/Listeners', 02:48:23 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:23 INFO - 'USER': 'cltbld', 02:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:23 INFO - 'XPC_FLAGS': '0x0', 02:48:23 INFO - 'XPC_SERVICE_NAME': '0', 02:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:23 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/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 02:48:23 INFO - mozversion application_buildid: 20160516004009 02:48:23 INFO - mozversion application_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:48:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:48:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:48:23 INFO - mozversion application_name: Firefox 02:48:23 INFO - mozversion application_remotingname: firefox-dev 02:48:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:23 INFO - mozversion application_vendor: Mozilla 02:48:23 INFO - mozversion application_version: 48.0a2 02:48:23 INFO - mozversion platform_buildid: 20160516004009 02:48:23 INFO - mozversion platform_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 02:48:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:23 INFO - mozversion platform_version: 48.0a2 02:48:23 INFO - using testdate: 1463392103 02:48:23 INFO - actual date: 1463392103 02:48:23 INFO - starting webserver on 'localhost:49275' 02:48:23 INFO - SUITE-START | Running 2 tests 02:48:23 INFO - TEST-START | dromaeo_css 02:48:23 INFO - operating with platform_type : mac_ 02:48:23 INFO - Initialising browser for dromaeo_css test... 02:48:23 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpXHGzez/profile http://localhost:49275/getInfo.html) 02:48:26 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 02:48:26 INFO - PROCESS | 1661 | colorDepth:24 02:48:26 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 02:48:26 INFO - PROCESS | 1661 | __metrics 02:48:26 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:48:26 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:48:26 INFO - TEST-INFO | 1661: exit 0 02:48:26 INFO - Browser initialized. 02:48:26 INFO - Running cycle 1/1 for dromaeo_css test... 02:48:26 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpXHGzez/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:38 INFO - PROCESS | 1664 | RSS: Main: 210898944 02:48:38 INFO - PROCESS | 1664 | 02:49:29 INFO - PROCESS | 1664 | 1463392169372 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:51:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 02:51:42 INFO - PROCESS | 1664 | RSS: Main: 343351296 02:51:42 INFO - PROCESS | 1664 | 02:54:45 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 02:54:45 INFO - PROCESS | 1664 | RSS: Main: 359804928 02:54:45 INFO - PROCESS | 1664 | 02:57:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 02:57:48 INFO - PROCESS | 1664 | RSS: Main: 358076416 02:57:48 INFO - PROCESS | 1664 | 03:00:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:00:51 INFO - PROCESS | 1664 | RSS: Main: 353087488 03:00:51 INFO - PROCESS | 1664 | 03:03:54 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:03:55 INFO - PROCESS | 1664 | RSS: Main: 325627904 03:03:55 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:06:57 INFO - PROCESS | 1664 | RSS: Main: 369811456 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | __start_tp_report 03:06:57 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:06:57 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:06:57 INFO - PROCESS | 1664 | |i|pagename|runs| 03:06:57 INFO - PROCESS | 1664 | |0;dojo.html;3403.9829800850994;3484.181217413909;3503.6146023937345;3507.018034950214;3544.1316877365034;5672.744726487308;5700.543956483481;5712.200291959119;5735.569832262581;5741.655500669952;5709.200711900337;5734.455226753462;5758.827235182941;5766.538676905848;5800.216970708953;5081.085404627165;5143.588512918966;5164.302819119427;5228.673370094072;5343.385510666265;5253.395859476152;5262.81580144695;5266.368035835705;5272.051030814452;5277.683338999673;5177.990291893082;5205.661631993928;5228.5555727763185;5235.790568377261;5272.841814745564;5122.001209764097;5131.589472842164;5153.587712982952;5157.458466860963;5204.063268611649;5048.368953880765;5048.7223202723835;5084.237364395333;5111.591072714174;5121.052605268;5120.334356533664;5612.326520817528;5673.149027645845;5696.088625819891;5708.486236238717;6261.499080073583;6270.122182894398;6271.717772700239;6271.874562508724;6273.3726627337355;6107.725152368154;6188;6258.655773932432;6286.748530058792;6291.653959032252;5879.853003674943;5889.116632505158;5913.881722365529;5914.4676979071655;5954.49386802118;6270.404311590391;6284.560080794392;6290.308066112724;6298.905516417258;6303.716332765037;6078.300995385499;6096.146539484475;6135.630783975068;6162.168107305456;6203;6237.563370564108;6255.468285195719;6282.120503129566;6287.7799277026;6296.43332100104;6140.754369825157;6171.722272497748;6206.317305096436;6212.06818977157;6214.4407003369915;6128.19758601264;6213.720382582795;6223.471004964629;6229;6234.407731265523;4172.624463798273;4868.9269660955115;4902.28916807061;4907.411110666671;4914.533119353656;4391.143726973273;4420.491643460979;4439.445069366329;4486.304622783474;4506.459224893034;5740.913886291709;5742.052561327418;5753.913691294634;5775.249217601685;5806.129080637938;5689.748255383809;5862.648241105548;6140.504248697391;6220.098085777628;6244.438000579969;5734.885302293931;5740.42595740431;5742.684152371576;5746.913796293059;5779.624324418899;5338.946610533845;5353.866153346197;5359.892802143935;5368.248445217672;5370.0065487884085;6220.533459990428;6226;6255.499560035186;6285.1515045470105;6298.968505157445;5976.874300634092;6281.937180628318;6282.905756413749;6301.149344838388;6332.018537126753;6307.337729538516;6312.14786003902;6319.178506794087;6323.652199129092;6325.051242313506;6094.963856144377;6140.074827791203;6175;6206.224221972254;6236.937630623635;6281.748730050746;6299.842503937438;6307.558470906993;6325.683715814283;6338.556301058881;6076.54425918049;6117.602355846856;6143.139960405636;6152.261728592597;6156.199694039746;5996.430339117776;6027.186329845415;6045.939540604538;6112.235970503687;6134.846628834136;5803.82588522345;5901.439363260483;5908.409159084057;5955.017422125301;5960.433758792908;6086.543509236913;6102.018589523632;6121.239585875234;6126.508817326647;6135.631862088289;5788.623739456922;5874.618149820248;5965.164876917148;6083.66539840313;6115.908261376172 03:06:57 INFO - PROCESS | 1664 | |1;ext.html;524.4493282053845;524.5384062025419;558.4945624210092;587.3011116771044;602.5992714844629;30629;31245.90639327624;31340.68659313406;31343.68656313439;31406.685933140663;38975.61024389755;40261.39607905884;40345.78962631118;40448.98877528063;40481.39277910833;9487.715368538955;9632;9658.758531036745;9669.12977831995;9676.467794271312;9581.377210481296;9717.951410242938;9729.027097290285;9735.610575577004;9736.026397360278;9679.90320096797;9738.318317717763;9753.853692194623;9806.509674516246;9837.803243935117;9720.854187187198;9790.118889299962;9793.706188814347;9800.95099524505;9805.509724513746;9544.188743956773;9673.758156046155;9680.515974201262;9690.32167748258;9694.079062489052;40249.59750402517;40662.59337406618;40720;40764.592354076376;40768.38847417291;3201.27971206479;3335.849886755102;3445.5693038370205;3666.6699997000132;3700.3709369406997;28299.717002829915;28355.71644283572;28509.429811403865;28648;29088.12735617935;2723.2783312422225;2740.6026126211586;2745.807793454459;2798.3983443559528;2856.9142925712263;438559.42160867614;439330.9999999968;440570;441443;441782;60686.69656651688;63142.73714525684;63380;63427;63439;68252.97620535696;69203;69278.65360673165;69309.30690693129;69386.30613693799;17144.39994600208;17335.739963900553;17336.133193340283;17352.39266625662;17423.73864392035;13265.204087754766;13359.599212023655;13365.933170334087;13369.532066377824;13406.597802065953;19110.88553173717;19794.60410791805;19810.50473738168;19815.900920495595;19846.801531984784;8322.250997410261;8403.453775504573;8447.788805279917;8456.957715211385;8494.748891543746;935.1677007463363;1005.4269066631949;1033.478093562746;1056.6777132974516;1071.2608300272943;29923.401531969237;29999.500024998662;30548.54177187344;30635.8468207662;30700.69299307023;1145.307089211032;1152.5332240442633;1178.5639313454076;1197.9700507487382;1204.241327963395;14920.477783277533;15259.847401526065;15275.312610932535;15594.376224951102;15642.68714625701;336980;339887.6011239856;342493;343510;631629;59879;60064.099038514454;60132;60146;60167.699161503915;1424.6153538544743;1464.4361920660622;1501.06183635228;1523.7028779387913;1524.7407940649894;28706.13881583556;28866.711332887244;29822.850885746302;29954.550681740227;30366.84816575903;48291.517084830106;48798.756006219744;48840.75579622221;48993;48998.75500622474;435642;439633;439707;439749;440180;23214.955327009986;26332.605010925236;26651.20046398611;26834.865825670746;27040;41906.37140442957;42306.36540451834;42814;43348.56651433571;43478;28444.85777571099;28821.855890720413;34803;35360;35906.10234744152;35258;35428.64571354254;35602.46596301109;35878;36061.45907811332;36144.096397589215;36898;37173.442398363506;37594.8120259397;37635;27455.725442746116;31420.371592568477;31940.840295799302;32416.513752293264;32601.391888649327;24286.757132429157;24287;24325.270241892773;24808.131715389874;24853 03:06:57 INFO - PROCESS | 1664 | |2;jquery.html;399.94600728901605;403.304300082358;404.7955782329923;404.90889549851266;413.1983951134798;15180.848191518082;15275.46535871242;19935.501612459677;20548.972551372426;20618.278360257376;19962.700559491583;20517.97410129495;20843.37469875906;21037.474063148427;21082.36752897412;5758.42415758425;5978.790742324019;5979.730912108966;6010.57925945184;6020.939790602104;5715.9428405716035;5859.7656093756195;5887.587868849182;5892.587518873659;5902.203202567642;5649.23735295734;5842.685395785957;5854.385289544601;5859.414058594128;5861.736221870005;5873.236479257669;5898.056310990264;5899.262592175978;5906.970465147669;5912.526997840162;5080.74596270185;5144.228365745131;5155.690658560461;5158.252053452228;5159.432462429129;24955.376115597075;25097.62353564698;25268.747312526822;25328.366790830194;25387;2215.601191785478;2229.8662080275235;2244.7418546866993;2269.863808171515;2274.73840508342;14426.855731442656;14623;14670.04644698102;15149.772753408708;15206.391744330214;1687.6371580110335;1733.8612910967092;1753.8070812210647;1754.2018381636324;1817.264008076731;79334;79618.99999999942;92855;97751;113923.86076139215;10958.506867190996;11104.888951110546;11179.4969226385;11213.66359009231;11313.490892909838;13896.23570703621;13973.930130349487;13993.860061399459;14015.439382424589;14026;8827.867581986275;8980.910190898137;9032.819343613222;9058.864117038249;9107.772305692411;7963.800904977422;9151.222146117654;9151.313651476166;9207.769805754775;9243.214326782165;6534.771283005072;6630.336966303331;6633.502487313471;6658.1344425224415;6669.7332106715185;1752.7458518514743;1794.4078454110113;1871.8315351618419;1902.8858268503934;1930.835878950305;921.8340698674303;1061.9575216991234;1091.9344839309667;1097.8408130820985;1128.5316593613559;19867.900660496893;20496.897515512625;21110;21297.893510532347;21332.253371131937;1017.4048181813722;1022.085233715835;1028.984565231522;1039.859618951432;1097.3470784882982;9525;11101.44492775358;11579;11594.536218551331;11601.361925094032;530351;590074;601583;630871;635050;113160;114549;115053;117727;118138.40930795291;1052.4264275969615;1061.3472714280663;1067.80779459698;1069.8662667166604;1071.4910417551757;14789.260536972894;15283.541493755205;18009.919404835753;18286.908565457088;19001.049947502845;47700;47818.28272575984;48753.75623121862;48960.75519622379;49065.754671226416;60076.39923600708;64240.678796605716;64480.03279950898;65270.67364663146;66281;6863.450923926173;7866.331361834308;7998.160193179632;8044.35645148375;8351.29014033814;9882.456464894212;12386.380680966096;12768.106232563632;12829.23024618526;13004.284764338052;10879.51042203087;11173.217874748689;11203.71990700239;11469.0251328638;11557.47991340375;9103.453792772454;9161.992180859967;10783.406912619881;11002.064824490008;11156.44217789091;11197.944010279896;11211.775764484828;11233.719157020812;11418.143639226924;11523.135764817505;7432.2567743225245;7912.28789408956;7918.3665306776475;8219.83560328802;8299.751007469786;10922.890771092187;11034.282771620141;11042.447877605928;11245.269057511236;11410.82883756761 03:06:57 INFO - PROCESS | 1664 | |3;mootools.html;1349.1298112717304;1569.7802307676923;1582.7784110224568;1862.6647203503373;1995.7704863940642;7923.445358824884;8572.485650860952;8682.826343473127;8720.127987201284;8741.519216443094;8381.28759055481;8654.350923680719;8680.869786953184;8686.739397818059;8700.99938507072;2397.9640305395433;2418.9274321770376;2422.5518279118332;2422.9757702423017;2426.623873299641;2425.9393515162087;2447.1801946347905;2467.629855521668;2474.0474917156844;2483.726790053093;2438.4635380216323;2444.828861979662;2452.9632055519182;2475.579151544233;2478.876056197192;2409;2419.8669073200967;2432.015033911269;2434.6834911461574;2455.1652438170927;2386.832921695482;2392.138830021191;2396.065534441569;2412.7104747430244;2428.939276518101;8513.106123856967;8760.167784060504;8796.912030879674;8816.515091669955;8831.072737362549;5326.547243484309;5507.118860982264;5511.696856672881;5527.889442211173;5534.08687566551;3504.824758762077;3596.6801413088674;3635.2547727715755;3639.927201455956;3673.5591728992426;1040.687793661906;1069.0538873097364;1070.0957690751302;1074.2050882347085;1075.8171110911164;11742.295462272206;11846.407679615984;11949.342786146759;11973.102017348734;11976.221545599596;4555.658325625525;4624.121416930772;4706.058788242365;4712.52874712526;4738.028704115648;9283.628654853865;9294.466413041773;9306.976232614406;9314;9323.580438880266;5355.3305836770405;5376.677399356051;5390.407055223922;5392.676439413569;5393.865153371197;4413.16149931512;4560.224761790503;4824.011077729057;4949.678270912379;5042.823501177441;3822.4266360046217;3838.788866612363;3912.2371137628456;3930.2336044471053;3933.4099885017476;1208.2629595946466;1209.60687776473;1210.2194084815244;1217.8112392579164;1221.0841868598552;714.8677494663503;835.757630287222;915.8076803871129;952.7951490429591;956.4500412262977;5679.31848178213;5785.82642520725;5847.830433913235;5903.173555702205;5949.137375080589;481.8867566121959;489.1439980034939;506.7694199139383;509.4294390282907;652.7976327338446;3726.664600185942;3876.8449262029767;3900.7854567998465;3981.203759248162;4001.519817621904;10039.19686425099;10275.897241027495;10553.84169237462;10611.57553697859;10618.36289822613;8897.777555561033;10373.27387082912;10383.84424233637;10500.422476763699;10504;7895.7236496722335;8150.796230094176;8198.262156405952;8280.089190188954;8308.418410711312;5738.913916291175;5872.212313760792;6019.759209631741;6065.787697430569;6081.239845019372;9281.67514137002;9668.9033109668;9879.06148915852;9891.307608467627;9891.802163956825;9776.169025632616;9873.259505537257;10025.398476091457;10044.648437304659;10065.597376105166;7553.7356192533;7625.351845093229;7685.346745526693;7697.65360558765;7700;8235.588220588827;8292.419530632798;8338.249557539608;8370.246677799027;8387.622556984828;7451.143118541437;7502.587357695161;7513.774586762406;7583.80220302726;7599.316061554487;7344.155422126524;7400.111986561647;7439.330460258603;7445.032145821008;7468.10382754073;6849.212340580897;6996.930030699627;7023.543469674455;7060.188078371053;7080.2919708028785;4666.78999445019;4737.857864264078;4804.279358096313;4811.807527698853;4858.975705121452;4938.987507561013;4950.8762280943265;5020.046191223656;5045.84862454127;5212.9739351303 03:06:57 INFO - PROCESS | 1664 | |4;prototype.html;323.18396049973813;328.4826398422484;335.18382738032886;337.7433150805387;341.18967452300785;6652.201735791712;6669.366410191036;6673.466122710183;6682.331766823315;6683.498737594681;6579.671016449171;6581;6631.933680663192;6690.933090669092;6729.327067293269;4705.7647117644065;4784.90430191396;4797.352357431754;4894.902101957959;4918.385201849769;4902.828401005965;4924.556789888909;4974.1543937530705;4977.228529577921;4980.402351717781;3708.128589781399;4050.9594904051023;4481.798319075633;4872.65891387603;4905.288733133693;4642.257238841786;4738.384010078702;4841.2012018016985;4847.975760121212;4848.757562121879;4177.811998460076;4181.916361672749;4189.937150942738;4199.433076534659;4208.389783481378;4793.568578827887;5854.443827836349;6389.8402539936405;6620.37106474889;6624.436922861562;5869.090291004901;5872.765089396419;5878.647281163144;5884.970575147139;5895.204147440084;6865.9313406866295;7118.145822501333;7127.964360178166;7131.465140114512;7137.714491420337;4786.305985632099;4789.090072886175;4799.7360145192;4815.711057336571;4821.156297647933;6087.573869829113;7458.664360103754;7534.849303013963;7536.811579710551;7551.848963020709;4855.781489832966;4858.708477491362;4874.610031197496;4875.317455556225;4895.4370247422;4949.406071271398;4950.861301900511;4960.255961605788;4961.677490963076;4971.45314015458;5675.1770993205755;5885.764569417261;5900.291964964146;5907.438793314628;5916.704164791743;7177.066981292503;7190.3888169505135;7192.460565457612;7205.855882882313;7225.205227424979;1680.126334306154;1725.2753843385808;1725.7583938248656;1727.6976529107355;1735.019713861664;1039.6725031615017;1054.5623566219933;1095.1239008792934;1105.3699391346854;1128.4639796096787;6669.699863506154;6775.4240889524945;6791.422729068085;6794.116764820542;6798.422134118655;7480.812979675552;7490.138634057044;7494;7495.138059123271;7510.812229694301;6658.600483970978;6771.424428923597;6778.2205046420295;6789.6605169741315;6947.548409353376;3405.931881362359;3788.956614200346;3964.266610677034;3964.5044369453817;3970.927849480624;6407.686424283011;7702.768916932501;7799.103103143211;7806.336461400844;7852.175521570153;7649.617519124022;7681.42389320792;7684.423668224906;7699.538027718355;7722.6910923563555;6289.308176100625;7809.648565814552;7810.1408845027;7820.804479887935;7832.412569057229;7199.1001124859395;7477.364424023801;7479.6634151464405;7493.4379921505015;7506.28690274423;7094.4679149062995;7313.378362839218;7388.1503627083575;7396.593187374746;7427.888581671387;6455.419012288917;7850.214978502104;7902.802429939297;7903.56530390834;7918.326942209977;4008.7995600220456;4654.16225079489;4686.695364801277;4724.881877953078;4734.55021772931;6485.124508191334;7008.649567521706;7164.319389658182;7431.479796414199;7459.552426854406;7132.714691412493;7198;7207.747728829676;7215.60314182725;7221.205667376479;7163.212046674966;7163.391111755559;7168.354848063699;7180.605066721171;7206.747763828241;6999.650017499206;7000.964995174992;7000.964995175195;7001.194862590867;7015.473839461959;6890.20762612306;7153;7174.964125179341;7195.8560828784175;7201.099862517185;5406.729663516887;6811.216710078206;6912.513809530975;7004.579725216503;7008.369246767611 03:06:57 INFO - PROCESS | 1664 | |5;yui.html;680.897865320202;747.5439981611213;755.3692666623373;762.9008228930238;780.4341852157185;14953.028053176537;15130.243487825619;15193.468228612;15232.466863659774;15234.314455849486;13788.448462061506;14033.649158771035;14037.22795246261;14042.508512202076;14055.086419382733;752.1801236652049;764.7591008832228;917.8669092981497;953.7806304549957;954.0697819625844;933.0948979489918;955.4171955107379;955.8614000969856;956.5934477846887;957.4159762544842;1007.4056306778999;1012.736688460998;1013.3767732844321;1013.5641674080142;1017.9287449878511;781.749840051181;984.251968503935;1001.6794625719773;1005.3465247589053;1006.753345430367;741.4698490579261;745.6644509970535;746.1792028768366;747.0960138232745;748.3938010211746;15157.317920693595;15194.088354698753;15194.316255768517;15214.543563693107;15265.694686106215;12684.238945663197;12749.681257968625;12772.805189483093;12867.806982895265;13034.022448316415;9297.302702297287;9508.667196648154;10151.086402223762;10298;10491.73770655732;3407.1822762537063;3519.454484554898;3525.0306165804354;3533.3286675531567;3583.157957879896;85191.72212416818;85820;86402;88987;89758.99999999935;9415.858762118574;9450.52747362643;9492.050794920453;9504.524773761284;10029.498525073723;22047;22051.89740512968;22052.11791528353;22073.227437039626;22086.558268834855;7349.191588925214;7457.179710231871;7547.811304717426;7647;7654.158042615308;4014.3777714454304;4077.836886524565;4083.612056854594;4140.68944829139;4332.848350307724;3671.5594128704724;3746.9437958430644;3749.531308586427;3757.5678796938155;3760.8495660173285;624.9157711370768;644.7711062572719;664.9135612370361;676.0670275020441;696.5472442912147;544.6840832317247;585.344414256036;594.625386006813;595.2529575382866;607.4259824465926;8144.674213031412;8199.344052475788;8254.009518857656;8269.245109492727;8293.460925039974;497.6118627470579;596.2874365133711;607.9057746049369;608.695652173913;620.3517324396017;3842.6733727632904;3876.476675648808;3893.493845800028;4002.419649150915;4005.759654420744;89185;89653;90769.54615227014;91030;91652;77875.61062194766;78413;79534;79791.00522486868;81059;65804.6709766448;65914.34085659082;67576;68822;69388.6530567354;7821.804454888674;7835.764927052198;7935.960320198362;7940.364770818436;7952.681892724227;47819.52180478151;49362.012759743884;49490.50509494859;50073.24890126724;50847.74576127244;64803;66825.66587167032;68552.6572367135;69173;70123;10012.04885535873;10139.391636501834;10140.949295253477;10222.386656800616;10243.897561024294;10772.743255892048;11033.779324413626;11148.832767508657;11199.72000699989;11725.824112638487;10123.949380253052;10479.161667066464;10592.576296948037;10686.89313106859;10707.19696022817;10298.897011030094;10401.94799026;10403.53184106732;10455.634052808115;10598.735031624272;9899.20806335477;9904.455254960758;10228.795424091626;10348.172146228435;10434.843477347998;8721.781955451164;8875.911240887766;8896.288296936358;8939.73180804577;9123.863142053007;8876.822463550821;8907.153820387053;8932.151445612657;8956.969948456011;9099.31755118382 03:06:57 INFO - PROCESS | 1664 | __end_tp_report 03:06:57 INFO - PROCESS | 1664 | __start_cc_report 03:06:57 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1203 03:06:57 INFO - PROCESS | 1664 | __end_cc_report 03:06:57 INFO - PROCESS | 1664 | __startTimestamp1463393217785__endTimestamp 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:06:57 INFO - PROCESS | 1664 | Number of tests: 6 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5742.66 Median:5955.02 stddev:637.51 (10.7%) stddev-sans-first:612.49 03:06:57 INFO - PROCESS | 1664 | Values: 3404.0 3484.2 3503.6 3507.0 3544.1 5672.7 5700.5 5712.2 5735.6 5741.7 5709.2 5734.5 5758.8 5766.5 5800.2 5081.1 5143.6 5164.3 5228.7 5343.4 5253.4 5262.8 5266.4 5272.1 5277.7 5178.0 5205.7 5228.6 5235.8 5272.8 5122.0 5131.6 5153.6 5157.5 5204.1 5048.4 5048.7 5084.2 5111.6 5121.1 5120.3 5612.3 5673.1 5696.1 5708.5 6261.5 6270.1 6271.7 6271.9 6273.4 6107.7 6188.0 6258.7 6286.7 6291.7 5879.9 5889.1 5913.9 5914.5 5954.5 6270.4 6284.6 6290.3 6298.9 6303.7 6078.3 6096.1 6135.6 6162.2 6203.0 6237.6 6255.5 6282.1 6287.8 6296.4 6140.8 6171.7 6206.3 6212.1 6214.4 6128.2 6213.7 6223.5 6229.0 6234.4 4172.6 4868.9 4902.3 4907.4 4914.5 4391.1 4420.5 4439.4 4486.3 4506.5 5740.9 5742.1 5753.9 5775.2 5806.1 5689.7 5862.6 6140.5 6220.1 6244.4 5734.9 5740.4 5742.7 5746.9 5779.6 5338.9 5353.9 5359.9 5368.2 5370.0 6220.5 6226.0 6255.5 6285.2 6299.0 5976.9 6281.9 6282.9 6301.1 6332.0 6307.3 6312.1 6319.2 6323.7 6325.1 6095.0 6140.1 6175.0 6206.2 6236.9 6281.7 6299.8 6307.6 6325.7 6338.6 6076.5 6117.6 6143.1 6152.3 6156.2 5996.4 6027.2 6045.9 6112.2 6134.8 5803.8 5901.4 5908.4 5955.0 5960.4 6086.5 6102.0 6121.2 6126.5 6135.6 5788.6 5874.6 5965.2 6083.7 6115.9 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:57844.09 Median:36102.78 stddev:114561.38 (317.3%) stddev-sans-first:114801.92 03:06:57 INFO - PROCESS | 1664 | Values: 524.4 524.5 558.5 587.3 602.6 30629.0 31245.9 31340.7 31343.7 31406.7 38975.6 40261.4 40345.8 40449.0 40481.4 9487.7 9632.0 9658.8 9669.1 9676.5 9581.4 9718.0 9729.0 9735.6 9736.0 9679.9 9738.3 9753.9 9806.5 9837.8 9720.9 9790.1 9793.7 9801.0 9805.5 9544.2 9673.8 9680.5 9690.3 9694.1 40249.6 40662.6 40720.0 40764.6 40768.4 3201.3 3335.8 3445.6 3666.7 3700.4 28299.7 28355.7 28509.4 28648.0 29088.1 2723.3 2740.6 2745.8 2798.4 2856.9 438559.4 439331.0 440570.0 441443.0 441782.0 60686.7 63142.7 63380.0 63427.0 63439.0 68253.0 69203.0 69278.7 69309.3 69386.3 17144.4 17335.7 17336.1 17352.4 17423.7 13265.2 13359.6 13365.9 13369.5 13406.6 19110.9 19794.6 19810.5 19815.9 19846.8 8322.3 8403.5 8447.8 8457.0 8494.7 935.2 1005.4 1033.5 1056.7 1071.3 29923.4 29999.5 30548.5 30635.8 30700.7 1145.3 1152.5 1178.6 1198.0 1204.2 14920.5 15259.8 15275.3 15594.4 15642.7 336980.0 339887.6 342493.0 343510.0 631629.0 59879.0 60064.1 60132.0 60146.0 60167.7 1424.6 1464.4 1501.1 1523.7 1524.7 28706.1 28866.7 29822.9 29954.6 30366.8 48291.5 48798.8 48840.8 48993.0 48998.8 435642.0 439633.0 439707.0 439749.0 440180.0 23215.0 26332.6 26651.2 26834.9 27040.0 41906.4 42306.4 42814.0 43348.6 43478.0 28444.9 28821.9 34803.0 35360.0 35906.1 35258.0 35428.6 35602.5 35878.0 36061.5 36144.1 36898.0 37173.4 37594.8 37635.0 27455.7 31420.4 31940.8 32416.5 32601.4 24286.8 24287.0 24325.3 24808.1 24853.0 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:33505.93 Median:11773.93 stddev:98970.49 (840.6%) stddev-sans-first:99216.91 03:06:57 INFO - PROCESS | 1664 | Values: 399.9 403.3 404.8 404.9 413.2 15180.8 15275.5 19935.5 20549.0 20618.3 19962.7 20518.0 20843.4 21037.5 21082.4 5758.4 5978.8 5979.7 6010.6 6020.9 5715.9 5859.8 5887.6 5892.6 5902.2 5649.2 5842.7 5854.4 5859.4 5861.7 5873.2 5898.1 5899.3 5907.0 5912.5 5080.7 5144.2 5155.7 5158.3 5159.4 24955.4 25097.6 25268.7 25328.4 25387.0 2215.6 2229.9 2244.7 2269.9 2274.7 14426.9 14623.0 14670.0 15149.8 15206.4 1687.6 1733.9 1753.8 1754.2 1817.3 79334.0 79619.0 92855.0 97751.0 113923.9 10958.5 11104.9 11179.5 11213.7 11313.5 13896.2 13973.9 13993.9 14015.4 14026.0 8827.9 8980.9 9032.8 9058.9 9107.8 7963.8 9151.2 9151.3 9207.8 9243.2 6534.8 6630.3 6633.5 6658.1 6669.7 1752.7 1794.4 1871.8 1902.9 1930.8 921.8 1062.0 1091.9 1097.8 1128.5 19867.9 20496.9 21110.0 21297.9 21332.3 1017.4 1022.1 1029.0 1039.9 1097.3 9525.0 11101.4 11579.0 11594.5 11601.4 530351.0 590074.0 601583.0 630871.0 635050.0 113160.0 114549.0 115053.0 117727.0 118138.4 1052.4 1061.3 1067.8 1069.9 1071.5 14789.3 15283.5 18009.9 18286.9 19001.0 47700.0 47818.3 48753.8 48960.8 49065.8 60076.4 64240.7 64480.0 65270.7 66281.0 6863.5 7866.3 7998.2 8044.4 8351.3 9882.5 12386.4 12768.1 12829.2 13004.3 10879.5 11173.2 11203.7 11469.0 11557.5 9103.5 9162.0 10783.4 11002.1 11156.4 11197.9 11211.8 11233.7 11418.1 11523.1 7432.3 7912.3 7918.4 8219.8 8299.8 10922.9 11034.3 11042.4 11245.3 11410.8 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5672.96 Median:5031.43 stddev:3147.34 (62.6%) stddev-sans-first:3139.39 03:06:57 INFO - PROCESS | 1664 | Values: 1349.1 1569.8 1582.8 1862.7 1995.8 7923.4 8572.5 8682.8 8720.1 8741.5 8381.3 8654.4 8680.9 8686.7 8701.0 2398.0 2418.9 2422.6 2423.0 2426.6 2425.9 2447.2 2467.6 2474.0 2483.7 2438.5 2444.8 2453.0 2475.6 2478.9 2409.0 2419.9 2432.0 2434.7 2455.2 2386.8 2392.1 2396.1 2412.7 2428.9 8513.1 8760.2 8796.9 8816.5 8831.1 5326.5 5507.1 5511.7 5527.9 5534.1 3504.8 3596.7 3635.3 3639.9 3673.6 1040.7 1069.1 1070.1 1074.2 1075.8 11742.3 11846.4 11949.3 11973.1 11976.2 4555.7 4624.1 4706.1 4712.5 4738.0 9283.6 9294.5 9307.0 9314.0 9323.6 5355.3 5376.7 5390.4 5392.7 5393.9 4413.2 4560.2 4824.0 4949.7 5042.8 3822.4 3838.8 3912.2 3930.2 3933.4 1208.3 1209.6 1210.2 1217.8 1221.1 714.9 835.8 915.8 952.8 956.5 5679.3 5785.8 5847.8 5903.2 5949.1 481.9 489.1 506.8 509.4 652.8 3726.7 3876.8 3900.8 3981.2 4001.5 10039.2 10275.9 10553.8 10611.6 10618.4 8897.8 10373.3 10383.8 10500.4 10504.0 7895.7 8150.8 8198.3 8280.1 8308.4 5738.9 5872.2 6019.8 6065.8 6081.2 9281.7 9668.9 9879.1 9891.3 9891.8 9776.2 9873.3 10025.4 10044.6 10065.6 7553.7 7625.4 7685.3 7697.7 7700.0 8235.6 8292.4 8338.2 8370.2 8387.6 7451.1 7502.6 7513.8 7583.8 7599.3 7344.2 7400.1 7439.3 7445.0 7468.1 6849.2 6996.9 7023.5 7060.2 7080.3 4666.8 4737.9 4804.3 4811.8 4859.0 4939.0 4950.9 5020.0 5045.8 5213.0 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5841.09 Median:6663.98 stddev:1874.82 (28.1%) stddev-sans-first:1833.77 03:06:57 INFO - PROCESS | 1664 | Values: 323.2 328.5 335.2 337.7 341.2 6652.2 6669.4 6673.5 6682.3 6683.5 6579.7 6581.0 6631.9 6690.9 6729.3 4705.8 4784.9 4797.4 4894.9 4918.4 4902.8 4924.6 4974.2 4977.2 4980.4 3708.1 4051.0 4481.8 4872.7 4905.3 4642.3 4738.4 4841.2 4848.0 4848.8 4177.8 4181.9 4189.9 4199.4 4208.4 4793.6 5854.4 6389.8 6620.4 6624.4 5869.1 5872.8 5878.6 5885.0 5895.2 6865.9 7118.1 7128.0 7131.5 7137.7 4786.3 4789.1 4799.7 4815.7 4821.2 6087.6 7458.7 7534.8 7536.8 7551.8 4855.8 4858.7 4874.6 4875.3 4895.4 4949.4 4950.9 4960.3 4961.7 4971.5 5675.2 5885.8 5900.3 5907.4 5916.7 7177.1 7190.4 7192.5 7205.9 7225.2 1680.1 1725.3 1725.8 1727.7 1735.0 1039.7 1054.6 1095.1 1105.4 1128.5 6669.7 6775.4 6791.4 6794.1 6798.4 7480.8 7490.1 7494.0 7495.1 7510.8 6658.6 6771.4 6778.2 6789.7 6947.5 3405.9 3789.0 3964.3 3964.5 3970.9 6407.7 7702.8 7799.1 7806.3 7852.2 7649.6 7681.4 7684.4 7699.5 7722.7 6289.3 7809.6 7810.1 7820.8 7832.4 7199.1 7477.4 7479.7 7493.4 7506.3 7094.5 7313.4 7388.2 7396.6 7427.9 6455.4 7850.2 7902.8 7903.6 7918.3 4008.8 4654.2 4686.7 4724.9 4734.6 6485.1 7008.6 7164.3 7431.5 7459.6 7132.7 7198.0 7207.7 7215.6 7221.2 7163.2 7163.4 7168.4 7180.6 7206.7 6999.7 7001.0 7001.0 7001.2 7015.5 6890.2 7153.0 7175.0 7195.9 7201.1 5406.7 6811.2 6912.5 7004.6 7008.4 03:06:57 INFO - PROCESS | 1664 | 03:06:57 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18252.28 Median:601.86 stddev:25990.83 (4318.4%) stddev-sans-first:26030.25 03:06:57 INFO - PROCESS | 1664 | Values: 680.9 747.5 755.4 762.9 780.4 14953.0 15130.2 15193.5 15232.5 15234.3 13788.4 14033.6 14037.2 14042.5 14055.1 752.2 764.8 917.9 953.8 954.1 933.1 955.4 955.9 956.6 957.4 1007.4 1012.7 1013.4 1013.6 1017.9 781.7 984.3 1001.7 1005.3 1006.8 741.5 745.7 746.2 747.1 748.4 15157.3 15194.1 15194.3 15214.5 15265.7 12684.2 12749.7 12772.8 12867.8 13034.0 9297.3 9508.7 10151.1 10298.0 10491.7 3407.2 3519.5 3525.0 3533.3 3583.2 85191.7 85820.0 86402.0 88987.0 89759.0 9415.9 9450.5 9492.1 9504.5 10029.5 22047.0 22051.9 22052.1 22073.2 22086.6 7349.2 7457.2 7547.8 7647.0 7654.2 4014.4 4077.8 4083.6 4140.7 4332.8 3671.6 3746.9 3749.5 3757.6 3760.8 624.9 644.8 664.9 676.1 696.5 544.7 585.3 594.6 595.3 607.4 8144.7 8199.3 8254.0 8269.2 8293.5 497.6 596.3 607.9 608.7 620.4 3842.7 3876.5 3893.5 4002.4 4005.8 89185.0 89653.0 90769.5 91030.0 91652.0 77875.6 78413.0 79534.0 79791.0 81059.0 65804.7 65914.3 67576.0 68822.0 69388.7 7821.8 7835.8 7936.0 7940.4 7952.7 47819.5 49362.0 49490.5 50073.2 50847.7 64803.0 66825.7 68552.7 69173.0 70123.0 10012.0 10139.4 10140.9 10222.4 10243.9 10772.7 11033.8 11148.8 11199.7 11725.8 10123.9 10479.2 10592.6 10686.9 10707.2 10298.9 10401.9 10403.5 10455.6 10598.7 9899.2 9904.5 10228.8 10348.2 10434.8 8721.8 8875.9 8896.3 8939.7 9123.9 8876.8 8907.2 8932.2 8957.0 9099.3 03:06:57 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:06:57 INFO - PROCESS | 1664 | 03:06:58 INFO - TEST-INFO | 1664: exit 0 03:06:58 INFO - TEST-OK | dromaeo_css | took 1114687ms 03:06:58 INFO - TEST-START | kraken 03:06:58 INFO - operating with platform_type : mac_ 03:06:58 INFO - Initialising browser for kraken test... 03:06:58 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpydUS3u/profile http://localhost:49275/getInfo.html) 03:07:00 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 03:07:00 INFO - PROCESS | 1675 | colorDepth:24 03:07:00 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 03:07:00 INFO - PROCESS | 1675 | __metrics 03:07:00 INFO - PROCESS | 1675 | 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" 03:07:00 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:07:01 INFO - TEST-INFO | 1675: exit 0 03:07:01 INFO - Browser initialized. 03:07:01 INFO - Running cycle 1/1 for kraken test... 03:07:01 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpydUS3u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:13 INFO - PROCESS | 1678 | RSS: Main: 211603456 03:07:13 INFO - PROCESS | 1678 | 03:07:15 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:07:16 INFO - PROCESS | 1678 | RSS: Main: 271577088 03:07:16 INFO - PROCESS | 1678 | 03:07:19 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:07:20 INFO - PROCESS | 1678 | RSS: Main: 285921280 03:07:20 INFO - PROCESS | 1678 | 03:07:23 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:07:24 INFO - PROCESS | 1678 | RSS: Main: 372355072 03:07:24 INFO - PROCESS | 1678 | 03:07:26 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:07:26 INFO - PROCESS | 1678 | RSS: Main: 279166976 03:07:26 INFO - PROCESS | 1678 | 03:07:28 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:07:29 INFO - PROCESS | 1678 | RSS: Main: 280403968 03:07:29 INFO - PROCESS | 1678 | 03:07:32 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:07:32 INFO - PROCESS | 1678 | RSS: Main: 319356928 03:07:32 INFO - PROCESS | 1678 | 03:07:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:07:36 INFO - PROCESS | 1678 | RSS: Main: 318779392 03:07:36 INFO - PROCESS | 1678 | 03:07:39 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:07:40 INFO - PROCESS | 1678 | RSS: Main: 318365696 03:07:40 INFO - PROCESS | 1678 | 03:07:41 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:07:42 INFO - PROCESS | 1678 | RSS: Main: 309104640 03:07:42 INFO - PROCESS | 1678 | 03:07:44 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:07:44 INFO - PROCESS | 1678 | RSS: Main: 316616704 03:07:44 INFO - PROCESS | 1678 | 03:07:46 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:07:47 INFO - PROCESS | 1678 | RSS: Main: 318959616 03:07:47 INFO - PROCESS | 1678 | 03:07:50 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:07:51 INFO - PROCESS | 1678 | RSS: Main: 323956736 03:07:51 INFO - PROCESS | 1678 | 03:07:53 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:07:54 INFO - PROCESS | 1678 | RSS: Main: 322027520 03:07:54 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:07:56 INFO - PROCESS | 1678 | RSS: Main: 386138112 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | __start_tp_report 03:07:56 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 03:07:56 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 03:07:56 INFO - PROCESS | 1678 | |i|pagename|runs| 03:07:56 INFO - PROCESS | 1678 | |0;ai-astar;113;113;119;113;111;110;114;113;113;141 03:07:56 INFO - PROCESS | 1678 | |1;audio-beat-detection;151;116;164;202;142;130;154;159;181;144 03:07:56 INFO - PROCESS | 1678 | |2;audio-dft;186;186;186;186;192;182;186;186;184;184 03:07:56 INFO - PROCESS | 1678 | |3;audio-fft;68;78;73;151;55;56;57;63;131;56 03:07:56 INFO - PROCESS | 1678 | |4;audio-oscillator;88;88;140;78;80;85;123;78;77;127 03:07:56 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;90;87;90;86;89;86;89;87;89;96 03:07:56 INFO - PROCESS | 1678 | |6;imaging-darkroom;94;94;94;106;148;119;95;113;98;148 03:07:56 INFO - PROCESS | 1678 | |7;imaging-desaturate;86;84;85;83;85;86;84;84;104;135 03:07:56 INFO - PROCESS | 1678 | |8;json-parse-financial;59;64;64;65;64;64;65;79;59;58 03:07:56 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;63;65;71;78;75;71;70;68;68;67 03:07:56 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;79;81;81;82;80;79;80;78;82;85 03:07:56 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;128;128;127;151;116;142;119;144;161;166 03:07:56 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;167;165;164;171;159;158;164;178;163;163 03:07:56 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;67;66;68;67;68;66;66;67;64;70 03:07:56 INFO - PROCESS | 1678 | __end_tp_report 03:07:56 INFO - PROCESS | 1678 | __start_cc_report 03:07:56 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,2108 03:07:56 INFO - PROCESS | 1678 | __end_cc_report 03:07:56 INFO - PROCESS | 1678 | __startTimestamp1463393276363__endTimestamp 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | ------- Summary: start ------- 03:07:56 INFO - PROCESS | 1678 | Number of tests: 14 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:116.00 Median:113.00 stddev:9.09 (8.0%) stddev-sans-first:9.58 03:07:56 INFO - PROCESS | 1678 | Values: 113.0 113.0 119.0 113.0 111.0 110.0 114.0 113.0 113.0 141.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:154.30 Median:156.50 stddev:24.56 (15.7%) stddev-sans-first:26.02 03:07:56 INFO - PROCESS | 1678 | Values: 151.0 116.0 164.0 202.0 142.0 130.0 154.0 159.0 181.0 144.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:185.80 Median:186.00 stddev:2.57 (1.4%) stddev-sans-first:2.73 03:07:56 INFO - PROCESS | 1678 | Values: 186.0 186.0 186.0 186.0 192.0 182.0 186.0 186.0 184.0 184.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:78.80 Median:60.00 stddev:34.03 (56.7%) stddev-sans-first:35.86 03:07:56 INFO - PROCESS | 1678 | Values: 68.0 78.0 73.0 151.0 55.0 56.0 57.0 63.0 131.0 56.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:96.40 Median:79.00 stddev:23.89 (30.2%) stddev-sans-first:25.15 03:07:56 INFO - PROCESS | 1678 | Values: 88.0 88.0 140.0 78.0 80.0 85.0 123.0 78.0 77.0 127.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:88.90 Median:89.00 stddev:2.92 (3.3%) stddev-sans-first:3.07 03:07:56 INFO - PROCESS | 1678 | Values: 90.0 87.0 90.0 86.0 89.0 86.0 89.0 87.0 89.0 96.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:94.00 stddev:21.40 (22.8%) stddev-sans-first:21.81 03:07:56 INFO - PROCESS | 1678 | Values: 94.0 94.0 94.0 106.0 148.0 119.0 95.0 113.0 98.0 148.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:84.50 stddev:16.45 (19.5%) stddev-sans-first:17.32 03:07:56 INFO - PROCESS | 1678 | Values: 86.0 84.0 85.0 83.0 85.0 86.0 84.0 84.0 104.0 135.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.10 Median:64.00 stddev:5.90 (9.2%) stddev-sans-first:5.96 03:07:56 INFO - PROCESS | 1678 | Values: 59.0 64.0 64.0 65.0 64.0 64.0 65.0 79.0 59.0 58.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.50 stddev:4.48 (6.4%) stddev-sans-first:4.06 03:07:56 INFO - PROCESS | 1678 | Values: 63.0 65.0 71.0 78.0 75.0 71.0 70.0 68.0 68.0 67.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:80.70 Median:81.00 stddev:2.00 (2.5%) stddev-sans-first:2.03 03:07:56 INFO - PROCESS | 1678 | Values: 79.0 81.0 81.0 82.0 80.0 79.0 80.0 78.0 82.0 85.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:143.00 stddev:17.32 (12.1%) stddev-sans-first:17.97 03:07:56 INFO - PROCESS | 1678 | Values: 128.0 128.0 127.0 151.0 116.0 142.0 119.0 144.0 161.0 166.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:164.50 stddev:5.81 (3.5%) stddev-sans-first:6.12 03:07:56 INFO - PROCESS | 1678 | Values: 167.0 165.0 164.0 171.0 159.0 158.0 164.0 178.0 163.0 163.0 03:07:56 INFO - PROCESS | 1678 | 03:07:56 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.90 Median:67.00 stddev:1.60 (2.4%) stddev-sans-first:1.69 03:07:56 INFO - PROCESS | 1678 | Values: 67.0 66.0 68.0 67.0 68.0 66.0 66.0 67.0 64.0 70.0 03:07:56 INFO - PROCESS | 1678 | -------- Summary: end -------- 03:07:56 INFO - PROCESS | 1678 | 03:07:57 INFO - TEST-INFO | 1678: exit 0 03:07:57 INFO - TEST-OK | kraken | took 58829ms 03:07:57 INFO - SUITE-END | took 1173s 03:07:57 INFO - Completed test suite (00:19:33) 03:07:57 INFO - javascript benchmark 03:07:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3403.9829800850994, 3484.181217413909, 3503.6146023937345, 3507.018034950214, 3544.1316877365034, 5672.744726487308, 5700.543956483481, 5712.200291959119, 5735.569832262581, 5741.655500669952, 5709.200711900337, 5734.455226753462, 5758.827235182941, 5766.538676905848, 5800.216970708953, 5081.085404627165, 5143.588512918966, 5164.302819119427, 5228.673370094072, 5343.385510666265, 5253.395859476152, 5262.81580144695, 5266.368035835705, 5272.051030814452, 5277.683338999673, 5177.990291893082, 5205.661631993928, 5228.5555727763185, 5235.790568377261, 5272.841814745564, 5122.001209764097, 5131.589472842164, 5153.587712982952, 5157.458466860963, 5204.063268611649, 5048.368953880765, 5048.7223202723835, 5084.237364395333, 5111.591072714174, 5121.052605268, 5120.334356533664, 5612.326520817528, 5673.149027645845, 5696.088625819891, 5708.486236238717, 6261.499080073583, 6270.122182894398, 6271.717772700239, 6271.874562508724, 6273.3726627337355, 6107.725152368154, 6188.0, 6258.655773932432, 6286.748530058792, 6291.653959032252, 5879.853003674943, 5889.116632505158, 5913.881722365529, 5914.4676979071655, 5954.49386802118, 6270.404311590391, 6284.560080794392, 6290.308066112724, 6298.905516417258, 6303.716332765037, 6078.300995385499, 6096.146539484475, 6135.630783975068, 6162.168107305456, 6203.0, 6237.563370564108, 6255.468285195719, 6282.120503129566, 6287.7799277026, 6296.43332100104, 6140.754369825157, 6171.722272497748, 6206.317305096436, 6212.06818977157, 6214.4407003369915, 6128.19758601264, 6213.720382582795, 6223.471004964629, 6229.0, 6234.407731265523, 4172.624463798273, 4868.9269660955115, 4902.28916807061, 4907.411110666671, 4914.533119353656, 4391.143726973273, 4420.491643460979, 4439.445069366329, 4486.304622783474, 4506.459224893034, 5740.913886291709, 5742.052561327418, 5753.913691294634, 5775.249217601685, 5806.129080637938, 5689.748255383809, 5862.648241105548, 6140.504248697391, 6220.098085777628, 6244.438000579969, 5734.885302293931, 5740.42595740431, 5742.684152371576, 5746.913796293059, 5779.624324418899, 5338.946610533845, 5353.866153346197, 5359.892802143935, 5368.248445217672, 5370.0065487884085, 6220.533459990428, 6226.0, 6255.499560035186, 6285.1515045470105, 6298.968505157445, 5976.874300634092, 6281.937180628318, 6282.905756413749, 6301.149344838388, 6332.018537126753, 6307.337729538516, 6312.14786003902, 6319.178506794087, 6323.652199129092, 6325.051242313506, 6094.963856144377, 6140.074827791203, 6175.0, 6206.224221972254, 6236.937630623635, 6281.748730050746, 6299.842503937438, 6307.558470906993, 6325.683715814283, 6338.556301058881, 6076.54425918049, 6117.602355846856, 6143.139960405636, 6152.261728592597, 6156.199694039746, 5996.430339117776, 6027.186329845415, 6045.939540604538, 6112.235970503687, 6134.846628834136, 5803.82588522345, 5901.439363260483, 5908.409159084057, 5955.017422125301, 5960.433758792908, 6086.543509236913, 6102.018589523632, 6121.239585875234, 6126.508817326647, 6135.631862088289, 5788.623739456922, 5874.618149820248, 5965.164876917148, 6083.66539840313, 6115.908261376172], "value": 5702.314444902406, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.4493282053845, 524.5384062025419, 558.4945624210092, 587.3011116771044, 602.5992714844629, 30629.0, 31245.90639327624, 31340.68659313406, 31343.68656313439, 31406.685933140663, 38975.61024389755, 40261.39607905884, 40345.78962631118, 40448.98877528063, 40481.39277910833, 9487.715368538955, 9632.0, 9658.758531036745, 9669.12977831995, 9676.467794271312, 9581.377210481296, 9717.951410242938, 9729.027097290285, 9735.610575577004, 9736.026397360278, 9679.90320096797, 9738.318317717763, 9753.853692194623, 9806.509674516246, 9837.803243935117, 9720.854187187198, 9790.118889299962, 9793.706188814347, 9800.95099524505, 9805.509724513746, 9544.188743956773, 9673.758156046155, 9680.515974201262, 9690.32167748258, 9694.079062489052, 40249.59750402517, 40662.59337406618, 40720.0, 40764.592354076376, 40768.38847417291, 3201.27971206479, 3335.849886755102, 3445.5693038370205, 3666.6699997000132, 3700.3709369406997, 28299.717002829915, 28355.71644283572, 28509.429811403865, 28648.0, 29088.12735617935, 2723.2783312422225, 2740.6026126211586, 2745.807793454459, 2798.3983443559528, 2856.9142925712263, 438559.42160867614, 439330.9999999968, 440570.0, 441443.0, 441782.0, 60686.69656651688, 63142.73714525684, 63380.0, 63427.0, 63439.0, 68252.97620535696, 69203.0, 69278.65360673165, 69309.30690693129, 69386.30613693799, 17144.39994600208, 17335.739963900553, 17336.133193340283, 17352.39266625662, 17423.73864392035, 13265.204087754766, 13359.599212023655, 13365.933170334087, 13369.532066377824, 13406.597802065953, 19110.88553173717, 19794.60410791805, 19810.50473738168, 19815.900920495595, 19846.801531984784, 8322.250997410261, 8403.453775504573, 8447.788805279917, 8456.957715211385, 8494.748891543746, 935.1677007463363, 1005.4269066631949, 1033.478093562746, 1056.6777132974516, 1071.2608300272943, 29923.401531969237, 29999.500024998662, 30548.54177187344, 30635.8468207662, 30700.69299307023, 1145.307089211032, 1152.5332240442633, 1178.5639313454076, 1197.9700507487382, 1204.241327963395, 14920.477783277533, 15259.847401526065, 15275.312610932535, 15594.376224951102, 15642.68714625701, 336980.0, 339887.6011239856, 342493.0, 343510.0, 631629.0, 59879.0, 60064.099038514454, 60132.0, 60146.0, 60167.699161503915, 1424.6153538544743, 1464.4361920660622, 1501.06183635228, 1523.7028779387913, 1524.7407940649894, 28706.13881583556, 28866.711332887244, 29822.850885746302, 29954.550681740227, 30366.84816575903, 48291.517084830106, 48798.756006219744, 48840.75579622221, 48993.0, 48998.75500622474, 435642.0, 439633.0, 439707.0, 439749.0, 440180.0, 23214.955327009986, 26332.605010925236, 26651.20046398611, 26834.865825670746, 27040.0, 41906.37140442957, 42306.36540451834, 42814.0, 43348.56651433571, 43478.0, 28444.85777571099, 28821.855890720413, 34803.0, 35360.0, 35906.10234744152, 35258.0, 35428.64571354254, 35602.46596301109, 35878.0, 36061.45907811332, 36144.096397589215, 36898.0, 37173.442398363506, 37594.8120259397, 37635.0, 27455.725442746116, 31420.371592568477, 31940.840295799302, 32416.513752293264, 32601.391888649327, 24286.757132429157, 24287.0, 24325.270241892773, 24808.131715389874, 24853.0], "value": 19399.302251753546, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.94600728901605, 403.304300082358, 404.7955782329923, 404.90889549851266, 413.1983951134798, 15180.848191518082, 15275.46535871242, 19935.501612459677, 20548.972551372426, 20618.278360257376, 19962.700559491583, 20517.97410129495, 20843.37469875906, 21037.474063148427, 21082.36752897412, 5758.42415758425, 5978.790742324019, 5979.730912108966, 6010.57925945184, 6020.939790602104, 5715.9428405716035, 5859.7656093756195, 5887.587868849182, 5892.587518873659, 5902.203202567642, 5649.23735295734, 5842.685395785957, 5854.385289544601, 5859.414058594128, 5861.736221870005, 5873.236479257669, 5898.056310990264, 5899.262592175978, 5906.970465147669, 5912.526997840162, 5080.74596270185, 5144.228365745131, 5155.690658560461, 5158.252053452228, 5159.432462429129, 24955.376115597075, 25097.62353564698, 25268.747312526822, 25328.366790830194, 25387.0, 2215.601191785478, 2229.8662080275235, 2244.7418546866993, 2269.863808171515, 2274.73840508342, 14426.855731442656, 14623.0, 14670.04644698102, 15149.772753408708, 15206.391744330214, 1687.6371580110335, 1733.8612910967092, 1753.8070812210647, 1754.2018381636324, 1817.264008076731, 79334.0, 79618.99999999942, 92855.0, 97751.0, 113923.86076139215, 10958.506867190996, 11104.888951110546, 11179.4969226385, 11213.66359009231, 11313.490892909838, 13896.23570703621, 13973.930130349487, 13993.860061399459, 14015.439382424589, 14026.0, 8827.867581986275, 8980.910190898137, 9032.819343613222, 9058.864117038249, 9107.772305692411, 7963.800904977422, 9151.222146117654, 9151.313651476166, 9207.769805754775, 9243.214326782165, 6534.771283005072, 6630.336966303331, 6633.502487313471, 6658.1344425224415, 6669.7332106715185, 1752.7458518514743, 1794.4078454110113, 1871.8315351618419, 1902.8858268503934, 1930.835878950305, 921.8340698674303, 1061.9575216991234, 1091.9344839309667, 1097.8408130820985, 1128.5316593613559, 19867.900660496893, 20496.897515512625, 21110.0, 21297.893510532347, 21332.253371131937, 1017.4048181813722, 1022.085233715835, 1028.984565231522, 1039.859618951432, 1097.3470784882982, 9525.0, 11101.44492775358, 11579.0, 11594.536218551331, 11601.361925094032, 530351.0, 590074.0, 601583.0, 630871.0, 635050.0, 113160.0, 114549.0, 115053.0, 117727.0, 118138.40930795291, 1052.4264275969615, 1061.3472714280663, 1067.80779459698, 1069.8662667166604, 1071.4910417551757, 14789.260536972894, 15283.541493755205, 18009.919404835753, 18286.908565457088, 19001.049947502845, 47700.0, 47818.28272575984, 48753.75623121862, 48960.75519622379, 49065.754671226416, 60076.39923600708, 64240.678796605716, 64480.03279950898, 65270.67364663146, 66281.0, 6863.450923926173, 7866.331361834308, 7998.160193179632, 8044.35645148375, 8351.29014033814, 9882.456464894212, 12386.380680966096, 12768.106232563632, 12829.23024618526, 13004.284764338052, 10879.51042203087, 11173.217874748689, 11203.71990700239, 11469.0251328638, 11557.47991340375, 9103.453792772454, 9161.992180859967, 10783.406912619881, 11002.064824490008, 11156.44217789091, 11197.944010279896, 11211.775764484828, 11233.719157020812, 11418.143639226924, 11523.135764817505, 7432.2567743225245, 7912.28789408956, 7918.3665306776475, 8219.83560328802, 8299.751007469786, 10922.890771092187, 11034.282771620141, 11042.447877605928, 11245.269057511236, 11410.82883756761], "value": 9584.590124843884, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1349.1298112717304, 1569.7802307676923, 1582.7784110224568, 1862.6647203503373, 1995.7704863940642, 7923.445358824884, 8572.485650860952, 8682.826343473127, 8720.127987201284, 8741.519216443094, 8381.28759055481, 8654.350923680719, 8680.869786953184, 8686.739397818059, 8700.99938507072, 2397.9640305395433, 2418.9274321770376, 2422.5518279118332, 2422.9757702423017, 2426.623873299641, 2425.9393515162087, 2447.1801946347905, 2467.629855521668, 2474.0474917156844, 2483.726790053093, 2438.4635380216323, 2444.828861979662, 2452.9632055519182, 2475.579151544233, 2478.876056197192, 2409.0, 2419.8669073200967, 2432.015033911269, 2434.6834911461574, 2455.1652438170927, 2386.832921695482, 2392.138830021191, 2396.065534441569, 2412.7104747430244, 2428.939276518101, 8513.106123856967, 8760.167784060504, 8796.912030879674, 8816.515091669955, 8831.072737362549, 5326.547243484309, 5507.118860982264, 5511.696856672881, 5527.889442211173, 5534.08687566551, 3504.824758762077, 3596.6801413088674, 3635.2547727715755, 3639.927201455956, 3673.5591728992426, 1040.687793661906, 1069.0538873097364, 1070.0957690751302, 1074.2050882347085, 1075.8171110911164, 11742.295462272206, 11846.407679615984, 11949.342786146759, 11973.102017348734, 11976.221545599596, 4555.658325625525, 4624.121416930772, 4706.058788242365, 4712.52874712526, 4738.028704115648, 9283.628654853865, 9294.466413041773, 9306.976232614406, 9314.0, 9323.580438880266, 5355.3305836770405, 5376.677399356051, 5390.407055223922, 5392.676439413569, 5393.865153371197, 4413.16149931512, 4560.224761790503, 4824.011077729057, 4949.678270912379, 5042.823501177441, 3822.4266360046217, 3838.788866612363, 3912.2371137628456, 3930.2336044471053, 3933.4099885017476, 1208.2629595946466, 1209.60687776473, 1210.2194084815244, 1217.8112392579164, 1221.0841868598552, 714.8677494663503, 835.757630287222, 915.8076803871129, 952.7951490429591, 956.4500412262977, 5679.31848178213, 5785.82642520725, 5847.830433913235, 5903.173555702205, 5949.137375080589, 481.8867566121959, 489.1439980034939, 506.7694199139383, 509.4294390282907, 652.7976327338446, 3726.664600185942, 3876.8449262029767, 3900.7854567998465, 3981.203759248162, 4001.519817621904, 10039.19686425099, 10275.897241027495, 10553.84169237462, 10611.57553697859, 10618.36289822613, 8897.777555561033, 10373.27387082912, 10383.84424233637, 10500.422476763699, 10504.0, 7895.7236496722335, 8150.796230094176, 8198.262156405952, 8280.089190188954, 8308.418410711312, 5738.913916291175, 5872.212313760792, 6019.759209631741, 6065.787697430569, 6081.239845019372, 9281.67514137002, 9668.9033109668, 9879.06148915852, 9891.307608467627, 9891.802163956825, 9776.169025632616, 9873.259505537257, 10025.398476091457, 10044.648437304659, 10065.597376105166, 7553.7356192533, 7625.351845093229, 7685.346745526693, 7697.65360558765, 7700.0, 8235.588220588827, 8292.419530632798, 8338.249557539608, 8370.246677799027, 8387.622556984828, 7451.143118541437, 7502.587357695161, 7513.774586762406, 7583.80220302726, 7599.316061554487, 7344.155422126524, 7400.111986561647, 7439.330460258603, 7445.032145821008, 7468.10382754073, 6849.212340580897, 6996.930030699627, 7023.543469674455, 7060.188078371053, 7080.2919708028785, 4666.78999445019, 4737.857864264078, 4804.279358096313, 4811.807527698853, 4858.975705121452, 4938.987507561013, 4950.8762280943265, 5020.046191223656, 5045.84862454127, 5212.9739351303], "value": 4518.251602717706, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.18396049973813, 328.4826398422484, 335.18382738032886, 337.7433150805387, 341.18967452300785, 6652.201735791712, 6669.366410191036, 6673.466122710183, 6682.331766823315, 6683.498737594681, 6579.671016449171, 6581.0, 6631.933680663192, 6690.933090669092, 6729.327067293269, 4705.7647117644065, 4784.90430191396, 4797.352357431754, 4894.902101957959, 4918.385201849769, 4902.828401005965, 4924.556789888909, 4974.1543937530705, 4977.228529577921, 4980.402351717781, 3708.128589781399, 4050.9594904051023, 4481.798319075633, 4872.65891387603, 4905.288733133693, 4642.257238841786, 4738.384010078702, 4841.2012018016985, 4847.975760121212, 4848.757562121879, 4177.811998460076, 4181.916361672749, 4189.937150942738, 4199.433076534659, 4208.389783481378, 4793.568578827887, 5854.443827836349, 6389.8402539936405, 6620.37106474889, 6624.436922861562, 5869.090291004901, 5872.765089396419, 5878.647281163144, 5884.970575147139, 5895.204147440084, 6865.9313406866295, 7118.145822501333, 7127.964360178166, 7131.465140114512, 7137.714491420337, 4786.305985632099, 4789.090072886175, 4799.7360145192, 4815.711057336571, 4821.156297647933, 6087.573869829113, 7458.664360103754, 7534.849303013963, 7536.811579710551, 7551.848963020709, 4855.781489832966, 4858.708477491362, 4874.610031197496, 4875.317455556225, 4895.4370247422, 4949.406071271398, 4950.861301900511, 4960.255961605788, 4961.677490963076, 4971.45314015458, 5675.1770993205755, 5885.764569417261, 5900.291964964146, 5907.438793314628, 5916.704164791743, 7177.066981292503, 7190.3888169505135, 7192.460565457612, 7205.855882882313, 7225.205227424979, 1680.126334306154, 1725.2753843385808, 1725.7583938248656, 1727.6976529107355, 1735.019713861664, 1039.6725031615017, 1054.5623566219933, 1095.1239008792934, 1105.3699391346854, 1128.4639796096787, 6669.699863506154, 6775.4240889524945, 6791.422729068085, 6794.116764820542, 6798.422134118655, 7480.812979675552, 7490.138634057044, 7494.0, 7495.138059123271, 7510.812229694301, 6658.600483970978, 6771.424428923597, 6778.2205046420295, 6789.6605169741315, 6947.548409353376, 3405.931881362359, 3788.956614200346, 3964.266610677034, 3964.5044369453817, 3970.927849480624, 6407.686424283011, 7702.768916932501, 7799.103103143211, 7806.336461400844, 7852.175521570153, 7649.617519124022, 7681.42389320792, 7684.423668224906, 7699.538027718355, 7722.6910923563555, 6289.308176100625, 7809.648565814552, 7810.1408845027, 7820.804479887935, 7832.412569057229, 7199.1001124859395, 7477.364424023801, 7479.6634151464405, 7493.4379921505015, 7506.28690274423, 7094.4679149062995, 7313.378362839218, 7388.1503627083575, 7396.593187374746, 7427.888581671387, 6455.419012288917, 7850.214978502104, 7902.802429939297, 7903.56530390834, 7918.326942209977, 4008.7995600220456, 4654.16225079489, 4686.695364801277, 4724.881877953078, 4734.55021772931, 6485.124508191334, 7008.649567521706, 7164.319389658182, 7431.479796414199, 7459.552426854406, 7132.714691412493, 7198.0, 7207.747728829676, 7215.60314182725, 7221.205667376479, 7163.212046674966, 7163.391111755559, 7168.354848063699, 7180.605066721171, 7206.747763828241, 6999.650017499206, 7000.964995174992, 7000.964995175195, 7001.194862590867, 7015.473839461959, 6890.20762612306, 7153.0, 7174.964125179341, 7195.8560828784175, 7201.099862517185, 5406.729663516887, 6811.216710078206, 6912.513809530975, 7004.579725216503, 7008.369246767611], "value": 5218.506418327421, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.897865320202, 747.5439981611213, 755.3692666623373, 762.9008228930238, 780.4341852157185, 14953.028053176537, 15130.243487825619, 15193.468228612, 15232.466863659774, 15234.314455849486, 13788.448462061506, 14033.649158771035, 14037.22795246261, 14042.508512202076, 14055.086419382733, 752.1801236652049, 764.7591008832228, 917.8669092981497, 953.7806304549957, 954.0697819625844, 933.0948979489918, 955.4171955107379, 955.8614000969856, 956.5934477846887, 957.4159762544842, 1007.4056306778999, 1012.736688460998, 1013.3767732844321, 1013.5641674080142, 1017.9287449878511, 781.749840051181, 984.251968503935, 1001.6794625719773, 1005.3465247589053, 1006.753345430367, 741.4698490579261, 745.6644509970535, 746.1792028768366, 747.0960138232745, 748.3938010211746, 15157.317920693595, 15194.088354698753, 15194.316255768517, 15214.543563693107, 15265.694686106215, 12684.238945663197, 12749.681257968625, 12772.805189483093, 12867.806982895265, 13034.022448316415, 9297.302702297287, 9508.667196648154, 10151.086402223762, 10298.0, 10491.73770655732, 3407.1822762537063, 3519.454484554898, 3525.0306165804354, 3533.3286675531567, 3583.157957879896, 85191.72212416818, 85820.0, 86402.0, 88987.0, 89758.99999999935, 9415.858762118574, 9450.52747362643, 9492.050794920453, 9504.524773761284, 10029.498525073723, 22047.0, 22051.89740512968, 22052.11791528353, 22073.227437039626, 22086.558268834855, 7349.191588925214, 7457.179710231871, 7547.811304717426, 7647.0, 7654.158042615308, 4014.3777714454304, 4077.836886524565, 4083.612056854594, 4140.68944829139, 4332.848350307724, 3671.5594128704724, 3746.9437958430644, 3749.531308586427, 3757.5678796938155, 3760.8495660173285, 624.9157711370768, 644.7711062572719, 664.9135612370361, 676.0670275020441, 696.5472442912147, 544.6840832317247, 585.344414256036, 594.625386006813, 595.2529575382866, 607.4259824465926, 8144.674213031412, 8199.344052475788, 8254.009518857656, 8269.245109492727, 8293.460925039974, 497.6118627470579, 596.2874365133711, 607.9057746049369, 608.695652173913, 620.3517324396017, 3842.6733727632904, 3876.476675648808, 3893.493845800028, 4002.419649150915, 4005.759654420744, 89185.0, 89653.0, 90769.54615227014, 91030.0, 91652.0, 77875.61062194766, 78413.0, 79534.0, 79791.00522486868, 81059.0, 65804.6709766448, 65914.34085659082, 67576.0, 68822.0, 69388.6530567354, 7821.804454888674, 7835.764927052198, 7935.960320198362, 7940.364770818436, 7952.681892724227, 47819.52180478151, 49362.012759743884, 49490.50509494859, 50073.24890126724, 50847.74576127244, 64803.0, 66825.66587167032, 68552.6572367135, 69173.0, 70123.0, 10012.04885535873, 10139.391636501834, 10140.949295253477, 10222.386656800616, 10243.897561024294, 10772.743255892048, 11033.779324413626, 11148.832767508657, 11199.72000699989, 11725.824112638487, 10123.949380253052, 10479.161667066464, 10592.576296948037, 10686.89313106859, 10707.19696022817, 10298.897011030094, 10401.94799026, 10403.53184106732, 10455.634052808115, 10598.735031624272, 9899.20806335477, 9904.455254960758, 10228.795424091626, 10348.172146228435, 10434.843477347998, 8721.781955451164, 8875.911240887766, 8896.288296936358, 8939.73180804577, 9123.863142053007, 8876.822463550821, 8907.153820387053, 8932.151445612657, 8956.969948456011, 9099.31755118382], "value": 6866.207465865076, "unit": "score"}], "name": "dromaeo_css", "value": 7454.986827044868, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 119.0, 113.0, 111.0, 110.0, 114.0, 113.0, 113.0, 141.0], "value": 116.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 116.0, 164.0, 202.0, 142.0, 130.0, 154.0, 159.0, 181.0, 144.0], "value": 154.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 186.0, 186.0, 192.0, 182.0, 186.0, 186.0, 184.0, 184.0], "value": 185.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 78.0, 73.0, 151.0, 55.0, 56.0, 57.0, 63.0, 131.0, 56.0], "value": 78.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 140.0, 78.0, 80.0, 85.0, 123.0, 78.0, 77.0, 127.0], "value": 96.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 86.0, 89.0, 86.0, 89.0, 87.0, 89.0, 96.0], "value": 88.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 106.0, 148.0, 119.0, 95.0, 113.0, 98.0, 148.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 83.0, 85.0, 86.0, 84.0, 84.0, 104.0, 135.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 79.0, 59.0, 58.0], "value": 64.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 71.0, 78.0, 75.0, 71.0, 70.0, 68.0, 68.0, 67.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 81.0, 81.0, 82.0, 80.0, 79.0, 80.0, 78.0, 82.0, 85.0], "value": 80.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 128.0, 127.0, 151.0, 116.0, 142.0, 119.0, 144.0, 161.0, 166.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 164.0, 171.0, 159.0, 158.0, 164.0, 178.0, 163.0, 163.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 68.0, 67.0, 68.0, 66.0, 66.0, 67.0, 64.0, 70.0], "value": 66.9, "unit": "score"}], "name": "kraken", "value": 1507.4, "alertThreshold": 2.0}]} 03:07:57 INFO - Return code: 0 03:07:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:07:57 ERROR - # TBPL SUCCESS # 03:07:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:57 INFO - 2016-05-16 03:07:57.694 screenresolution[1682:10329] starting screenresolution argv=screenresolution get 03:07:57 INFO - 2016-05-16 03:07:57.696 screenresolution[1682:10329] Display 0: 1600x1200x32@60 03:07:57 INFO - 2016-05-16 03:07:57.702 screenresolution[1683:10332] starting screenresolution argv=screenresolution list 03:07:57 INFO - Available Modes on Display 0 03:07:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:57 INFO - Intel Iris: 03:07:57 INFO - Chipset Model: Intel Iris 03:07:57 INFO - Type: GPU 03:07:57 INFO - Bus: Built-In 03:07:57 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:57 INFO - Vendor: Intel (0x8086) 03:07:57 INFO - Device ID: 0x0a2e 03:07:57 INFO - Revision ID: 0x0009 03:07:57 INFO - Displays: 03:07:57 INFO - Display: 03:07:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:57 INFO - Display Serial Number: 1600x1200 60 03:07:57 INFO - Main Display: Yes 03:07:57 INFO - Mirror: Off 03:07:57 INFO - Online: Yes 03:07:57 INFO - Rotation: Supported 03:07:57 INFO - Return code: 0 03:07:57 INFO - Running post-action listener: _resource_record_post_action 03:07:57 INFO - Running post-run listener: _resource_record_post_run 03:07:58 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 36742656; Write bytes: 656302592; Read time: 239; Write time: 1833 03:07:58 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354497024; Write bytes: 357304320; Read time: 27567; Write time: 722 03:07:59 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 34915840; Write bytes: 296895488; Read time: 222; Write time: 1102 03:07:59 INFO - Running post-run listener: _upload_blobber_files 03:07:59 INFO - Blob upload gear active. 03:07:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:07:59 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:07:59 INFO - (blobuploader) - INFO - Open directory for files ... 03:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:08:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:01 INFO - (blobuploader) - INFO - Done attempting. 03:08:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:08:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:08:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:08:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:08:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:08:02 INFO - (blobuploader) - INFO - Done attempting. 03:08:02 INFO - (blobuploader) - INFO - Iteration through files over. 03:08:02 INFO - Return code: 0 03:08:02 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:08:02 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:08:02 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67c1b221b46a3b7526cd6074c8389954583f67149032fe9102d3b2a8310873ce050bbf47348b1b872741d37df51e7f32160729c30ff46b6cad71415d2d966ffe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c77be39d07fd29d835ba77404733e53ab442708b5daa7f09a0193131b12a511838e942ac033b5f91b4c34b6f32bb3c9da4b274e865f402407cb2f521b190d062"} 03:08:02 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:08:02 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:08:02 INFO - Contents: 03:08:02 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/67c1b221b46a3b7526cd6074c8389954583f67149032fe9102d3b2a8310873ce050bbf47348b1b872741d37df51e7f32160729c30ff46b6cad71415d2d966ffe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c77be39d07fd29d835ba77404733e53ab442708b5daa7f09a0193131b12a511838e942ac033b5f91b4c34b6f32bb3c9da4b274e865f402407cb2f521b190d062"} 03:08:02 INFO - Running post-run listener: copy_logs_to_upload_dir 03:08:02 INFO - Copying logs to upload dir... 03:08:02 INFO - mkdir: /builds/slave/test/build/upload/logs 03:08:02 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1289.209203 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-16 03:08:02.915865) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:02.920072) ========= 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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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/67c1b221b46a3b7526cd6074c8389954583f67149032fe9102d3b2a8310873ce050bbf47348b1b872741d37df51e7f32160729c30ff46b6cad71415d2d966ffe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c77be39d07fd29d835ba77404733e53ab442708b5daa7f09a0193131b12a511838e942ac033b5f91b4c34b6f32bb3c9da4b274e865f402407cb2f521b190d062"} build_url:https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011067 build_url: 'https://queue.taskcluster.net/v1/task/C-Et-HWSQYCduFbWndn9Ow/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/67c1b221b46a3b7526cd6074c8389954583f67149032fe9102d3b2a8310873ce050bbf47348b1b872741d37df51e7f32160729c30ff46b6cad71415d2d966ffe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c77be39d07fd29d835ba77404733e53ab442708b5daa7f09a0193131b12a511838e942ac033b5f91b4c34b6f32bb3c9da4b274e865f402407cb2f521b190d062"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:02.951473) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:02.951804) ========= 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.5zXPTncoKY/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.RyyxRmr8Mk/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-16 03:08:03.000787) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 03:08:03.001104) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-16 03:08:03.001440) ========= ========= Total master_lag: 0.19 =========