builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0257 starttime: 1463793522.23 results: success (0) buildid: 20160520162336 builduid: e38a8976e8ac4cc58d44f0d7275e05a5 revision: 782b4767770b8d7f9ddea913167c49a12087d6af ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.227734) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.228168) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.228437) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.246782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.247133) ========= 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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-20 18:18:41-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.96M=0.001s 2016-05-20 18:18:42 (7.96 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.122726 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.388001) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.388323) ========= 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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.020106 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:42.436518) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 18:18:42.436877) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 782b4767770b8d7f9ddea913167c49a12087d6af --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 782b4767770b8d7f9ddea913167c49a12087d6af --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-20 18:18:42,208 truncating revision to first 12 chars 2016-05-20 18:18:42,208 Setting DEBUG logging. 2016-05-20 18:18:42,208 attempt 1/10 2016-05-20 18:18:42,208 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/782b4767770b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-20 18:18:43,294 unpacking tar archive at: mozilla-aurora-782b4767770b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.342108 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-20 18:18:43.798027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:43.798386) ========= script_repo_revision: 782b4767770b8d7f9ddea913167c49a12087d6af ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:43.798826) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:43.799105) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-20 18:18:43.817000) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-20 18:18:43.817394) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:18:43 INFO - MultiFileLogger online at 20160520 18:18:43 in /builds/slave/test 18:18:43 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:18:43 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:18:43 INFO - {'append_to_log': False, 18:18:43 INFO - 'base_work_dir': '/builds/slave/test', 18:18:43 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:18:43 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:18:43 INFO - 'branch': 'Mozilla-Aurora', 18:18:43 INFO - 'buildbot_json_path': 'buildprops.json', 18:18:43 INFO - 'config_files': ('talos/mac_config.py',), 18:18:43 INFO - 'default_actions': ('clobber', 18:18:43 INFO - 'read-buildbot-config', 18:18:43 INFO - 'download-and-extract', 18:18:43 INFO - 'populate-webroot', 18:18:43 INFO - 'create-virtualenv', 18:18:43 INFO - 'install', 18:18:43 INFO - 'run-tests'), 18:18:43 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:18:43 INFO - 'download_minidump_stackwalk': True, 18:18:43 INFO - 'download_symbols': 'ondemand', 18:18:43 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:18:43 INFO - 'tooltool.py': '/tools/tooltool.py', 18:18:43 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:18:43 INFO - '/tools/misc-python/virtualenv.py')}, 18:18:43 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:18:43 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:18:43 INFO - 'installer_path': 'installer.exe', 18:18:43 INFO - 'log_level': 'info', 18:18:43 INFO - 'log_name': 'talos', 18:18:43 INFO - 'log_to_console': True, 18:18:43 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:18:43 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:18:43 INFO - 'opt_config_files': (), 18:18:43 INFO - 'pip_index': False, 18:18:43 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:18:43 INFO - 'cmd': ('bash', 18:18:43 INFO - '-c', 18:18:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:18:43 INFO - 'enabled': True, 18:18:43 INFO - 'halt_on_failure': False, 18:18:43 INFO - 'name': 'check_screen_resolution'},), 18:18:43 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:18:43 INFO - 'cmd': ('bash', 18:18:43 INFO - '-c', 18:18:43 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:18:43 INFO - 'enabled': True, 18:18:43 INFO - 'halt_on_failure': False, 18:18:43 INFO - 'name': 'check_screen_resolution'},), 18:18:43 INFO - 'run_cmd_checks_enabled': True, 18:18:43 INFO - 'sps_profile': False, 18:18:43 INFO - 'sps_profile_interval': 0, 18:18:43 INFO - 'suite': 'dromaeojs', 18:18:43 INFO - 'system_bits': '32', 18:18:43 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:18:43 INFO - 'title': 't-yosemite-r7-0257', 18:18:43 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:18:43 INFO - 'use_talos_json': True, 18:18:43 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:18:43 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:18:43 INFO - 'work_dir': 'build'} 18:18:43 INFO - ##### 18:18:43 INFO - ##### Running clobber step. 18:18:43 INFO - ##### 18:18:43 INFO - Running pre-action listener: _resource_record_pre_action 18:18:43 INFO - Running main action method: clobber 18:18:43 INFO - rmtree: /builds/slave/test/build 18:18:43 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:18:44 INFO - Running post-action listener: _resource_record_post_action 18:18:44 INFO - ##### 18:18:44 INFO - ##### Running read-buildbot-config step. 18:18:44 INFO - ##### 18:18:44 INFO - Running pre-action listener: _resource_record_pre_action 18:18:44 INFO - Running main action method: read_buildbot_config 18:18:44 INFO - Using buildbot properties: 18:18:44 INFO - { 18:18:44 INFO - "project": "", 18:18:44 INFO - "product": "firefox", 18:18:44 INFO - "script_repo_revision": "production", 18:18:44 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:18:44 INFO - "repository": "", 18:18:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:18:44 INFO - "buildid": "20160520162336", 18:18:44 INFO - "pgo_build": "False", 18:18:44 INFO - "basedir": "/builds/slave/test", 18:18:44 INFO - "buildnumber": 348, 18:18:44 INFO - "slavename": "t-yosemite-r7-0257", 18:18:44 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 18:18:44 INFO - "platform": "yosemite_r7", 18:18:44 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:18:44 INFO - "branch": "mozilla-aurora", 18:18:44 INFO - "repo_path": "releases/mozilla-aurora", 18:18:44 INFO - "revision": "782b4767770b8d7f9ddea913167c49a12087d6af", 18:18:44 INFO - "stage_platform": "macosx64", 18:18:44 INFO - "builduid": "e38a8976e8ac4cc58d44f0d7275e05a5", 18:18:44 INFO - "slavebuilddir": "test" 18:18:44 INFO - } 18:18:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:18:44 INFO - Running post-action listener: _resource_record_post_action 18:18:44 INFO - ##### 18:18:44 INFO - ##### Running download-and-extract step. 18:18:44 INFO - ##### 18:18:44 INFO - Running pre-action listener: _resource_record_pre_action 18:18:44 INFO - Running main action method: download_and_extract 18:18:44 INFO - mkdir: /builds/slave/test/build/tests 18: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')]} 18:18:44 INFO - https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:18:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:18:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:18:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:18:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:18:46 INFO - Downloaded 1199 bytes. 18:18:46 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:18:46 INFO - Using the following test package requirements: 18:18:46 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:18:46 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:18:46 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:18:46 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'jsshell-mac.zip'], 18:18:46 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:18:46 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:18:46 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:18:46 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:18:46 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:18:46 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:18:46 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:18:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:18:46 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')]} 18:18:46 INFO - https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:18:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:18:48 INFO - Downloaded 18102468 bytes. 18:18:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:18:51 INFO - Return code: 0 18:18:51 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:18:51 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')]} 18:18:51 INFO - https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:18:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:18:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:18:52 INFO - Downloaded 18102468 bytes. 18:18:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:18:55 INFO - Return code: 0 18:18:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:18:55 INFO - https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:18:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:18:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:18:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:18:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:18:57 INFO - Downloaded 10840145 bytes. 18:18:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:18:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:18:57 INFO - Return code: 0 18:18:57 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')]} 18:18:57 INFO - https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:18:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:18:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:18:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:18:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:19:00 INFO - Downloaded 114099226 bytes. 18:19:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:19:00 INFO - mkdir: /builds/slave/test/properties 18:19:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:19:00 INFO - Writing to file /builds/slave/test/properties/build_url 18:19:00 INFO - Contents: 18:19:00 INFO - build_url:https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:19:01 INFO - Running post-action listener: _resource_record_post_action 18:19:01 INFO - Running post-action listener: set_extra_try_arguments 18:19:01 INFO - ##### 18:19:01 INFO - ##### Running populate-webroot step. 18:19:01 INFO - ##### 18:19:01 INFO - Running pre-action listener: _resource_record_pre_action 18:19:01 INFO - Running main action method: populate_webroot 18:19:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:19:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:19:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:19:01 INFO - '--tppagecycles', 18:19:01 INFO - '7'], 18:19:01 INFO - 'tests': ['tsvgm']}}, 18:19:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:19:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:19:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:19:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:19:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:19:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['damp', 'tps']}, 18:19:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'tests': ['damp', 'tps']}, 18:19:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['dromaeo_dom']}, 18:19:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:19:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['a11yr', 18:19:01 INFO - 'ts_paint', 18:19:01 INFO - 'tpaint', 18:19:01 INFO - 'sessionrestore', 18:19:01 INFO - 'sessionrestore_no_auto_restore', 18:19:01 INFO - 'tabpaint']}, 18:19:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['tsvgx', 18:19:01 INFO - 'tsvgr_opacity', 18:19:01 INFO - 'tart', 18:19:01 INFO - 'tscrollx', 18:19:01 INFO - 'cart']}, 18:19:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:19:01 INFO - 'tsvgr_opacity', 18:19:01 INFO - 'tart', 18:19:01 INFO - 'tscrollx', 18:19:01 INFO - 'cart']}, 18:19:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'talos_options': ['--disable-e10s'], 18:19:01 INFO - 'tests': ['tp5o']}, 18:19:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'tests': ['tp5o']}, 18:19:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'talos_options': ['--disable-e10s', 18:19:01 INFO - '--xperf_path', 18:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:01 INFO - 'tests': ['tp5n']}, 18:19:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:19:01 INFO - 'talos_options': ['--xperf_path', 18:19:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:19:01 INFO - 'tests': ['tp5n']}}, 18:19:01 INFO - 'talos.zip': {'path': '', 18:19:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:19:01 INFO - Running post-action listener: _resource_record_post_action 18:19:01 INFO - ##### 18:19:01 INFO - ##### Running create-virtualenv step. 18:19:01 INFO - ##### 18:19:01 INFO - Running pre-action listener: _resource_record_pre_action 18:19:01 INFO - Running main action method: create_virtualenv 18:19:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:19:01 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 18:19:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:19:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:19:01 INFO - Using real prefix '/tools/python27' 18:19:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:19:02 INFO - Installing distribute.............................................................................................................................................................................................done. 18:19:05 INFO - Installing pip.................done. 18:19:05 INFO - Return code: 0 18:19:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:05 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:05 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 18:19:05 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 18:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:05 INFO - 'HOME': '/Users/cltbld', 18:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:05 INFO - 'LOGNAME': 'cltbld', 18:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:05 INFO - 'MOZ_NO_REMOTE': '1', 18:19:05 INFO - 'NO_EM_RESTART': '1', 18:19:05 INFO - 'PAGER': '/bin/cat', 18:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:05 INFO - 'PWD': '/builds/slave/test', 18:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:05 INFO - 'SHELL': '/bin/bash', 18:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:05 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:05 INFO - 'USER': 'cltbld', 18:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:05 INFO - 'XPC_FLAGS': '0x0', 18:19:05 INFO - 'XPC_SERVICE_NAME': '0', 18:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:05 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 18:19:05 INFO - Cleaning up... 18:19:05 INFO - Return code: 0 18:19:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:05 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:05 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 18:19:05 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 18:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:05 INFO - 'HOME': '/Users/cltbld', 18:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:05 INFO - 'LOGNAME': 'cltbld', 18:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:05 INFO - 'MOZ_NO_REMOTE': '1', 18:19:05 INFO - 'NO_EM_RESTART': '1', 18:19:05 INFO - 'PAGER': '/bin/cat', 18:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:05 INFO - 'PWD': '/builds/slave/test', 18:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:05 INFO - 'SHELL': '/bin/bash', 18:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:05 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:05 INFO - 'USER': 'cltbld', 18:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:05 INFO - 'XPC_FLAGS': '0x0', 18:19:05 INFO - 'XPC_SERVICE_NAME': '0', 18:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:05 INFO - Downloading/unpacking psutil>=0.7.1 18:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:19:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:08 INFO - Installing collected packages: psutil 18:19:08 INFO - Running setup.py install for psutil 18:19:08 INFO - building 'psutil._psutil_osx' extension 18:19:08 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 INFO - building 'psutil._psutil_posix' extension 18:19:09 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 18:19:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:19:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:19:09 INFO - ^ 18:19:09 INFO - 1 warning generated. 18:19:09 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 18:19:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:19:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:19:09 INFO - Successfully installed psutil 18:19:09 INFO - Cleaning up... 18:19:09 INFO - Return code: 0 18:19:09 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:09 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:09 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 18:19:09 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 18:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:09 INFO - 'HOME': '/Users/cltbld', 18:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:09 INFO - 'LOGNAME': 'cltbld', 18:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:09 INFO - 'MOZ_NO_REMOTE': '1', 18:19:09 INFO - 'NO_EM_RESTART': '1', 18:19:09 INFO - 'PAGER': '/bin/cat', 18:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:09 INFO - 'PWD': '/builds/slave/test', 18:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:09 INFO - 'SHELL': '/bin/bash', 18:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:09 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:09 INFO - 'USER': 'cltbld', 18:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:09 INFO - 'XPC_FLAGS': '0x0', 18:19:09 INFO - 'XPC_SERVICE_NAME': '0', 18:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:09 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 18:19:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:19:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:19:12 INFO - Installing collected packages: mozsystemmonitor 18:19:12 INFO - Running setup.py install for mozsystemmonitor 18:19:12 INFO - Successfully installed mozsystemmonitor 18:19:12 INFO - Cleaning up... 18:19:12 INFO - Return code: 0 18:19:12 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:19:12 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')]} 18:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:12 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')]} 18:19:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:12 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:12 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 18:19:12 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 18:19:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:12 INFO - 'HOME': '/Users/cltbld', 18:19:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:12 INFO - 'LOGNAME': 'cltbld', 18:19:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:12 INFO - 'MOZ_NO_REMOTE': '1', 18:19:12 INFO - 'NO_EM_RESTART': '1', 18:19:12 INFO - 'PAGER': '/bin/cat', 18:19:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:12 INFO - 'PWD': '/builds/slave/test', 18:19:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:12 INFO - 'SHELL': '/bin/bash', 18:19:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:12 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:12 INFO - 'USER': 'cltbld', 18:19:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:12 INFO - 'XPC_FLAGS': '0x0', 18:19:12 INFO - 'XPC_SERVICE_NAME': '0', 18:19:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:19:12 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 18:19:12 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 18:19:12 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 18:19:12 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 18:19:12 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 18:19:12 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 18:19:15 INFO - Downloading blobuploader-1.2.4.tar.gz 18:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:19:15 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:19:15 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 18:19:15 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 18:19:15 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 18:19:15 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 18:19:15 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 18:19:15 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 18:19:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:19:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:19:16 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 18:19:16 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 18:19:16 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 18:19:16 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 18:19:16 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 18:19:16 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 18:19:16 INFO - Downloading docopt-0.6.1.tar.gz 18:19:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:19:16 INFO - Installing collected packages: blobuploader, requests, docopt 18:19:16 INFO - Running setup.py install for blobuploader 18:19:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:19:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:19:16 INFO - Running setup.py install for requests 18:19:17 INFO - Running setup.py install for docopt 18:19:17 INFO - Successfully installed blobuploader requests docopt 18:19:17 INFO - Cleaning up... 18:19:17 INFO - Return code: 0 18:19:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:17 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:17 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 18:19:17 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 18:19:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:17 INFO - 'HOME': '/Users/cltbld', 18:19:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:17 INFO - 'LOGNAME': 'cltbld', 18:19:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:17 INFO - 'MOZ_NO_REMOTE': '1', 18:19:17 INFO - 'NO_EM_RESTART': '1', 18:19:17 INFO - 'PAGER': '/bin/cat', 18:19:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:17 INFO - 'PWD': '/builds/slave/test', 18:19:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:17 INFO - 'SHELL': '/bin/bash', 18:19:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:17 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:17 INFO - 'USER': 'cltbld', 18:19:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:17 INFO - 'XPC_FLAGS': '0x0', 18:19:17 INFO - 'XPC_SERVICE_NAME': '0', 18:19:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-tbjBwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-8i8u1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:17 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-QvaEOC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-IJVwbJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-1BWCil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Of65Db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-2tlUX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Mfk1xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-1_R6_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-mWyOsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-blWRGv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-3paL_S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:18 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-mSMT70-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:19 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-bL_ljc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:19 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-M9USTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:19 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-jIZqp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:19 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-NJsiuc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:19 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:19:19 INFO - Running setup.py install for manifestparser 18:19:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:19:19 INFO - Running setup.py install for mozcrash 18:19:19 INFO - Running setup.py install for mozdebug 18:19:19 INFO - Running setup.py install for mozdevice 18:19:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Running setup.py install for mozfile 18:19:20 INFO - Running setup.py install for mozhttpd 18:19:20 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Running setup.py install for mozinfo 18:19:20 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Running setup.py install for mozInstall 18:19:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Running setup.py install for mozleak 18:19:20 INFO - Running setup.py install for mozlog 18:19:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:19:20 INFO - Running setup.py install for moznetwork 18:19:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:19:21 INFO - Running setup.py install for mozprocess 18:19:21 INFO - Running setup.py install for mozprofile 18:19:21 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:19:21 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:19:21 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:19:21 INFO - Running setup.py install for mozrunner 18:19:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:19:21 INFO - Running setup.py install for mozscreenshot 18:19:21 INFO - Running setup.py install for moztest 18:19:21 INFO - Running setup.py install for mozversion 18:19:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:19:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:19:22 INFO - Cleaning up... 18:19:22 INFO - Return code: 0 18:19:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19: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')]} 18:19:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19: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')]} 18:19:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:22 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:22 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 18:19:22 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 18:19:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:22 INFO - 'HOME': '/Users/cltbld', 18:19:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:22 INFO - 'LOGNAME': 'cltbld', 18:19:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:22 INFO - 'MOZ_NO_REMOTE': '1', 18:19:22 INFO - 'NO_EM_RESTART': '1', 18:19:22 INFO - 'PAGER': '/bin/cat', 18:19:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:22 INFO - 'PWD': '/builds/slave/test', 18:19:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:22 INFO - 'SHELL': '/bin/bash', 18:19:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:22 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:22 INFO - 'USER': 'cltbld', 18:19:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:22 INFO - 'XPC_FLAGS': '0x0', 18:19:22 INFO - 'XPC_SERVICE_NAME': '0', 18:19:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-rRHTgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-n42LPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-xUsRr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-ez5sFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Z7qg3s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-EpOQnv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:19:22 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)) 18:19:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:19:22 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-EPWCtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Y3eZcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-YBx_a7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-sgl3qg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-WlyJHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-SDhYWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-o_KlZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-Hj5jcf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-vlDxDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:19:23 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)) 18:19:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:19:23 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-j073pZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:19:24 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)) 18:19:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:19:24 INFO - Running setup.py (path:/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/pip-WFBZP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:19:24 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)) 18:19:24 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)) 18:19:24 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)) 18:19:24 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)) 18:19:24 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)) 18:19:24 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)) 18:19:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:19:24 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 18:19:24 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 18:19:24 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 18:19:24 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 18:19:24 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 18:19:24 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 18:19:26 INFO - Downloading blessings-1.6.tar.gz 18:19:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:19:26 INFO - Installing collected packages: blessings 18:19:26 INFO - Running setup.py install for blessings 18:19:26 INFO - Successfully installed blessings 18:19:26 INFO - Cleaning up... 18:19:26 INFO - Return code: 0 18:19:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:27 INFO - Reading from file tmpfile_stdout 18:19:27 INFO - Current package versions: 18:19:27 INFO - blessings == 1.6 18:19:27 INFO - blobuploader == 1.2.4 18:19:27 INFO - docopt == 0.6.1 18:19:27 INFO - manifestparser == 1.1 18:19:27 INFO - mozInstall == 1.12 18:19:27 INFO - mozcrash == 0.17 18:19:27 INFO - mozdebug == 0.1 18:19:27 INFO - mozdevice == 0.48 18:19:27 INFO - mozfile == 1.2 18:19:27 INFO - mozhttpd == 0.7 18:19:27 INFO - mozinfo == 0.9 18:19:27 INFO - mozleak == 0.1 18:19:27 INFO - mozlog == 3.1 18:19:27 INFO - moznetwork == 0.27 18:19:27 INFO - mozprocess == 0.22 18:19:27 INFO - mozprofile == 0.28 18:19:27 INFO - mozrunner == 6.11 18:19:27 INFO - mozscreenshot == 0.1 18:19:27 INFO - mozsystemmonitor == 0.0 18:19:27 INFO - moztest == 0.7 18:19:27 INFO - mozversion == 1.4 18:19:27 INFO - psutil == 3.1.1 18:19:27 INFO - requests == 1.2.3 18:19:27 INFO - wsgiref == 0.1.2 18:19:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:27 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:27 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 18:19:27 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 18:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:27 INFO - 'HOME': '/Users/cltbld', 18:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:27 INFO - 'LOGNAME': 'cltbld', 18:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:27 INFO - 'MOZ_NO_REMOTE': '1', 18:19:27 INFO - 'NO_EM_RESTART': '1', 18:19:27 INFO - 'PAGER': '/bin/cat', 18:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:27 INFO - 'PWD': '/builds/slave/test', 18:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:27 INFO - 'SHELL': '/bin/bash', 18:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:27 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:27 INFO - 'USER': 'cltbld', 18:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:27 INFO - 'XPC_FLAGS': '0x0', 18:19:27 INFO - 'XPC_SERVICE_NAME': '0', 18:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 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)) 18:19:27 INFO - Cleaning up... 18:19:27 INFO - Return code: 0 18:19:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:27 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 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:27 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 18:19:27 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 18:19:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:27 INFO - 'HOME': '/Users/cltbld', 18:19:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:27 INFO - 'LOGNAME': 'cltbld', 18:19:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:27 INFO - 'MOZ_NO_REMOTE': '1', 18:19:27 INFO - 'NO_EM_RESTART': '1', 18:19:27 INFO - 'PAGER': '/bin/cat', 18:19:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:27 INFO - 'PWD': '/builds/slave/test', 18:19:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:27 INFO - 'SHELL': '/bin/bash', 18:19:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:27 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:27 INFO - 'USER': 'cltbld', 18:19:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:27 INFO - 'XPC_FLAGS': '0x0', 18:19:27 INFO - 'XPC_SERVICE_NAME': '0', 18:19:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:27 INFO - Downloading/unpacking jsonschema 18:19:27 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 18:19:27 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 18:19:27 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 18:19:27 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 18:19:27 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 18:19:27 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 18:19:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:19:30 INFO - Downloading/unpacking functools32 (from jsonschema) 18:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:19:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:19:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:19:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:19:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:19:30 INFO - warning: no files found matching '*.txt' 18:19:30 INFO - no previously-included directories found matching 'build' 18:19:30 INFO - no previously-included directories found matching 'dist' 18:19:30 INFO - no previously-included directories found matching '.git*' 18:19:30 INFO - Installing collected packages: jsonschema, functools32 18:19:30 INFO - Running setup.py install for functools32 18:19:30 INFO - warning: no files found matching '*.txt' 18:19:30 INFO - no previously-included directories found matching 'build' 18:19:30 INFO - no previously-included directories found matching 'dist' 18:19:30 INFO - no previously-included directories found matching '.git*' 18:19:30 INFO - Successfully installed jsonschema functools32 18:19:30 INFO - Cleaning up... 18:19:30 INFO - Return code: 0 18:19:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:19:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:19:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:19:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:19:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a425a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a435420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f907a58e090>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, '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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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 18:19:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:19:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:19:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:19:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:19:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:19:30 INFO - 'HOME': '/Users/cltbld', 18:19:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:19:30 INFO - 'LOGNAME': 'cltbld', 18:19:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:19:30 INFO - 'MOZ_NO_REMOTE': '1', 18:19:30 INFO - 'NO_EM_RESTART': '1', 18:19:30 INFO - 'PAGER': '/bin/cat', 18:19:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:19:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:19:30 INFO - 'PWD': '/builds/slave/test', 18:19:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:19:30 INFO - 'SHELL': '/bin/bash', 18:19:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:19:30 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:19:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:19:30 INFO - 'USER': 'cltbld', 18:19:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:19:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:19:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:19:30 INFO - 'XPC_FLAGS': '0x0', 18:19:30 INFO - 'XPC_SERVICE_NAME': '0', 18:19:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:19:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:19:31 INFO - Downloading/unpacking flake8 18:19:31 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 18:19:31 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 18:19:31 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 18:19:31 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 18:19:31 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 18:19:31 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 18:19:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:19:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:19:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:33 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 18:19:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:19:34 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 18:19:34 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 18:19:34 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 18:19:34 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 18:19:34 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 18:19:34 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 18:19:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:19:34 INFO - no previously-included directories found matching 'docs/_build' 18:19:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:19:34 INFO - Running setup.py install for pep8 18:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:19:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:19:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:19:34 INFO - no previously-included directories found matching 'docs/_build' 18:19:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:19:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:19:35 INFO - Cleaning up... 18:19:35 INFO - Return code: 0 18:19:35 INFO - Running post-action listener: _resource_record_post_action 18:19:35 INFO - Running post-action listener: _start_resource_monitoring 18:19:35 INFO - Starting resource monitoring. 18:19:35 INFO - ##### 18:19:35 INFO - ##### Running install step. 18:19:35 INFO - ##### 18:19:35 INFO - Running pre-action listener: _resource_record_pre_action 18:19:35 INFO - Running main action method: install 18:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:19:35 INFO - Reading from file tmpfile_stdout 18:19:35 INFO - Detecting whether we're running mozinstall >=1.0... 18:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:19:35 INFO - Reading from file tmpfile_stdout 18:19:35 INFO - Output received: 18:19:35 INFO - Usage: mozinstall [options] installer 18:19:35 INFO - Options: 18:19:35 INFO - -h, --help show this help message and exit 18:19:35 INFO - -d DEST, --destination=DEST 18:19:35 INFO - Directory to install application into. [default: 18:19:35 INFO - "/builds/slave/test"] 18:19:35 INFO - --app=APP Application being installed. [default: firefox] 18:19:35 INFO - mkdir: /builds/slave/test/build/application 18:19:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:19:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:20:07 INFO - Reading from file tmpfile_stdout 18:20:07 INFO - Output received: 18:20:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:20:07 INFO - Running post-action listener: _resource_record_post_action 18:20:07 INFO - ##### 18:20:07 INFO - ##### Running run-tests step. 18:20:07 INFO - ##### 18:20:07 INFO - Running pre-action listener: _resource_record_pre_action 18:20:07 INFO - Running main action method: run_tests 18:20:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:20:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:20:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:20:07 INFO - 2016-05-20 18:20:07.413 screenresolution[2052:15383] starting screenresolution argv=screenresolution get 18:20:07 INFO - 2016-05-20 18:20:07.416 screenresolution[2052:15383] Display 0: 1600x1200x32@60 18:20:07 INFO - 2016-05-20 18:20:07.422 screenresolution[2053:15387] starting screenresolution argv=screenresolution list 18:20:07 INFO - Available Modes on Display 0 18:20:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:20:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:20:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:20:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:20:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:20:07 INFO - Intel Iris: 18:20:07 INFO - Chipset Model: Intel Iris 18:20:07 INFO - Type: GPU 18:20:07 INFO - Bus: Built-In 18:20:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:20:07 INFO - Vendor: Intel (0x8086) 18:20:07 INFO - Device ID: 0x0a2e 18:20:07 INFO - Revision ID: 0x0009 18:20:07 INFO - Displays: 18:20:07 INFO - Display: 18:20:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:20:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:20:07 INFO - Display Serial Number: 1600x1200 60 18:20:07 INFO - Main Display: Yes 18:20:07 INFO - Mirror: Off 18:20:07 INFO - Online: Yes 18:20:07 INFO - Rotation: Supported 18:20:07 INFO - Return code: 0 18:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:20:07 INFO - Python 2.7.3 18:20:07 INFO - Return code: 0 18:20:07 INFO - grabbing minidump binary from tooltool 18:20:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:07 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 0x10a2c4e00>, '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 0x10a12ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a2bfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:20:07 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 18:20:07 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 18:20:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:20:07 INFO - Return code: 0 18:20:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:20:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:20:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:20:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:20:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:20:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:20:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:20:08 INFO - Return code: 0 18:20:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 18:20:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0257 --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 18:20:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f4Aeha2cQ6/Render', 18:20:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:08 INFO - 'HOME': '/Users/cltbld', 18:20:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:08 INFO - 'LOGNAME': 'cltbld', 18:20:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:20:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:20:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:08 INFO - 'MOZ_NO_REMOTE': '1', 18:20:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:20:08 INFO - 'NO_EM_RESTART': '1', 18:20:08 INFO - 'PAGER': '/bin/cat', 18:20:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:08 INFO - 'PWD': '/builds/slave/test', 18:20:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:20:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:08 INFO - 'SHELL': '/bin/bash', 18:20:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YK1WhpzPtQ/Listeners', 18:20:08 INFO - 'TMPDIR': '/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/', 18:20:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:08 INFO - 'USER': 'cltbld', 18:20:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:08 INFO - 'XPC_FLAGS': '0x0', 18:20:08 INFO - 'XPC_SERVICE_NAME': '0', 18:20:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0257', '--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 18:20:09 INFO - mozversion application_buildid: 20160520162336 18:20:09 INFO - mozversion application_changeset: 782b4767770b8d7f9ddea913167c49a12087d6af 18:20:09 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:20:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:20:09 INFO - mozversion application_name: Firefox 18:20:09 INFO - mozversion application_remotingname: firefox-dev 18:20:09 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:20:09 INFO - mozversion application_vendor: Mozilla 18:20:09 INFO - mozversion application_version: 48.0a2 18:20:09 INFO - mozversion platform_buildid: 20160520162336 18:20:09 INFO - mozversion platform_changeset: 782b4767770b8d7f9ddea913167c49a12087d6af 18:20:09 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:20:09 INFO - mozversion platform_version: 48.0a2 18:20:09 INFO - using testdate: 1463793609 18:20:09 INFO - actual date: 1463793609 18:20:09 INFO - starting webserver on 'localhost:51389' 18:20:09 INFO - SUITE-START | Running 2 tests 18:20:09 INFO - TEST-START | dromaeo_css 18:20:09 INFO - operating with platform_type : mac_ 18:20:09 INFO - Initialising browser for dromaeo_css test... 18:20:09 INFO - TEST-INFO | started process 2071 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmptPHcdQ/profile http://localhost:51389/getInfo.html) 18:20:11 INFO - PROCESS | 2071 | __metrics Screen width/height:1600/1200 18:20:11 INFO - PROCESS | 2071 | colorDepth:24 18:20:11 INFO - PROCESS | 2071 | Browser inner width/height: 1024/689 18:20:11 INFO - PROCESS | 2071 | __metrics 18:20:11 INFO - PROCESS | 2071 | 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" 18:20:11 ERROR - PROCESS | 2071 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:20:12 INFO - TEST-INFO | 2071: exit 0 18:20:12 INFO - Browser initialized. 18:20:12 INFO - Running cycle 1/1 for dromaeo_css test... 18:20:12 INFO - TEST-INFO | started process 2073 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmptPHcdQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:20:23 INFO - PROCESS | 2073 | RSS: Main: 211001344 18:20:23 INFO - PROCESS | 2073 | 18:21:15 INFO - PROCESS | 2073 | 1463793675498 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] 18:23:26 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51389/tests/dromaeo/cssquery-ext.html) 18:23:27 INFO - PROCESS | 2073 | RSS: Main: 302981120 18:23:27 INFO - PROCESS | 2073 | 18:26:30 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-ext.html (next: http://localhost:51389/tests/dromaeo/cssquery-jquery.html) 18:26:30 INFO - PROCESS | 2073 | RSS: Main: 358920192 18:26:30 INFO - PROCESS | 2073 | 18:29:32 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51389/tests/dromaeo/cssquery-mootools.html) 18:29:33 INFO - PROCESS | 2073 | RSS: Main: 354881536 18:29:33 INFO - PROCESS | 2073 | 18:32:35 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51389/tests/dromaeo/cssquery-prototype.html) 18:32:36 INFO - PROCESS | 2073 | RSS: Main: 360148992 18:32:36 INFO - PROCESS | 2073 | 18:35:39 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51389/tests/dromaeo/cssquery-yui.html) 18:35:39 INFO - PROCESS | 2073 | RSS: Main: 326742016 18:35:39 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | Cycle 1(1): loaded http://localhost:51389/tests/dromaeo/cssquery-yui.html (next: http://localhost:51389/tests/dromaeo/cssquery-dojo.html) 18:38:42 INFO - PROCESS | 2073 | RSS: Main: 366780416 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | __start_tp_report 18:38:42 INFO - PROCESS | 2073 | _x_x_mozilla_page_load 18:38:42 INFO - PROCESS | 2073 | _x_x_mozilla_page_load_details 18:38:42 INFO - PROCESS | 2073 | |i|pagename|runs| 18:38:42 INFO - PROCESS | 2073 | |0;dojo.html;3378.1385746634605;3443.7761545499534;3444.173398384387;3481.3733527964955;3493.895183144505;4670.462896766873;5711.771529138835;5731.283589551306;5731.283589551306;5763.452472015157;5739.913901291484;5756.683382413966;5762.222100016496;5762.3949485303965;5771.567132465071;5136.743162841862;5187.1181899077055;5203.921941170895;5221.190715439094;5298.973505132469;5262.342207224097;5269.683818970874;5278.181881808326;5279.524842764151;5287.391949925769;5197.948020519803;5256.106461901467;5259.8422047338645;5273.709945952971;5273.736313184345;5252.92120618193;5273.630845840793;5287.629865909388;5304.602154838402;5327.094393953037;5085.949140508585;5119.6160287978555;5152.690838549699;5204.557612602934;5223.347081614798;5638.2670252866865;5671.602987790856;5672.659640421547;5682.289713785777;5689.658620482743;6263.498920086382;6270.87458250837;6275.874482510324;6282.026285925689;6286;6078.214075338701;6167.722452489649;6283.8114856554375;6300.905486417708;6309.779157729503;5937.910931336033;5939.46544810969;5961.791337303173;5967.6121052131475;5987.131865879466;6330.0821380899515;6333.968330158412;6345.555811093179;6358.459530939829;6362.45919096882;6093.177421048191;6120.571559990847;6149.446549810538;6199.504039676815;6215.12988181655;6261.467775239064;6275.403836635513;6289.276733175743;6429.54993150475;6471.741130354827;6150.569460137747;6212.875742485125;6227.221597300338;6228.781992630236;6242.906356404657;6130.000698177752;6145.740123274718;6218.844528886814;6244.718987645567;6248.656323902138;4954.727489988085;4988.575971042429;4988.72562009093;4989.975050124799;4996.525330093636;4488.236999710057;4492.899239686232;4504.121696269196;4510.751908645056;4527.750973696412;5670.574706896917;5769.105788602773;5795.304563452412;5851.531877449794;5857.794977175864;4882.609391248691;6245.937540624626;6247.375262473807;6252.781152659635;6272.3100458949475;5803.390643982406;5811;5825.417458254141;5828.6502809831545;5846.532277417797;5291.788328466818;5370.838874833761;5433.945660543423;5442.156465747816;5466.453354664502;6333.398327158912;6362.968185159045;6395.488360931115;6400.935990640034;6407.391297826699;5158.484151584811;6124.777645458507;6157.784477543264;6228.501719862491;6268.623882567061;5425.674459532441;6383.521235907233;6418.069379939976;6427.678616069272;6449.258335291502;6190.288116866438;6194.4115309045565;6206.472449841814;6216.502679785697;6219.782307619212;5171.250168725439;6126.2123341605975;6130.141780150693;6262.906056409249;6269.247690277196;5293.629445938747;5999.460048595647;6106.847328816815;6176.722047507963;6178.042403427476;6138.263408390843;6183.752649893954;6200.844978875564;6207.348228435949;6211.81364559064;5845.766169353178;5995.250593675791;6028.849278768066;6046.969765151146;6063.211782468251;5508.788066625336;6025.337212906489;6083.513318934386;6189.597676151036;6200.472959798287;5965.254343207099;5986.850328741816;6010.188624535806;6010.699465026643;6025.6083354581815 18:38:42 INFO - PROCESS | 2073 | |1;ext.html;514.5523394646657;531.2588938430889;535.5661913849782;588.5997521685255;624.9844003494321;37011.629883701156;38147.046323841845;38199.236015279675;38270.04324891876;38392.84821455354;40865.97835054125;41111.794441027756;41116.38325425121;41356.379654305136;41570;8471.322294216448;8616.569171541429;9461.432314061161;9677.419354838697;9678.983706710764;11436.027937625311;11668.241564298336;11677.065834733243;11678.357690327071;11696.532138714441;11347.092232621371;11417.657470275863;11430.257033292852;11433.14251431146;11468.082553395709;8573.485590864537;8794;8799.648014079428;11305.886941130566;11421.086313095015;11316.20786544934;11449.656510304705;11450.31298122107;11455.59905403307;11456.942715286454;38209.23581528381;38548;38554.03614909622;38573.8427847165;38701.419478707845;3181.236503239229;3276.508523721437;3377.3751855906735;3506.965445193674;3688.723345749053;27135.321616959536;27237.319067023484;27998.860005700044;28001.159965201077;28601;2728.0997270900557;2732.0847516082135;2766.7648249898784;2787.4703806276743;2800.1459554835765;477223.6138819284;478599;478634.6068269671;479464.9999999965;480632;35287.941361759185;58080.70959645175;58812.7059364709;58944.41055589475;59070.70464647649;35893.82053089718;36100.81949590235;36175.27649446997;36238.09404764849;36272.27455450876;12227.755444891052;15466.5360039199;15470.381184752709;15493.675948607799;15503;11686;11870.525178992744;12835.037372196935;12996.090273680751;13011.349432528335;7911.327537159291;7923.564203968722;7928.088269848926;7989.760307190793;8002.51984880909;2905.7236369441903;2945.23423909785;3357.1271469418125;3400.285939952638;3409.533900422842;1000.0299709281985;1007.7984403119406;1018.1447584029451;1049.4886470722436;1108.5067145120506;29228.415431691246;29414;29730.851345743133;29835.806567737047;29964.550531742043;1052.0689190066846;1157.6064138193055;1157.9421028948518;1194.0416726484268;1305.8563558008611;15019.474318398803;15044.473443429428;15091.849081509044;15166.740530123903;15189.62025949338;645337;651212;653696;653744;654112;57367.71316143392;58006;58452.707736461896;58535.78053207114;58640.413595864346;1368.589423173044;1400.509821562445;1541.9845801542065;1545.2891669832004;1616.8787340949475;24021.27936161918;28663;29190.416191675624;29203;29925.55111673283;48046.75976620094;48741.75629121832;48742.75628621834;48770;48800;465301;485706;485982;487567.12432876637;488340;35205.59177632968;35411;41924.790376047924;42174.578254217064;42284.57715422929;42380.57619423766;42482;43826.34260486159;44087.77956110199;44328.7783561093;33210.66789332076;34645.48031779576;35814.28371432609;36041.0989725259;36272.09319767027;29897;35340.64659353373;35719.10702232465;36439.63560364468;37267.44098838574;37122.25755484929;37153;37233.882983510535;37381.439278410304;37747.43378849264;32008.269432421042;32577.837110814293;32893.17767055843;32902.506462402605;33042.504362434105;24769.876150619133;24892.626610600495;24943.501129976936;25033.49933001293;25133.062967341553 18:38:42 INFO - PROCESS | 2073 | |2;jquery.html;400.95589485156626;402.4667315806557;406.14303818942045;406.34375483594005;411.4876978162188;14055.313243457584;16445.83554164461;20524.768513889157;21203.68194477084;21232.787672123275;16467.505974820775;17739.37912173074;20280.087396067174;20913.058912348944;21139;5740.167675687033;6032.336442991267;6038.305594856603;6075.605085669439;6091.604045737013;5972.641641501502;6018.729157187937;6066.423689749467;6072.51419886408;6080.118382834508;5799.681017544033;5949.940500594982;5964.552658550604;5975.671338076404;5984.88030239395;5779.682117483537;5963.61236519627;6026.728797204137;6037.486813620848;6040.1241819936085;5834.329052159013;5844.26946631671;5848.766049358063;5849.239598852165;5856.414358564152;13007.479700812051;13125.015623828156;13361.933190334083;13480.595582132551;13496.527621533298;2100.47488127968;2109.367189843056;2137.2626443876993;2159.1363454618277;2190.9452263693374;7728.420368472387;9521;9626.951865240699;9928.354656947275;10078.748031299276;1433.240382597225;1688.2065429248235;1717.6392957478945;1729.2218501674297;1739.747736578189;101645;117321.41339293249;118615.40692296483;120432;121341;11405.486753096131;11435;11572.537098515964;11628.18602697803;11877;14732.263386830615;14901.180435075958;14912.850871491146;14960.251987400587;14963.653271205643;8631.136886311444;8637.309015278763;8667.91332086671;8759.868601970848;8768.430052046724;8633.481991080555;8693.913060869438;8737.737867863974;8849.070847560914;8850.557472126367;4511.593956543927;4587.243104887723;4607.6083532900075;4617.168909596305;4678.368420263289;1727.3090763694622;1943.8444924406015;1962.6663467210608;1989.682020446164;2004.128204231164;901.4320977783954;1022.2384323678907;1027.1628622672497;1029.562435964727;1043.7286305560608;19035.95302258389;19880.90059549722;19882.1053052613;20012.099455524538;20022.49943751418;981.489625394805;986.3046552180696;992.9950350248203;995.8307087794948;1077.3912739302425;7721.575313357819;10615.363078215332;10708.03627673513;10802.243842931077;10965.725856853642;524527;528783;533249;619474;624721;114496;129968.3501582505;133522;136872;138465.30767346098;1026.8408396698374;1050.4117694091333;1058.2486434631498;1065.9360438373722;1067.3169171730099;17589.20848561792;17661.470155895342;17781.46655600334;18141.727874082164;18331.450056497793;35731.642683573875;47966.52033479621;51589.48410515997;52055.438336849955;54198.91602167856;52280;69564.30435695578;72677.27322726705;73168;74180.62909685417;7176.21061683204;7560.924390756022;7849.5682737447705;7877;7992.440529162903;10458.529366178387;11993.220440671328;12190.146689731988;12311.815322770344;12427.254364738144;9700.320977531504;10439.217058720778;10696.465176740976;10748.785024299626;10958.178136639943;10198.898011020092;10362.585496580052;10539.31000704679;10599.89400105989;10704.250702450754;10963.616273430393;11181.776364472827;11229.4385280734;11369.31784092957;11396.715082123013;7710.653020614198;7975.601219938864;8017.679292828455;8026.357891368793;8393.370497212856;8190.631421585924;10269.794604108025;10538.051575358255;10914.399708016135;10995.505202266077 18:38:42 INFO - PROCESS | 2073 | |3;mootools.html;1386.6602682342827;1594.808622965244;1619.5870053136452;1874.1191639929236;1990.5222746540826;7882.054153501581;8407.117252688471;8576.013758417785;8580.656773729052;8610.784730381727;8458.32333413327;8676.392652514327;8697.739067827959;8705.303575713944;8763.342749293812;2446.217210492643;2469.9135530256444;2473.1591258971944;2475.10896077412;2478.281298423455;2226.4545186429345;2312.7511143982315;2409.8795060246916;2474.5916923707655;2478.107881162789;1915.7988411216832;2429.246933450636;2470.530599186158;2471.0486462711897;2472.7403622619636;2415.9879200603887;2451.1788550835313;2452.9942723483377;2463.8275320727557;2470;2398.256540472459;2423.5152969406013;2431.197704757426;2434.342727463593;2435.6468312094826;8507.191816777397;8766.123387661182;8814.074522175208;8818.823623527558;8829.293656507463;5362.383325917531;5552.305961754781;5594.692291923983;5600.383957764642;5601.747921343549;3008.3832814273023;3041.958041958042;3575.302815950891;3595.46068089786;3633.2370202257293;1051.4795176387738;1056.3397876327294;1086.5110700184948;1630.3723066619318;1663.7088509510788;11449.198556101077;11540.884591154065;11557;11557.768844623144;11584.073274138049;4239.1521695660995;4596.632269418438;4598.471175814757;4598.954010459919;4615.123126606001;9029.729108126767;9055.320850936207;9062.456252624863;9072.092790720875;9107.271418286522;5302.310699609026;5309.070912590281;5314.654547454403;5346;5371.919421208685;4403.3174857897075;4562.817487300471;4753.429588449409;4847.660663753503;4996.350474438373;3774.6602805747616;3831.1954489557506;3880.6119388061534;3902.9804850975565;3941.0541470046983;1078.7378766842655;1179.775842589905;1203.175824560179;1221.407617305606;1226.9631911042504;851.114840565817;852.9849479119828;876.7588913048962;919.9926080941359;927.679950417105;5748.655080695172;5883.264591926009;5974.551908606872;6037;6047.969760151171;397.73749325445067;472.75653038685107;480.9495003024632;1014.8832884218261;1030.9278350515392;3854.325493038707;3906.00396898789;3932.7640341579026;3935.92128157441;3986.142979259473;10210.540525676362;10523.315984460985;10602.734931626772;10644.201684873511;10657.84013239802;10180.745481363025;10548.894511054945;10587.258891877495;10591.94704026475;10680;8063.951686280863;8300.169983001771;8354.540500272422;8443.704470343508;8448.662053517914;5705.115707065412;5753.683547404766;5808.7095645218415;5834.38738932406;5841.386654401226;9499.38254013487;9602.32783705134;9654.806903861743;9780.510974451106;9804.705858824247;9374.203192728422;9942.254330925065;9993.750156246151;10220.386776793417;10229.795404091728;6425.58233714807;6553.574017688835;6837.042814005944;6961.61711105894;7025.964870175617;6963.477739169683;8164.959175204086;8186.304164145876;8254.917450825415;8275;7156.570605763671;7257.854842903218;7272.381847542806;7372.041634587469;7426.405887529093;6973.930260697531;7125.82185445368;7186.6047367395295;7197.1363436387965;7263.927360726537;6792.524523283323;7002.614856182959;7012;7025;7027.121609798775;4843.418789745253;4889.55993960531;4893.461719210813;4897.142999975062;5015.22264049073;4851.684640498356;4892.510748925079;4925.261210818406;4930.309756634002;5161.716105614228 18:38:42 INFO - PROCESS | 2073 | |4;prototype.html;318.76252192116874;322.86116969703033;328.3531443057178;331.420014973796;335.6492465373684;5540.972295138518;6676.031975363576;6709.396154346108;6712.664366781666;6723.73105075797;6665.533412661103;6689.030090636855;6693.99590061491;6694.062831203635;6720.294369091236;4694.929576056354;4762.499937506564;4792.856214313576;4846.442659094214;4864.343313652665;4795.352627395309;4869.4887036861155;4882.584980276663;4901.485344038878;4905.754712264373;4807.254875494286;4826.710397376169;4838.346823178861;4841.951580484203;4845.757712114398;3898.869327894907;4653.790579423934;4787;4791.640626952958;4800.42394912611;4066.979665101685;4161.126163505667;4164.354525048622;4173.853915112987;4174.269502836992;5319.468053194688;6315.652639104847;6488.416042556146;6537.967310163419;6623.602583844985;5878.235829342201;5879.206307148523;5887.234659494238;5890.852728681817;5897.941020589782;5784.047748236562;5786.913196302059;7161.570305781618;7164.641767911636;7169.247229040928;4226.239276930151;4261.4460120184485;4742.834000809989;4747.95252047482;4767.594754445876;7459.664315105833;7513.887291690629;7517.887231691474;7530.774076777706;7532.284432978859;4827.300041493963;4829.275608658659;4831.661783675109;4832.34763306949;4839.225723884161;4890.1931181355385;4903.41159060915;4905.7301848397965;4910.8281210157475;4917.311576379309;5841.328247251536;5842.182094506772;5850.063989761617;5850.970745146332;5869.4717475427415;6904.654767261617;7218.60297683622;7222.494425390278;7223.458240631973;7246.891296630555;1681.1846254566526;1692.1285537947967;1706.428346503935;1707.7694511241073;1709.1283445442666;1087.9401632910267;1088.6353071721062;1106.972325691848;1113.799516087096;1115.4757264085868;6412.4549413299455;6590.2421221560135;6641.074088514277;6666.766663166766;6669.066330713704;5708.31500219968;7503.474756766974;7506.211847756016;7509.770798556564;7513.699452021858;6737.023131645982;6741.056252124707;6741.460683145435;6744.325567443315;6769.796906092825;3897.142628621699;3952.3676211805973;3954.9011274718364;3962.623550762673;3963.5441924178513;7572.924270757222;7714.961425192838;7833.060032795987;7850.646720897574;7853.8821917671285;7655.1962043985695;7694.307512323918;7708.730194443167;7712.6529306181355;7750.302472777477;7077.433805295564;7182.281771822776;7810.6875724971505;7826.334761545332;7838.960805195938;7454.291842274975;7460.7015719372785;7467;7470.439717021354;7477.775666730006;7071.116110486189;7346.236903143314;7360.705571777069;7373.63131843395;7383.593902335423;7736.071671399468;7792.37660987131;7810.882836757567;7812.3750099993;7823.921760782547;4804.183288840835;4818.469968303554;4818.542238487338;4820.276958456259;4821.46963833985;7422.072240969879;7424.925750742424;7438.5536867788105;7446.813829654086;7447.143578488326;6872.484563657846;7175.569465832067;7180.892286615809;7181.892271616034;7197;7118.537295075804;7164.462665300019;7169.784906452606;7171;7183;6193.163922870355;6550.017497375432;7017.649117544204;7022.332878376546;7024.8595028098125;6911.688973996082;7180.210176880434;7180.461465390221;7198.064251647253;7199.1001124859395;6815.182178138655;6977.092977912839;6995.300469952964;7011.859762804614;7015 18:38:42 INFO - PROCESS | 2073 | |5;yui.html;681.543365944817;688.0779755128127;738.7672883041843;742.5878637356265;767.8848172774083;14841.777373339395;14995.325210365532;15038.624034399145;15066.39734410625;15078.849211507883;12293.262404255729;13449.327533623304;13540.984426168028;13544.322783860816;13548.45806167752;708.7731925783751;891.6255172827427;910.1308250620641;912.1699253679153;913.5067063785581;894.9776255593597;901.5086777706166;906.9138431848996;907.9046700096494;909.6907051602453;767.2864236260276;962.6101428921298;969.0261287406147;970.1171933540479;970.7864269860619;773.9729109481141;779.2091027606983;780.7150390107627;940.1773448232797;966.0966995858895;713.2974020589714;714.3285311806885;715.8067321823079;716.1155972374114;716.827961289292;14646.560603181922;14712.852871471363;14716.926415367961;14793.630159245997;14835.703285934329;12354.320512371816;12408.689782755504;12562.623121306375;12699.492020319176;12755.489780408865;9858.359206651616;10148.543315550744;10192.235582331356;10432.947835260851;10435.478226088666;3459.5848498180126;3504.877329293488;3507.9473807892646;3558.839852206657;3762.1159027628487;81659;81662;82819;83521.58239208766;83850.16149838545;9230.030846760994;9534.904650953402;9622.422654640744;9759.707208783748;9881.011898810206;21276.82977436225;21468.355949321547;21500.569988600455;21541.461463463227;21582;7347.63261836906;7348.926510734931;7404.148522919825;7437.070366204224;7612.352949999312;4029.597040295947;4086.6322031017353;4125.855595054159;4190.161967606491;4192.413062171298;3529.735269854771;3559.9644003559633;3583.928321433557;3594.4428613564937;3595.94606080909;575.3958343739057;672.67711498481;683.3508167241141;696.5994553131868;706.6749295324106;497.5994324568757;519.8050730975884;612.8467883029243;618.2056057965493;623.6040114527308;7948.960255198687;8191.450055421827;8265.95867020661;8316.334693224528;8351.1648835116;506.33417056570494;516.3158814570634;536.2224774077603;591.1398914579363;610.1488423648984;3766.60450652683;3862.3240932836643;3867.3232184368185;3901.8634347797692;3929.4891664084057;89849;90734.5559266446;91555;91641.62537561942;91967.1606567865;73877;74252;74465.6276718613;76816.61591692005;78203.60898195472;65909.67045164744;66131;68122;68182.65908670524;68773.65613172002;7490.475666703279;7809.258120478546;7826.804329891571;7899.68401263943;7909.881351779843;46703.06593868036;47438.52561474341;47814;48111.51888481071;48227;61621.075683865674;63835;66084;66152;67466;9872.160866326443;10119.949400252952;10123.544440500342;10257.897421025993;10290.176785857264;10676.626318079141;11225.045871101049;11359.977602015859;11375.374354410587;11457.713557161138;10389;10529.999650033235;10544.57821687124;10636.680899573024;10701.983311585387;10409;10499.527521261714;10614.469276536296;10627.149828013586;10709.571617135227;9801.215902727907;10119.139873110867;10339.896601034194;10435.739106522095;10628.8937110631;8679.956600216958;8822.470651760916;8847.557622118997;8868.866966995629;9018.143276388733;8627.396082274181;8828.382013259266;8853.247473964786;8881.911180888366;8927.866082008904 18:38:42 INFO - PROCESS | 2073 | __end_tp_report 18:38:42 INFO - PROCESS | 2073 | __start_cc_report 18:38:42 INFO - PROCESS | 2073 | _x_x_mozilla_cycle_collect,1185 18:38:42 INFO - PROCESS | 2073 | __end_cc_report 18:38:42 INFO - PROCESS | 2073 | __startTimestamp1463794722859__endTimestamp 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | ------- Summary: start ------- 18:38:42 INFO - PROCESS | 2073 | Number of tests: 6 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#0] dojo.html Cycles:165 Average:5748.70 Median:5986.85 stddev:639.53 (10.7%) stddev-sans-first:613.86 18:38:42 INFO - PROCESS | 2073 | Values: 3378.1 3443.8 3444.2 3481.4 3493.9 4670.5 5711.8 5731.3 5731.3 5763.5 5739.9 5756.7 5762.2 5762.4 5771.6 5136.7 5187.1 5203.9 5221.2 5299.0 5262.3 5269.7 5278.2 5279.5 5287.4 5197.9 5256.1 5259.8 5273.7 5273.7 5252.9 5273.6 5287.6 5304.6 5327.1 5085.9 5119.6 5152.7 5204.6 5223.3 5638.3 5671.6 5672.7 5682.3 5689.7 6263.5 6270.9 6275.9 6282.0 6286.0 6078.2 6167.7 6283.8 6300.9 6309.8 5937.9 5939.5 5961.8 5967.6 5987.1 6330.1 6334.0 6345.6 6358.5 6362.5 6093.2 6120.6 6149.4 6199.5 6215.1 6261.5 6275.4 6289.3 6429.5 6471.7 6150.6 6212.9 6227.2 6228.8 6242.9 6130.0 6145.7 6218.8 6244.7 6248.7 4954.7 4988.6 4988.7 4990.0 4996.5 4488.2 4492.9 4504.1 4510.8 4527.8 5670.6 5769.1 5795.3 5851.5 5857.8 4882.6 6245.9 6247.4 6252.8 6272.3 5803.4 5811.0 5825.4 5828.7 5846.5 5291.8 5370.8 5433.9 5442.2 5466.5 6333.4 6363.0 6395.5 6400.9 6407.4 5158.5 6124.8 6157.8 6228.5 6268.6 5425.7 6383.5 6418.1 6427.7 6449.3 6190.3 6194.4 6206.5 6216.5 6219.8 5171.3 6126.2 6130.1 6262.9 6269.2 5293.6 5999.5 6106.8 6176.7 6178.0 6138.3 6183.8 6200.8 6207.3 6211.8 5845.8 5995.3 6028.8 6047.0 6063.2 5508.8 6025.3 6083.5 6189.6 6200.5 5965.3 5986.9 6010.2 6010.7 6025.6 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#1] ext.html Cycles:180 Average:66078.28 Median:35314.29 stddev:145430.64 (411.8%) stddev-sans-first:145755.30 18:38:42 INFO - PROCESS | 2073 | Values: 514.6 531.3 535.6 588.6 625.0 37011.6 38147.0 38199.2 38270.0 38392.8 40866.0 41111.8 41116.4 41356.4 41570.0 8471.3 8616.6 9461.4 9677.4 9679.0 11436.0 11668.2 11677.1 11678.4 11696.5 11347.1 11417.7 11430.3 11433.1 11468.1 8573.5 8794.0 8799.6 11305.9 11421.1 11316.2 11449.7 11450.3 11455.6 11456.9 38209.2 38548.0 38554.0 38573.8 38701.4 3181.2 3276.5 3377.4 3507.0 3688.7 27135.3 27237.3 27998.9 28001.2 28601.0 2728.1 2732.1 2766.8 2787.5 2800.1 477223.6 478599.0 478634.6 479465.0 480632.0 35287.9 58080.7 58812.7 58944.4 59070.7 35893.8 36100.8 36175.3 36238.1 36272.3 12227.8 15466.5 15470.4 15493.7 15503.0 11686.0 11870.5 12835.0 12996.1 13011.3 7911.3 7923.6 7928.1 7989.8 8002.5 2905.7 2945.2 3357.1 3400.3 3409.5 1000.0 1007.8 1018.1 1049.5 1108.5 29228.4 29414.0 29730.9 29835.8 29964.6 1052.1 1157.6 1157.9 1194.0 1305.9 15019.5 15044.5 15091.8 15166.7 15189.6 645337.0 651212.0 653696.0 653744.0 654112.0 57367.7 58006.0 58452.7 58535.8 58640.4 1368.6 1400.5 1542.0 1545.3 1616.9 24021.3 28663.0 29190.4 29203.0 29925.6 48046.8 48741.8 48742.8 48770.0 48800.0 465301.0 485706.0 485982.0 487567.1 488340.0 35205.6 35411.0 41924.8 42174.6 42284.6 42380.6 42482.0 43826.3 44087.8 44328.8 33210.7 34645.5 35814.3 36041.1 36272.1 29897.0 35340.6 35719.1 36439.6 37267.4 37122.3 37153.0 37233.9 37381.4 37747.4 32008.3 32577.8 32893.2 32902.5 33042.5 24769.9 24892.6 24943.5 25033.5 25133.1 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#2] jquery.html Cycles:180 Average:33204.79 Median:2104.92 stddev:95122.76 (4519.1%) stddev-sans-first:95357.72 18:38:42 INFO - PROCESS | 2073 | Values: 401.0 402.5 406.1 406.3 411.5 14055.3 16445.8 20524.8 21203.7 21232.8 16467.5 17739.4 20280.1 20913.1 21139.0 5740.2 6032.3 6038.3 6075.6 6091.6 5972.6 6018.7 6066.4 6072.5 6080.1 5799.7 5949.9 5964.6 5975.7 5984.9 5779.7 5963.6 6026.7 6037.5 6040.1 5834.3 5844.3 5848.8 5849.2 5856.4 13007.5 13125.0 13361.9 13480.6 13496.5 2100.5 2109.4 2137.3 2159.1 2190.9 7728.4 9521.0 9627.0 9928.4 10078.7 1433.2 1688.2 1717.6 1729.2 1739.7 101645.0 117321.4 118615.4 120432.0 121341.0 11405.5 11435.0 11572.5 11628.2 11877.0 14732.3 14901.2 14912.9 14960.3 14963.7 8631.1 8637.3 8667.9 8759.9 8768.4 8633.5 8693.9 8737.7 8849.1 8850.6 4511.6 4587.2 4607.6 4617.2 4678.4 1727.3 1943.8 1962.7 1989.7 2004.1 901.4 1022.2 1027.2 1029.6 1043.7 19036.0 19880.9 19882.1 20012.1 20022.5 981.5 986.3 993.0 995.8 1077.4 7721.6 10615.4 10708.0 10802.2 10965.7 524527.0 528783.0 533249.0 619474.0 624721.0 114496.0 129968.4 133522.0 136872.0 138465.3 1026.8 1050.4 1058.2 1065.9 1067.3 17589.2 17661.5 17781.5 18141.7 18331.5 35731.6 47966.5 51589.5 52055.4 54198.9 52280.0 69564.3 72677.3 73168.0 74180.6 7176.2 7560.9 7849.6 7877.0 7992.4 10458.5 11993.2 12190.1 12311.8 12427.3 9700.3 10439.2 10696.5 10748.8 10958.2 10198.9 10362.6 10539.3 10599.9 10704.3 10963.6 11181.8 11229.4 11369.3 11396.7 7710.7 7975.6 8017.7 8026.4 8393.4 8190.6 10269.8 10538.1 10914.4 10995.5 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#3] mootools.html Cycles:180 Average:5617.57 Median:4895.30 stddev:3105.69 (63.4%) stddev-sans-first:3098.12 18:38:42 INFO - PROCESS | 2073 | Values: 1386.7 1594.8 1619.6 1874.1 1990.5 7882.1 8407.1 8576.0 8580.7 8610.8 8458.3 8676.4 8697.7 8705.3 8763.3 2446.2 2469.9 2473.2 2475.1 2478.3 2226.5 2312.8 2409.9 2474.6 2478.1 1915.8 2429.2 2470.5 2471.0 2472.7 2416.0 2451.2 2453.0 2463.8 2470.0 2398.3 2423.5 2431.2 2434.3 2435.6 8507.2 8766.1 8814.1 8818.8 8829.3 5362.4 5552.3 5594.7 5600.4 5601.7 3008.4 3042.0 3575.3 3595.5 3633.2 1051.5 1056.3 1086.5 1630.4 1663.7 11449.2 11540.9 11557.0 11557.8 11584.1 4239.2 4596.6 4598.5 4599.0 4615.1 9029.7 9055.3 9062.5 9072.1 9107.3 5302.3 5309.1 5314.7 5346.0 5371.9 4403.3 4562.8 4753.4 4847.7 4996.4 3774.7 3831.2 3880.6 3903.0 3941.1 1078.7 1179.8 1203.2 1221.4 1227.0 851.1 853.0 876.8 920.0 927.7 5748.7 5883.3 5974.6 6037.0 6048.0 397.7 472.8 480.9 1014.9 1030.9 3854.3 3906.0 3932.8 3935.9 3986.1 10210.5 10523.3 10602.7 10644.2 10657.8 10180.7 10548.9 10587.3 10591.9 10680.0 8064.0 8300.2 8354.5 8443.7 8448.7 5705.1 5753.7 5808.7 5834.4 5841.4 9499.4 9602.3 9654.8 9780.5 9804.7 9374.2 9942.3 9993.8 10220.4 10229.8 6425.6 6553.6 6837.0 6961.6 7026.0 6963.5 8165.0 8186.3 8254.9 8275.0 7156.6 7257.9 7272.4 7372.0 7426.4 6973.9 7125.8 7186.6 7197.1 7263.9 6792.5 7002.6 7012.0 7025.0 7027.1 4843.4 4889.6 4893.5 4897.1 5015.2 4851.7 4892.5 4925.3 4930.3 5161.7 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#4] prototype.html Cycles:180 Average:5847.45 Median:6667.92 stddev:1872.85 (28.1%) stddev-sans-first:1831.55 18:38:42 INFO - PROCESS | 2073 | Values: 318.8 322.9 328.4 331.4 335.6 5541.0 6676.0 6709.4 6712.7 6723.7 6665.5 6689.0 6694.0 6694.1 6720.3 4694.9 4762.5 4792.9 4846.4 4864.3 4795.4 4869.5 4882.6 4901.5 4905.8 4807.3 4826.7 4838.3 4842.0 4845.8 3898.9 4653.8 4787.0 4791.6 4800.4 4067.0 4161.1 4164.4 4173.9 4174.3 5319.5 6315.7 6488.4 6538.0 6623.6 5878.2 5879.2 5887.2 5890.9 5897.9 5784.0 5786.9 7161.6 7164.6 7169.2 4226.2 4261.4 4742.8 4748.0 4767.6 7459.7 7513.9 7517.9 7530.8 7532.3 4827.3 4829.3 4831.7 4832.3 4839.2 4890.2 4903.4 4905.7 4910.8 4917.3 5841.3 5842.2 5850.1 5851.0 5869.5 6904.7 7218.6 7222.5 7223.5 7246.9 1681.2 1692.1 1706.4 1707.8 1709.1 1087.9 1088.6 1107.0 1113.8 1115.5 6412.5 6590.2 6641.1 6666.8 6669.1 5708.3 7503.5 7506.2 7509.8 7513.7 6737.0 6741.1 6741.5 6744.3 6769.8 3897.1 3952.4 3954.9 3962.6 3963.5 7572.9 7715.0 7833.1 7850.6 7853.9 7655.2 7694.3 7708.7 7712.7 7750.3 7077.4 7182.3 7810.7 7826.3 7839.0 7454.3 7460.7 7467.0 7470.4 7477.8 7071.1 7346.2 7360.7 7373.6 7383.6 7736.1 7792.4 7810.9 7812.4 7823.9 4804.2 4818.5 4818.5 4820.3 4821.5 7422.1 7424.9 7438.6 7446.8 7447.1 6872.5 7175.6 7180.9 7181.9 7197.0 7118.5 7164.5 7169.8 7171.0 7183.0 6193.2 6550.0 7017.6 7022.3 7024.9 6911.7 7180.2 7180.5 7198.1 7199.1 6815.2 6977.1 6995.3 7011.9 7015.0 18:38:42 INFO - PROCESS | 2073 | 18:38:42 INFO - PROCESS | 2073 | [#5] yui.html Cycles:180 Average:17836.47 Median:65996.84 stddev:25263.59 (38.3%) stddev-sans-first:25301.63 18:38:42 INFO - PROCESS | 2073 | Values: 681.5 688.1 738.8 742.6 767.9 14841.8 14995.3 15038.6 15066.4 15078.8 12293.3 13449.3 13541.0 13544.3 13548.5 708.8 891.6 910.1 912.2 913.5 895.0 901.5 906.9 907.9 909.7 767.3 962.6 969.0 970.1 970.8 774.0 779.2 780.7 940.2 966.1 713.3 714.3 715.8 716.1 716.8 14646.6 14712.9 14716.9 14793.6 14835.7 12354.3 12408.7 12562.6 12699.5 12755.5 9858.4 10148.5 10192.2 10432.9 10435.5 3459.6 3504.9 3507.9 3558.8 3762.1 81659.0 81662.0 82819.0 83521.6 83850.2 9230.0 9534.9 9622.4 9759.7 9881.0 21276.8 21468.4 21500.6 21541.5 21582.0 7347.6 7348.9 7404.1 7437.1 7612.4 4029.6 4086.6 4125.9 4190.2 4192.4 3529.7 3560.0 3583.9 3594.4 3595.9 575.4 672.7 683.4 696.6 706.7 497.6 519.8 612.8 618.2 623.6 7949.0 8191.5 8266.0 8316.3 8351.2 506.3 516.3 536.2 591.1 610.1 3766.6 3862.3 3867.3 3901.9 3929.5 89849.0 90734.6 91555.0 91641.6 91967.2 73877.0 74252.0 74465.6 76816.6 78203.6 65909.7 66131.0 68122.0 68182.7 68773.7 7490.5 7809.3 7826.8 7899.7 7909.9 46703.1 47438.5 47814.0 48111.5 48227.0 61621.1 63835.0 66084.0 66152.0 67466.0 9872.2 10119.9 10123.5 10257.9 10290.2 10676.6 11225.0 11360.0 11375.4 11457.7 10389.0 10530.0 10544.6 10636.7 10702.0 10409.0 10499.5 10614.5 10627.1 10709.6 9801.2 10119.1 10339.9 10435.7 10628.9 8680.0 8822.5 8847.6 8868.9 9018.1 8627.4 8828.4 8853.2 8881.9 8927.9 18:38:42 INFO - PROCESS | 2073 | -------- Summary: end -------- 18:38:42 INFO - PROCESS | 2073 | 18:38:43 INFO - TEST-INFO | 2073: exit 0 18:38:43 INFO - TEST-OK | dromaeo_css | took 1114365ms 18:38:43 INFO - TEST-START | kraken 18:38:43 INFO - operating with platform_type : mac_ 18:38:43 INFO - Initialising browser for kraken test... 18:38:43 INFO - TEST-INFO | started process 2077 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpFMJbDs/profile http://localhost:51389/getInfo.html) 18:38:45 INFO - PROCESS | 2077 | __metrics Screen width/height:1600/1200 18:38:45 INFO - PROCESS | 2077 | colorDepth:24 18:38:45 INFO - PROCESS | 2077 | Browser inner width/height: 1024/689 18:38:45 INFO - PROCESS | 2077 | __metrics 18:38:45 INFO - PROCESS | 2077 | 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" 18:38:45 ERROR - PROCESS | 2077 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:38:46 INFO - TEST-INFO | 2077: exit 0 18:38:46 INFO - Browser initialized. 18:38:46 INFO - Running cycle 1/1 for kraken test... 18:38:46 INFO - TEST-INFO | started process 2079 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/tmpFMJbDs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:38:58 INFO - PROCESS | 2079 | RSS: Main: 211382272 18:38:58 INFO - PROCESS | 2079 | 18:39:01 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51389/tests/kraken/driver.html?testName=audio-beat-detection) 18:39:01 INFO - PROCESS | 2079 | RSS: Main: 271470592 18:39:01 INFO - PROCESS | 2079 | 18:39:04 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51389/tests/kraken/driver.html?testName=audio-dft) 18:39:05 INFO - PROCESS | 2079 | RSS: Main: 285995008 18:39:05 INFO - PROCESS | 2079 | 18:39:08 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51389/tests/kraken/driver.html?testName=audio-fft) 18:39:09 INFO - PROCESS | 2079 | RSS: Main: 374071296 18:39:09 INFO - PROCESS | 2079 | 18:39:11 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51389/tests/kraken/driver.html?testName=audio-oscillator) 18:39:11 INFO - PROCESS | 2079 | RSS: Main: 278495232 18:39:11 INFO - PROCESS | 2079 | 18:39:14 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51389/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:39:14 INFO - PROCESS | 2079 | RSS: Main: 280289280 18:39:14 INFO - PROCESS | 2079 | 18:39:17 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51389/tests/kraken/driver.html?testName=imaging-darkroom) 18:39:18 INFO - PROCESS | 2079 | RSS: Main: 319270912 18:39:18 INFO - PROCESS | 2079 | 18:39:21 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51389/tests/kraken/driver.html?testName=imaging-desaturate) 18:39:21 INFO - PROCESS | 2079 | RSS: Main: 319217664 18:39:21 INFO - PROCESS | 2079 | 18:39:24 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51389/tests/kraken/driver.html?testName=json-parse-financial) 18:39:25 INFO - PROCESS | 2079 | RSS: Main: 317444096 18:39:25 INFO - PROCESS | 2079 | 18:39:26 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51389/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:39:27 INFO - PROCESS | 2079 | RSS: Main: 309469184 18:39:27 INFO - PROCESS | 2079 | 18:39:29 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:39:29 INFO - PROCESS | 2079 | RSS: Main: 315994112 18:39:29 INFO - PROCESS | 2079 | 18:39:31 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:39:32 INFO - PROCESS | 2079 | RSS: Main: 319053824 18:39:32 INFO - PROCESS | 2079 | 18:39:35 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:39:35 INFO - PROCESS | 2079 | RSS: Main: 324116480 18:39:35 INFO - PROCESS | 2079 | 18:39:38 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:39:39 INFO - PROCESS | 2079 | RSS: Main: 322187264 18:39:39 INFO - PROCESS | 2079 | 18:39:40 INFO - PROCESS | 2079 | Cycle 1(1): loaded http://localhost:51389/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51389/tests/kraken/driver.html?testName=ai-astar) 18:39:41 INFO - PROCESS | 2079 | RSS: Main: 386887680 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | __start_tp_report 18:39:41 INFO - PROCESS | 2079 | _x_x_mozilla_page_load 18:39:41 INFO - PROCESS | 2079 | _x_x_mozilla_page_load_details 18:39:41 INFO - PROCESS | 2079 | |i|pagename|runs| 18:39:41 INFO - PROCESS | 2079 | |0;ai-astar;112;114;120;113;113;113;114;115;113;134 18:39:41 INFO - PROCESS | 2079 | |1;audio-beat-detection;154;122;146;166;121;142;163;102;202;147 18:39:41 INFO - PROCESS | 2079 | |2;audio-dft;187;189;184;186;186;186;186;218;261;189 18:39:41 INFO - PROCESS | 2079 | |3;audio-fft;73;77;74;152;57;72;83;96;154;56 18:39:41 INFO - PROCESS | 2079 | |4;audio-oscillator;90;88;141;77;80;89;157;118;107;136 18:39:41 INFO - PROCESS | 2079 | |5;imaging-gaussian-blur;88;87;88;85;88;85;89;85;89;84 18:39:41 INFO - PROCESS | 2079 | |6;imaging-darkroom;94;95;95;95;95;94;95;95;93;95 18:39:41 INFO - PROCESS | 2079 | |7;imaging-desaturate;88;83;86;80;86;84;85;85;85;85 18:39:41 INFO - PROCESS | 2079 | |8;json-parse-financial;59;64;61;64;64;63;63;83;59;58 18:39:41 INFO - PROCESS | 2079 | |9;json-stringify-tinderbox;63;65;69;79;75;74;69;68;69;70 18:39:41 INFO - PROCESS | 2079 | |10;stanford-crypto-aes;82;81;81;80;79;80;81;80;82;83 18:39:41 INFO - PROCESS | 2079 | |11;stanford-crypto-ccm;128;129;130;128;126;128;128;128;146;131 18:39:41 INFO - PROCESS | 2079 | |12;stanford-crypto-pbkdf2;163;164;167;166;167;165;165;162;161;157 18:39:41 INFO - PROCESS | 2079 | |13;stanford-crypto-sha256-iterative;67;68;67;67;66;69;68;69;66;66 18:39:41 INFO - PROCESS | 2079 | __end_tp_report 18:39:41 INFO - PROCESS | 2079 | __start_cc_report 18:39:41 INFO - PROCESS | 2079 | _x_x_mozilla_cycle_collect,1989 18:39:41 INFO - PROCESS | 2079 | __end_cc_report 18:39:41 INFO - PROCESS | 2079 | __startTimestamp1463794781133__endTimestamp 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | ------- Summary: start ------- 18:39:41 INFO - PROCESS | 2079 | Number of tests: 14 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#0] ai-astar Cycles:10 Average:116.10 Median:114.00 stddev:6.67 (5.9%) stddev-sans-first:6.91 18:39:41 INFO - PROCESS | 2079 | Values: 112.0 114.0 120.0 113.0 113.0 113.0 114.0 115.0 113.0 134.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#1] audio-beat-detection Cycles:10 Average:146.50 Median:150.50 stddev:27.97 (18.6%) stddev-sans-first:29.53 18:39:41 INFO - PROCESS | 2079 | Values: 154.0 122.0 146.0 166.0 121.0 142.0 163.0 102.0 202.0 147.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#2] audio-dft Cycles:10 Average:197.20 Median:188.00 stddev:24.53 (13.1%) stddev-sans-first:25.74 18:39:41 INFO - PROCESS | 2079 | Values: 187.0 189.0 184.0 186.0 186.0 186.0 186.0 218.0 261.0 189.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#3] audio-fft Cycles:10 Average:89.40 Median:73.50 stddev:35.44 (48.2%) stddev-sans-first:37.09 18:39:41 INFO - PROCESS | 2079 | Values: 73.0 77.0 74.0 152.0 57.0 72.0 83.0 96.0 154.0 56.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#4] audio-oscillator Cycles:10 Average:108.30 Median:78.50 stddev:28.29 (36.0%) stddev-sans-first:29.22 18:39:41 INFO - PROCESS | 2079 | Values: 90.0 88.0 141.0 77.0 80.0 89.0 157.0 118.0 107.0 136.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:88.00 stddev:1.87 (2.1%) stddev-sans-first:1.94 18:39:41 INFO - PROCESS | 2079 | Values: 88.0 87.0 88.0 85.0 88.0 85.0 89.0 85.0 89.0 84.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 18:39:41 INFO - PROCESS | 2079 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 95.0 93.0 95.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:2.11 (2.5%) stddev-sans-first:1.87 18:39:41 INFO - PROCESS | 2079 | Values: 88.0 83.0 86.0 80.0 86.0 84.0 85.0 85.0 85.0 85.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:63.50 stddev:7.13 (11.2%) stddev-sans-first:7.35 18:39:41 INFO - PROCESS | 2079 | Values: 59.0 64.0 61.0 64.0 64.0 63.0 63.0 83.0 59.0 58.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:69.50 stddev:4.75 (6.8%) stddev-sans-first:4.28 18:39:41 INFO - PROCESS | 2079 | Values: 63.0 65.0 69.0 79.0 75.0 74.0 69.0 68.0 69.0 70.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.20 (1.5%) stddev-sans-first:1.20 18:39:41 INFO - PROCESS | 2079 | Values: 82.0 81.0 81.0 80.0 79.0 80.0 81.0 80.0 82.0 83.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#11] stanford-crypto-ccm Cycles:10 Average:130.20 Median:128.50 stddev:5.71 (4.4%) stddev-sans-first:6.00 18:39:41 INFO - PROCESS | 2079 | Values: 128.0 129.0 130.0 128.0 126.0 128.0 128.0 128.0 146.0 131.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:165.00 stddev:3.09 (1.9%) stddev-sans-first:3.27 18:39:41 INFO - PROCESS | 2079 | Values: 163.0 164.0 167.0 166.0 167.0 165.0 165.0 162.0 161.0 157.0 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - PROCESS | 2079 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:1.16 (1.7%) stddev-sans-first:1.22 18:39:41 INFO - PROCESS | 2079 | Values: 67.0 68.0 67.0 67.0 66.0 69.0 68.0 69.0 66.0 66.0 18:39:41 INFO - PROCESS | 2079 | -------- Summary: end -------- 18:39:41 INFO - PROCESS | 2079 | 18:39:41 INFO - TEST-INFO | 2079: exit 0 18:39:41 INFO - TEST-OK | kraken | took 58484ms 18:39:41 INFO - SUITE-END | took 1172s 18:39:42 INFO - Completed test suite (00:19:33) 18:39:42 INFO - javascript benchmark 18:39:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3378.1385746634605, 3443.7761545499534, 3444.173398384387, 3481.3733527964955, 3493.895183144505, 4670.462896766873, 5711.771529138835, 5731.283589551306, 5731.283589551306, 5763.452472015157, 5739.913901291484, 5756.683382413966, 5762.222100016496, 5762.3949485303965, 5771.567132465071, 5136.743162841862, 5187.1181899077055, 5203.921941170895, 5221.190715439094, 5298.973505132469, 5262.342207224097, 5269.683818970874, 5278.181881808326, 5279.524842764151, 5287.391949925769, 5197.948020519803, 5256.106461901467, 5259.8422047338645, 5273.709945952971, 5273.736313184345, 5252.92120618193, 5273.630845840793, 5287.629865909388, 5304.602154838402, 5327.094393953037, 5085.949140508585, 5119.6160287978555, 5152.690838549699, 5204.557612602934, 5223.347081614798, 5638.2670252866865, 5671.602987790856, 5672.659640421547, 5682.289713785777, 5689.658620482743, 6263.498920086382, 6270.87458250837, 6275.874482510324, 6282.026285925689, 6286.0, 6078.214075338701, 6167.722452489649, 6283.8114856554375, 6300.905486417708, 6309.779157729503, 5937.910931336033, 5939.46544810969, 5961.791337303173, 5967.6121052131475, 5987.131865879466, 6330.0821380899515, 6333.968330158412, 6345.555811093179, 6358.459530939829, 6362.45919096882, 6093.177421048191, 6120.571559990847, 6149.446549810538, 6199.504039676815, 6215.12988181655, 6261.467775239064, 6275.403836635513, 6289.276733175743, 6429.54993150475, 6471.741130354827, 6150.569460137747, 6212.875742485125, 6227.221597300338, 6228.781992630236, 6242.906356404657, 6130.000698177752, 6145.740123274718, 6218.844528886814, 6244.718987645567, 6248.656323902138, 4954.727489988085, 4988.575971042429, 4988.72562009093, 4989.975050124799, 4996.525330093636, 4488.236999710057, 4492.899239686232, 4504.121696269196, 4510.751908645056, 4527.750973696412, 5670.574706896917, 5769.105788602773, 5795.304563452412, 5851.531877449794, 5857.794977175864, 4882.609391248691, 6245.937540624626, 6247.375262473807, 6252.781152659635, 6272.3100458949475, 5803.390643982406, 5811.0, 5825.417458254141, 5828.6502809831545, 5846.532277417797, 5291.788328466818, 5370.838874833761, 5433.945660543423, 5442.156465747816, 5466.453354664502, 6333.398327158912, 6362.968185159045, 6395.488360931115, 6400.935990640034, 6407.391297826699, 5158.484151584811, 6124.777645458507, 6157.784477543264, 6228.501719862491, 6268.623882567061, 5425.674459532441, 6383.521235907233, 6418.069379939976, 6427.678616069272, 6449.258335291502, 6190.288116866438, 6194.4115309045565, 6206.472449841814, 6216.502679785697, 6219.782307619212, 5171.250168725439, 6126.2123341605975, 6130.141780150693, 6262.906056409249, 6269.247690277196, 5293.629445938747, 5999.460048595647, 6106.847328816815, 6176.722047507963, 6178.042403427476, 6138.263408390843, 6183.752649893954, 6200.844978875564, 6207.348228435949, 6211.81364559064, 5845.766169353178, 5995.250593675791, 6028.849278768066, 6046.969765151146, 6063.211782468251, 5508.788066625336, 6025.337212906489, 6083.513318934386, 6189.597676151036, 6200.472959798287, 5965.254343207099, 5986.850328741816, 6010.188624535806, 6010.699465026643, 6025.6083354581815], "value": 5710.647200784119, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.5523394646657, 531.2588938430889, 535.5661913849782, 588.5997521685255, 624.9844003494321, 37011.629883701156, 38147.046323841845, 38199.236015279675, 38270.04324891876, 38392.84821455354, 40865.97835054125, 41111.794441027756, 41116.38325425121, 41356.379654305136, 41570.0, 8471.322294216448, 8616.569171541429, 9461.432314061161, 9677.419354838697, 9678.983706710764, 11436.027937625311, 11668.241564298336, 11677.065834733243, 11678.357690327071, 11696.532138714441, 11347.092232621371, 11417.657470275863, 11430.257033292852, 11433.14251431146, 11468.082553395709, 8573.485590864537, 8794.0, 8799.648014079428, 11305.886941130566, 11421.086313095015, 11316.20786544934, 11449.656510304705, 11450.31298122107, 11455.59905403307, 11456.942715286454, 38209.23581528381, 38548.0, 38554.03614909622, 38573.8427847165, 38701.419478707845, 3181.236503239229, 3276.508523721437, 3377.3751855906735, 3506.965445193674, 3688.723345749053, 27135.321616959536, 27237.319067023484, 27998.860005700044, 28001.159965201077, 28601.0, 2728.0997270900557, 2732.0847516082135, 2766.7648249898784, 2787.4703806276743, 2800.1459554835765, 477223.6138819284, 478599.0, 478634.6068269671, 479464.9999999965, 480632.0, 35287.941361759185, 58080.70959645175, 58812.7059364709, 58944.41055589475, 59070.70464647649, 35893.82053089718, 36100.81949590235, 36175.27649446997, 36238.09404764849, 36272.27455450876, 12227.755444891052, 15466.5360039199, 15470.381184752709, 15493.675948607799, 15503.0, 11686.0, 11870.525178992744, 12835.037372196935, 12996.090273680751, 13011.349432528335, 7911.327537159291, 7923.564203968722, 7928.088269848926, 7989.760307190793, 8002.51984880909, 2905.7236369441903, 2945.23423909785, 3357.1271469418125, 3400.285939952638, 3409.533900422842, 1000.0299709281985, 1007.7984403119406, 1018.1447584029451, 1049.4886470722436, 1108.5067145120506, 29228.415431691246, 29414.0, 29730.851345743133, 29835.806567737047, 29964.550531742043, 1052.0689190066846, 1157.6064138193055, 1157.9421028948518, 1194.0416726484268, 1305.8563558008611, 15019.474318398803, 15044.473443429428, 15091.849081509044, 15166.740530123903, 15189.62025949338, 645337.0, 651212.0, 653696.0, 653744.0, 654112.0, 57367.71316143392, 58006.0, 58452.707736461896, 58535.78053207114, 58640.413595864346, 1368.589423173044, 1400.509821562445, 1541.9845801542065, 1545.2891669832004, 1616.8787340949475, 24021.27936161918, 28663.0, 29190.416191675624, 29203.0, 29925.55111673283, 48046.75976620094, 48741.75629121832, 48742.75628621834, 48770.0, 48800.0, 465301.0, 485706.0, 485982.0, 487567.12432876637, 488340.0, 35205.59177632968, 35411.0, 41924.790376047924, 42174.578254217064, 42284.57715422929, 42380.57619423766, 42482.0, 43826.34260486159, 44087.77956110199, 44328.7783561093, 33210.66789332076, 34645.48031779576, 35814.28371432609, 36041.0989725259, 36272.09319767027, 29897.0, 35340.64659353373, 35719.10702232465, 36439.63560364468, 37267.44098838574, 37122.25755484929, 37153.0, 37233.882983510535, 37381.439278410304, 37747.43378849264, 32008.269432421042, 32577.837110814293, 32893.17767055843, 32902.506462402605, 33042.504362434105, 24769.876150619133, 24892.626610600495, 24943.501129976936, 25033.49933001293, 25133.062967341553], "value": 18767.61982307313, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.95589485156626, 402.4667315806557, 406.14303818942045, 406.34375483594005, 411.4876978162188, 14055.313243457584, 16445.83554164461, 20524.768513889157, 21203.68194477084, 21232.787672123275, 16467.505974820775, 17739.37912173074, 20280.087396067174, 20913.058912348944, 21139.0, 5740.167675687033, 6032.336442991267, 6038.305594856603, 6075.605085669439, 6091.604045737013, 5972.641641501502, 6018.729157187937, 6066.423689749467, 6072.51419886408, 6080.118382834508, 5799.681017544033, 5949.940500594982, 5964.552658550604, 5975.671338076404, 5984.88030239395, 5779.682117483537, 5963.61236519627, 6026.728797204137, 6037.486813620848, 6040.1241819936085, 5834.329052159013, 5844.26946631671, 5848.766049358063, 5849.239598852165, 5856.414358564152, 13007.479700812051, 13125.015623828156, 13361.933190334083, 13480.595582132551, 13496.527621533298, 2100.47488127968, 2109.367189843056, 2137.2626443876993, 2159.1363454618277, 2190.9452263693374, 7728.420368472387, 9521.0, 9626.951865240699, 9928.354656947275, 10078.748031299276, 1433.240382597225, 1688.2065429248235, 1717.6392957478945, 1729.2218501674297, 1739.747736578189, 101645.0, 117321.41339293249, 118615.40692296483, 120432.0, 121341.0, 11405.486753096131, 11435.0, 11572.537098515964, 11628.18602697803, 11877.0, 14732.263386830615, 14901.180435075958, 14912.850871491146, 14960.251987400587, 14963.653271205643, 8631.136886311444, 8637.309015278763, 8667.91332086671, 8759.868601970848, 8768.430052046724, 8633.481991080555, 8693.913060869438, 8737.737867863974, 8849.070847560914, 8850.557472126367, 4511.593956543927, 4587.243104887723, 4607.6083532900075, 4617.168909596305, 4678.368420263289, 1727.3090763694622, 1943.8444924406015, 1962.6663467210608, 1989.682020446164, 2004.128204231164, 901.4320977783954, 1022.2384323678907, 1027.1628622672497, 1029.562435964727, 1043.7286305560608, 19035.95302258389, 19880.90059549722, 19882.1053052613, 20012.099455524538, 20022.49943751418, 981.489625394805, 986.3046552180696, 992.9950350248203, 995.8307087794948, 1077.3912739302425, 7721.575313357819, 10615.363078215332, 10708.03627673513, 10802.243842931077, 10965.725856853642, 524527.0, 528783.0, 533249.0, 619474.0, 624721.0, 114496.0, 129968.3501582505, 133522.0, 136872.0, 138465.30767346098, 1026.8408396698374, 1050.4117694091333, 1058.2486434631498, 1065.9360438373722, 1067.3169171730099, 17589.20848561792, 17661.470155895342, 17781.46655600334, 18141.727874082164, 18331.450056497793, 35731.642683573875, 47966.52033479621, 51589.48410515997, 52055.438336849955, 54198.91602167856, 52280.0, 69564.30435695578, 72677.27322726705, 73168.0, 74180.62909685417, 7176.21061683204, 7560.924390756022, 7849.5682737447705, 7877.0, 7992.440529162903, 10458.529366178387, 11993.220440671328, 12190.146689731988, 12311.815322770344, 12427.254364738144, 9700.320977531504, 10439.217058720778, 10696.465176740976, 10748.785024299626, 10958.178136639943, 10198.898011020092, 10362.585496580052, 10539.31000704679, 10599.89400105989, 10704.250702450754, 10963.616273430393, 11181.776364472827, 11229.4385280734, 11369.31784092957, 11396.715082123013, 7710.653020614198, 7975.601219938864, 8017.679292828455, 8026.357891368793, 8393.370497212856, 8190.631421585924, 10269.794604108025, 10538.051575358255, 10914.399708016135, 10995.505202266077], "value": 9235.14811759998, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1386.6602682342827, 1594.808622965244, 1619.5870053136452, 1874.1191639929236, 1990.5222746540826, 7882.054153501581, 8407.117252688471, 8576.013758417785, 8580.656773729052, 8610.784730381727, 8458.32333413327, 8676.392652514327, 8697.739067827959, 8705.303575713944, 8763.342749293812, 2446.217210492643, 2469.9135530256444, 2473.1591258971944, 2475.10896077412, 2478.281298423455, 2226.4545186429345, 2312.7511143982315, 2409.8795060246916, 2474.5916923707655, 2478.107881162789, 1915.7988411216832, 2429.246933450636, 2470.530599186158, 2471.0486462711897, 2472.7403622619636, 2415.9879200603887, 2451.1788550835313, 2452.9942723483377, 2463.8275320727557, 2470.0, 2398.256540472459, 2423.5152969406013, 2431.197704757426, 2434.342727463593, 2435.6468312094826, 8507.191816777397, 8766.123387661182, 8814.074522175208, 8818.823623527558, 8829.293656507463, 5362.383325917531, 5552.305961754781, 5594.692291923983, 5600.383957764642, 5601.747921343549, 3008.3832814273023, 3041.958041958042, 3575.302815950891, 3595.46068089786, 3633.2370202257293, 1051.4795176387738, 1056.3397876327294, 1086.5110700184948, 1630.3723066619318, 1663.7088509510788, 11449.198556101077, 11540.884591154065, 11557.0, 11557.768844623144, 11584.073274138049, 4239.1521695660995, 4596.632269418438, 4598.471175814757, 4598.954010459919, 4615.123126606001, 9029.729108126767, 9055.320850936207, 9062.456252624863, 9072.092790720875, 9107.271418286522, 5302.310699609026, 5309.070912590281, 5314.654547454403, 5346.0, 5371.919421208685, 4403.3174857897075, 4562.817487300471, 4753.429588449409, 4847.660663753503, 4996.350474438373, 3774.6602805747616, 3831.1954489557506, 3880.6119388061534, 3902.9804850975565, 3941.0541470046983, 1078.7378766842655, 1179.775842589905, 1203.175824560179, 1221.407617305606, 1226.9631911042504, 851.114840565817, 852.9849479119828, 876.7588913048962, 919.9926080941359, 927.679950417105, 5748.655080695172, 5883.264591926009, 5974.551908606872, 6037.0, 6047.969760151171, 397.73749325445067, 472.75653038685107, 480.9495003024632, 1014.8832884218261, 1030.9278350515392, 3854.325493038707, 3906.00396898789, 3932.7640341579026, 3935.92128157441, 3986.142979259473, 10210.540525676362, 10523.315984460985, 10602.734931626772, 10644.201684873511, 10657.84013239802, 10180.745481363025, 10548.894511054945, 10587.258891877495, 10591.94704026475, 10680.0, 8063.951686280863, 8300.169983001771, 8354.540500272422, 8443.704470343508, 8448.662053517914, 5705.115707065412, 5753.683547404766, 5808.7095645218415, 5834.38738932406, 5841.386654401226, 9499.38254013487, 9602.32783705134, 9654.806903861743, 9780.510974451106, 9804.705858824247, 9374.203192728422, 9942.254330925065, 9993.750156246151, 10220.386776793417, 10229.795404091728, 6425.58233714807, 6553.574017688835, 6837.042814005944, 6961.61711105894, 7025.964870175617, 6963.477739169683, 8164.959175204086, 8186.304164145876, 8254.917450825415, 8275.0, 7156.570605763671, 7257.854842903218, 7272.381847542806, 7372.041634587469, 7426.405887529093, 6973.930260697531, 7125.82185445368, 7186.6047367395295, 7197.1363436387965, 7263.927360726537, 6792.524523283323, 7002.614856182959, 7012.0, 7025.0, 7027.121609798775, 4843.418789745253, 4889.55993960531, 4893.461719210813, 4897.142999975062, 5015.22264049073, 4851.684640498356, 4892.510748925079, 4925.261210818406, 4930.309756634002, 5161.716105614228], "value": 4525.386918394515, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [318.76252192116874, 322.86116969703033, 328.3531443057178, 331.420014973796, 335.6492465373684, 5540.972295138518, 6676.031975363576, 6709.396154346108, 6712.664366781666, 6723.73105075797, 6665.533412661103, 6689.030090636855, 6693.99590061491, 6694.062831203635, 6720.294369091236, 4694.929576056354, 4762.499937506564, 4792.856214313576, 4846.442659094214, 4864.343313652665, 4795.352627395309, 4869.4887036861155, 4882.584980276663, 4901.485344038878, 4905.754712264373, 4807.254875494286, 4826.710397376169, 4838.346823178861, 4841.951580484203, 4845.757712114398, 3898.869327894907, 4653.790579423934, 4787.0, 4791.640626952958, 4800.42394912611, 4066.979665101685, 4161.126163505667, 4164.354525048622, 4173.853915112987, 4174.269502836992, 5319.468053194688, 6315.652639104847, 6488.416042556146, 6537.967310163419, 6623.602583844985, 5878.235829342201, 5879.206307148523, 5887.234659494238, 5890.852728681817, 5897.941020589782, 5784.047748236562, 5786.913196302059, 7161.570305781618, 7164.641767911636, 7169.247229040928, 4226.239276930151, 4261.4460120184485, 4742.834000809989, 4747.95252047482, 4767.594754445876, 7459.664315105833, 7513.887291690629, 7517.887231691474, 7530.774076777706, 7532.284432978859, 4827.300041493963, 4829.275608658659, 4831.661783675109, 4832.34763306949, 4839.225723884161, 4890.1931181355385, 4903.41159060915, 4905.7301848397965, 4910.8281210157475, 4917.311576379309, 5841.328247251536, 5842.182094506772, 5850.063989761617, 5850.970745146332, 5869.4717475427415, 6904.654767261617, 7218.60297683622, 7222.494425390278, 7223.458240631973, 7246.891296630555, 1681.1846254566526, 1692.1285537947967, 1706.428346503935, 1707.7694511241073, 1709.1283445442666, 1087.9401632910267, 1088.6353071721062, 1106.972325691848, 1113.799516087096, 1115.4757264085868, 6412.4549413299455, 6590.2421221560135, 6641.074088514277, 6666.766663166766, 6669.066330713704, 5708.31500219968, 7503.474756766974, 7506.211847756016, 7509.770798556564, 7513.699452021858, 6737.023131645982, 6741.056252124707, 6741.460683145435, 6744.325567443315, 6769.796906092825, 3897.142628621699, 3952.3676211805973, 3954.9011274718364, 3962.623550762673, 3963.5441924178513, 7572.924270757222, 7714.961425192838, 7833.060032795987, 7850.646720897574, 7853.8821917671285, 7655.1962043985695, 7694.307512323918, 7708.730194443167, 7712.6529306181355, 7750.302472777477, 7077.433805295564, 7182.281771822776, 7810.6875724971505, 7826.334761545332, 7838.960805195938, 7454.291842274975, 7460.7015719372785, 7467.0, 7470.439717021354, 7477.775666730006, 7071.116110486189, 7346.236903143314, 7360.705571777069, 7373.63131843395, 7383.593902335423, 7736.071671399468, 7792.37660987131, 7810.882836757567, 7812.3750099993, 7823.921760782547, 4804.183288840835, 4818.469968303554, 4818.542238487338, 4820.276958456259, 4821.46963833985, 7422.072240969879, 7424.925750742424, 7438.5536867788105, 7446.813829654086, 7447.143578488326, 6872.484563657846, 7175.569465832067, 7180.892286615809, 7181.892271616034, 7197.0, 7118.537295075804, 7164.462665300019, 7169.784906452606, 7171.0, 7183.0, 6193.163922870355, 6550.017497375432, 7017.649117544204, 7022.332878376546, 7024.8595028098125, 6911.688973996082, 7180.210176880434, 7180.461465390221, 7198.064251647253, 7199.1001124859395, 6815.182178138655, 6977.092977912839, 6995.300469952964, 7011.859762804614, 7015.0], "value": 5222.85109691217, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [681.543365944817, 688.0779755128127, 738.7672883041843, 742.5878637356265, 767.8848172774083, 14841.777373339395, 14995.325210365532, 15038.624034399145, 15066.39734410625, 15078.849211507883, 12293.262404255729, 13449.327533623304, 13540.984426168028, 13544.322783860816, 13548.45806167752, 708.7731925783751, 891.6255172827427, 910.1308250620641, 912.1699253679153, 913.5067063785581, 894.9776255593597, 901.5086777706166, 906.9138431848996, 907.9046700096494, 909.6907051602453, 767.2864236260276, 962.6101428921298, 969.0261287406147, 970.1171933540479, 970.7864269860619, 773.9729109481141, 779.2091027606983, 780.7150390107627, 940.1773448232797, 966.0966995858895, 713.2974020589714, 714.3285311806885, 715.8067321823079, 716.1155972374114, 716.827961289292, 14646.560603181922, 14712.852871471363, 14716.926415367961, 14793.630159245997, 14835.703285934329, 12354.320512371816, 12408.689782755504, 12562.623121306375, 12699.492020319176, 12755.489780408865, 9858.359206651616, 10148.543315550744, 10192.235582331356, 10432.947835260851, 10435.478226088666, 3459.5848498180126, 3504.877329293488, 3507.9473807892646, 3558.839852206657, 3762.1159027628487, 81659.0, 81662.0, 82819.0, 83521.58239208766, 83850.16149838545, 9230.030846760994, 9534.904650953402, 9622.422654640744, 9759.707208783748, 9881.011898810206, 21276.82977436225, 21468.355949321547, 21500.569988600455, 21541.461463463227, 21582.0, 7347.63261836906, 7348.926510734931, 7404.148522919825, 7437.070366204224, 7612.352949999312, 4029.597040295947, 4086.6322031017353, 4125.855595054159, 4190.161967606491, 4192.413062171298, 3529.735269854771, 3559.9644003559633, 3583.928321433557, 3594.4428613564937, 3595.94606080909, 575.3958343739057, 672.67711498481, 683.3508167241141, 696.5994553131868, 706.6749295324106, 497.5994324568757, 519.8050730975884, 612.8467883029243, 618.2056057965493, 623.6040114527308, 7948.960255198687, 8191.450055421827, 8265.95867020661, 8316.334693224528, 8351.1648835116, 506.33417056570494, 516.3158814570634, 536.2224774077603, 591.1398914579363, 610.1488423648984, 3766.60450652683, 3862.3240932836643, 3867.3232184368185, 3901.8634347797692, 3929.4891664084057, 89849.0, 90734.5559266446, 91555.0, 91641.62537561942, 91967.1606567865, 73877.0, 74252.0, 74465.6276718613, 76816.61591692005, 78203.60898195472, 65909.67045164744, 66131.0, 68122.0, 68182.65908670524, 68773.65613172002, 7490.475666703279, 7809.258120478546, 7826.804329891571, 7899.68401263943, 7909.881351779843, 46703.06593868036, 47438.52561474341, 47814.0, 48111.51888481071, 48227.0, 61621.075683865674, 63835.0, 66084.0, 66152.0, 67466.0, 9872.160866326443, 10119.949400252952, 10123.544440500342, 10257.897421025993, 10290.176785857264, 10676.626318079141, 11225.045871101049, 11359.977602015859, 11375.374354410587, 11457.713557161138, 10389.0, 10529.999650033235, 10544.57821687124, 10636.680899573024, 10701.983311585387, 10409.0, 10499.527521261714, 10614.469276536296, 10627.149828013586, 10709.571617135227, 9801.215902727907, 10119.139873110867, 10339.896601034194, 10435.739106522095, 10628.8937110631, 8679.956600216958, 8822.470651760916, 8847.557622118997, 8868.866966995629, 9018.143276388733, 8627.396082274181, 8828.382013259266, 8853.247473964786, 8881.911180888366, 8927.866082008904], "value": 6722.390761131885, "unit": "score"}], "name": "dromaeo_css", "value": 7347.001498893871, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 120.0, 113.0, 113.0, 113.0, 114.0, 115.0, 113.0, 134.0], "value": 116.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 122.0, 146.0, 166.0, 121.0, 142.0, 163.0, 102.0, 202.0, 147.0], "value": 146.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 189.0, 184.0, 186.0, 186.0, 186.0, 186.0, 218.0, 261.0, 189.0], "value": 197.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 77.0, 74.0, 152.0, 57.0, 72.0, 83.0, 96.0, 154.0, 56.0], "value": 89.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 141.0, 77.0, 80.0, 89.0, 157.0, 118.0, 107.0, 136.0], "value": 108.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 88.0, 85.0, 88.0, 85.0, 89.0, 85.0, 89.0, 84.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 93.0, 95.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 86.0, 80.0, 86.0, 84.0, 85.0, 85.0, 85.0, 85.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 61.0, 64.0, 64.0, 63.0, 63.0, 83.0, 59.0, 58.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 69.0, 79.0, 75.0, 74.0, 69.0, 68.0, 69.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 80.0, 79.0, 80.0, 81.0, 80.0, 82.0, 83.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 129.0, 130.0, 128.0, 126.0, 128.0, 128.0, 128.0, 146.0, 131.0], "value": 130.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 167.0, 166.0, 167.0, 165.0, 165.0, 162.0, 161.0, 157.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 66.0, 69.0, 68.0, 69.0, 66.0, 66.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1499.6000000000001, "alertThreshold": 2.0}]} 18:39:42 INFO - Return code: 0 18:39:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:39:42 ERROR - # TBPL SUCCESS # 18:39:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:39:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:39:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:39:42 INFO - 2016-05-20 18:39:42.451 screenresolution[2083:17362] starting screenresolution argv=screenresolution get 18:39:42 INFO - 2016-05-20 18:39:42.454 screenresolution[2083:17362] Display 0: 1600x1200x32@60 18:39:42 INFO - 2016-05-20 18:39:42.460 screenresolution[2084:17365] starting screenresolution argv=screenresolution list 18:39:42 INFO - Available Modes on Display 0 18:39:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:39:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:39:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:39:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:39:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:39:42 INFO - Intel Iris: 18:39:42 INFO - Chipset Model: Intel Iris 18:39:42 INFO - Type: GPU 18:39:42 INFO - Bus: Built-In 18:39:42 INFO - VRAM (Dynamic, Max): 1536 MB 18:39:42 INFO - Vendor: Intel (0x8086) 18:39:42 INFO - Device ID: 0x0a2e 18:39:42 INFO - Revision ID: 0x0009 18:39:42 INFO - Displays: 18:39:42 INFO - Display: 18:39:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:39:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:39:42 INFO - Display Serial Number: 1600x1200 60 18:39:42 INFO - Main Display: Yes 18:39:42 INFO - Mirror: Off 18:39:42 INFO - Online: Yes 18:39:42 INFO - Rotation: Supported 18:39:42 INFO - Return code: 0 18:39:42 INFO - Running post-action listener: _resource_record_post_action 18:39:42 INFO - Running post-run listener: _resource_record_post_run 18:39:43 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 872960; Write bytes: 652519936; Read time: 11; Write time: 1856 18:39:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 352852480; Write bytes: 348211200; Read time: 28002; Write time: 748 18:39:43 INFO - run-tests - Wall time: 1175s; CPU: 52.0%; Read bytes: 872960; Write bytes: 294568448; Read time: 11; Write time: 1073 18:39:43 INFO - Running post-run listener: _upload_blobber_files 18:39:43 INFO - Blob upload gear active. 18:39:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:39:43 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 18:39:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:39:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:39:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:39:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:39:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:45 INFO - (blobuploader) - INFO - Done attempting. 18:39:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:39:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:39:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:39:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:39:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:39:46 INFO - (blobuploader) - INFO - Done attempting. 18:39:46 INFO - (blobuploader) - INFO - Iteration through files over. 18:39:46 INFO - Return code: 0 18:39:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:39:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:39:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed8be3f147ac6c5bf4f7aca6b12194f81e48ab8bf14d1f2a27156edd96eb8621f1e1ab26bfb10b3a2fa37927a3d3d717f5b16182da51ac81c95b68655e2518", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa88c7ad8b4c0bea1ea8d8707f1780b5e2f647c4d0f338dc34b95778a16fa1100062712a6739df14335ec40a92a8f667a464e8b3aa4dfda04044a989497cd029"} 18:39:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:39:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:39:46 INFO - Contents: 18:39:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed8be3f147ac6c5bf4f7aca6b12194f81e48ab8bf14d1f2a27156edd96eb8621f1e1ab26bfb10b3a2fa37927a3d3d717f5b16182da51ac81c95b68655e2518", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa88c7ad8b4c0bea1ea8d8707f1780b5e2f647c4d0f338dc34b95778a16fa1100062712a6739df14335ec40a92a8f667a464e8b3aa4dfda04044a989497cd029"} 18:39:46 INFO - Running post-run listener: copy_logs_to_upload_dir 18:39:46 INFO - Copying logs to upload dir... 18:39:46 INFO - mkdir: /builds/slave/test/build/upload/logs 18:39:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.966604 ========= master_lag: 0.32 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-05-20 18:39:47.102161) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 18:39:47.106310) ========= 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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed8be3f147ac6c5bf4f7aca6b12194f81e48ab8bf14d1f2a27156edd96eb8621f1e1ab26bfb10b3a2fa37927a3d3d717f5b16182da51ac81c95b68655e2518", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa88c7ad8b4c0bea1ea8d8707f1780b5e2f647c4d0f338dc34b95778a16fa1100062712a6739df14335ec40a92a8f667a464e8b3aa4dfda04044a989497cd029"} build_url:https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011688 build_url: 'https://queue.taskcluster.net/v1/task/JVoy6euRTfeOzEfAcK6Omw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83ed8be3f147ac6c5bf4f7aca6b12194f81e48ab8bf14d1f2a27156edd96eb8621f1e1ab26bfb10b3a2fa37927a3d3d717f5b16182da51ac81c95b68655e2518", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fa88c7ad8b4c0bea1ea8d8707f1780b5e2f647c4d0f338dc34b95778a16fa1100062712a6739df14335ec40a92a8f667a464e8b3aa4dfda04044a989497cd029"}' ========= master_lag: 0.26 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-20 18:39:47.373272) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:39:47.373618) ========= 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.f4Aeha2cQ6/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.YK1WhpzPtQ/Listeners TMPDIR=/var/folders/xd/brxx9n092_bcwjxpfyv8f64c00000w/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.005177 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-20 18:39:47.405126) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 18:39:47.405524) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-20 18:39:47.407642) ========= ========= Total master_lag: 0.67 =========