builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0081 starttime: 1461619073.9 results: success (0) buildid: 20160425095909 builduid: bf4af95f322a4411b3423b15fcf5776e revision: dcaf0a6fa115ad73bf7505a8e76464250b971a04 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 14:17:53.896882) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 14:17:53.897257) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-04-25 14:17:53.897522) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 5 secs) (at 2016-04-25 14:17:59.111236) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 14:17:59.111538) ========= 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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-25 14:17:59-- 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% 152M=0s 2016-04-25 14:17:59 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073170 ========= master_lag: 1.20 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 14:18:00.384061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 14:18:00.384417) ========= 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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.020521 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 14:18:00.423968) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 14:18:00.424299) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dcaf0a6fa115ad73bf7505a8e76464250b971a04 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev dcaf0a6fa115ad73bf7505a8e76464250b971a04 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-25 14:18:00,446 truncating revision to first 12 chars 2016-04-25 14:18:00,446 Setting DEBUG logging. 2016-04-25 14:18:00,446 attempt 1/10 2016-04-25 14:18:00,446 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/dcaf0a6fa115?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 14:18:01,327 unpacking tar archive at: mozilla-beta-dcaf0a6fa115/testing/mozharness/ program finished with exit code 0 elapsedTime=1.125176 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 14:18:01.697388) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 14:18:01.697676) ========= script_repo_revision: dcaf0a6fa115ad73bf7505a8e76464250b971a04 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 14:18:01.698045) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-25 14:18:01.698322) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2016-04-25 14:18:02.985995) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-04-25 14:18:02.986282) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:03 INFO - MultiFileLogger online at 20160425 14:18:03 in /builds/slave/test 14:18:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:18:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:03 INFO - {'append_to_log': False, 14:18:03 INFO - 'base_work_dir': '/builds/slave/test', 14:18:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:18:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:03 INFO - 'branch': 'Mozilla-Beta', 14:18:03 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:03 INFO - 'config_files': ('talos/mac_config.py',), 14:18:03 INFO - 'default_actions': ('clobber', 14:18:03 INFO - 'read-buildbot-config', 14:18:03 INFO - 'download-and-extract', 14:18:03 INFO - 'populate-webroot', 14:18:03 INFO - 'create-virtualenv', 14:18:03 INFO - 'install', 14:18:03 INFO - 'run-tests'), 14:18:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:03 INFO - 'download_minidump_stackwalk': True, 14:18:03 INFO - 'download_symbols': 'ondemand', 14:18:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:03 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:03 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:03 INFO - 'installer_path': 'installer.exe', 14:18:03 INFO - 'log_level': 'info', 14:18:03 INFO - 'log_name': 'talos', 14:18:03 INFO - 'log_to_console': True, 14:18:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:18:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:18:03 INFO - 'opt_config_files': (), 14:18:03 INFO - 'pip_index': False, 14:18:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:03 INFO - 'cmd': ('bash', 14:18:03 INFO - '-c', 14:18:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:03 INFO - 'enabled': True, 14:18:03 INFO - 'halt_on_failure': False, 14:18:03 INFO - 'name': 'check_screen_resolution'},), 14:18:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:03 INFO - 'cmd': ('bash', 14:18:03 INFO - '-c', 14:18:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:03 INFO - 'enabled': True, 14:18:03 INFO - 'halt_on_failure': False, 14:18:03 INFO - 'name': 'check_screen_resolution'},), 14:18:03 INFO - 'run_cmd_checks_enabled': True, 14:18:03 INFO - 'sps_profile': False, 14:18:03 INFO - 'sps_profile_interval': 0, 14:18:03 INFO - 'suite': 'dromaeojs', 14:18:03 INFO - 'system_bits': '32', 14:18:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:03 INFO - 'title': 't-yosemite-r7-0081', 14:18:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:03 INFO - 'use_talos_json': True, 14:18:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:03 INFO - 'work_dir': 'build'} 14:18:03 INFO - ##### 14:18:03 INFO - ##### Running clobber step. 14:18:03 INFO - ##### 14:18:03 INFO - Running pre-action listener: _resource_record_pre_action 14:18:03 INFO - Running main action method: clobber 14:18:03 INFO - rmtree: /builds/slave/test/build 14:18:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:03 INFO - Running post-action listener: _resource_record_post_action 14:18:03 INFO - ##### 14:18:03 INFO - ##### Running read-buildbot-config step. 14:18:03 INFO - ##### 14:18:03 INFO - Running pre-action listener: _resource_record_pre_action 14:18:03 INFO - Running main action method: read_buildbot_config 14:18:03 INFO - Using buildbot properties: 14:18:03 INFO - { 14:18:03 INFO - "project": "", 14:18:03 INFO - "product": "firefox", 14:18:03 INFO - "script_repo_revision": "production", 14:18:03 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 14:18:03 INFO - "repository": "", 14:18:03 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 14:18:03 INFO - "buildid": "20160425095909", 14:18:03 INFO - "pgo_build": "False", 14:18:03 INFO - "basedir": "/builds/slave/test", 14:18:03 INFO - "buildnumber": 157, 14:18:03 INFO - "slavename": "t-yosemite-r7-0081", 14:18:03 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 14:18:03 INFO - "platform": "yosemite_r7", 14:18:03 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 14:18:03 INFO - "branch": "mozilla-beta", 14:18:03 INFO - "repo_path": "releases/mozilla-beta", 14:18:03 INFO - "revision": "dcaf0a6fa115ad73bf7505a8e76464250b971a04", 14:18:03 INFO - "stage_platform": "macosx64", 14:18:03 INFO - "builduid": "bf4af95f322a4411b3423b15fcf5776e", 14:18:03 INFO - "slavebuilddir": "test" 14:18:03 INFO - } 14:18:03 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 14:18:03 INFO - Running post-action listener: _resource_record_post_action 14:18:03 INFO - ##### 14:18:03 INFO - ##### Running download-and-extract step. 14:18:03 INFO - ##### 14:18:03 INFO - Running pre-action listener: _resource_record_pre_action 14:18:03 INFO - Running main action method: download_and_extract 14:18:03 INFO - mkdir: /builds/slave/test/build/tests 14:18:03 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')]} 14:18:03 INFO - https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:18:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 14:18:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 14:18:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 14:18:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 14:18:04 INFO - Downloaded 1241 bytes. 14:18:04 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 14:18:04 INFO - Using the following test package requirements: 14:18:04 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 14:18:04 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 14:18:04 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 14:18:04 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:18:04 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 14:18:04 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 14:18:04 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 14:18:04 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 14:18:04 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 14:18:04 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 14:18:04 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 14:18:04 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 14:18:04 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 14:18:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:04 INFO - https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:18:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 14:18:05 INFO - Downloaded 17745658 bytes. 14:18:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:18:08 INFO - Return code: 0 14:18:08 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:18:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:08 INFO - https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:18:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 14:18:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 14:18:10 INFO - Downloaded 17745658 bytes. 14:18:10 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:10 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:18:13 INFO - Return code: 0 14:18:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:13 INFO - https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:18:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 14:18:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 14:18:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 14:18:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 14:18:14 INFO - Downloaded 10845194 bytes. 14:18:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:18:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:18:14 INFO - Return code: 0 14:18:14 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')]} 14:18:14 INFO - https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 14:18:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 14:18:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 14:18:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 14:18:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 14:18:17 INFO - Downloaded 88300452 bytes. 14:18:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 14:18:17 INFO - mkdir: /builds/slave/test/properties 14:18:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:17 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:17 INFO - Contents: 14:18:17 INFO - build_url:https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 14:18:18 INFO - Running post-action listener: _resource_record_post_action 14:18:18 INFO - Running post-action listener: set_extra_try_arguments 14:18:18 INFO - ##### 14:18:18 INFO - ##### Running populate-webroot step. 14:18:18 INFO - ##### 14:18:18 INFO - Running pre-action listener: _resource_record_pre_action 14:18:18 INFO - Running main action method: populate_webroot 14:18:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:18 INFO - '--tppagecycles', 14:18:18 INFO - '7'], 14:18:18 INFO - 'tests': ['tsvgm']}}, 14:18:18 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 14:18:18 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:18 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 14:18:18 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'tests': ['damp', 'tps']}, 14:18:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['damp', 'tps']}, 14:18:18 INFO - 'g3': {'tests': ['dromaeo_dom']}, 14:18:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:18 INFO - 'other': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other_l64': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'other_nol64': {'tests': ['a11yr', 14:18:18 INFO - 'ts_paint', 14:18:18 INFO - 'tpaint', 14:18:18 INFO - 'sessionrestore', 14:18:18 INFO - 'sessionrestore_no_auto_restore', 14:18:18 INFO - 'tabpaint']}, 14:18:18 INFO - 'svgr': {'tests': ['tsvgx', 14:18:18 INFO - 'tsvgr_opacity', 14:18:18 INFO - 'tart', 14:18:18 INFO - 'tscrollx', 14:18:18 INFO - 'cart']}, 14:18:18 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['tsvgx', 14:18:18 INFO - 'tsvgr_opacity', 14:18:18 INFO - 'tart', 14:18:18 INFO - 'tscrollx', 14:18:18 INFO - 'cart']}, 14:18:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'tests': ['tp5o']}, 14:18:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'talos_options': ['--e10s'], 14:18:18 INFO - 'tests': ['tp5o']}, 14:18:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'talos_options': ['--xperf_path', 14:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:18 INFO - 'tests': ['tp5n']}, 14:18:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:18 INFO - 'talos_options': ['--e10s', 14:18:18 INFO - '--xperf_path', 14:18:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:18 INFO - 'tests': ['tp5n']}}, 14:18:18 INFO - 'talos.zip': {'path': '', 14:18:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:18 INFO - Running post-action listener: _resource_record_post_action 14:18:18 INFO - ##### 14:18:18 INFO - ##### Running create-virtualenv step. 14:18:18 INFO - ##### 14:18:18 INFO - Running pre-action listener: _resource_record_pre_action 14:18:18 INFO - Running main action method: create_virtualenv 14:18:18 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:18 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 14:18:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:18 INFO - Using real prefix '/tools/python27' 14:18:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:19 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:22 INFO - Installing pip.................done. 14:18:22 INFO - Return code: 0 14:18:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18: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', 'pip>=1.5'] in /builds/slave/test/build 14:18: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 pip>=1.5 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 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 14:18:22 INFO - Cleaning up... 14:18:22 INFO - Return code: 0 14:18:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:18: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')]} 14:18:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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')]} 14:18:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18: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', '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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18: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', 'psutil>=0.7.1'] in /builds/slave/test/build 14:18: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 psutil>=0.7.1 14:18:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:22 INFO - 'HOME': '/Users/cltbld', 14:18:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:22 INFO - 'LOGNAME': 'cltbld', 14:18:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:22 INFO - 'MOZ_NO_REMOTE': '1', 14:18:22 INFO - 'NO_EM_RESTART': '1', 14:18:22 INFO - 'PAGER': '/bin/cat', 14:18:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:22 INFO - 'PWD': '/builds/slave/test', 14:18:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:22 INFO - 'SHELL': '/bin/bash', 14:18:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:22 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:22 INFO - 'USER': 'cltbld', 14:18:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:22 INFO - 'XPC_FLAGS': '0x0', 14:18:22 INFO - 'XPC_SERVICE_NAME': '0', 14:18:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:22 INFO - Downloading/unpacking psutil>=0.7.1 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:18:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:25 INFO - Installing collected packages: psutil 14:18:25 INFO - Running setup.py install for psutil 14:18:25 INFO - building 'psutil._psutil_osx' extension 14:18:25 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 14:18:25 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 14:18:25 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 14:18:26 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 14:18:26 INFO - building 'psutil._psutil_posix' extension 14:18:26 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 14:18:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:18:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:18:26 INFO - ^ 14:18:26 INFO - 1 warning generated. 14:18:26 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 14:18:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:18:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:18:26 INFO - Successfully installed psutil 14:18:26 INFO - Cleaning up... 14:18:26 INFO - Return code: 0 14:18:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 14:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 14:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:26 INFO - 'HOME': '/Users/cltbld', 14:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:26 INFO - 'LOGNAME': 'cltbld', 14:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:26 INFO - 'MOZ_NO_REMOTE': '1', 14:18:26 INFO - 'NO_EM_RESTART': '1', 14:18:26 INFO - 'PAGER': '/bin/cat', 14:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:26 INFO - 'PWD': '/builds/slave/test', 14:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:26 INFO - 'SHELL': '/bin/bash', 14:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:26 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:26 INFO - 'USER': 'cltbld', 14:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:26 INFO - 'XPC_FLAGS': '0x0', 14:18:26 INFO - 'XPC_SERVICE_NAME': '0', 14:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:26 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:18:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:18:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:18:29 INFO - Installing collected packages: mozsystemmonitor 14:18:29 INFO - Running setup.py install for mozsystemmonitor 14:18:29 INFO - Successfully installed mozsystemmonitor 14:18:29 INFO - Cleaning up... 14:18:29 INFO - Return code: 0 14:18:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:18:29 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')]} 14:18:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:29 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')]} 14:18:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:29 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:29 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 14:18:29 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 14:18:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:29 INFO - 'HOME': '/Users/cltbld', 14:18:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:29 INFO - 'LOGNAME': 'cltbld', 14:18:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:29 INFO - 'MOZ_NO_REMOTE': '1', 14:18:29 INFO - 'NO_EM_RESTART': '1', 14:18:29 INFO - 'PAGER': '/bin/cat', 14:18:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:29 INFO - 'PWD': '/builds/slave/test', 14:18:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:29 INFO - 'SHELL': '/bin/bash', 14:18:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:29 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:29 INFO - 'USER': 'cltbld', 14:18:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:29 INFO - 'XPC_FLAGS': '0x0', 14:18:29 INFO - 'XPC_SERVICE_NAME': '0', 14:18:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:29 INFO - Downloading/unpacking blobuploader==1.2.4 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:32 INFO - Downloading blobuploader-1.2.4.tar.gz 14:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:18:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:18:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:32 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 14:18:33 INFO - Downloading docopt-0.6.1.tar.gz 14:18:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:18:33 INFO - Installing collected packages: blobuploader, requests, docopt 14:18:33 INFO - Running setup.py install for blobuploader 14:18:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:18:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:18:33 INFO - Running setup.py install for requests 14:18:33 INFO - Running setup.py install for docopt 14:18:34 INFO - Successfully installed blobuploader requests docopt 14:18:34 INFO - Cleaning up... 14:18:34 INFO - Return code: 0 14:18:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:34 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:34 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 14:18:34 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 14:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:34 INFO - 'HOME': '/Users/cltbld', 14:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:34 INFO - 'LOGNAME': 'cltbld', 14:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:34 INFO - 'MOZ_NO_REMOTE': '1', 14:18:34 INFO - 'NO_EM_RESTART': '1', 14:18:34 INFO - 'PAGER': '/bin/cat', 14:18:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:34 INFO - 'PWD': '/builds/slave/test', 14:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:34 INFO - 'SHELL': '/bin/bash', 14:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:34 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:34 INFO - 'USER': 'cltbld', 14:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:34 INFO - 'XPC_FLAGS': '0x0', 14:18:34 INFO - 'XPC_SERVICE_NAME': '0', 14:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-5EaHgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Z_YOaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-N5lSE7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-BlqONF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-_r9pxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:34 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Mnu7OW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-leJLVy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-TufeIZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xB7We4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-BTHKw4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-sD0Nix-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-G2xLIY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-aCxbSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-IrDQ3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:35 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-zmIC0p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-3O0kh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:36 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-JEbMml-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:18:36 INFO - Running setup.py install for manifestparser 14:18:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Running setup.py install for mozcrash 14:18:36 INFO - Running setup.py install for mozdebug 14:18:36 INFO - Running setup.py install for mozdevice 14:18:36 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:18:36 INFO - Running setup.py install for mozfile 14:18:37 INFO - Running setup.py install for mozhttpd 14:18:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozinfo 14:18:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozInstall 14:18:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozleak 14:18:37 INFO - Running setup.py install for mozlog 14:18:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for moznetwork 14:18:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:18:37 INFO - Running setup.py install for mozprocess 14:18:38 INFO - Running setup.py install for mozprofile 14:18:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Running setup.py install for mozrunner 14:18:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Running setup.py install for mozscreenshot 14:18:38 INFO - Running setup.py install for moztest 14:18:38 INFO - Running setup.py install for mozversion 14:18:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:18:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:18:38 INFO - Cleaning up... 14:18:38 INFO - Return code: 0 14:18:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:38 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:38 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 14:18:38 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 14:18:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:38 INFO - 'HOME': '/Users/cltbld', 14:18:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:38 INFO - 'LOGNAME': 'cltbld', 14:18:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:38 INFO - 'MOZ_NO_REMOTE': '1', 14:18:38 INFO - 'NO_EM_RESTART': '1', 14:18:38 INFO - 'PAGER': '/bin/cat', 14:18:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:38 INFO - 'PWD': '/builds/slave/test', 14:18:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:38 INFO - 'SHELL': '/bin/bash', 14:18:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:38 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:38 INFO - 'USER': 'cltbld', 14:18:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:38 INFO - 'XPC_FLAGS': '0x0', 14:18:38 INFO - 'XPC_SERVICE_NAME': '0', 14:18:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-SdHlh1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-xBTHW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-h7N5uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-YGCvQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-hPadhx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-QuLAc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-CnMPBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-R_pWBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:18:39 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)) 14:18:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:18:39 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-4khzao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-32p_wb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Kndejo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-PZh4cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-blGpNB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-juuDJ8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-j8kj6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-CHJ43_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:18:40 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)) 14:18:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:18:40 INFO - Running setup.py (path:/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/pip-Uog1lJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 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)) 14:18:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:43 INFO - Downloading blessings-1.6.tar.gz 14:18:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:18:43 INFO - Installing collected packages: blessings 14:18:43 INFO - Running setup.py install for blessings 14:18:43 INFO - Successfully installed blessings 14:18:43 INFO - Cleaning up... 14:18:43 INFO - Return code: 0 14:18:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:18:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:43 INFO - Reading from file tmpfile_stdout 14:18:43 INFO - Current package versions: 14:18:43 INFO - blessings == 1.6 14:18:43 INFO - blobuploader == 1.2.4 14:18:43 INFO - docopt == 0.6.1 14:18:43 INFO - manifestparser == 1.1 14:18:43 INFO - mozInstall == 1.12 14:18:43 INFO - mozcrash == 0.17 14:18:43 INFO - mozdebug == 0.1 14:18:43 INFO - mozdevice == 0.48 14:18:43 INFO - mozfile == 1.2 14:18:43 INFO - mozhttpd == 0.7 14:18:43 INFO - mozinfo == 0.9 14:18:43 INFO - mozleak == 0.1 14:18:43 INFO - mozlog == 3.1 14:18:43 INFO - moznetwork == 0.27 14:18:43 INFO - mozprocess == 0.22 14:18:43 INFO - mozprofile == 0.28 14:18:43 INFO - mozrunner == 6.11 14:18:43 INFO - mozscreenshot == 0.1 14:18:43 INFO - mozsystemmonitor == 0.0 14:18:43 INFO - moztest == 0.7 14:18:43 INFO - mozversion == 1.4 14:18:43 INFO - psutil == 3.1.1 14:18:43 INFO - requests == 1.2.3 14:18:43 INFO - wsgiref == 0.1.2 14:18:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:43 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:43 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 14:18:43 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 14:18:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:43 INFO - 'HOME': '/Users/cltbld', 14:18:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:43 INFO - 'LOGNAME': 'cltbld', 14:18:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:43 INFO - 'MOZ_NO_REMOTE': '1', 14:18:43 INFO - 'NO_EM_RESTART': '1', 14:18:43 INFO - 'PAGER': '/bin/cat', 14:18:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:43 INFO - 'PWD': '/builds/slave/test', 14:18:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:43 INFO - 'SHELL': '/bin/bash', 14:18:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:43 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:43 INFO - 'USER': 'cltbld', 14:18:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:43 INFO - 'XPC_FLAGS': '0x0', 14:18:43 INFO - 'XPC_SERVICE_NAME': '0', 14:18:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 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)) 14:18:44 INFO - Cleaning up... 14:18:44 INFO - Return code: 0 14:18:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:18:44 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')]} 14:18:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:44 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')]} 14:18:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:44 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:44 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 14:18:44 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 14:18:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:44 INFO - 'HOME': '/Users/cltbld', 14:18:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:44 INFO - 'LOGNAME': 'cltbld', 14:18:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:44 INFO - 'MOZ_NO_REMOTE': '1', 14:18:44 INFO - 'NO_EM_RESTART': '1', 14:18:44 INFO - 'PAGER': '/bin/cat', 14:18:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:44 INFO - 'PWD': '/builds/slave/test', 14:18:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:44 INFO - 'SHELL': '/bin/bash', 14:18:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:44 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:44 INFO - 'USER': 'cltbld', 14:18:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:44 INFO - 'XPC_FLAGS': '0x0', 14:18:44 INFO - 'XPC_SERVICE_NAME': '0', 14:18:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:44 INFO - Downloading/unpacking jsonschema 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18: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 14:18:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:18:46 INFO - Downloading/unpacking functools32 (from jsonschema) 14:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:47 INFO - Downloading functools32-3.2.3-2.tar.gz 14:18:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:18:47 INFO - warning: no files found matching '*.txt' 14:18:47 INFO - no previously-included directories found matching 'build' 14:18:47 INFO - no previously-included directories found matching 'dist' 14:18:47 INFO - no previously-included directories found matching '.git*' 14:18:47 INFO - Installing collected packages: jsonschema, functools32 14:18:47 INFO - Running setup.py install for functools32 14:18:47 INFO - warning: no files found matching '*.txt' 14:18:47 INFO - no previously-included directories found matching 'build' 14:18:47 INFO - no previously-included directories found matching 'dist' 14:18:47 INFO - no previously-included directories found matching '.git*' 14:18:47 INFO - Successfully installed jsonschema functools32 14:18:47 INFO - Cleaning up... 14:18:47 INFO - Return code: 0 14:18:47 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:18:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:47 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 0x100e28a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e2f4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff161d9ac60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, '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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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 14:18:47 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 14:18:47 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 14:18:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:18:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:47 INFO - 'HOME': '/Users/cltbld', 14:18:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:47 INFO - 'LOGNAME': 'cltbld', 14:18:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:47 INFO - 'MOZ_NO_REMOTE': '1', 14:18:47 INFO - 'NO_EM_RESTART': '1', 14:18:47 INFO - 'PAGER': '/bin/cat', 14:18:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:47 INFO - 'PWD': '/builds/slave/test', 14:18:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:47 INFO - 'SHELL': '/bin/bash', 14:18:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:18:47 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:18:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:47 INFO - 'USER': 'cltbld', 14:18:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:47 INFO - 'XPC_FLAGS': '0x0', 14:18:47 INFO - 'XPC_SERVICE_NAME': '0', 14:18:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:47 INFO - Downloading/unpacking flake8 14:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:18:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:18:50 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:50 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 14:18:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:18:51 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 14:18:51 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 14:18:51 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 14:18:51 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 14:18:51 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 14:18:51 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 14:18:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:18:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:18:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:18:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:18:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:18:51 INFO - no previously-included directories found matching 'docs/_build' 14:18:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:18:51 INFO - Running setup.py install for pep8 14:18:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:18:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:18:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:18:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:18:51 INFO - no previously-included directories found matching 'docs/_build' 14:18:51 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:18:51 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:18:51 INFO - Cleaning up... 14:18:52 INFO - Return code: 0 14:18:52 INFO - Running post-action listener: _resource_record_post_action 14:18:52 INFO - Running post-action listener: _start_resource_monitoring 14:18:52 INFO - Starting resource monitoring. 14:18:52 INFO - ##### 14:18:52 INFO - ##### Running install step. 14:18:52 INFO - ##### 14:18:52 INFO - Running pre-action listener: _resource_record_pre_action 14:18:52 INFO - Running main action method: install 14:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:18:52 INFO - Reading from file tmpfile_stdout 14:18:52 INFO - Detecting whether we're running mozinstall >=1.0... 14:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:18:52 INFO - Reading from file tmpfile_stdout 14:18:52 INFO - Output received: 14:18:52 INFO - Usage: mozinstall [options] installer 14:18:52 INFO - Options: 14:18:52 INFO - -h, --help show this help message and exit 14:18:52 INFO - -d DEST, --destination=DEST 14:18:52 INFO - Directory to install application into. [default: 14:18:52 INFO - "/builds/slave/test"] 14:18:52 INFO - --app=APP Application being installed. [default: firefox] 14:18:52 INFO - mkdir: /builds/slave/test/build/application 14:18:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:19:14 INFO - Reading from file tmpfile_stdout 14:19:14 INFO - Output received: 14:19:14 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 14:19:14 INFO - Running post-action listener: _resource_record_post_action 14:19:14 INFO - ##### 14:19:14 INFO - ##### Running run-tests step. 14:19:14 INFO - ##### 14:19:14 INFO - Running pre-action listener: _resource_record_pre_action 14:19:14 INFO - Running main action method: run_tests 14:19:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:14 INFO - 2016-04-25 14:19:14.910 screenresolution[2490:24079] starting screenresolution argv=screenresolution get 14:19:14 INFO - 2016-04-25 14:19:14.912 screenresolution[2490:24079] Display 0: 1600x1200x32@60 14:19:14 INFO - 2016-04-25 14:19:14.918 screenresolution[2491:24082] starting screenresolution argv=screenresolution list 14:19:14 INFO - Available Modes on Display 0 14:19:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:19:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:19:15 INFO - Intel Iris: 14:19:15 INFO - Chipset Model: Intel Iris 14:19:15 INFO - Type: GPU 14:19:15 INFO - Bus: Built-In 14:19:15 INFO - VRAM (Dynamic, Max): 1536 MB 14:19:15 INFO - Vendor: Intel (0x8086) 14:19:15 INFO - Device ID: 0x0a2e 14:19:15 INFO - Revision ID: 0x0009 14:19:15 INFO - Displays: 14:19:15 INFO - Display: 14:19:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:19:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:19:15 INFO - Display Serial Number: 1600x1200 60 14:19:15 INFO - Main Display: Yes 14:19:15 INFO - Mirror: Off 14:19:15 INFO - Online: Yes 14:19:15 INFO - Rotation: Supported 14:19:15 INFO - Return code: 0 14:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:19:15 INFO - Python 2.7.3 14:19:15 INFO - Return code: 0 14:19:15 INFO - grabbing minidump binary from tooltool 14:19:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:19:15 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 0x100cccd30>, '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 0x100b30db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:19:15 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 14:19:15 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 14:19:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:19:15 INFO - Return code: 0 14:19:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:19:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:19:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:19:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:19:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:19:16 INFO - Return code: 0 14:19:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 14:19:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0081 --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 14:19:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SlFjG2rsOR/Render', 14:19:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:16 INFO - 'HOME': '/Users/cltbld', 14:19:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:16 INFO - 'LOGNAME': 'cltbld', 14:19:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:19:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:19:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:16 INFO - 'MOZ_NO_REMOTE': '1', 14:19:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:19:16 INFO - 'NO_EM_RESTART': '1', 14:19:16 INFO - 'PAGER': '/bin/cat', 14:19:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:16 INFO - 'PWD': '/builds/slave/test', 14:19:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:19:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:16 INFO - 'SHELL': '/bin/bash', 14:19:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YARlqdkB4Q/Listeners', 14:19:16 INFO - 'TMPDIR': '/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/', 14:19:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:16 INFO - 'USER': 'cltbld', 14:19:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:16 INFO - 'XPC_FLAGS': '0x0', 14:19:16 INFO - 'XPC_SERVICE_NAME': '0', 14:19:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0081', '--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 14:19:16 INFO - mozversion application_buildid: 20160425095909 14:19:16 INFO - mozversion application_changeset: dcaf0a6fa115ad73bf7505a8e76464250b971a04 14:19:16 INFO - mozversion application_display_name: Firefox 14:19:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:19:16 INFO - mozversion application_name: Firefox 14:19:16 INFO - mozversion application_remotingname: firefox 14:19:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:19:16 INFO - mozversion application_vendor: Mozilla 14:19:16 INFO - mozversion application_version: 47.0 14:19:16 INFO - mozversion platform_buildid: 20160425095909 14:19:16 INFO - mozversion platform_changeset: dcaf0a6fa115ad73bf7505a8e76464250b971a04 14:19:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:19:16 INFO - mozversion platform_version: 47.0 14:19:16 INFO - using testdate: 1461619156 14:19:16 INFO - actual date: 1461619156 14:19:16 INFO - starting webserver on 'localhost:59996' 14:19:16 INFO - SUITE-START | Running 2 tests 14:19:16 INFO - TEST-START | dromaeo_css 14:19:16 INFO - operating with platform_type : mac_ 14:19:16 INFO - Initialising browser for dromaeo_css test... 14:19:16 INFO - TEST-INFO | started process 2509 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmp93Vmxx/profile http://localhost:59996/getInfo.html) 14:19:18 INFO - PROCESS | 2509 | __metrics Screen width/height:1600/1200 14:19:18 INFO - PROCESS | 2509 | colorDepth:24 14:19:18 INFO - PROCESS | 2509 | Browser inner width/height: 1024/689 14:19:18 INFO - PROCESS | 2509 | __metrics 14:19:19 INFO - PROCESS | 2509 | 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" 14:19:19 INFO - PROCESS | 2509 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:19:19 INFO - TEST-INFO | 2509: exit 0 14:19:19 INFO - Browser initialized. 14:19:19 INFO - Running cycle 1/1 for dromaeo_css test... 14:19:19 INFO - TEST-INFO | started process 2512 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmp93Vmxx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:19:31 INFO - PROCESS | 2512 | RSS: Main: 157184000 14:19:31 INFO - PROCESS | 2512 | 14:20:22 INFO - PROCESS | 2512 | 1461619222243 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] 14:22:32 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-dojo.html (next: http://localhost:59996/tests/dromaeo/cssquery-ext.html) 14:22:33 INFO - PROCESS | 2512 | RSS: Main: 292597760 14:22:33 INFO - PROCESS | 2512 | 14:25:35 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-ext.html (next: http://localhost:59996/tests/dromaeo/cssquery-jquery.html) 14:25:36 INFO - PROCESS | 2512 | RSS: Main: 293810176 14:25:36 INFO - PROCESS | 2512 | 14:28:38 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-jquery.html (next: http://localhost:59996/tests/dromaeo/cssquery-mootools.html) 14:28:38 INFO - PROCESS | 2512 | RSS: Main: 296726528 14:28:38 INFO - PROCESS | 2512 | 14:31:40 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-mootools.html (next: http://localhost:59996/tests/dromaeo/cssquery-prototype.html) 14:31:41 INFO - PROCESS | 2512 | RSS: Main: 253546496 14:31:41 INFO - PROCESS | 2512 | 14:34:44 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-prototype.html (next: http://localhost:59996/tests/dromaeo/cssquery-yui.html) 14:34:44 INFO - PROCESS | 2512 | RSS: Main: 255098880 14:34:44 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | Cycle 1(1): loaded http://localhost:59996/tests/dromaeo/cssquery-yui.html (next: http://localhost:59996/tests/dromaeo/cssquery-dojo.html) 14:37:47 INFO - PROCESS | 2512 | RSS: Main: 302469120 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | __start_tp_report 14:37:47 INFO - PROCESS | 2512 | _x_x_mozilla_page_load 14:37:47 INFO - PROCESS | 2512 | _x_x_mozilla_page_load_details 14:37:47 INFO - PROCESS | 2512 | |i|pagename|runs| 14:37:47 INFO - PROCESS | 2512 | |0;dojo.html;3496.0735405117643;3548.1839176989274;3571.9821400892993;3601.2437388148496;3635.2002559436914;5875.941240587593;5899.4395532424405;5944.167816505693;5948.821535353935;5967.1049342598635;5890.734916928748;5938.435848594388;5944.762209511614;5955.464008239258;5960;5219.739013049351;5283.8150664726745;5313.282706834586;5381.327334083239;5432.022235997519;5417.7291135443265;5425.810096646618;5427.538659213971;5432.972835135819;5435.021696094701;5322.308099947001;5382.6770393776305;5402.35171779387;5411.161270003156;5412.783488660469;5387.515123638872;5399.19012148177;5420.891582168335;5436.10304299792;5484.533814625762;5159.355080614923;5340.893182136336;5366.812161574326;5379.75791089398;5383.4616538346245;5726.427357264281;5736.684482353469;5771.567132465082;5786.392428794958;5811.883762324772;5037.697738135724;6245.937540624626;6259.687015649245;6298.937010629927;6310.747570097191;6230.688465576703;6271.62370257786;6273.40402661751;6301.117843501914;6307.653079080695;4907.362042934431;5864.824055278348;5922;5922.111683247461;5937.643741375488;6181.567290289727;6263.217097862767;6322.873542529123;6398.072279519537;6425.3574642536305;6144.938550614526;6198.783042593487;6198.946179149465;6277.591956522811;6311.084892690626;6356.618602883842;6369.649669268235;6373.362663733589;6377.808665740035;6385.393387628168;6124.197855633957;6195.008798592204;6234.220722409699;6239.282482514569;6249.625022498664;6222.439980401786;6228.128062071313;6253.6873156342;6260.593061450991;6284;4917.286993385939;5025.396952365666;5027.773750181251;5050.545450909436;5060.367454068241;4487.169873573399;4530.456345238593;4548.385967894358;4549.294859296814;4566.246569316092;6084.148219249309;6110.633361998294;6111.266648002268;6118.388161183935;6121.908171377433;6207.689615519206;6332.778352757631;6342.238931328178;6348.904766428507;6355.983042713144;6040.184575082308;6107;6117.736059405574;6121.331035925228;6127.128444391265;5505.064139096363;5545.251391062155;5551.444855514497;5552.278203833475;5552.583556233299;6318.494520438354;6398.456131228898;6549.24683661385;6553.770618028346;6568.080468734381;6252.343503932022;6340.365963403808;6347.111404403479;6347.301796802256;6349.269833968968;6421.293657697743;6493;6513.446357059703;6520.413162815369;6526.151600291932;4916.55750982399;6232.314445410911;6270.905936410866;6277.121203031488;6298.149749783721;6268.4671802897265;6283.151774510383;6303.747850085945;6304.558680892477;6313.305536390902;6216.44052035319;6291.528135389773;6293.748250070129;6297.842553936188;6303.338149494237;6135.07955838608;6197.349278325892;6256.468200203034;6276.780312689034;6309.558330916791;6191.597546159485;6206.131141640084;6208.565400421927;6214.25428948529;6216.7824126155365;5131.923021154611;6336.904946425715;6338.239411270677;6346.2067241594805;6358.459530939737;5977.850553736192;6134.957077260823;6142.3550527194;6216.191895053614;6222.906656400067 14:37:47 INFO - PROCESS | 2512 | |1;ext.html;540.9026375252455;551.6000899347973;594.8066878264565;613.9746623139359;668.8060462465884;37676;40192;40252.59747402525;40259;40351.19297614046;43266.567334326646;43942.90142746433;43954.56045439545;43995;44063.898402539955;9793.951030244856;10002.89968103508;10006.34958727684;10017.69946901594;10024.198064154887;9851.95074024629;9974.451405172713;9995.200383969264;10008.699739007841;10028.348157369785;10077.19382449406;10157.187425005981;10157.28898977072;10166.339187952797;10166.69499915;10061.698149055503;10077.596896124147;10102.898971010269;10148.137408320265;10152.492375381202;9886.406815591015;10043.497825108716;10059.698209053668;10079.092881640687;10083.193344532494;43036;43363.566364336584;43369.78315108436;43411;43420.69737907868;2831.603575499432;3301.735861131104;3392.5759280089987;3537.876174333912;3747.2130852521004;29033.70966290331;29368.853155734083;31070.378592428024;31080.533791993137;31270.374592508022;2838.9432211355866;2862.685104638488;2890.624218851557;2918.970810291891;2921.4449254641763;480870;483004;483858;484814;485880;47551.762241188575;47987;65005.34994649993;66526;66546;46451.5354846454;46713.5328646716;46755.53244467512;46870;47085.76457117761;17519.124043797758;17688.646227075387;17695.911520442573;17705.7344139838;17755.822441775676;14174.291285435687;14275.214863182417;14277.500287490097;14291;14291.356888940023;15101.018433801988;15183.924080379527;15228.923855380652;15233.00985435943;15277.694446111016;5452.972735136299;6498.740050398026;6556.901646475307;6564.507661925375;6568.737250510022;990.64336838738;1060.692399204222;1086.7935092332432;1089.3082892363407;1157.8784227656142;30605.08184754461;30810.229744256572;31176.844115779277;31231;31434.899778507643;1111.1943840715578;1158.808796548577;1176.147293212414;1193.1528614683673;1215.525944881497;15421;16061.598460038593;16423.932444391077;16833.242504087346;16862.915685421496;520009;523808;525746;526195;527792;59126.704366478756;60671.39328606657;60926;60958.39041609616;61097.69451152716;1203.5727316802584;1423.2030063164455;1568.6941046496052;1572.6068482879052;1690.8224636413245;29604.555931660612;29621.703782961893;29783.65973531153;30324.69675303219;30545;49755.75122124366;50451.74774126106;50548.24177637412;50592.98814023773;50729.49270507395;412095;413818;414191;415423;415966;27802;32796;33563;34026.659733403336;34052.48921266232;41158.588414115475;41464.58535414728;41472;43078.923026924575;43105.92235194145;33906.49140262947;35063.82468087643;35144.76993305222;35149.47275790916;35986.280274393845;33537;34732;35505.28989420249;35929.46105808467;36136.81931590325;35993.460098098025;36195.81902090578;36482;36916;37146.44280335743;30934.69065309408;31716;31745;31825.363492730477;32164.35671286608;24627.630585540872;24760.009599616533;25073.24780256595;25084.24747257585;25132 14:37:47 INFO - PROCESS | 2512 | |2;jquery.html;380.9885703428897;381.61266314690585;386.37600275554985;390.8885967499263;396.7976332070647;20239.190432382704;21965;22003.66994495082;22054.228102016434;22065.33803985879;22531.662025069636;22674.433139171488;22896.855157242153;22925.77074229257;22938.96774645141;5782.190493330937;5900.144479050535;5913.793017244398;6024.819255422344;6119.785807496739;5941.762329506814;6027.608205466652;6064.211652485171;6067.878642427148;6068.393160683941;5711.457411545896;5971.402859714038;5979.760809567634;6000.75996960121;6018.247719035121;5926.644401335933;5934.406559344053;5974.910376344358;5980.252468441445;5987.281526216838;5223.268742376071;5227.790888364499;5473.31583552056;5732.742026608813;5735.283089613798;13956.092853964561;13959.022868399215;14113.858861411358;14223.928880355636;14231.50189743354;2142.6036183306137;2191.5726433345503;2255.1994042114975;2336.953260934789;2765.9074665507014;9958.950205248928;9982.650607228712;10253.692389228336;10306.79386412275;10706.732331691692;1730.2127531972922;1760.595063135486;1767.531604124908;1782.97325540117;1821.2532861526843;100550;103300.48349758203;103413;105625.47187264092;107170.9282907161;10741.89258107409;11265.831012534818;11541.249818761753;11568.421578921021;11702.88297117018;14240.216788076548;14292.857071429153;14320.427182912566;14322.713545729028;14348.497802577069;8756.781080472912;8904.465732056098;8911.465312081296;9000.909990900138;9139.954300228455;9206.079392060874;9228.661844032578;9267.67563135287;9277.304202184729;9329.067093290752;4564.2240819060835;4570.680052396367;4572.3827283317;4589.58693717567;4634.096351211481;1896.4121122452084;1908.8186622270862;1913.8373238274903;1928.7299778031088;1967.4097770668743;948.6110694615174;954.6038394066385;1051.358671210561;1073.8442925775719;1084.1182620415018;20710.79289207119;21228.893855530932;21447.356579302334;21607.049770386;21716.131354745634;911.9435672558338;988.8233002726732;990.4700984973005;1007.66243308491;1020.5710409226143;10476.68569942903;11358.318500889973;11782.939535441852;11793.29240245588;11804.468798903898;566295;658238;661320;661356;665271;131754.68245317423;131948;132319;134453.3277333607;136283;1015.4465816130227;1033.1528146919604;1034.2243317511866;1036.1658864613908;1069.2461814420815;18610.96689164907;18724;18725.06374681233;18737;19041.714374284675;51116.72208194676;53090.869537271705;54135.45864541304;54923.72538137444;55450;83920.58039709763;84605.57697211475;84995.15004849872;85242;86051.5697421509;6840.452763778985;8123.147069557612;8278.668853246043;8391.622376992927;8496.150384961455;9848.113669769755;12998.805017924928;13258.07193496446;13491.32543372847;14072.296385180496;8722.607482663168;11880.108991825475;12068.758624827278;12116.75766484683;12126.96920761745;11535.250208736405;11915.702107447383;11974.461149248478;11999.920007199045;12151.088668349732;11680.766384672428;12022.63932082039;12352.382380880741;12352.53227408143;12483.750324993267;8332.250097491255;8378.413511054168;8382.077971423261;8594.140585941355;8762.25520830711;11621.012213961907;11976.16166868311;12050.879491205327;12219.877801221874;12221.388930553614 14:37:47 INFO - PROCESS | 2512 | |3;mootools.html;1487.754520504117;1635.3867299762587;1657.8010638723347;1984.5633960528687;2075.0765909170404;8392.118827523105;8990.91009089909;9183;9189.264858811295;9195.678151264707;8874.600642971065;9127.68053118141;9149.947756008061;9160.541972901361;9161.633534658606;2578.22653204039;2606.700229473606;2606.947861042778;2607.9348016299555;2610.099515667092;2528.671272734542;2610.89556417744;2611.086119858044;2612.960805587908;2620.6593142891397;2577.6391305217285;2597.5454295498307;2610.6084087386853;2610.8172427930053;2616.6075088736748;2555.9361015974564;2576.471823276224;2608.6347911292432;2610.1386542441046;2610.517054344933;2559.1682703121373;2594.610808378758;2600.089968511025;2613.8039647026358;2615.908443204498;9141.908580914172;9210.493422861808;9218.769530761718;9238.630454781867;9242.768930776785;5537.861553461195;5690.2887139107615;5690.630109042941;5739.139129130664;5750.88498230033;3620.601733809305;3711.758735682172;3849.480320156799;3858.131920317934;3861.2470568239205;1067.5196161727254;1086.2613422872444;1086.7989421956884;1122.326604037579;1123.1913022623698;10388.428636424993;12520.561780337644;12539.24764514132;12541.059420543495;12572.182808117443;4992.625553083533;4999.300377796022;5085.211792172219;5141.640085194075;5149.9742501287255;10494.108000820015;10554.050135487843;10555.261131720707;10557;10570.2600817942;5732.197492351074;5767.36558978512;5769.336526299446;5780.595358324961;5781.82654520365;4586.518415566385;4716.434027916672;4965.82619608312;5075.137226671401;5204.141316682705;4033.354663253924;4092.979535102365;4103.4870641169855;4135.58644135584;4137.172565486914;1175.723704929341;1373.8282714660668;1384.2179168769612;1390.5828251524501;1411.1392050848974;1423.1745587559192;1428.0646176754242;1452.8547145285386;1466.091023565375;1477.5567329801017;8410;8574.614142363484;8621.353398495137;8623.051464338918;8632.956835215784;1251.599488163797;1256.7674980128706;1278.442353011511;1278.725074109071;1303.9739205216033;6529.3470652935275;6584.407403333723;6638.137042184581;6639.402453779183;6640.335966403321;10973.50619222137;11323.490442930088;11345.943270283597;11452.312861228353;11488.77022459546;10996.28524145928;10997.065249453886;11109;11211;11285.43572821372;8568.52873091986;8719.38964272495;8724.127587241352;8745.91254087451;8799.868001979976;6743.561668491532;8225.83548329042;8356.87464688018;8438.493690378597;8458.323334133378;9775;9786.51067446639;9845.753856153653;9862.21102311799;10025.348352357074;9944.403335799876;10240.9759024097;10272.794544109225;10290.742731431774;10454.425006624404;7050.753223637148;7436.18201997769;7499.850003000019;7703.537787732754;7774.650140743567;8424.031236407647;8450.450720703135;8477.788055298666;8506.787330316543;8511.106333834858;7462.440316976357;7575.545467271982;7643.923560764321;7674.079110506775;7693.730719424793;7452.776416707507;7485.026946496921;7491.475596708397;7587.127480339832;7596.658150383136;8047.919520804951;8079.555624440713;8105.027396712432;8118.878216826634;8128;7187.065681461377;7210.098737657793;7227.927720722725;7267.345938865527;7271.818204544928;6248.687565621792;7362.926370736438;7383.409327253914;7412.184659687322;7418.369438597564 14:37:47 INFO - PROCESS | 2512 | |4;prototype.html;341.3105526835792;346.26418859922666;349.49846969598644;350.9192885636303;355.1299316674147;6668.099806526117;6696.866062678756;6702.296258892813;6761.695723692434;6812.318768123185;6652.800415987516;6661.134052573158;6670.199576050881;6671.733130674779;6672.232693240267;4775.737334446604;4850.078485087822;4929.014197160564;4968.602511799066;4994.325766021577;4935.851924442272;4942.283368911506;4945.67853089548;4952.306677065214;4954.1330267203275;4641.793133785206;4647.651426143036;4979.253112033206;4980.626453016021;4992.151334273182;4733.242681171013;4836.97581512092;4955.925661115086;4972.776225069881;4977.42759582649;3526.682598566141;4283.186194623012;4294.806733696991;4302.720323178984;4314.676399270067;6357.554971151975;6407.8398040048905;6426.61440313578;6437.163168788027;6479.384458476437;5862.765489380419;5870.442307980735;5873.706314684248;5874.618149820248;5901.17383566301;6829.795106146824;7060;7062.117235345582;7070.681819318143;7071.964640176766;4792.257200133985;4816.7350795706225;4818.156822556074;4824;4829.203181475053;7412.888806667905;7476.962615186943;7478.2895624915545;7487.812804679872;7492.213317601682;4067.7966101694797;4859.101066302675;4886.218205087169;4887.36464259644;4889.926651100236;4959.008198360342;4962.627802914796;4963.280324353021;4966.106100901872;4969.478204788517;5696.629719068247;5861.267341582302;5870.853228669317;5906.350301466835;5908.940910590925;7137.393321567621;7145.606991615407;7160.498765086394;7169.749058783022;7184.1019872515935;1702.8297170282872;1740.9477715668297;1743.834335738103;1745.1797655102082;1750.1424302091884;1052.0846863228887;1094.68801391603;1114.7603265298;1122.8708698499613;1147.9024282935877;6750.1562304711915;6780.559263647847;6836;6876.518643694993;6879.690413931385;7506.437017223621;7511.624418779039;7519.624018799038;7546.622668866535;7559.3196612305155;6858.142732158481;6874.312568743186;6879.6560171991205;6884.863997440366;6907.447404207652;3838.692904567628;3912.5523556284356;3953.13031133156;3959.109200429938;3971.940420893689;7768.65041073153;7814.765557033298;7830.960845195737;7831.6475758591005;7882.132965373804;6717.395434410926;7291.79685351911;7697.730579429693;7713.575753333508;7713.884291735629;6571.3757193066585;6904.585724856524;7201.92798072023;7518.586477743776;7779.144294127642;6077.726502307495;6528.608283503005;6816.965915170393;7477.850442991218;7504.662290196845;7321.707131714885;7350.522216056153;7377.524495100894;7403.66683499233;7408.814779630552;7857.371410287277;7858.724944626911;7862.2923936845955;7866.331361834308;7876.606169691607;4727.787249573847;4771.403574553181;4777.880552986203;4781.139394908811;4784.976075119602;7190.316919892602;7435.330820226206;7460.29127232912;7472.0286362772495;7479.962600186965;7190.568565886064;7206;7223;7234.421246300389;7245.021922040668;7230.927690723025;7238.963805181151;7241.022461967567;7241.782746517613;7260.382867456326;7059.894101588583;7061.576305421691;7083.964580177066;7190.280971902768;7215.242399547972;5904.852378690567;7079.902615094668;7197.71209151628;7233.348998590152;7250.3112204340505;6764.729410823709;6889.689963951534;6924.3421874923815;6954.921987092009;6978.94121234227 14:37:47 INFO - PROCESS | 2512 | |5;yui.html;704.9859002819942;705.9470539709522;714.8105751975727;743.4498471131365;792.1049214387738;14379.209143497104;14508.637284067903;14584.708305833878;14644.414223431064;14663.486777962773;13408.59774206773;14313.499027534039;14403.711925761478;14429.278536073181;14446.205458699767;986.3046552180731;1024.7028361775076;1026.4508487958944;1026.563710423071;1028.1260928211036;995.3182070281844;1006.8137394581989;1010.6917390195987;1010.8028934357803;1012.2307046644567;832.2759199496446;1040.3237895367995;1047.3140093238903;1050.821360368739;1053.0049103597105;1044.1698849414695;1046.1578429364358;1046.5499835070916;1051.1328154272717;1053.3153450257316;788.2117882117882;794.4438892775081;795.0340336491191;796.2913007423398;797.7845981584998;15514.689706205925;15565.45520906774;15579.532614021597;15602.985805922579;15606;13096;13305.201687898758;13366.198028118246;13592.728145437135;13774.862251377559;10214.744631384201;10223.977602239791;10573.995470430296;10705.357678539234;10974.396408197546;3546.5744110706623;3604.7476676632646;3633.691136253395;3677.5403074615674;3685.0787303174206;61809;84689;85454.57272713722;85824;85983.57008214919;9964.153046990941;9985.251106167067;10058.899411005796;10109;10187;22003.779962200493;22319.330420087423;22353.105875764788;23499.7650023501;23503;7891.723789667449;7904.6838126474295;8043.356531477468;8211.384146189059;8294.087650358455;4341.110072435144;4428.6678499112695;4430.689851710349;4462.665300102505;4629.976850115728;3719.088823238324;3771.735978481535;3775.716821238418;3776.999095239764;3783.9243215135543;597.396629404296;660.4023358860239;661.503872095928;687.5187368841831;692.5809885019592;593.9376365481648;609.8261995331397;612.8682333298362;613.4295105551879;617.9073139029092;8167.387445941578;8425.536595487185;8487.660493580312;8576.399652024424;8639.222469977733;550.9504394129191;573.2061095382925;598.5032423088826;606.869523052537;650.0541711809307;3201.2797120648133;3745.4007358823;3923.2349691809823;3932.7640341579026;4051.9797401012806;86167.56916215378;88533;88601.55699221462;88691.22617547742;88782.55608722044;75006;77297.6135119332;80185;84904.15095848963;90019;73422.99999999894;73913;75571.62214189004;76264;76682.23317766862;8113.8234955929875;8493.10822363643;8864.86702699447;9051.683191088281;9077.409968352036;50511;51122;51687;51824.96350072902;53661.195082074584;66438.66780666065;66533;66837;67341.65316693737;69111.30888691048;11180.496877640688;11284;11522.135839812216;11734.882651173379;11806.409679516162;11472.827907581226;11553.480093395649;11709.648710538697;11773.352465614473;12655.93672031634;11262.155338349492;11273.267237629529;11631.360275184947;11977.101717371406;12415.62753117408;11533;11714.531418743154;11763.411829408324;11880.584179553674;12443.253404795742;11391.487383067617;11523.884761152616;11664.416779160838;11671.533138674358;12381;10064.345817521838;10078.6472473466;10199.337043092472;10299.433531155559;10363.94818025905;9752.414855108716;10056.54745536438;10137.44244066554;10149.594016239564;10150.54322555472 14:37:47 INFO - PROCESS | 2512 | __end_tp_report 14:37:47 INFO - PROCESS | 2512 | __start_cc_report 14:37:47 INFO - PROCESS | 2512 | _x_x_mozilla_cycle_collect,1134 14:37:47 INFO - PROCESS | 2512 | __end_cc_report 14:37:47 INFO - PROCESS | 2512 | __startTimestamp1461620267431__endTimestamp 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | ------- Summary: start ------- 14:37:47 INFO - PROCESS | 2512 | Number of tests: 6 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#0] dojo.html Cycles:165 Average:5868.74 Median:6142.36 stddev:634.92 (10.3%) stddev-sans-first:608.98 14:37:47 INFO - PROCESS | 2512 | Values: 3496.1 3548.2 3572.0 3601.2 3635.2 5875.9 5899.4 5944.2 5948.8 5967.1 5890.7 5938.4 5944.8 5955.5 5960.0 5219.7 5283.8 5313.3 5381.3 5432.0 5417.7 5425.8 5427.5 5433.0 5435.0 5322.3 5382.7 5402.4 5411.2 5412.8 5387.5 5399.2 5420.9 5436.1 5484.5 5159.4 5340.9 5366.8 5379.8 5383.5 5726.4 5736.7 5771.6 5786.4 5811.9 5037.7 6245.9 6259.7 6298.9 6310.7 6230.7 6271.6 6273.4 6301.1 6307.7 4907.4 5864.8 5922.0 5922.1 5937.6 6181.6 6263.2 6322.9 6398.1 6425.4 6144.9 6198.8 6198.9 6277.6 6311.1 6356.6 6369.6 6373.4 6377.8 6385.4 6124.2 6195.0 6234.2 6239.3 6249.6 6222.4 6228.1 6253.7 6260.6 6284.0 4917.3 5025.4 5027.8 5050.5 5060.4 4487.2 4530.5 4548.4 4549.3 4566.2 6084.1 6110.6 6111.3 6118.4 6121.9 6207.7 6332.8 6342.2 6348.9 6356.0 6040.2 6107.0 6117.7 6121.3 6127.1 5505.1 5545.3 5551.4 5552.3 5552.6 6318.5 6398.5 6549.2 6553.8 6568.1 6252.3 6340.4 6347.1 6347.3 6349.3 6421.3 6493.0 6513.4 6520.4 6526.2 4916.6 6232.3 6270.9 6277.1 6298.1 6268.5 6283.2 6303.7 6304.6 6313.3 6216.4 6291.5 6293.7 6297.8 6303.3 6135.1 6197.3 6256.5 6276.8 6309.6 6191.6 6206.1 6208.6 6214.3 6216.8 5131.9 6336.9 6338.2 6346.2 6358.5 5977.9 6135.0 6142.4 6216.2 6222.9 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#1] ext.html Cycles:180 Average:61831.80 Median:18649.53 stddev:126537.92 (678.5%) stddev-sans-first:126809.22 14:37:47 INFO - PROCESS | 2512 | Values: 540.9 551.6 594.8 614.0 668.8 37676.0 40192.0 40252.6 40259.0 40351.2 43266.6 43942.9 43954.6 43995.0 44063.9 9794.0 10002.9 10006.3 10017.7 10024.2 9852.0 9974.5 9995.2 10008.7 10028.3 10077.2 10157.2 10157.3 10166.3 10166.7 10061.7 10077.6 10102.9 10148.1 10152.5 9886.4 10043.5 10059.7 10079.1 10083.2 43036.0 43363.6 43369.8 43411.0 43420.7 2831.6 3301.7 3392.6 3537.9 3747.2 29033.7 29368.9 31070.4 31080.5 31270.4 2838.9 2862.7 2890.6 2919.0 2921.4 480870.0 483004.0 483858.0 484814.0 485880.0 47551.8 47987.0 65005.3 66526.0 66546.0 46451.5 46713.5 46755.5 46870.0 47085.8 17519.1 17688.6 17695.9 17705.7 17755.8 14174.3 14275.2 14277.5 14291.0 14291.4 15101.0 15183.9 15228.9 15233.0 15277.7 5453.0 6498.7 6556.9 6564.5 6568.7 990.6 1060.7 1086.8 1089.3 1157.9 30605.1 30810.2 31176.8 31231.0 31434.9 1111.2 1158.8 1176.1 1193.2 1215.5 15421.0 16061.6 16423.9 16833.2 16862.9 520009.0 523808.0 525746.0 526195.0 527792.0 59126.7 60671.4 60926.0 60958.4 61097.7 1203.6 1423.2 1568.7 1572.6 1690.8 29604.6 29621.7 29783.7 30324.7 30545.0 49755.8 50451.7 50548.2 50593.0 50729.5 412095.0 413818.0 414191.0 415423.0 415966.0 27802.0 32796.0 33563.0 34026.7 34052.5 41158.6 41464.6 41472.0 43078.9 43105.9 33906.5 35063.8 35144.8 35149.5 35986.3 33537.0 34732.0 35505.3 35929.5 36136.8 35993.5 36195.8 36482.0 36916.0 37146.4 30934.7 31716.0 31745.0 31825.4 32164.4 24627.6 24760.0 25073.2 25084.2 25132.0 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#2] jquery.html Cycles:180 Average:36192.87 Median:22028.95 stddev:106967.91 (485.6%) stddev-sans-first:107234.18 14:37:47 INFO - PROCESS | 2512 | Values: 381.0 381.6 386.4 390.9 396.8 20239.2 21965.0 22003.7 22054.2 22065.3 22531.7 22674.4 22896.9 22925.8 22939.0 5782.2 5900.1 5913.8 6024.8 6119.8 5941.8 6027.6 6064.2 6067.9 6068.4 5711.5 5971.4 5979.8 6000.8 6018.2 5926.6 5934.4 5974.9 5980.3 5987.3 5223.3 5227.8 5473.3 5732.7 5735.3 13956.1 13959.0 14113.9 14223.9 14231.5 2142.6 2191.6 2255.2 2337.0 2765.9 9959.0 9982.7 10253.7 10306.8 10706.7 1730.2 1760.6 1767.5 1783.0 1821.3 100550.0 103300.5 103413.0 105625.5 107170.9 10741.9 11265.8 11541.2 11568.4 11702.9 14240.2 14292.9 14320.4 14322.7 14348.5 8756.8 8904.5 8911.5 9000.9 9140.0 9206.1 9228.7 9267.7 9277.3 9329.1 4564.2 4570.7 4572.4 4589.6 4634.1 1896.4 1908.8 1913.8 1928.7 1967.4 948.6 954.6 1051.4 1073.8 1084.1 20710.8 21228.9 21447.4 21607.0 21716.1 911.9 988.8 990.5 1007.7 1020.6 10476.7 11358.3 11782.9 11793.3 11804.5 566295.0 658238.0 661320.0 661356.0 665271.0 131754.7 131948.0 132319.0 134453.3 136283.0 1015.4 1033.2 1034.2 1036.2 1069.2 18611.0 18724.0 18725.1 18737.0 19041.7 51116.7 53090.9 54135.5 54923.7 55450.0 83920.6 84605.6 84995.2 85242.0 86051.6 6840.5 8123.1 8278.7 8391.6 8496.2 9848.1 12998.8 13258.1 13491.3 14072.3 8722.6 11880.1 12068.8 12116.8 12127.0 11535.3 11915.7 11974.5 11999.9 12151.1 11680.8 12022.6 12352.4 12352.5 12483.8 8332.3 8378.4 8382.1 8594.1 8762.3 11621.0 11976.2 12050.9 12219.9 12221.4 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#3] mootools.html Cycles:180 Average:6312.70 Median:5080.17 stddev:3286.93 (64.7%) stddev-sans-first:3276.14 14:37:47 INFO - PROCESS | 2512 | Values: 1487.8 1635.4 1657.8 1984.6 2075.1 8392.1 8990.9 9183.0 9189.3 9195.7 8874.6 9127.7 9149.9 9160.5 9161.6 2578.2 2606.7 2606.9 2607.9 2610.1 2528.7 2610.9 2611.1 2613.0 2620.7 2577.6 2597.5 2610.6 2610.8 2616.6 2555.9 2576.5 2608.6 2610.1 2610.5 2559.2 2594.6 2600.1 2613.8 2615.9 9141.9 9210.5 9218.8 9238.6 9242.8 5537.9 5690.3 5690.6 5739.1 5750.9 3620.6 3711.8 3849.5 3858.1 3861.2 1067.5 1086.3 1086.8 1122.3 1123.2 10388.4 12520.6 12539.2 12541.1 12572.2 4992.6 4999.3 5085.2 5141.6 5150.0 10494.1 10554.1 10555.3 10557.0 10570.3 5732.2 5767.4 5769.3 5780.6 5781.8 4586.5 4716.4 4965.8 5075.1 5204.1 4033.4 4093.0 4103.5 4135.6 4137.2 1175.7 1373.8 1384.2 1390.6 1411.1 1423.2 1428.1 1452.9 1466.1 1477.6 8410.0 8574.6 8621.4 8623.1 8633.0 1251.6 1256.8 1278.4 1278.7 1304.0 6529.3 6584.4 6638.1 6639.4 6640.3 10973.5 11323.5 11345.9 11452.3 11488.8 10996.3 10997.1 11109.0 11211.0 11285.4 8568.5 8719.4 8724.1 8745.9 8799.9 6743.6 8225.8 8356.9 8438.5 8458.3 9775.0 9786.5 9845.8 9862.2 10025.3 9944.4 10241.0 10272.8 10290.7 10454.4 7050.8 7436.2 7499.9 7703.5 7774.7 8424.0 8450.5 8477.8 8506.8 8511.1 7462.4 7575.5 7643.9 7674.1 7693.7 7452.8 7485.0 7491.5 7587.1 7596.7 8047.9 8079.6 8105.0 8118.9 8128.0 7187.1 7210.1 7227.9 7267.3 7271.8 6248.7 7362.9 7383.4 7412.2 7418.4 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#4] prototype.html Cycles:180 Average:5887.22 Median:6699.58 stddev:1857.03 (27.7%) stddev-sans-first:1814.99 14:37:47 INFO - PROCESS | 2512 | Values: 341.3 346.3 349.5 350.9 355.1 6668.1 6696.9 6702.3 6761.7 6812.3 6652.8 6661.1 6670.2 6671.7 6672.2 4775.7 4850.1 4929.0 4968.6 4994.3 4935.9 4942.3 4945.7 4952.3 4954.1 4641.8 4647.7 4979.3 4980.6 4992.2 4733.2 4837.0 4955.9 4972.8 4977.4 3526.7 4283.2 4294.8 4302.7 4314.7 6357.6 6407.8 6426.6 6437.2 6479.4 5862.8 5870.4 5873.7 5874.6 5901.2 6829.8 7060.0 7062.1 7070.7 7072.0 4792.3 4816.7 4818.2 4824.0 4829.2 7412.9 7477.0 7478.3 7487.8 7492.2 4067.8 4859.1 4886.2 4887.4 4889.9 4959.0 4962.6 4963.3 4966.1 4969.5 5696.6 5861.3 5870.9 5906.4 5908.9 7137.4 7145.6 7160.5 7169.7 7184.1 1702.8 1740.9 1743.8 1745.2 1750.1 1052.1 1094.7 1114.8 1122.9 1147.9 6750.2 6780.6 6836.0 6876.5 6879.7 7506.4 7511.6 7519.6 7546.6 7559.3 6858.1 6874.3 6879.7 6884.9 6907.4 3838.7 3912.6 3953.1 3959.1 3971.9 7768.7 7814.8 7831.0 7831.6 7882.1 6717.4 7291.8 7697.7 7713.6 7713.9 6571.4 6904.6 7201.9 7518.6 7779.1 6077.7 6528.6 6817.0 7477.9 7504.7 7321.7 7350.5 7377.5 7403.7 7408.8 7857.4 7858.7 7862.3 7866.3 7876.6 4727.8 4771.4 4777.9 4781.1 4785.0 7190.3 7435.3 7460.3 7472.0 7480.0 7190.6 7206.0 7223.0 7234.4 7245.0 7230.9 7239.0 7241.0 7241.8 7260.4 7059.9 7061.6 7084.0 7190.3 7215.2 5904.9 7079.9 7197.7 7233.3 7250.3 6764.7 6889.7 6924.3 6954.9 6978.9 14:37:47 INFO - PROCESS | 2512 | 14:37:47 INFO - PROCESS | 2512 | [#5] yui.html Cycles:180 Average:18691.78 Median:3732.24 stddev:25930.30 (694.8%) stddev-sans-first:25967.87 14:37:47 INFO - PROCESS | 2512 | Values: 705.0 705.9 714.8 743.4 792.1 14379.2 14508.6 14584.7 14644.4 14663.5 13408.6 14313.5 14403.7 14429.3 14446.2 986.3 1024.7 1026.5 1026.6 1028.1 995.3 1006.8 1010.7 1010.8 1012.2 832.3 1040.3 1047.3 1050.8 1053.0 1044.2 1046.2 1046.5 1051.1 1053.3 788.2 794.4 795.0 796.3 797.8 15514.7 15565.5 15579.5 15603.0 15606.0 13096.0 13305.2 13366.2 13592.7 13774.9 10214.7 10224.0 10574.0 10705.4 10974.4 3546.6 3604.7 3633.7 3677.5 3685.1 61809.0 84689.0 85454.6 85824.0 85983.6 9964.2 9985.3 10058.9 10109.0 10187.0 22003.8 22319.3 22353.1 23499.8 23503.0 7891.7 7904.7 8043.4 8211.4 8294.1 4341.1 4428.7 4430.7 4462.7 4630.0 3719.1 3771.7 3775.7 3777.0 3783.9 597.4 660.4 661.5 687.5 692.6 593.9 609.8 612.9 613.4 617.9 8167.4 8425.5 8487.7 8576.4 8639.2 551.0 573.2 598.5 606.9 650.1 3201.3 3745.4 3923.2 3932.8 4052.0 86167.6 88533.0 88601.6 88691.2 88782.6 75006.0 77297.6 80185.0 84904.2 90019.0 73423.0 73913.0 75571.6 76264.0 76682.2 8113.8 8493.1 8864.9 9051.7 9077.4 50511.0 51122.0 51687.0 51825.0 53661.2 66438.7 66533.0 66837.0 67341.7 69111.3 11180.5 11284.0 11522.1 11734.9 11806.4 11472.8 11553.5 11709.6 11773.4 12655.9 11262.2 11273.3 11631.4 11977.1 12415.6 11533.0 11714.5 11763.4 11880.6 12443.3 11391.5 11523.9 11664.4 11671.5 12381.0 10064.3 10078.6 10199.3 10299.4 10363.9 9752.4 10056.5 10137.4 10149.6 10150.5 14:37:47 INFO - PROCESS | 2512 | -------- Summary: end -------- 14:37:47 INFO - PROCESS | 2512 | 14:37:48 INFO - TEST-INFO | 2512: exit 0 14:37:48 INFO - TEST-OK | dromaeo_css | took 1111404ms 14:37:48 INFO - TEST-START | kraken 14:37:48 INFO - operating with platform_type : mac_ 14:37:48 INFO - Initialising browser for kraken test... 14:37:48 INFO - TEST-INFO | started process 2517 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpqujEnQ/profile http://localhost:59996/getInfo.html) 14:37:50 INFO - PROCESS | 2517 | __metrics Screen width/height:1600/1200 14:37:50 INFO - PROCESS | 2517 | colorDepth:24 14:37:50 INFO - PROCESS | 2517 | Browser inner width/height: 1024/689 14:37:50 INFO - PROCESS | 2517 | __metrics 14:37:50 INFO - PROCESS | 2517 | 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" 14:37:50 INFO - PROCESS | 2517 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:37:51 INFO - TEST-INFO | 2517: exit 0 14:37:51 INFO - Browser initialized. 14:37:51 INFO - Running cycle 1/1 for kraken test... 14:37:51 INFO - TEST-INFO | started process 2520 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/tmpqujEnQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:03 INFO - PROCESS | 2520 | RSS: Main: 157372416 14:38:03 INFO - PROCESS | 2520 | 14:38:05 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:59996/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:06 INFO - PROCESS | 2520 | RSS: Main: 212938752 14:38:06 INFO - PROCESS | 2520 | 14:38:08 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:59996/tests/kraken/driver.html?testName=audio-dft) 14:38:09 INFO - PROCESS | 2520 | RSS: Main: 223256576 14:38:09 INFO - PROCESS | 2520 | 14:38:12 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:59996/tests/kraken/driver.html?testName=audio-fft) 14:38:13 INFO - PROCESS | 2520 | RSS: Main: 213737472 14:38:13 INFO - PROCESS | 2520 | 14:38:15 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:59996/tests/kraken/driver.html?testName=audio-oscillator) 14:38:15 INFO - PROCESS | 2520 | RSS: Main: 215134208 14:38:15 INFO - PROCESS | 2520 | 14:38:18 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:59996/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:38:18 INFO - PROCESS | 2520 | RSS: Main: 214740992 14:38:18 INFO - PROCESS | 2520 | 14:38:21 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:59996/tests/kraken/driver.html?testName=imaging-darkroom) 14:38:22 INFO - PROCESS | 2520 | RSS: Main: 214495232 14:38:22 INFO - PROCESS | 2520 | 14:38:24 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:59996/tests/kraken/driver.html?testName=imaging-desaturate) 14:38:25 INFO - PROCESS | 2520 | RSS: Main: 214634496 14:38:25 INFO - PROCESS | 2520 | 14:38:28 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:59996/tests/kraken/driver.html?testName=json-parse-financial) 14:38:28 INFO - PROCESS | 2520 | RSS: Main: 214904832 14:38:28 INFO - PROCESS | 2520 | 14:38:30 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:59996/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:38:30 INFO - PROCESS | 2520 | RSS: Main: 247934976 14:38:30 INFO - PROCESS | 2520 | 14:38:32 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:38:33 INFO - PROCESS | 2520 | RSS: Main: 249692160 14:38:33 INFO - PROCESS | 2520 | 14:38:35 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:38:36 INFO - PROCESS | 2520 | RSS: Main: 254320640 14:38:36 INFO - PROCESS | 2520 | 14:38:38 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:38:39 INFO - PROCESS | 2520 | RSS: Main: 256450560 14:38:39 INFO - PROCESS | 2520 | 14:38:41 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:38:42 INFO - PROCESS | 2520 | RSS: Main: 256217088 14:38:42 INFO - PROCESS | 2520 | 14:38:43 INFO - PROCESS | 2520 | Cycle 1(1): loaded http://localhost:59996/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:59996/tests/kraken/driver.html?testName=ai-astar) 14:38:44 INFO - PROCESS | 2520 | RSS: Main: 319836160 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | __start_tp_report 14:38:44 INFO - PROCESS | 2520 | _x_x_mozilla_page_load 14:38:44 INFO - PROCESS | 2520 | _x_x_mozilla_page_load_details 14:38:44 INFO - PROCESS | 2520 | |i|pagename|runs| 14:38:44 INFO - PROCESS | 2520 | |0;ai-astar;113;109;110;111;112;112;120;113;111;111 14:38:44 INFO - PROCESS | 2520 | |1;audio-beat-detection;150;120;154;147;118;138;116;148;151;115 14:38:44 INFO - PROCESS | 2520 | |2;audio-dft;184;189;186;186;186;190;183;195;256;208 14:38:44 INFO - PROCESS | 2520 | |3;audio-fft;79;71;74;124;69;79;77;92;126;73 14:38:44 INFO - PROCESS | 2520 | |4;audio-oscillator;88;78;129;120;116;123;152;83;78;113 14:38:44 INFO - PROCESS | 2520 | |5;imaging-gaussian-blur;88;86;89;85;87;84;88;86;88;84 14:38:44 INFO - PROCESS | 2520 | |6;imaging-darkroom;92;92;93;94;140;149;112;93;92;92 14:38:44 INFO - PROCESS | 2520 | |7;imaging-desaturate;85;84;85;86;86;86;85;87;85;86 14:38:44 INFO - PROCESS | 2520 | |8;json-parse-financial;55;57;58;58;57;57;60;70;53;51 14:38:44 INFO - PROCESS | 2520 | |9;json-stringify-tinderbox;62;68;65;79;69;67;66;67;66;68 14:38:44 INFO - PROCESS | 2520 | |10;stanford-crypto-aes;80;81;82;79;81;81;80;79;82;84 14:38:44 INFO - PROCESS | 2520 | |11;stanford-crypto-ccm;118;132;125;124;114;122;129;116;117;119 14:38:44 INFO - PROCESS | 2520 | |12;stanford-crypto-pbkdf2;134;125;134;131;133;124;135;134;134;138 14:38:44 INFO - PROCESS | 2520 | |13;stanford-crypto-sha256-iterative;65;66;64;66;65;64;67;66;65;63 14:38:44 INFO - PROCESS | 2520 | __end_tp_report 14:38:44 INFO - PROCESS | 2520 | __start_cc_report 14:38:44 INFO - PROCESS | 2520 | _x_x_mozilla_cycle_collect,1240 14:38:44 INFO - PROCESS | 2520 | __end_cc_report 14:38:44 INFO - PROCESS | 2520 | __startTimestamp1461620324181__endTimestamp 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | ------- Summary: start ------- 14:38:44 INFO - PROCESS | 2520 | Number of tests: 14 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#0] ai-astar Cycles:10 Average:112.20 Median:112.00 stddev:3.01 (2.7%) stddev-sans-first:3.18 14:38:44 INFO - PROCESS | 2520 | Values: 113.0 109.0 110.0 111.0 112.0 112.0 120.0 113.0 111.0 111.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:147.50 stddev:16.45 (11.1%) stddev-sans-first:16.61 14:38:44 INFO - PROCESS | 2520 | Values: 150.0 120.0 154.0 147.0 118.0 138.0 116.0 148.0 151.0 115.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#2] audio-dft Cycles:10 Average:196.30 Median:189.50 stddev:22.22 (11.7%) stddev-sans-first:23.11 14:38:44 INFO - PROCESS | 2520 | Values: 184.0 189.0 186.0 186.0 186.0 190.0 183.0 195.0 256.0 208.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#3] audio-fft Cycles:10 Average:86.40 Median:75.50 stddev:21.30 (28.2%) stddev-sans-first:22.43 14:38:44 INFO - PROCESS | 2520 | Values: 79.0 71.0 74.0 124.0 69.0 79.0 77.0 92.0 126.0 73.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#4] audio-oscillator Cycles:10 Average:108.00 Median:115.00 stddev:25.08 (21.8%) stddev-sans-first:25.53 14:38:44 INFO - PROCESS | 2520 | Values: 88.0 78.0 129.0 120.0 116.0 123.0 152.0 83.0 78.0 113.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.78 (2.0%) stddev-sans-first:1.80 14:38:44 INFO - PROCESS | 2520 | Values: 88.0 86.0 89.0 85.0 87.0 84.0 88.0 86.0 88.0 84.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:92.00 stddev:21.85 (23.7%) stddev-sans-first:22.67 14:38:44 INFO - PROCESS | 2520 | Values: 92.0 92.0 93.0 94.0 140.0 149.0 112.0 93.0 92.0 92.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#7] imaging-desaturate Cycles:10 Average:85.50 Median:86.00 stddev:0.85 (1.0%) stddev-sans-first:0.88 14:38:44 INFO - PROCESS | 2520 | Values: 85.0 84.0 85.0 86.0 86.0 86.0 85.0 87.0 85.0 86.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:5.08 (8.8%) stddev-sans-first:5.30 14:38:44 INFO - PROCESS | 2520 | Values: 55.0 57.0 58.0 58.0 57.0 57.0 60.0 70.0 53.0 51.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:67.50 stddev:4.42 (6.6%) stddev-sans-first:4.18 14:38:44 INFO - PROCESS | 2520 | Values: 62.0 68.0 65.0 79.0 69.0 67.0 66.0 67.0 66.0 68.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.52 (1.9%) stddev-sans-first:1.58 14:38:44 INFO - PROCESS | 2520 | Values: 80.0 81.0 82.0 79.0 81.0 81.0 80.0 79.0 82.0 84.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#11] stanford-crypto-ccm Cycles:10 Average:121.60 Median:123.00 stddev:5.87 (4.8%) stddev-sans-first:6.08 14:38:44 INFO - PROCESS | 2520 | Values: 118.0 132.0 125.0 124.0 114.0 122.0 129.0 116.0 117.0 119.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:132.20 Median:134.00 stddev:4.42 (3.3%) stddev-sans-first:4.64 14:38:44 INFO - PROCESS | 2520 | Values: 134.0 125.0 134.0 131.0 133.0 124.0 135.0 134.0 134.0 138.0 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - PROCESS | 2520 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.50 stddev:1.20 (1.8%) stddev-sans-first:1.27 14:38:44 INFO - PROCESS | 2520 | Values: 65.0 66.0 64.0 66.0 65.0 64.0 67.0 66.0 65.0 63.0 14:38:44 INFO - PROCESS | 2520 | -------- Summary: end -------- 14:38:44 INFO - PROCESS | 2520 | 14:38:44 INFO - TEST-INFO | 2520: exit 0 14:38:44 INFO - TEST-OK | kraken | took 56852ms 14:38:44 INFO - SUITE-END | took 1168s 14:38:45 INFO - Completed test suite (00:19:28) 14:38:45 INFO - javascript benchmark 14:38:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3496.0735405117643, 3548.1839176989274, 3571.9821400892993, 3601.2437388148496, 3635.2002559436914, 5875.941240587593, 5899.4395532424405, 5944.167816505693, 5948.821535353935, 5967.1049342598635, 5890.734916928748, 5938.435848594388, 5944.762209511614, 5955.464008239258, 5960.0, 5219.739013049351, 5283.8150664726745, 5313.282706834586, 5381.327334083239, 5432.022235997519, 5417.7291135443265, 5425.810096646618, 5427.538659213971, 5432.972835135819, 5435.021696094701, 5322.308099947001, 5382.6770393776305, 5402.35171779387, 5411.161270003156, 5412.783488660469, 5387.515123638872, 5399.19012148177, 5420.891582168335, 5436.10304299792, 5484.533814625762, 5159.355080614923, 5340.893182136336, 5366.812161574326, 5379.75791089398, 5383.4616538346245, 5726.427357264281, 5736.684482353469, 5771.567132465082, 5786.392428794958, 5811.883762324772, 5037.697738135724, 6245.937540624626, 6259.687015649245, 6298.937010629927, 6310.747570097191, 6230.688465576703, 6271.62370257786, 6273.40402661751, 6301.117843501914, 6307.653079080695, 4907.362042934431, 5864.824055278348, 5922.0, 5922.111683247461, 5937.643741375488, 6181.567290289727, 6263.217097862767, 6322.873542529123, 6398.072279519537, 6425.3574642536305, 6144.938550614526, 6198.783042593487, 6198.946179149465, 6277.591956522811, 6311.084892690626, 6356.618602883842, 6369.649669268235, 6373.362663733589, 6377.808665740035, 6385.393387628168, 6124.197855633957, 6195.008798592204, 6234.220722409699, 6239.282482514569, 6249.625022498664, 6222.439980401786, 6228.128062071313, 6253.6873156342, 6260.593061450991, 6284.0, 4917.286993385939, 5025.396952365666, 5027.773750181251, 5050.545450909436, 5060.367454068241, 4487.169873573399, 4530.456345238593, 4548.385967894358, 4549.294859296814, 4566.246569316092, 6084.148219249309, 6110.633361998294, 6111.266648002268, 6118.388161183935, 6121.908171377433, 6207.689615519206, 6332.778352757631, 6342.238931328178, 6348.904766428507, 6355.983042713144, 6040.184575082308, 6107.0, 6117.736059405574, 6121.331035925228, 6127.128444391265, 5505.064139096363, 5545.251391062155, 5551.444855514497, 5552.278203833475, 5552.583556233299, 6318.494520438354, 6398.456131228898, 6549.24683661385, 6553.770618028346, 6568.080468734381, 6252.343503932022, 6340.365963403808, 6347.111404403479, 6347.301796802256, 6349.269833968968, 6421.293657697743, 6493.0, 6513.446357059703, 6520.413162815369, 6526.151600291932, 4916.55750982399, 6232.314445410911, 6270.905936410866, 6277.121203031488, 6298.149749783721, 6268.4671802897265, 6283.151774510383, 6303.747850085945, 6304.558680892477, 6313.305536390902, 6216.44052035319, 6291.528135389773, 6293.748250070129, 6297.842553936188, 6303.338149494237, 6135.07955838608, 6197.349278325892, 6256.468200203034, 6276.780312689034, 6309.558330916791, 6191.597546159485, 6206.131141640084, 6208.565400421927, 6214.25428948529, 6216.7824126155365, 5131.923021154611, 6336.904946425715, 6338.239411270677, 6346.2067241594805, 6358.459530939737, 5977.850553736192, 6134.957077260823, 6142.3550527194, 6216.191895053614, 6222.906656400067], "unit": "score", "name": "dojo.html", "value": 5831.378032241582}, {"lowerIsBetter": false, "replicates": [540.9026375252455, 551.6000899347973, 594.8066878264565, 613.9746623139359, 668.8060462465884, 37676.0, 40192.0, 40252.59747402525, 40259.0, 40351.19297614046, 43266.567334326646, 43942.90142746433, 43954.56045439545, 43995.0, 44063.898402539955, 9793.951030244856, 10002.89968103508, 10006.34958727684, 10017.69946901594, 10024.198064154887, 9851.95074024629, 9974.451405172713, 9995.200383969264, 10008.699739007841, 10028.348157369785, 10077.19382449406, 10157.187425005981, 10157.28898977072, 10166.339187952797, 10166.69499915, 10061.698149055503, 10077.596896124147, 10102.898971010269, 10148.137408320265, 10152.492375381202, 9886.406815591015, 10043.497825108716, 10059.698209053668, 10079.092881640687, 10083.193344532494, 43036.0, 43363.566364336584, 43369.78315108436, 43411.0, 43420.69737907868, 2831.603575499432, 3301.735861131104, 3392.5759280089987, 3537.876174333912, 3747.2130852521004, 29033.70966290331, 29368.853155734083, 31070.378592428024, 31080.533791993137, 31270.374592508022, 2838.9432211355866, 2862.685104638488, 2890.624218851557, 2918.970810291891, 2921.4449254641763, 480870.0, 483004.0, 483858.0, 484814.0, 485880.0, 47551.762241188575, 47987.0, 65005.34994649993, 66526.0, 66546.0, 46451.5354846454, 46713.5328646716, 46755.53244467512, 46870.0, 47085.76457117761, 17519.124043797758, 17688.646227075387, 17695.911520442573, 17705.7344139838, 17755.822441775676, 14174.291285435687, 14275.214863182417, 14277.500287490097, 14291.0, 14291.356888940023, 15101.018433801988, 15183.924080379527, 15228.923855380652, 15233.00985435943, 15277.694446111016, 5452.972735136299, 6498.740050398026, 6556.901646475307, 6564.507661925375, 6568.737250510022, 990.64336838738, 1060.692399204222, 1086.7935092332432, 1089.3082892363407, 1157.8784227656142, 30605.08184754461, 30810.229744256572, 31176.844115779277, 31231.0, 31434.899778507643, 1111.1943840715578, 1158.808796548577, 1176.147293212414, 1193.1528614683673, 1215.525944881497, 15421.0, 16061.598460038593, 16423.932444391077, 16833.242504087346, 16862.915685421496, 520009.0, 523808.0, 525746.0, 526195.0, 527792.0, 59126.704366478756, 60671.39328606657, 60926.0, 60958.39041609616, 61097.69451152716, 1203.5727316802584, 1423.2030063164455, 1568.6941046496052, 1572.6068482879052, 1690.8224636413245, 29604.555931660612, 29621.703782961893, 29783.65973531153, 30324.69675303219, 30545.0, 49755.75122124366, 50451.74774126106, 50548.24177637412, 50592.98814023773, 50729.49270507395, 412095.0, 413818.0, 414191.0, 415423.0, 415966.0, 27802.0, 32796.0, 33563.0, 34026.659733403336, 34052.48921266232, 41158.588414115475, 41464.58535414728, 41472.0, 43078.923026924575, 43105.92235194145, 33906.49140262947, 35063.82468087643, 35144.76993305222, 35149.47275790916, 35986.280274393845, 33537.0, 34732.0, 35505.28989420249, 35929.46105808467, 36136.81931590325, 35993.460098098025, 36195.81902090578, 36482.0, 36916.0, 37146.44280335743, 30934.69065309408, 31716.0, 31745.0, 31825.363492730477, 32164.35671286608, 24627.630585540872, 24760.009599616533, 25073.24780256595, 25084.24747257585, 25132.0], "unit": "score", "name": "ext.html", "value": 19705.418315614912}, {"lowerIsBetter": false, "replicates": [380.9885703428897, 381.61266314690585, 386.37600275554985, 390.8885967499263, 396.7976332070647, 20239.190432382704, 21965.0, 22003.66994495082, 22054.228102016434, 22065.33803985879, 22531.662025069636, 22674.433139171488, 22896.855157242153, 22925.77074229257, 22938.96774645141, 5782.190493330937, 5900.144479050535, 5913.793017244398, 6024.819255422344, 6119.785807496739, 5941.762329506814, 6027.608205466652, 6064.211652485171, 6067.878642427148, 6068.393160683941, 5711.457411545896, 5971.402859714038, 5979.760809567634, 6000.75996960121, 6018.247719035121, 5926.644401335933, 5934.406559344053, 5974.910376344358, 5980.252468441445, 5987.281526216838, 5223.268742376071, 5227.790888364499, 5473.31583552056, 5732.742026608813, 5735.283089613798, 13956.092853964561, 13959.022868399215, 14113.858861411358, 14223.928880355636, 14231.50189743354, 2142.6036183306137, 2191.5726433345503, 2255.1994042114975, 2336.953260934789, 2765.9074665507014, 9958.950205248928, 9982.650607228712, 10253.692389228336, 10306.79386412275, 10706.732331691692, 1730.2127531972922, 1760.595063135486, 1767.531604124908, 1782.97325540117, 1821.2532861526843, 100550.0, 103300.48349758203, 103413.0, 105625.47187264092, 107170.9282907161, 10741.89258107409, 11265.831012534818, 11541.249818761753, 11568.421578921021, 11702.88297117018, 14240.216788076548, 14292.857071429153, 14320.427182912566, 14322.713545729028, 14348.497802577069, 8756.781080472912, 8904.465732056098, 8911.465312081296, 9000.909990900138, 9139.954300228455, 9206.079392060874, 9228.661844032578, 9267.67563135287, 9277.304202184729, 9329.067093290752, 4564.2240819060835, 4570.680052396367, 4572.3827283317, 4589.58693717567, 4634.096351211481, 1896.4121122452084, 1908.8186622270862, 1913.8373238274903, 1928.7299778031088, 1967.4097770668743, 948.6110694615174, 954.6038394066385, 1051.358671210561, 1073.8442925775719, 1084.1182620415018, 20710.79289207119, 21228.893855530932, 21447.356579302334, 21607.049770386, 21716.131354745634, 911.9435672558338, 988.8233002726732, 990.4700984973005, 1007.66243308491, 1020.5710409226143, 10476.68569942903, 11358.318500889973, 11782.939535441852, 11793.29240245588, 11804.468798903898, 566295.0, 658238.0, 661320.0, 661356.0, 665271.0, 131754.68245317423, 131948.0, 132319.0, 134453.3277333607, 136283.0, 1015.4465816130227, 1033.1528146919604, 1034.2243317511866, 1036.1658864613908, 1069.2461814420815, 18610.96689164907, 18724.0, 18725.06374681233, 18737.0, 19041.714374284675, 51116.72208194676, 53090.869537271705, 54135.45864541304, 54923.72538137444, 55450.0, 83920.58039709763, 84605.57697211475, 84995.15004849872, 85242.0, 86051.5697421509, 6840.452763778985, 8123.147069557612, 8278.668853246043, 8391.622376992927, 8496.150384961455, 9848.113669769755, 12998.805017924928, 13258.07193496446, 13491.32543372847, 14072.296385180496, 8722.607482663168, 11880.108991825475, 12068.758624827278, 12116.75766484683, 12126.96920761745, 11535.250208736405, 11915.702107447383, 11974.461149248478, 11999.920007199045, 12151.088668349732, 11680.766384672428, 12022.63932082039, 12352.382380880741, 12352.53227408143, 12483.750324993267, 8332.250097491255, 8378.413511054168, 8382.077971423261, 8594.140585941355, 8762.25520830711, 11621.012213961907, 11976.16166868311, 12050.879491205327, 12219.877801221874, 12221.388930553614], "unit": "score", "name": "jquery.html", "value": 9687.633751786752}, {"lowerIsBetter": false, "replicates": [1487.754520504117, 1635.3867299762587, 1657.8010638723347, 1984.5633960528687, 2075.0765909170404, 8392.118827523105, 8990.91009089909, 9183.0, 9189.264858811295, 9195.678151264707, 8874.600642971065, 9127.68053118141, 9149.947756008061, 9160.541972901361, 9161.633534658606, 2578.22653204039, 2606.700229473606, 2606.947861042778, 2607.9348016299555, 2610.099515667092, 2528.671272734542, 2610.89556417744, 2611.086119858044, 2612.960805587908, 2620.6593142891397, 2577.6391305217285, 2597.5454295498307, 2610.6084087386853, 2610.8172427930053, 2616.6075088736748, 2555.9361015974564, 2576.471823276224, 2608.6347911292432, 2610.1386542441046, 2610.517054344933, 2559.1682703121373, 2594.610808378758, 2600.089968511025, 2613.8039647026358, 2615.908443204498, 9141.908580914172, 9210.493422861808, 9218.769530761718, 9238.630454781867, 9242.768930776785, 5537.861553461195, 5690.2887139107615, 5690.630109042941, 5739.139129130664, 5750.88498230033, 3620.601733809305, 3711.758735682172, 3849.480320156799, 3858.131920317934, 3861.2470568239205, 1067.5196161727254, 1086.2613422872444, 1086.7989421956884, 1122.326604037579, 1123.1913022623698, 10388.428636424993, 12520.561780337644, 12539.24764514132, 12541.059420543495, 12572.182808117443, 4992.625553083533, 4999.300377796022, 5085.211792172219, 5141.640085194075, 5149.9742501287255, 10494.108000820015, 10554.050135487843, 10555.261131720707, 10557.0, 10570.2600817942, 5732.197492351074, 5767.36558978512, 5769.336526299446, 5780.595358324961, 5781.82654520365, 4586.518415566385, 4716.434027916672, 4965.82619608312, 5075.137226671401, 5204.141316682705, 4033.354663253924, 4092.979535102365, 4103.4870641169855, 4135.58644135584, 4137.172565486914, 1175.723704929341, 1373.8282714660668, 1384.2179168769612, 1390.5828251524501, 1411.1392050848974, 1423.1745587559192, 1428.0646176754242, 1452.8547145285386, 1466.091023565375, 1477.5567329801017, 8410.0, 8574.614142363484, 8621.353398495137, 8623.051464338918, 8632.956835215784, 1251.599488163797, 1256.7674980128706, 1278.442353011511, 1278.725074109071, 1303.9739205216033, 6529.3470652935275, 6584.407403333723, 6638.137042184581, 6639.402453779183, 6640.335966403321, 10973.50619222137, 11323.490442930088, 11345.943270283597, 11452.312861228353, 11488.77022459546, 10996.28524145928, 10997.065249453886, 11109.0, 11211.0, 11285.43572821372, 8568.52873091986, 8719.38964272495, 8724.127587241352, 8745.91254087451, 8799.868001979976, 6743.561668491532, 8225.83548329042, 8356.87464688018, 8438.493690378597, 8458.323334133378, 9775.0, 9786.51067446639, 9845.753856153653, 9862.21102311799, 10025.348352357074, 9944.403335799876, 10240.9759024097, 10272.794544109225, 10290.742731431774, 10454.425006624404, 7050.753223637148, 7436.18201997769, 7499.850003000019, 7703.537787732754, 7774.650140743567, 8424.031236407647, 8450.450720703135, 8477.788055298666, 8506.787330316543, 8511.106333834858, 7462.440316976357, 7575.545467271982, 7643.923560764321, 7674.079110506775, 7693.730719424793, 7452.776416707507, 7485.026946496921, 7491.475596708397, 7587.127480339832, 7596.658150383136, 8047.919520804951, 8079.555624440713, 8105.027396712432, 8118.878216826634, 8128.0, 7187.065681461377, 7210.098737657793, 7227.927720722725, 7267.345938865527, 7271.818204544928, 6248.687565621792, 7362.926370736438, 7383.409327253914, 7412.184659687322, 7418.369438597564], "unit": "score", "name": "mootools.html", "value": 5184.366540731051}, {"lowerIsBetter": false, "replicates": [341.3105526835792, 346.26418859922666, 349.49846969598644, 350.9192885636303, 355.1299316674147, 6668.099806526117, 6696.866062678756, 6702.296258892813, 6761.695723692434, 6812.318768123185, 6652.800415987516, 6661.134052573158, 6670.199576050881, 6671.733130674779, 6672.232693240267, 4775.737334446604, 4850.078485087822, 4929.014197160564, 4968.602511799066, 4994.325766021577, 4935.851924442272, 4942.283368911506, 4945.67853089548, 4952.306677065214, 4954.1330267203275, 4641.793133785206, 4647.651426143036, 4979.253112033206, 4980.626453016021, 4992.151334273182, 4733.242681171013, 4836.97581512092, 4955.925661115086, 4972.776225069881, 4977.42759582649, 3526.682598566141, 4283.186194623012, 4294.806733696991, 4302.720323178984, 4314.676399270067, 6357.554971151975, 6407.8398040048905, 6426.61440313578, 6437.163168788027, 6479.384458476437, 5862.765489380419, 5870.442307980735, 5873.706314684248, 5874.618149820248, 5901.17383566301, 6829.795106146824, 7060.0, 7062.117235345582, 7070.681819318143, 7071.964640176766, 4792.257200133985, 4816.7350795706225, 4818.156822556074, 4824.0, 4829.203181475053, 7412.888806667905, 7476.962615186943, 7478.2895624915545, 7487.812804679872, 7492.213317601682, 4067.7966101694797, 4859.101066302675, 4886.218205087169, 4887.36464259644, 4889.926651100236, 4959.008198360342, 4962.627802914796, 4963.280324353021, 4966.106100901872, 4969.478204788517, 5696.629719068247, 5861.267341582302, 5870.853228669317, 5906.350301466835, 5908.940910590925, 7137.393321567621, 7145.606991615407, 7160.498765086394, 7169.749058783022, 7184.1019872515935, 1702.8297170282872, 1740.9477715668297, 1743.834335738103, 1745.1797655102082, 1750.1424302091884, 1052.0846863228887, 1094.68801391603, 1114.7603265298, 1122.8708698499613, 1147.9024282935877, 6750.1562304711915, 6780.559263647847, 6836.0, 6876.518643694993, 6879.690413931385, 7506.437017223621, 7511.624418779039, 7519.624018799038, 7546.622668866535, 7559.3196612305155, 6858.142732158481, 6874.312568743186, 6879.6560171991205, 6884.863997440366, 6907.447404207652, 3838.692904567628, 3912.5523556284356, 3953.13031133156, 3959.109200429938, 3971.940420893689, 7768.65041073153, 7814.765557033298, 7830.960845195737, 7831.6475758591005, 7882.132965373804, 6717.395434410926, 7291.79685351911, 7697.730579429693, 7713.575753333508, 7713.884291735629, 6571.3757193066585, 6904.585724856524, 7201.92798072023, 7518.586477743776, 7779.144294127642, 6077.726502307495, 6528.608283503005, 6816.965915170393, 7477.850442991218, 7504.662290196845, 7321.707131714885, 7350.522216056153, 7377.524495100894, 7403.66683499233, 7408.814779630552, 7857.371410287277, 7858.724944626911, 7862.2923936845955, 7866.331361834308, 7876.606169691607, 4727.787249573847, 4771.403574553181, 4777.880552986203, 4781.139394908811, 4784.976075119602, 7190.316919892602, 7435.330820226206, 7460.29127232912, 7472.0286362772495, 7479.962600186965, 7190.568565886064, 7206.0, 7223.0, 7234.421246300389, 7245.021922040668, 7230.927690723025, 7238.963805181151, 7241.022461967567, 7241.782746517613, 7260.382867456326, 7059.894101588583, 7061.576305421691, 7083.964580177066, 7190.280971902768, 7215.242399547972, 5904.852378690567, 7079.902615094668, 7197.71209151628, 7233.348998590152, 7250.3112204340505, 6764.729410823709, 6889.689963951534, 6924.3421874923815, 6954.921987092009, 6978.94121234227], "unit": "score", "name": "prototype.html", "value": 5271.980314683774}, {"lowerIsBetter": false, "replicates": [704.9859002819942, 705.9470539709522, 714.8105751975727, 743.4498471131365, 792.1049214387738, 14379.209143497104, 14508.637284067903, 14584.708305833878, 14644.414223431064, 14663.486777962773, 13408.59774206773, 14313.499027534039, 14403.711925761478, 14429.278536073181, 14446.205458699767, 986.3046552180731, 1024.7028361775076, 1026.4508487958944, 1026.563710423071, 1028.1260928211036, 995.3182070281844, 1006.8137394581989, 1010.6917390195987, 1010.8028934357803, 1012.2307046644567, 832.2759199496446, 1040.3237895367995, 1047.3140093238903, 1050.821360368739, 1053.0049103597105, 1044.1698849414695, 1046.1578429364358, 1046.5499835070916, 1051.1328154272717, 1053.3153450257316, 788.2117882117882, 794.4438892775081, 795.0340336491191, 796.2913007423398, 797.7845981584998, 15514.689706205925, 15565.45520906774, 15579.532614021597, 15602.985805922579, 15606.0, 13096.0, 13305.201687898758, 13366.198028118246, 13592.728145437135, 13774.862251377559, 10214.744631384201, 10223.977602239791, 10573.995470430296, 10705.357678539234, 10974.396408197546, 3546.5744110706623, 3604.7476676632646, 3633.691136253395, 3677.5403074615674, 3685.0787303174206, 61809.0, 84689.0, 85454.57272713722, 85824.0, 85983.57008214919, 9964.153046990941, 9985.251106167067, 10058.899411005796, 10109.0, 10187.0, 22003.779962200493, 22319.330420087423, 22353.105875764788, 23499.7650023501, 23503.0, 7891.723789667449, 7904.6838126474295, 8043.356531477468, 8211.384146189059, 8294.087650358455, 4341.110072435144, 4428.6678499112695, 4430.689851710349, 4462.665300102505, 4629.976850115728, 3719.088823238324, 3771.735978481535, 3775.716821238418, 3776.999095239764, 3783.9243215135543, 597.396629404296, 660.4023358860239, 661.503872095928, 687.5187368841831, 692.5809885019592, 593.9376365481648, 609.8261995331397, 612.8682333298362, 613.4295105551879, 617.9073139029092, 8167.387445941578, 8425.536595487185, 8487.660493580312, 8576.399652024424, 8639.222469977733, 550.9504394129191, 573.2061095382925, 598.5032423088826, 606.869523052537, 650.0541711809307, 3201.2797120648133, 3745.4007358823, 3923.2349691809823, 3932.7640341579026, 4051.9797401012806, 86167.56916215378, 88533.0, 88601.55699221462, 88691.22617547742, 88782.55608722044, 75006.0, 77297.6135119332, 80185.0, 84904.15095848963, 90019.0, 73422.99999999894, 73913.0, 75571.62214189004, 76264.0, 76682.23317766862, 8113.8234955929875, 8493.10822363643, 8864.86702699447, 9051.683191088281, 9077.409968352036, 50511.0, 51122.0, 51687.0, 51824.96350072902, 53661.195082074584, 66438.66780666065, 66533.0, 66837.0, 67341.65316693737, 69111.30888691048, 11180.496877640688, 11284.0, 11522.135839812216, 11734.882651173379, 11806.409679516162, 11472.827907581226, 11553.480093395649, 11709.648710538697, 11773.352465614473, 12655.93672031634, 11262.155338349492, 11273.267237629529, 11631.360275184947, 11977.101717371406, 12415.62753117408, 11533.0, 11714.531418743154, 11763.411829408324, 11880.584179553674, 12443.253404795742, 11391.487383067617, 11523.884761152616, 11664.416779160838, 11671.533138674358, 12381.0, 10064.345817521838, 10078.6472473466, 10199.337043092472, 10299.433531155559, 10363.94818025905, 9752.414855108716, 10056.54745536438, 10137.44244066554, 10149.594016239564, 10150.54322555472], "unit": "score", "name": "yui.html", "value": 7204.53917445073}], "name": "dromaeo_css", "value": 7765.100376086758}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 109.0, 110.0, 111.0, 112.0, 112.0, 120.0, 113.0, 111.0, 111.0], "unit": "score", "name": "ai-astar", "value": 112.2}, {"lowerIsBetter": true, "replicates": [150.0, 120.0, 154.0, 147.0, 118.0, 138.0, 116.0, 148.0, 151.0, 115.0], "unit": "score", "name": "audio-beat-detection", "value": 135.7}, {"lowerIsBetter": true, "replicates": [184.0, 189.0, 186.0, 186.0, 186.0, 190.0, 183.0, 195.0, 256.0, 208.0], "unit": "score", "name": "audio-dft", "value": 196.3}, {"lowerIsBetter": true, "replicates": [79.0, 71.0, 74.0, 124.0, 69.0, 79.0, 77.0, 92.0, 126.0, 73.0], "unit": "score", "name": "audio-fft", "value": 86.4}, {"lowerIsBetter": true, "replicates": [88.0, 78.0, 129.0, 120.0, 116.0, 123.0, 152.0, 83.0, 78.0, 113.0], "unit": "score", "name": "audio-oscillator", "value": 108.0}, {"lowerIsBetter": true, "replicates": [88.0, 86.0, 89.0, 85.0, 87.0, 84.0, 88.0, 86.0, 88.0, 84.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 86.5}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 93.0, 94.0, 140.0, 149.0, 112.0, 93.0, 92.0, 92.0], "unit": "score", "name": "imaging-darkroom", "value": 104.9}, {"lowerIsBetter": true, "replicates": [85.0, 84.0, 85.0, 86.0, 86.0, 86.0, 85.0, 87.0, 85.0, 86.0], "unit": "score", "name": "imaging-desaturate", "value": 85.5}, {"lowerIsBetter": true, "replicates": [55.0, 57.0, 58.0, 58.0, 57.0, 57.0, 60.0, 70.0, 53.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.6}, {"lowerIsBetter": true, "replicates": [62.0, 68.0, 65.0, 79.0, 69.0, 67.0, 66.0, 67.0, 66.0, 68.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 82.0, 79.0, 81.0, 81.0, 80.0, 79.0, 82.0, 84.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.9}, {"lowerIsBetter": true, "replicates": [118.0, 132.0, 125.0, 124.0, 114.0, 122.0, 129.0, 116.0, 117.0, 119.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 121.6}, {"lowerIsBetter": true, "replicates": [134.0, 125.0, 134.0, 131.0, 133.0, 124.0, 135.0, 134.0, 134.0, 138.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 132.2}, {"lowerIsBetter": true, "replicates": [65.0, 66.0, 64.0, 66.0, 65.0, 64.0, 67.0, 66.0, 65.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.1}], "name": "kraken", "value": 1440.6}]} 14:38:45 INFO - Return code: 0 14:38:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:38:45 INFO - # TBPL SUCCESS # 14:38:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:45 INFO - 2016-04-25 14:38:45.522 screenresolution[2524:26043] starting screenresolution argv=screenresolution get 14:38:45 INFO - 2016-04-25 14:38:45.524 screenresolution[2524:26043] Display 0: 1600x1200x32@60 14:38:45 INFO - 2016-04-25 14:38:45.530 screenresolution[2525:26046] starting screenresolution argv=screenresolution list 14:38:45 INFO - Available Modes on Display 0 14:38:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:38:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:38:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:38:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:38:45 INFO - Intel Iris: 14:38:45 INFO - Chipset Model: Intel Iris 14:38:45 INFO - Type: GPU 14:38:45 INFO - Bus: Built-In 14:38:45 INFO - VRAM (Dynamic, Max): 1536 MB 14:38:45 INFO - Vendor: Intel (0x8086) 14:38:45 INFO - Device ID: 0x0a2e 14:38:45 INFO - Revision ID: 0x0009 14:38:45 INFO - Displays: 14:38:45 INFO - Display: 14:38:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:38:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:38:45 INFO - Display Serial Number: 1600x1200 60 14:38:45 INFO - Main Display: Yes 14:38:45 INFO - Mirror: Off 14:38:45 INFO - Online: Yes 14:38:45 INFO - Rotation: Supported 14:38:45 INFO - Return code: 0 14:38:45 INFO - Running post-action listener: _resource_record_post_action 14:38:45 INFO - Running post-run listener: _resource_record_post_run 14:38:46 INFO - Total resource usage - Wall time: 1193s; CPU: 52.0%; Read bytes: 163840; Write bytes: 508244992; Read time: 4; Write time: 1833 14:38:46 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 196755968; Write bytes: 198404096; Read time: 18251; Write time: 413 14:38:46 INFO - run-tests - Wall time: 1171s; CPU: 52.0%; Read bytes: 163840; Write bytes: 299883520; Read time: 4; Write time: 1380 14:38:46 INFO - Running post-run listener: _upload_blobber_files 14:38:46 INFO - Blob upload gear active. 14:38:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:38:46 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:38:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:38:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:38:47 INFO - (blobuploader) - INFO - Open directory for files ... 14:38:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:38:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:38:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:48 INFO - (blobuploader) - INFO - Done attempting. 14:38:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:38:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:38:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:38:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:38:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:38:49 INFO - (blobuploader) - INFO - Done attempting. 14:38:49 INFO - (blobuploader) - INFO - Iteration through files over. 14:38:49 INFO - Return code: 0 14:38:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:38:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:38:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4aad6717217f7a7b76193aac419d3059bfcf0367a03fc49c996bb5cbc9f0bde7ecc454da8dd922d2fab507fd21c7dc70c969d5697a9e8f1f6d84d68f00e78c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/335e0cb7c554ec7fc2292a51a6f80f6daeac88bbbdfdf834fe6e27cf356cd5b32bb238aa65209ef0ab9c27382be35698d2dc7063cf0b1bcb5738524a083c0339"} 14:38:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:38:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:38:49 INFO - Contents: 14:38:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4aad6717217f7a7b76193aac419d3059bfcf0367a03fc49c996bb5cbc9f0bde7ecc454da8dd922d2fab507fd21c7dc70c969d5697a9e8f1f6d84d68f00e78c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/335e0cb7c554ec7fc2292a51a6f80f6daeac88bbbdfdf834fe6e27cf356cd5b32bb238aa65209ef0ab9c27382be35698d2dc7063cf0b1bcb5738524a083c0339"} 14:38:49 INFO - Running post-run listener: copy_logs_to_upload_dir 14:38:49 INFO - Copying logs to upload dir... 14:38:49 INFO - mkdir: /builds/slave/test/build/upload/logs 14:38:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1246.432138 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 46 secs) (at 2016-04-25 14:38:49.854376) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-25 14:38:49.856482) ========= 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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4aad6717217f7a7b76193aac419d3059bfcf0367a03fc49c996bb5cbc9f0bde7ecc454da8dd922d2fab507fd21c7dc70c969d5697a9e8f1f6d84d68f00e78c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/335e0cb7c554ec7fc2292a51a6f80f6daeac88bbbdfdf834fe6e27cf356cd5b32bb238aa65209ef0ab9c27382be35698d2dc7063cf0b1bcb5738524a083c0339"} build_url:https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011419 build_url: 'https://queue.taskcluster.net/v1/task/Ldkw5WppQsCyV7f_wMcgGg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4aad6717217f7a7b76193aac419d3059bfcf0367a03fc49c996bb5cbc9f0bde7ecc454da8dd922d2fab507fd21c7dc70c969d5697a9e8f1f6d84d68f00e78c3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/335e0cb7c554ec7fc2292a51a6f80f6daeac88bbbdfdf834fe6e27cf356cd5b32bb238aa65209ef0ab9c27382be35698d2dc7063cf0b1bcb5738524a083c0339"}' ========= master_lag: 2.47 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 2 secs) (at 2016-04-25 14:38:52.336640) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 14:38:52.336968) ========= 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.SlFjG2rsOR/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.YARlqdkB4Q/Listeners TMPDIR=/var/folders/q8/1vnm3qf56qs7z6b9j3w0gwl800000w/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.005134 ========= master_lag: 0.10 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 14:38:52.442820) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 14:38:52.443135) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 14:38:52.444371) ========= ========= Total master_lag: 4.37 =========