builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0177 starttime: 1464620952.72 results: success (0) buildid: 20160530035543 builduid: 865ed214ad954ae683a81bd20aaeb948 revision: 04934785596476f6dd8baccc355202aeba59af35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.718966) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.719536) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.719864) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.743098) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.743519) ========= 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.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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-30 08:09:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.3M=0.001s 2016-05-30 08:09:12 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096191 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.861244) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.861656) ========= 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.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.037247 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:12.918549) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:09:12.918954) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 04934785596476f6dd8baccc355202aeba59af35 --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 04934785596476f6dd8baccc355202aeba59af35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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-30 08:09:12,994 truncating revision to first 12 chars 2016-05-30 08:09:12,994 Setting DEBUG logging. 2016-05-30 08:09:12,994 attempt 1/10 2016-05-30 08:09:12,994 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/049347855964?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 08:09:14,477 unpacking tar archive at: mozilla-aurora-049347855964/testing/mozharness/ program finished with exit code 0 elapsedTime=1.748065 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 08:09:14.687928) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:14.688328) ========= script_repo_revision: 04934785596476f6dd8baccc355202aeba59af35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:14.688968) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:14.689316) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 08:09:14.707946) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 08:09:14.708353) ========= /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.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:14 INFO - MultiFileLogger online at 20160530 08:09:14 in /builds/slave/test 08:09:14 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 08:09:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:09:14 INFO - {'append_to_log': False, 08:09:14 INFO - 'base_work_dir': '/builds/slave/test', 08:09:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:09:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:09:14 INFO - 'branch': 'Mozilla-Aurora', 08:09:14 INFO - 'buildbot_json_path': 'buildprops.json', 08:09:14 INFO - 'config_files': ('talos/mac_config.py',), 08:09:14 INFO - 'default_actions': ('clobber', 08:09:14 INFO - 'read-buildbot-config', 08:09:14 INFO - 'download-and-extract', 08:09:14 INFO - 'populate-webroot', 08:09:14 INFO - 'create-virtualenv', 08:09:14 INFO - 'install', 08:09:14 INFO - 'run-tests'), 08:09:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:09:14 INFO - 'download_minidump_stackwalk': True, 08:09:14 INFO - 'download_symbols': 'ondemand', 08:09:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:09:14 INFO - 'tooltool.py': '/tools/tooltool.py', 08:09:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:09:14 INFO - '/tools/misc-python/virtualenv.py')}, 08:09:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:09:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:09:14 INFO - 'installer_path': 'installer.exe', 08:09:14 INFO - 'log_level': 'info', 08:09:14 INFO - 'log_name': 'talos', 08:09:14 INFO - 'log_to_console': True, 08:09:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:09:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:09:14 INFO - 'opt_config_files': (), 08:09:14 INFO - 'pip_index': False, 08:09:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:14 INFO - 'cmd': ('bash', 08:09:14 INFO - '-c', 08:09:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:14 INFO - 'enabled': True, 08:09:14 INFO - 'halt_on_failure': False, 08:09:14 INFO - 'name': 'check_screen_resolution'},), 08:09:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:09:14 INFO - 'cmd': ('bash', 08:09:14 INFO - '-c', 08:09:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:09:14 INFO - 'enabled': True, 08:09:14 INFO - 'halt_on_failure': False, 08:09:14 INFO - 'name': 'check_screen_resolution'},), 08:09:14 INFO - 'run_cmd_checks_enabled': True, 08:09:14 INFO - 'sps_profile': False, 08:09:14 INFO - 'sps_profile_interval': 0, 08:09:14 INFO - 'suite': 'dromaeojs', 08:09:14 INFO - 'system_bits': '32', 08:09:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:09:14 INFO - 'title': 't-yosemite-r7-0177', 08:09:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:09:14 INFO - 'use_talos_json': True, 08:09:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:09:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:09:14 INFO - 'work_dir': 'build'} 08:09:14 INFO - ##### 08:09:14 INFO - ##### Running clobber step. 08:09:14 INFO - ##### 08:09:14 INFO - Running pre-action listener: _resource_record_pre_action 08:09:14 INFO - Running main action method: clobber 08:09:14 INFO - rmtree: /builds/slave/test/build 08:09:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:09:16 INFO - Running post-action listener: _resource_record_post_action 08:09:16 INFO - ##### 08:09:16 INFO - ##### Running read-buildbot-config step. 08:09:16 INFO - ##### 08:09:16 INFO - Running pre-action listener: _resource_record_pre_action 08:09:16 INFO - Running main action method: read_buildbot_config 08:09:16 INFO - Using buildbot properties: 08:09:16 INFO - { 08:09:16 INFO - "project": "", 08:09:16 INFO - "product": "firefox", 08:09:16 INFO - "script_repo_revision": "production", 08:09:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:09:16 INFO - "repository": "", 08:09:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:09:16 INFO - "buildid": "20160530035543", 08:09:16 INFO - "pgo_build": "False", 08:09:16 INFO - "basedir": "/builds/slave/test", 08:09:16 INFO - "buildnumber": 13, 08:09:16 INFO - "slavename": "t-yosemite-r7-0177", 08:09:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 08:09:16 INFO - "platform": "yosemite_r7", 08:09:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:09:16 INFO - "branch": "mozilla-aurora", 08:09:16 INFO - "repo_path": "releases/mozilla-aurora", 08:09:16 INFO - "revision": "04934785596476f6dd8baccc355202aeba59af35", 08:09:16 INFO - "stage_platform": "macosx64", 08:09:16 INFO - "builduid": "865ed214ad954ae683a81bd20aaeb948", 08:09:16 INFO - "slavebuilddir": "test" 08:09:16 INFO - } 08:09:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 08:09:16 INFO - Running post-action listener: _resource_record_post_action 08:09:16 INFO - ##### 08:09:16 INFO - ##### Running download-and-extract step. 08:09:16 INFO - ##### 08:09:16 INFO - Running pre-action listener: _resource_record_pre_action 08:09:16 INFO - Running main action method: download_and_extract 08:09:16 INFO - mkdir: /builds/slave/test/build/tests 08:09:16 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')]} 08:09:16 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:09:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:18 INFO - Downloaded 1199 bytes. 08:09:18 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:09:18 INFO - Using the following test package requirements: 08:09:18 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:09:18 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 08:09:18 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 08:09:18 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'jsshell-mac.zip'], 08:09:18 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 08:09:18 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:09:18 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 08:09:18 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 08:09:18 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 08:09:18 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:09:18 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 08:09:18 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:18 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:20 INFO - Downloaded 18425494 bytes. 08:09:20 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'] 08:09:20 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 08:09:23 INFO - Return code: 0 08:09:23 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 08:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:23 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:24 INFO - Downloaded 18425494 bytes. 08:09:24 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'] 08:09:24 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 08:09:28 INFO - Return code: 0 08:09:28 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')]} 08:09:28 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:09:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:09:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:09:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:29 INFO - Downloaded 10840145 bytes. 08:09:29 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'] 08:09:29 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 08:09:29 INFO - Return code: 0 08:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:29 INFO - https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:09:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:09:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:09:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 08:09:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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 08:09:32 INFO - Downloaded 114139611 bytes. 08:09:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:09:32 INFO - mkdir: /builds/slave/test/properties 08:09:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:09:32 INFO - Writing to file /builds/slave/test/properties/build_url 08:09:32 INFO - Contents: 08:09:32 INFO - build_url:https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:09:33 INFO - Running post-action listener: _resource_record_post_action 08:09:33 INFO - Running post-action listener: set_extra_try_arguments 08:09:33 INFO - ##### 08:09:33 INFO - ##### Running populate-webroot step. 08:09:33 INFO - ##### 08:09:33 INFO - Running pre-action listener: _resource_record_pre_action 08:09:33 INFO - Running main action method: populate_webroot 08:09:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:09:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:09:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:09:33 INFO - '--tppagecycles', 08:09:33 INFO - '7'], 08:09:33 INFO - 'tests': ['tsvgm']}}, 08:09:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:09:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:09:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:09:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:09:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:09:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['damp', 'tps']}, 08:09:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'tests': ['damp', 'tps']}, 08:09:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['dromaeo_dom']}, 08:09:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:09:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'other-e10s': {'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['a11yr', 08:09:33 INFO - 'ts_paint', 08:09:33 INFO - 'tpaint', 08:09:33 INFO - 'sessionrestore', 08:09:33 INFO - 'sessionrestore_no_auto_restore', 08:09:33 INFO - 'tabpaint']}, 08:09:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['tsvgx', 08:09:33 INFO - 'tsvgr_opacity', 08:09:33 INFO - 'tart', 08:09:33 INFO - 'tscrollx', 08:09:33 INFO - 'cart']}, 08:09:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:09:33 INFO - 'tsvgr_opacity', 08:09:33 INFO - 'tart', 08:09:33 INFO - 'tscrollx', 08:09:33 INFO - 'cart']}, 08:09:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'talos_options': ['--disable-e10s'], 08:09:33 INFO - 'tests': ['tp5o']}, 08:09:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'tests': ['tp5o']}, 08:09:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'talos_options': ['--disable-e10s', 08:09:33 INFO - '--xperf_path', 08:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:33 INFO - 'tests': ['tp5n']}, 08:09:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:09:33 INFO - 'talos_options': ['--xperf_path', 08:09:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:09:33 INFO - 'tests': ['tp5n']}}, 08:09:33 INFO - 'talos.zip': {'path': '', 08:09:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:09:33 INFO - Running post-action listener: _resource_record_post_action 08:09:33 INFO - ##### 08:09:33 INFO - ##### Running create-virtualenv step. 08:09:33 INFO - ##### 08:09:33 INFO - Running pre-action listener: _resource_record_pre_action 08:09:33 INFO - Running main action method: create_virtualenv 08:09:33 INFO - Creating virtualenv /builds/slave/test/build/venv 08:09:33 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 08:09:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:09:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:09:34 INFO - Using real prefix '/tools/python27' 08:09:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:09:34 INFO - Installing distribute.............................................................................................................................................................................................done. 08:09:37 INFO - Installing pip.................done. 08:09:37 INFO - Return code: 0 08:09:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:09:37 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')]} 08:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:37 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')]} 08:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:37 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:37 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 08:09:37 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 08:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:37 INFO - 'HOME': '/Users/cltbld', 08:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:37 INFO - 'LOGNAME': 'cltbld', 08:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:37 INFO - 'MOZ_NO_REMOTE': '1', 08:09:37 INFO - 'NO_EM_RESTART': '1', 08:09:37 INFO - 'PAGER': '/bin/cat', 08:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:37 INFO - 'PWD': '/builds/slave/test', 08:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:37 INFO - 'SHELL': '/bin/bash', 08:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:37 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:37 INFO - 'USER': 'cltbld', 08:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:37 INFO - 'XPC_FLAGS': '0x0', 08:09:37 INFO - 'XPC_SERVICE_NAME': '0', 08:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:37 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 08:09:37 INFO - Cleaning up... 08:09:37 INFO - Return code: 0 08:09:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:09:37 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')]} 08:09:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:37 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')]} 08:09:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:37 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:37 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 08:09:37 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 08:09:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:37 INFO - 'HOME': '/Users/cltbld', 08:09:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:37 INFO - 'LOGNAME': 'cltbld', 08:09:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:37 INFO - 'MOZ_NO_REMOTE': '1', 08:09:37 INFO - 'NO_EM_RESTART': '1', 08:09:37 INFO - 'PAGER': '/bin/cat', 08:09:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:37 INFO - 'PWD': '/builds/slave/test', 08:09:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:37 INFO - 'SHELL': '/bin/bash', 08:09:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:37 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:37 INFO - 'USER': 'cltbld', 08:09:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:37 INFO - 'XPC_FLAGS': '0x0', 08:09:37 INFO - 'XPC_SERVICE_NAME': '0', 08:09:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:38 INFO - Downloading/unpacking psutil>=0.7.1 08:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:09:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:42 INFO - Installing collected packages: psutil 08:09:42 INFO - Running setup.py install for psutil 08:09:42 INFO - building 'psutil._psutil_osx' extension 08:09:42 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 08:09:42 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 08:09:42 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 08:09:42 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 08:09:43 INFO - building 'psutil._psutil_posix' extension 08:09:43 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 08:09:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:09:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:09:43 INFO - ^ 08:09:43 INFO - 1 warning generated. 08:09:43 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 08:09:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:09:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:09:43 INFO - Successfully installed psutil 08:09:43 INFO - Cleaning up... 08:09:43 INFO - Return code: 0 08:09:43 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:09:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:09:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:43 INFO - 'HOME': '/Users/cltbld', 08:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:43 INFO - 'LOGNAME': 'cltbld', 08:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:43 INFO - 'MOZ_NO_REMOTE': '1', 08:09:43 INFO - 'NO_EM_RESTART': '1', 08:09:43 INFO - 'PAGER': '/bin/cat', 08:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:43 INFO - 'PWD': '/builds/slave/test', 08:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:43 INFO - 'SHELL': '/bin/bash', 08:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:43 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:43 INFO - 'USER': 'cltbld', 08:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:43 INFO - 'XPC_FLAGS': '0x0', 08:09:43 INFO - 'XPC_SERVICE_NAME': '0', 08:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:43 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:43 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 08:09:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:09:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:09:46 INFO - Installing collected packages: mozsystemmonitor 08:09:46 INFO - Running setup.py install for mozsystemmonitor 08:09:46 INFO - Successfully installed mozsystemmonitor 08:09:46 INFO - Cleaning up... 08:09:46 INFO - Return code: 0 08:09:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:46 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:46 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 08:09:46 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 08:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:46 INFO - 'HOME': '/Users/cltbld', 08:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:46 INFO - 'LOGNAME': 'cltbld', 08:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:46 INFO - 'MOZ_NO_REMOTE': '1', 08:09:46 INFO - 'NO_EM_RESTART': '1', 08:09:46 INFO - 'PAGER': '/bin/cat', 08:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:46 INFO - 'PWD': '/builds/slave/test', 08:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:46 INFO - 'SHELL': '/bin/bash', 08:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:46 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:46 INFO - 'USER': 'cltbld', 08:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:46 INFO - 'XPC_FLAGS': '0x0', 08:09:46 INFO - 'XPC_SERVICE_NAME': '0', 08:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:46 INFO - Downloading/unpacking blobuploader==1.2.4 08:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:09:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:09:49 INFO - Downloading blobuploader-1.2.4.tar.gz 08:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:09:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:09:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:49 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 08:09:50 INFO - Downloading docopt-0.6.1.tar.gz 08:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:09:50 INFO - Installing collected packages: blobuploader, requests, docopt 08:09:50 INFO - Running setup.py install for blobuploader 08:09:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:09:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:09:50 INFO - Running setup.py install for requests 08:09:51 INFO - Running setup.py install for docopt 08:09:51 INFO - Successfully installed blobuploader requests docopt 08:09:51 INFO - Cleaning up... 08:09:51 INFO - Return code: 0 08:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:51 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:51 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 08:09:51 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 08:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:51 INFO - 'HOME': '/Users/cltbld', 08:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:51 INFO - 'LOGNAME': 'cltbld', 08:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:51 INFO - 'MOZ_NO_REMOTE': '1', 08:09:51 INFO - 'NO_EM_RESTART': '1', 08:09:51 INFO - 'PAGER': '/bin/cat', 08:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:51 INFO - 'PWD': '/builds/slave/test', 08:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:51 INFO - 'SHELL': '/bin/bash', 08:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:51 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:51 INFO - 'USER': 'cltbld', 08:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:51 INFO - 'XPC_FLAGS': '0x0', 08:09:51 INFO - 'XPC_SERVICE_NAME': '0', 08:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:51 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-5SPlqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:51 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-geRk_q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:51 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-M9Xlxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:51 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-C6VYcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:51 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-ROlBFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-cWAI5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-yzBDKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-Du4EKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-G23QJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-CEqUq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-QBWd3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-fPoQ8y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-mJEV6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-zup5Q4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:52 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-tOITm6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:53 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-YU_xBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:53 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-pp2n9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:09:53 INFO - Running setup.py install for manifestparser 08:09:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozcrash 08:09:53 INFO - Running setup.py install for mozdebug 08:09:53 INFO - Running setup.py install for mozdevice 08:09:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:09:53 INFO - Running setup.py install for mozfile 08:09:54 INFO - Running setup.py install for mozhttpd 08:09:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozinfo 08:09:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozInstall 08:09:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozleak 08:09:54 INFO - Running setup.py install for mozlog 08:09:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for moznetwork 08:09:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:09:54 INFO - Running setup.py install for mozprocess 08:09:55 INFO - Running setup.py install for mozprofile 08:09:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Running setup.py install for mozrunner 08:09:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Running setup.py install for mozscreenshot 08:09:55 INFO - Running setup.py install for moztest 08:09:55 INFO - Running setup.py install for mozversion 08:09:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:09:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:09:55 INFO - Cleaning up... 08:09:55 INFO - Return code: 0 08:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:09: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')]} 08:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09: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')]} 08:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:09:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:09:55 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:09:55 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 08:09:55 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 08:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:09:55 INFO - 'HOME': '/Users/cltbld', 08:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:09:55 INFO - 'LOGNAME': 'cltbld', 08:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:09:55 INFO - 'MOZ_NO_REMOTE': '1', 08:09:55 INFO - 'NO_EM_RESTART': '1', 08:09:55 INFO - 'PAGER': '/bin/cat', 08:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:09:55 INFO - 'PWD': '/builds/slave/test', 08:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:09:55 INFO - 'SHELL': '/bin/bash', 08:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:09:55 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:09:55 INFO - 'USER': 'cltbld', 08:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:09:55 INFO - 'XPC_FLAGS': '0x0', 08:09:55 INFO - 'XPC_SERVICE_NAME': '0', 08:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-R216ui-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-L6Nr5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-152yOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-rBjOot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-RH5A7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-xFLfvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-eJPK0X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-voRb0C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:09:56 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)) 08:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:09:56 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-7Gu4wh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-PJEP22-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-CgSnqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-R7B0bO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-TeU4es-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-vrJvzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-cGBeFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-A9SYZK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:09:57 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)) 08:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:09:57 INFO - Running setup.py (path:/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/pip-34Il7B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 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)) 08:09:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:09:57 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 08:09:57 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 08:09:57 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 08:09:57 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 08:09:57 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 08:09:57 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 08:10:00 INFO - Downloading blessings-1.6.tar.gz 08:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:10:00 INFO - Installing collected packages: blessings 08:10:00 INFO - Running setup.py install for blessings 08:10:00 INFO - Successfully installed blessings 08:10:00 INFO - Cleaning up... 08:10:00 INFO - Return code: 0 08:10:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:10:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:00 INFO - Reading from file tmpfile_stdout 08:10:00 INFO - Current package versions: 08:10:00 INFO - blessings == 1.6 08:10:00 INFO - blobuploader == 1.2.4 08:10:00 INFO - docopt == 0.6.1 08:10:00 INFO - manifestparser == 1.1 08:10:00 INFO - mozInstall == 1.12 08:10:00 INFO - mozcrash == 0.17 08:10:00 INFO - mozdebug == 0.1 08:10:00 INFO - mozdevice == 0.48 08:10:00 INFO - mozfile == 1.2 08:10:00 INFO - mozhttpd == 0.7 08:10:00 INFO - mozinfo == 0.9 08:10:00 INFO - mozleak == 0.1 08:10:00 INFO - mozlog == 3.1 08:10:00 INFO - moznetwork == 0.27 08:10:00 INFO - mozprocess == 0.22 08:10:00 INFO - mozprofile == 0.28 08:10:00 INFO - mozrunner == 6.11 08:10:00 INFO - mozscreenshot == 0.1 08:10:00 INFO - mozsystemmonitor == 0.0 08:10:00 INFO - moztest == 0.7 08:10:00 INFO - mozversion == 1.4 08:10:00 INFO - psutil == 3.1.1 08:10:00 INFO - requests == 1.2.3 08:10:00 INFO - wsgiref == 0.1.2 08:10:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:00 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:10:00 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 08:10:00 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 08:10:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:10:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:00 INFO - 'HOME': '/Users/cltbld', 08:10:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:00 INFO - 'LOGNAME': 'cltbld', 08:10:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:00 INFO - 'MOZ_NO_REMOTE': '1', 08:10:00 INFO - 'NO_EM_RESTART': '1', 08:10:00 INFO - 'PAGER': '/bin/cat', 08:10:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:00 INFO - 'PWD': '/builds/slave/test', 08:10:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:00 INFO - 'SHELL': '/bin/bash', 08:10:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:10:00 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:10:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:00 INFO - 'USER': 'cltbld', 08:10:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:00 INFO - 'XPC_FLAGS': '0x0', 08:10:00 INFO - 'XPC_SERVICE_NAME': '0', 08:10:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 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)) 08:10:01 INFO - Cleaning up... 08:10:01 INFO - Return code: 0 08:10:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:10:01 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')]} 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:01 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')]} 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:01 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:10:01 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 08:10:01 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 08:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:01 INFO - 'HOME': '/Users/cltbld', 08:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:01 INFO - 'LOGNAME': 'cltbld', 08:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:01 INFO - 'MOZ_NO_REMOTE': '1', 08:10:01 INFO - 'NO_EM_RESTART': '1', 08:10:01 INFO - 'PAGER': '/bin/cat', 08:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:01 INFO - 'PWD': '/builds/slave/test', 08:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:01 INFO - 'SHELL': '/bin/bash', 08:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:10:01 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:01 INFO - 'USER': 'cltbld', 08:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:01 INFO - 'XPC_FLAGS': '0x0', 08:10:01 INFO - 'XPC_SERVICE_NAME': '0', 08:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:01 INFO - Downloading/unpacking jsonschema 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:10:03 INFO - Downloading/unpacking functools32 (from jsonschema) 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:03 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 08:10:04 INFO - Downloading functools32-3.2.3-2.tar.gz 08:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:10:04 INFO - warning: no files found matching '*.txt' 08:10:04 INFO - no previously-included directories found matching 'build' 08:10:04 INFO - no previously-included directories found matching 'dist' 08:10:04 INFO - no previously-included directories found matching '.git*' 08:10:04 INFO - Installing collected packages: jsonschema, functools32 08:10:04 INFO - Running setup.py install for functools32 08:10:04 INFO - warning: no files found matching '*.txt' 08:10:04 INFO - no previously-included directories found matching 'build' 08:10:04 INFO - no previously-included directories found matching 'dist' 08:10:04 INFO - no previously-included directories found matching '.git*' 08:10:04 INFO - Successfully installed jsonschema functools32 08:10:04 INFO - Cleaning up... 08:10:04 INFO - Return code: 0 08:10:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:10:04 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 0x10fa45a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa55420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe15949e020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, '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.pghku4EK0F/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.XSavsvdCMk/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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 08:10:04 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 08:10:04 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 08:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:04 INFO - 'HOME': '/Users/cltbld', 08:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:04 INFO - 'LOGNAME': 'cltbld', 08:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:04 INFO - 'MOZ_NO_REMOTE': '1', 08:10:04 INFO - 'NO_EM_RESTART': '1', 08:10:04 INFO - 'PAGER': '/bin/cat', 08:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:04 INFO - 'PWD': '/builds/slave/test', 08:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:04 INFO - 'SHELL': '/bin/bash', 08:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:10:04 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:04 INFO - 'USER': 'cltbld', 08:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:04 INFO - 'XPC_FLAGS': '0x0', 08:10:04 INFO - 'XPC_SERVICE_NAME': '0', 08:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:10:04 INFO - Downloading/unpacking flake8 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:10:07 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:10:07 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:10:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:07 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 08:10:08 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 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 08:10:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:08 INFO - no previously-included directories found matching 'docs/_build' 08:10:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:10:08 INFO - Running setup.py install for pep8 08:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:10:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:10:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:10:08 INFO - no previously-included directories found matching 'docs/_build' 08:10:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:10:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:10:08 INFO - Cleaning up... 08:10:08 INFO - Return code: 0 08:10:08 INFO - Running post-action listener: _resource_record_post_action 08:10:08 INFO - Running post-action listener: _start_resource_monitoring 08:10:09 INFO - Starting resource monitoring. 08:10:09 INFO - ##### 08:10:09 INFO - ##### Running install step. 08:10:09 INFO - ##### 08:10:09 INFO - Running pre-action listener: _resource_record_pre_action 08:10:09 INFO - Running main action method: install 08:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:10:09 INFO - Reading from file tmpfile_stdout 08:10:09 INFO - Detecting whether we're running mozinstall >=1.0... 08:10:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:10:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:10:09 INFO - Reading from file tmpfile_stdout 08:10:09 INFO - Output received: 08:10:09 INFO - Usage: mozinstall [options] installer 08:10:09 INFO - Options: 08:10:09 INFO - -h, --help show this help message and exit 08:10:09 INFO - -d DEST, --destination=DEST 08:10:09 INFO - Directory to install application into. [default: 08:10:09 INFO - "/builds/slave/test"] 08:10:09 INFO - --app=APP Application being installed. [default: firefox] 08:10:09 INFO - mkdir: /builds/slave/test/build/application 08:10:09 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'] 08:10:09 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 08:10:40 INFO - Reading from file tmpfile_stdout 08:10:40 INFO - Output received: 08:10:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:10:40 INFO - Running post-action listener: _resource_record_post_action 08:10:40 INFO - ##### 08:10:40 INFO - ##### Running run-tests step. 08:10:40 INFO - ##### 08:10:40 INFO - Running pre-action listener: _resource_record_pre_action 08:10:40 INFO - Running main action method: run_tests 08:10:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:10:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:10:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:10:40 INFO - 2016-05-30 08:10:40.926 screenresolution[1642:7391] starting screenresolution argv=screenresolution get 08:10:40 INFO - 2016-05-30 08:10:40.929 screenresolution[1642:7391] Display 0: 1600x1200x32@60 08:10:40 INFO - 2016-05-30 08:10:40.934 screenresolution[1643:7393] starting screenresolution argv=screenresolution list 08:10:40 INFO - Available Modes on Display 0 08:10:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:10:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:10:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:10:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:10:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:10:41 INFO - Intel Iris: 08:10:41 INFO - Chipset Model: Intel Iris 08:10:41 INFO - Type: GPU 08:10:41 INFO - Bus: Built-In 08:10:41 INFO - VRAM (Dynamic, Max): 1536 MB 08:10:41 INFO - Vendor: Intel (0x8086) 08:10:41 INFO - Device ID: 0x0a2e 08:10:41 INFO - Revision ID: 0x0009 08:10:41 INFO - Displays: 08:10:41 INFO - Display: 08:10:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:10:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:10:41 INFO - Display Serial Number: 1600x1200 60 08:10:41 INFO - Main Display: Yes 08:10:41 INFO - Mirror: Off 08:10:41 INFO - Online: Yes 08:10:41 INFO - Rotation: Supported 08:10:41 INFO - Return code: 0 08:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:10:41 INFO - Python 2.7.3 08:10:41 INFO - Return code: 0 08:10:41 INFO - grabbing minidump binary from tooltool 08:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:10:41 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 0x10f8e4e00>, '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 0x10f74ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8dfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:10:41 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 08:10:41 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 08:10:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:10:41 INFO - Return code: 0 08:10:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:10:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:10:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:10:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:10:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:10:42 INFO - Return code: 0 08:10:42 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/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 08:10:42 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/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0177 --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 08:10:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.pghku4EK0F/Render', 08:10:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:10:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:10:42 INFO - 'HOME': '/Users/cltbld', 08:10:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:10:42 INFO - 'LOGNAME': 'cltbld', 08:10:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:10:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:10:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:10:42 INFO - 'MOZ_NO_REMOTE': '1', 08:10:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:10:42 INFO - 'NO_EM_RESTART': '1', 08:10:42 INFO - 'PAGER': '/bin/cat', 08:10:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:10:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:10:42 INFO - 'PWD': '/builds/slave/test', 08:10:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:10:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:10:42 INFO - 'SHELL': '/bin/bash', 08:10:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XSavsvdCMk/Listeners', 08:10:42 INFO - 'TMPDIR': '/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/', 08:10:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:10:42 INFO - 'USER': 'cltbld', 08:10:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:10:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:10:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:10:42 INFO - 'XPC_FLAGS': '0x0', 08:10:42 INFO - 'XPC_SERVICE_NAME': '0', 08:10:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:10:42 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/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0177', '--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 08:10:42 INFO - mozversion application_buildid: 20160530035543 08:10:42 INFO - mozversion application_changeset: 04934785596476f6dd8baccc355202aeba59af35 08:10:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:10:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:10:42 INFO - mozversion application_name: Firefox 08:10:42 INFO - mozversion application_remotingname: firefox-dev 08:10:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:42 INFO - mozversion application_vendor: Mozilla 08:10:42 INFO - mozversion application_version: 48.0a2 08:10:42 INFO - mozversion platform_buildid: 20160530035543 08:10:42 INFO - mozversion platform_changeset: 04934785596476f6dd8baccc355202aeba59af35 08:10:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:10:42 INFO - mozversion platform_version: 48.0a2 08:10:42 INFO - using testdate: 1464621042 08:10:42 INFO - actual date: 1464621042 08:10:42 INFO - starting webserver on 'localhost:49273' 08:10:42 INFO - SUITE-START | Running 2 tests 08:10:42 INFO - TEST-START | dromaeo_css 08:10:42 INFO - operating with platform_type : mac_ 08:10:42 INFO - Initialising browser for dromaeo_css test... 08:10:42 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpuSxYN8/profile http://localhost:49273/getInfo.html) 08:10:44 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 08:10:44 INFO - PROCESS | 1661 | colorDepth:24 08:10:44 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 08:10:44 INFO - PROCESS | 1661 | __metrics 08:10:44 INFO - PROCESS | 1661 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:10:44 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:10:45 INFO - TEST-INFO | 1661: exit 0 08:10:45 INFO - Browser initialized. 08:10:45 INFO - Running cycle 1/1 for dromaeo_css test... 08:10:45 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpuSxYN8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:57 INFO - PROCESS | 1663 | RSS: Main: 211025920 08:10:57 INFO - PROCESS | 1663 | 08:11:48 INFO - PROCESS | 1663 | 1464621108952 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] 08:13:59 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 08:14:00 INFO - PROCESS | 1663 | RSS: Main: 342433792 08:14:00 INFO - PROCESS | 1663 | 08:17:03 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 08:17:04 INFO - PROCESS | 1663 | RSS: Main: 353476608 08:17:04 INFO - PROCESS | 1663 | 08:20:06 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 08:20:06 INFO - PROCESS | 1663 | RSS: Main: 358289408 08:20:06 INFO - PROCESS | 1663 | 08:23:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 08:23:09 INFO - PROCESS | 1663 | RSS: Main: 360198144 08:23:09 INFO - PROCESS | 1663 | 08:26:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 08:26:13 INFO - PROCESS | 1663 | RSS: Main: 324579328 08:26:13 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 08:29:16 INFO - PROCESS | 1663 | RSS: Main: 365858816 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | __start_tp_report 08:29:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 08:29:16 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 08:29:16 INFO - PROCESS | 1663 | |i|pagename|runs| 08:29:16 INFO - PROCESS | 1663 | |0;dojo.html;3145.858436370363;3425.2978139481393;3440.088376580208;3445.2592692571093;3495.6679115484035;5637.267155269814;5647.576431767624;5650.576206784493;5654.802081927133;5668.263125793641;5614.017546929292;5666.914996275049;5669.489745922866;5672.886542269152;5687.374388817227;5073.137566613666;5127.48725127488;5204.401493828221;5222.190560463135;5225;5203.55769759569;5213.905079933219;5214;5217.060929032773;5225.2162175673575;4274.29474136767;4499.1901457737595;4926.630502712293;5196.038732834438;5208.713520756357;5218.008578370135;5221.869453263661;5226.686398816083;5227.085260079471;5231.659942103751;5055.671381360236;5066.062778385974;5157.200633901713;5166.5866730661455;5179.834537229131;5523.420040895729;5574.219609254707;5593.52455041322;5594.552435805126;5610.915836262459;6115.571909966304;6119.847003824895;6122.571420000601;6140.171076904606;6145.631262124287;6109.419605137505;6169.7532098716;6186.443220110212;6211.223597050369;6304.211973503312;5803.274590676166;5831.329397119301;5833.300003999547;5844.9415505845245;5867.149263356832;6235.251769787655;6277.466415354655;6292.559520833589;6294.0558916162945;6318.241810982619;5986.2217911671205;6067.86188181536;6070.575059745775;6179.721912513947;6191.876162476725;6240.906386404207;6275.811725648238;6277.78027769035;6286.4342209201395;6330.955392360301;6211.2857021442205;6211.534134939898;6219.502439804804;6232.968835155795;6244.562880598405;6109.192117492145;6115.724792384353;6183.350748171467;6192.473639740582;6244.968775156095;4831.9275210871865;4846.903061938741;4848.369711937426;4895.804167833318;4905.484924082992;4410.448693913261;4423.712458690175;4449.1546606145375;4475.4181956345465;4495.078508905666;5618.129189975561;5624.831255062355;5629.155626656035;5644.74598643062;5646.2377579027125;5044.520770526794;6151.800398922167;6271.404216599416;6273.592216505913;6290.968545157337;5600.915986260209;5607.243022191952;5627.155926610959;5627.43725627434;5633.8309850704545;5325.467453254643;5347.037533243975;5354.437784032621;5355.705436201047;5365.7585408656705;6108.328083910677;6247.531435142473;6247.9062814057825;6251.218597675291;6313.905291420632;5550.972245138749;6265.780697675559;6287.874242515032;6300.810975670737;6329.398707122816;6292.2134733158355;6314.55798094129;6316.936830631818;6317.747290108528;6326.905096423466;6046.727897244723;6113.358097399887;6180.134781130555;6200.534959878118;6214.9689251553455;6244.562880598314;6249.593776404519;6264.123022776906;6269.373062693694;6271.247450305992;5781.364049954585;6077.726502307319;6086.786962456293;6089.269287685506;6105.236845394325;5925;6046.486048685911;6108.51131909455;6112.449879510687;6119.502553941512;5913.852153696192;5928.733207005609;5931.911021334769;5938.940610593839;5945.583809133319;5908.527317814651;6093.817185484442;6109.969450152721;6119.785807496716;6139.938600614116;5774.105013722879;5864.120381942572;5867.20792692981;5956;5963.9105413417965 08:29:16 INFO - PROCESS | 1663 | |1;ext.html;523.8140460136651;536.0672429971848;564.700708624429;570.8896196897036;626.9091780302273;24450.633240501385;31530.211744706336;31547.526787098213;31562;31631;31955.84022079892;41194.382084268684;41918;41940.79029604855;41980.580194198126;9502.477363745009;9672.806543869154;9680.951595242013;9684;9704.563294651722;8660.956695216546;8843.734687959372;9561.235101191924;9702.175315098226;9704.078112579293;8737.60680769367;8806.911930880706;8817.250533704642;11655.941720291428;11835.993940515023;11428.08575313977;11450.713732156679;11451.770964580663;11457.598984035601;11488.712782180428;11512.76974460506;11646.59236926712;11651.951324380847;11655.475503602274;11658.183927125105;32677.673223267702;41364.37953430701;41873.581264187575;41892.79053604743;41914.37128443076;3213.791103578283;3338.315645292709;3361.478970759536;3788.299164654548;3789.147441825567;26512.469750605072;26967.32581685454;27714.307142321602;28470;28853.711462885312;2798.8320700758018;2826.660800703908;2839.7586205172584;2849.985750071257;2888.393437378153;358988;362527;362667;363266;364883.17558412306;36198.19009049537;36553.268934621155;36664.08339791527;36699.633003670155;36734.265314693555;36060.459093113626;36222.45666315007;36364.81817590895;36399;36476.63523364785;15752.68494630101;15957.361705531881;15962.920185398998;15969.840301597067;15971.760423593656;13351;13412.731745365038;13426.395812188475;13596.796048059286;13599.660008499866;7752.186020467882;8304.584770761438;8317.584120793936;8390.244877961013;8418.873716894252;3024.1985873743474;3229.2249860033894;3339.19859233787;3354.4129777288877;3470.514128022079;983.4492684096929;992.7617371830853;1010.3937637417491;1011.049613363436;1138.481990694232;28894.711052889623;30620.54069188964;30712.846435768126;30902.69097309043;31542.684573154434;1088.967330980072;1148.4372657397814;1169.1932566529067;1193.8030225012776;1219.4146809531474;15203.923980380248;15539.766903496457;15715.842841571437;15783.289751961189;15859.682806343808;662650;666617;670481;670571;671041;54045.45954540404;55057.449425506034;61399;61682.38317616766;62175;1474.2997076388629;1476.9926150369179;1485.2128371962876;1491.3065424577624;1498.7826765118996;23837;27590;29369.118926432242;29530.114096577137;30211;51741;51743.741281293354;51992.480075198764;52115.73942130265;52219.73890130525;398950;487600;634844;646784;849395;27378.726212738413;27522;27701.307467312672;27709.445811084068;27730.86134569314;44881.32678009898;47184;47292.52707472881;47480.52519474761;48994;37348;38766.80616597012;38874.80562597169;38890.02774930649;39384.2123157541;35724.64275357317;38310.62153268443;38523.80738096291;38898.22203555857;39512.802435988786;36751.448728269635;38332.50838695476;38348.424773628976;38734.2253154941;39358.803205983786;29323.266918327212;33353.16617084592;33898.661013390534;34135.31729365449;34776.9566912993;22079.006444709707;24868.967649596536;25655.743442565334;25820.612690809277;26128 08:29:16 INFO - PROCESS | 1663 | |2;jquery.html;366.85692579893845;401.07151943251387;403.4956304619226;406.0802282829392;417.1198770594046;18864.5283867903;19608.70586941195;20225.595488090246;20227.797722022777;21193.364199074014;20118.59762804743;20467.48831279219;20774.06516706748;20784.68822967653;20794.48013799652;4933.506649335065;5566.109422492402;5833.095870140135;5896.587238893279;5914.083317085837;5797.072468405056;5814.215080964058;5827.067669172933;5856.355800861902;5862.091375836752;5536.169574563807;5790.7104644767805;5791.942080579182;5810.15752715856;5817.738201780909;5694.088945768678;5718.59969802114;5753.827385178452;5755.079187330049;5759.913601295984;4102.405151253081;5069.974650126763;5070.923936140924;5076.06092872816;5077.26379674945;24515.387115322083;24947.87526062376;25216.369590760194;25254.873725631252;25359.11243106501;2206.9227577034726;2214.966775498369;2268.6937263469385;2452.7424620414777;2609.5302845487804;14381;14591.48929787463;14593.051451655714;14807.629809254748;15061.322240499203;1670.9415170468976;1711.6063305439818;1749.6413235286864;1754.0528114817982;1814.6280012597385;109947.45026274897;112655.43672281668;113630.06884277197;113884.43057784658;114873;10650.786984260272;10958.068564171976;11188;11202.495887685074;11292.209545331747;14283.428662853577;14284.357203925849;14298.714025719428;14302.070365426214;14309.141451512734;8758.781030474289;8916.37585369031;8923.687670931486;9017.954910225408;9025.458472491671;9046.592903319366;9047.638094476148;9063.184313411824;9138.223251023604;9164.770880727901;6625.3043430440075;6767.796966091025;6771.729130834711;6779.661016949133;6796.592204467748;1589.2689362893198;1720.9827901720823;1883.2278765705994;1912.6898074818794;1927.2194761121768;893.4817805672824;967.67099186277;1026.440589878518;1056.9682909512726;1062.5909025025421;19763.60472790536;19927.501812454513;20505.38483845487;20826.32490337057;20999.580008399746;956.9138777510058;986.447589349952;996.7807082441851;1009.4498498318434;1052.0584077250817;11085.612003579836;11156.386398748147;11174.385408802595;11304.886951130547;11313.20807543464;628952;632518;636979;642051;647435;108316;112668;113036;114582.42708786402;116643;1044.2116202267239;1046.3669479964665;1062.8140075486758;1063.0591926145416;1066.1896958311584;17818;17909.910450447664;17990.910045449687;18194.81805181931;18889.90555047216;49913.75043124761;50173;50531;50976;52182.739086304326;71078.64460677664;71869.56260874649;73076.90384644341;73376.89934650878;74748.62625686837;7515.398768098648;7852.214778522102;8135.349172066102;8163.816246644322;8427.241548260687;9097.34831101973;9878.753031174278;12037.337946413032;12212.145149839782;12440.502379904703;11022.614208502664;11164.832527512257;11221.66335009951;11341.603043893754;11358.94320528392;8599.914000859912;10388.961103889551;10547.78904421892;10778.622748204089;11221.102311814871;11037.779244415227;11042.282251653616;11227.719307017063;11250.606228781953;11654.825177622512;7757.224277572198;8074.192580741878;8277.95861020691;8332.916670833456;8456.365772567206;9131.954340228256;9677.854832177381;10944;11074.723131921766;11249.831252531056 08:29:16 INFO - PROCESS | 1663 | |3;mootools.html;1432.9283535823206;1562.4297131546984;1589.9284532196045;1781.1895587507686;1919.539310565464;7699.153093159748;8489.91510084899;8585.699500517467;8593.742187734379;8621.008584012841;8392.118827523098;8441.409101362908;8512.446690965084;8513.702020429286;8526.317894568436;1967.1246295398553;2115.492281852352;2426.9878650606765;2453.092355828346;2455.0793452455327;2429.2347910408166;2464.938376540592;2472.418981539337;2475.269795410349;2475.2697954103583;2397.9958826301577;2417.033186725306;2443.2548072837776;2461.9261422157365;2462.1259452894233;2141.9250326238507;2260.446190683293;2426.8301218914685;2429.757024297574;2444.608862581987;2364.609839376501;2384.594618914771;2385.9045638174625;2390.270967354956;2400.9639855402183;8310.70912518059;8359.373047021438;8363.58182090899;8364.121767214416;8365.623546940342;5201.323827902348;5281.524662780368;5288.2596436498925;5300.867478313035;5303.124984377573;3386.7798593091616;3434.347473979961;3545.9290814183573;3572.3034008368377;3583.6416358364218;1040.1886528507775;1051.9158467322595;1062.102523367753;1065.440643662076;1072.4423299884095;11841.64475065758;11863.347515886622;11892.751261117632;11896.524139034429;11909.10681698876;4157.854475093418;4514.774261286922;4638.281066434641;4793.952060479421;4842.055799119138;9946.850797238047;9962.701118966443;9995.400275983464;10009.799804004026;10046.29675922693;5390.9191362129595;5391.299131113009;5412.702301373462;5428.945710542923;5461.2354270402175;4373.212821692063;4475.597196252353;4820.6143508519235;4856.8785780355065;4928.507149285043;3961.6830653547645;3973.443717879499;4031.959680403217;4059.573744756758;4077.7757223352405;1326.9137506062075;1333.4199623163954;1333.7399207154706;1346.4344975110478;1351.3175846197607;834.7245409015011;840.6133178737726;907.5190149220923;912.6988093928987;913.6208473483563;5558.055130627803;5708.457696518824;5746.798862039808;5828.388019257917;5892.292924849045;958.9136977672043;959.1271942532262;967.7000129959595;980.6959842448864;992.5583115513527;3777.036855601804;3829.80850957451;3856.2865869814173;3880.8835734927998;3930.6462418382484;10321.483925803679;10430.16558675304;10758.354498730101;10776.568937242422;10784.460776961121;10501.794623129075;10627.202959778047;10671.46642667879;10678.62624808128;10687.62593309246;7708.537487750753;7826.921730782733;8098.676052957933;8355.456895301786;8355.874661880076;4733.242681170997;5508.91736623943;5637.46444087811;5671.347795003628;5917.993941029949;8958.641654333753;9104.317176211678;9164.587593558172;9269.351145419756;9282.257419406566;9072.319576031956;9217.631294748135;9223.262139028728;9253.306002049738;9371.718848434557;6730.125083739083;6878.621675807879;7295.2340004298785;7341.70633174667;7460.9927659765235;8001.799955001172;8008.439409241297;8082.313003394542;8086.231807978233;8094.635741391534;7056.223815380202;7171.713131474683;7176.641167941686;7277.9272207277245;7305.926940730525;7047.260037695968;7128.28717128283;7173.426125910019;7189.496735228693;7253.746118885813;6855.828604284933;6920.30796920304;6941.2364639888565;6942.06282151903;6951.096357473698;4668.393108895822;4704.529547045405;4730.432348118248;4852.539008794159;4868.050730107595;4779.235322348408;4841.588464980376;4843.418789745112;4874.049560335842;5048.293238946624 08:29:16 INFO - PROCESS | 1663 | |4;prototype.html;329.49258142460604;333.5280577982155;339.71124544137484;344.58133367957925;349.3536956630236;6593.241777195624;6637.502187335951;6651.102101216335;6652.800415987516;6658.067870498128;5761.654300741948;6041.818745437633;6557.606543607387;6565.573237739543;6586.8682626347445;4695.8356457523905;4752;4793.32893394925;4854.101991131652;4892.241702536113;4809.807607695706;4868.367112275399;4871.4397844248015;4872.683275587093;4887.3402090717655;4786.545278198566;4823.252395878644;4827.927581086286;4846.030793841246;4852.805887764503;4680.391549098613;4694.554017368345;4799.7360145192;4855.441624213226;4887.682300650464;4134.400511925784;4145.461090058273;4150.107726838744;4150.83396664133;4161.875143745692;6376.458001069915;6399.200099987502;6402.48780097591;6414.839629009265;6426.518011149183;4880.389951256093;5731.598788084836;5748.683822389767;5762.078067509178;5768.913466298009;5750.79872204475;6740.325967403221;7072.222055573883;7074;7085;4547.522510136421;4682.789274482622;4735.644826638016;4737.360456338385;4742.668013239075;7406;7449.366803821682;7465.925340746578;7468.103827540675;7471.813204669927;4785.473597904228;4799.760011999386;4802.711837289704;4802.711837289774;4805.447373552016;4863.076015557032;4863.95136048635;4870.70926204549;4872.561469467765;4882.829100981448;5765.538756899438;5768.913466298009;5773.191753154478;5784.71076446176;5784.768609255667;6804.829879253059;7068.0811494506415;7098.219195888448;7120.430365570742;7182.676779544933;1620.7568864670159;1640.7456844189169;1652.9421470248483;1655;1655.0318063932493;979.4417182206074;1029.45953374479;1030.6341248856697;1052.7315534538643;1056.2447849787438;6436.098946147543;6486.35136486357;6579.17760279965;6669.699863506057;6671.299513551007;6235.657038862906;7351.713450146203;7441.962790186014;7452.962735186397;7459.776206713807;6596.538242323088;6618.371254730793;6620.271770105284;6621.933780662228;6629.3370662934285;3870.612938706107;3934.429507721364;3937.291287568265;3944.9408258876138;3945.0531872351003;7452.962735186289;7711.383089352838;7726.7681969541;7735.265149810759;7768.339691126317;7580.54516728998;7690.115636701739;7748.496347737379;7757.883631745528;7758.340541053961;7743.457957943003;7744.41916856238;7748.070231572247;7752.999999999887;7754.534727916343;7189.99340092398;7419.740309089156;7492.9625351875075;7503.024606801081;7512.887306690294;5930.614510056833;7262.455315851226;7346.228645992094;7351.889721654073;7356.705731770883;6809.216940051958;7128.964355178398;7742.496737712256;7750.379969602305;7755.534667919943;4623.237165867728;4677.602403795579;4687.015726697432;4689.366935463669;4690.484046754786;7097.183823860322;7355.852882942419;7369.8157546061775;7382.815429614087;7411.629418528944;7090.574565526085;7122.85754284901;7143.499955003308;7163.677634506564;7172.641367931687;7123.252058533986;7127.394171495273;7128.215896251512;7131.85736285261;7149;6946.305369463013;6953.965230173817;6961.095057642474;6961.89557156633;6964.79105626832;5750.281214848144;6807.115075040213;7070.505064645426;7094.6097964612445;7140.143182818095;6729.1252137221845;6862.970554416677;6932.202796678446;7014.894776578458;7045.859082818418 08:29:16 INFO - PROCESS | 1663 | |5;yui.html;662.2648859765662;705.1256442011907;712.070747674285;729.3763831923703;757.579543353439;13729.45082196711;16546;16882.831171688278;16888.155592220384;16890.831091689062;10865.728356791084;15455.918085734003;15460.613484662888;15552.53342399727;16331.59171020725;763.9083310002808;775.0892701076241;907.9001309855927;932.6782260120282;932.9253659707207;918.3755046568364;922.0042354257386;925.796324808541;928.475411392561;928.8885333759958;966.917811985982;967.090934521551;969.6606187834273;972.6644307713863;977.799551092028;784.831261278825;957.5834511987238;958.6788425877339;959.7456673981366;959.8560215967591;722.6820199112374;722.9349358557755;723.7973367457121;725.1081170160732;731.3783284208433;14886.330115144845;14949.551513454613;14957.102573845495;15004.624884377869;15006.62483437912;12065.33640649764;12184.81722774159;12284.877151228462;12356;12375.93812030943;9895.109440150422;9932.702018939442;10012.199024078056;10050.69847904564;10118.645847395306;3493.336266109431;3519.2961407718303;3546.2552863898613;3588.1567831559564;3703.2778608171416;77275.22724772737;78883.60558197173;79316;79410;79632;8929.73210803677;9364.765880852898;9426.575804088696;9472;9480.146786789222;20867.478313042295;21094.89452552758;21153.576928461345;21186.470338241666;21238.25666101679;7359.6688149033425;7481.916704522267;7513.436492263102;7672.194419585975;7857;4146.771927544014;4151.315033019579;4186.330187170099;4246.023414614594;4375.321825117112;3634.818259087035;3657.4330978698345;3671.1372827385544;3676.7977761222855;3688.5942546319884;586.8180863932195;643.0418676172562;663.0404573788093;664.4285914113858;684.3806355248508;536.5734241278193;553.8310815201322;558.4666643355586;581.8138195777395;604.2597817673314;7948.284654381134;7996.400269979775;8102.797430064295;8235.258826705625;8262.669493220204;535.4163961282221;547.206550501774;565.9632123911932;596.2308621877816;600.6336134957672;3765.5104836371097;3768.397056471006;3788.734788564774;3791.4123310886857;3995.20095980805;70819;83037.75443368354;86051.5697421509;87758.56120719356;88871.55564222137;68527;68638.62722745517;71695;72632;74000.62999684968;62831.37168628255;63255.68372158202;65757.34242657512;66116;68380.97428538477;7652.853698945497;7662.655180516778;7730.497517661334;7751.147373788992;7770.0287464067;47599.762001189774;48730.75634621804;49178;49609.07831836587;49856;64109.358906412206;64394;66063.67872642617;66341.00976970699;66985;10455.529501172618;10589.735256618646;10650.57397704083;11038.779224415306;11053.447327633747;9917;10316.845247321446;11340.773184536429;12213.206141600767;12319;8965.731028069169;10893.455327233449;10959;11008.834867477155;11109.611163609236;11133.498992545194;11389.974902258837;11481.425928703364;11503.769924601294;11753.808967170662;10865.239433239598;10869;10975.890241097486;11147.665570032912;11707.063434925356;9484.573194206416;9569.282303827102;9683.983181766094;9883.11052005323;9887.802243954937;9355.114540248354;9445.952770236105;9496.667616633385;9535.23718102564;9611.711648650551 08:29:16 INFO - PROCESS | 1663 | __end_tp_report 08:29:16 INFO - PROCESS | 1663 | __start_cc_report 08:29:16 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1261 08:29:16 INFO - PROCESS | 1663 | __end_cc_report 08:29:16 INFO - PROCESS | 1663 | __startTimestamp1464622156542__endTimestamp 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | ------- Summary: start ------- 08:29:16 INFO - PROCESS | 1663 | Number of tests: 6 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5699.11 Median:5913.85 stddev:649.94 (11.0%) stddev-sans-first:620.30 08:29:16 INFO - PROCESS | 1663 | Values: 3145.9 3425.3 3440.1 3445.3 3495.7 5637.3 5647.6 5650.6 5654.8 5668.3 5614.0 5666.9 5669.5 5672.9 5687.4 5073.1 5127.5 5204.4 5222.2 5225.0 5203.6 5213.9 5214.0 5217.1 5225.2 4274.3 4499.2 4926.6 5196.0 5208.7 5218.0 5221.9 5226.7 5227.1 5231.7 5055.7 5066.1 5157.2 5166.6 5179.8 5523.4 5574.2 5593.5 5594.6 5610.9 6115.6 6119.8 6122.6 6140.2 6145.6 6109.4 6169.8 6186.4 6211.2 6304.2 5803.3 5831.3 5833.3 5844.9 5867.1 6235.3 6277.5 6292.6 6294.1 6318.2 5986.2 6067.9 6070.6 6179.7 6191.9 6240.9 6275.8 6277.8 6286.4 6331.0 6211.3 6211.5 6219.5 6233.0 6244.6 6109.2 6115.7 6183.4 6192.5 6245.0 4831.9 4846.9 4848.4 4895.8 4905.5 4410.4 4423.7 4449.2 4475.4 4495.1 5618.1 5624.8 5629.2 5644.7 5646.2 5044.5 6151.8 6271.4 6273.6 6291.0 5600.9 5607.2 5627.2 5627.4 5633.8 5325.5 5347.0 5354.4 5355.7 5365.8 6108.3 6247.5 6247.9 6251.2 6313.9 5551.0 6265.8 6287.9 6300.8 6329.4 6292.2 6314.6 6316.9 6317.7 6326.9 6046.7 6113.4 6180.1 6200.5 6215.0 6244.6 6249.6 6264.1 6269.4 6271.2 5781.4 6077.7 6086.8 6089.3 6105.2 5925.0 6046.5 6108.5 6112.4 6119.5 5913.9 5928.7 5931.9 5938.9 5945.6 5908.5 6093.8 6110.0 6119.8 6139.9 5774.1 5864.1 5867.2 5956.0 5963.9 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:66145.68 Median:197356.32 stddev:152661.97 (77.4%) stddev-sans-first:153010.72 08:29:16 INFO - PROCESS | 1663 | Values: 523.8 536.1 564.7 570.9 626.9 24450.6 31530.2 31547.5 31562.0 31631.0 31955.8 41194.4 41918.0 41940.8 41980.6 9502.5 9672.8 9681.0 9684.0 9704.6 8661.0 8843.7 9561.2 9702.2 9704.1 8737.6 8806.9 8817.3 11655.9 11836.0 11428.1 11450.7 11451.8 11457.6 11488.7 11512.8 11646.6 11652.0 11655.5 11658.2 32677.7 41364.4 41873.6 41892.8 41914.4 3213.8 3338.3 3361.5 3788.3 3789.1 26512.5 26967.3 27714.3 28470.0 28853.7 2798.8 2826.7 2839.8 2850.0 2888.4 358988.0 362527.0 362667.0 363266.0 364883.2 36198.2 36553.3 36664.1 36699.6 36734.3 36060.5 36222.5 36364.8 36399.0 36476.6 15752.7 15957.4 15962.9 15969.8 15971.8 13351.0 13412.7 13426.4 13596.8 13599.7 7752.2 8304.6 8317.6 8390.2 8418.9 3024.2 3229.2 3339.2 3354.4 3470.5 983.4 992.8 1010.4 1011.0 1138.5 28894.7 30620.5 30712.8 30902.7 31542.7 1089.0 1148.4 1169.2 1193.8 1219.4 15203.9 15539.8 15715.8 15783.3 15859.7 662650.0 666617.0 670481.0 670571.0 671041.0 54045.5 55057.4 61399.0 61682.4 62175.0 1474.3 1477.0 1485.2 1491.3 1498.8 23837.0 27590.0 29369.1 29530.1 30211.0 51741.0 51743.7 51992.5 52115.7 52219.7 398950.0 487600.0 634844.0 646784.0 849395.0 27378.7 27522.0 27701.3 27709.4 27730.9 44881.3 47184.0 47292.5 47480.5 48994.0 37348.0 38766.8 38874.8 38890.0 39384.2 35724.6 38310.6 38523.8 38898.2 39512.8 36751.4 38332.5 38348.4 38734.2 39358.8 29323.3 33353.2 33898.7 34135.3 34777.0 22079.0 24869.0 25655.7 25820.6 26128.0 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35421.41 Median:2210.94 stddev:105545.51 (4773.8%) stddev-sans-first:105808.77 08:29:16 INFO - PROCESS | 1663 | Values: 366.9 401.1 403.5 406.1 417.1 18864.5 19608.7 20225.6 20227.8 21193.4 20118.6 20467.5 20774.1 20784.7 20794.5 4933.5 5566.1 5833.1 5896.6 5914.1 5797.1 5814.2 5827.1 5856.4 5862.1 5536.2 5790.7 5791.9 5810.2 5817.7 5694.1 5718.6 5753.8 5755.1 5759.9 4102.4 5070.0 5070.9 5076.1 5077.3 24515.4 24947.9 25216.4 25254.9 25359.1 2206.9 2215.0 2268.7 2452.7 2609.5 14381.0 14591.5 14593.1 14807.6 15061.3 1670.9 1711.6 1749.6 1754.1 1814.6 109947.5 112655.4 113630.1 113884.4 114873.0 10650.8 10958.1 11188.0 11202.5 11292.2 14283.4 14284.4 14298.7 14302.1 14309.1 8758.8 8916.4 8923.7 9018.0 9025.5 9046.6 9047.6 9063.2 9138.2 9164.8 6625.3 6767.8 6771.7 6779.7 6796.6 1589.3 1721.0 1883.2 1912.7 1927.2 893.5 967.7 1026.4 1057.0 1062.6 19763.6 19927.5 20505.4 20826.3 20999.6 956.9 986.4 996.8 1009.4 1052.1 11085.6 11156.4 11174.4 11304.9 11313.2 628952.0 632518.0 636979.0 642051.0 647435.0 108316.0 112668.0 113036.0 114582.4 116643.0 1044.2 1046.4 1062.8 1063.1 1066.2 17818.0 17909.9 17990.9 18194.8 18889.9 49913.8 50173.0 50531.0 50976.0 52182.7 71078.6 71869.6 73076.9 73376.9 74748.6 7515.4 7852.2 8135.3 8163.8 8427.2 9097.3 9878.8 12037.3 12212.1 12440.5 11022.6 11164.8 11221.7 11341.6 11358.9 8599.9 10389.0 10547.8 10778.6 11221.1 11037.8 11042.3 11227.7 11250.6 11654.8 7757.2 8074.2 8278.0 8332.9 8456.4 9132.0 9677.9 10944.0 11074.7 11249.8 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5592.43 Median:4901.28 stddev:3098.37 (63.2%) stddev-sans-first:3091.30 08:29:16 INFO - PROCESS | 1663 | Values: 1432.9 1562.4 1589.9 1781.2 1919.5 7699.2 8489.9 8585.7 8593.7 8621.0 8392.1 8441.4 8512.4 8513.7 8526.3 1967.1 2115.5 2427.0 2453.1 2455.1 2429.2 2464.9 2472.4 2475.3 2475.3 2398.0 2417.0 2443.3 2461.9 2462.1 2141.9 2260.4 2426.8 2429.8 2444.6 2364.6 2384.6 2385.9 2390.3 2401.0 8310.7 8359.4 8363.6 8364.1 8365.6 5201.3 5281.5 5288.3 5300.9 5303.1 3386.8 3434.3 3545.9 3572.3 3583.6 1040.2 1051.9 1062.1 1065.4 1072.4 11841.6 11863.3 11892.8 11896.5 11909.1 4157.9 4514.8 4638.3 4794.0 4842.1 9946.9 9962.7 9995.4 10009.8 10046.3 5390.9 5391.3 5412.7 5428.9 5461.2 4373.2 4475.6 4820.6 4856.9 4928.5 3961.7 3973.4 4032.0 4059.6 4077.8 1326.9 1333.4 1333.7 1346.4 1351.3 834.7 840.6 907.5 912.7 913.6 5558.1 5708.5 5746.8 5828.4 5892.3 958.9 959.1 967.7 980.7 992.6 3777.0 3829.8 3856.3 3880.9 3930.6 10321.5 10430.2 10758.4 10776.6 10784.5 10501.8 10627.2 10671.5 10678.6 10687.6 7708.5 7826.9 8098.7 8355.5 8355.9 4733.2 5508.9 5637.5 5671.3 5918.0 8958.6 9104.3 9164.6 9269.4 9282.3 9072.3 9217.6 9223.3 9253.3 9371.7 6730.1 6878.6 7295.2 7341.7 7461.0 8001.8 8008.4 8082.3 8086.2 8094.6 7056.2 7171.7 7176.6 7277.9 7305.9 7047.3 7128.3 7173.4 7189.5 7253.7 6855.8 6920.3 6941.2 6942.1 6951.1 4668.4 4704.5 4730.4 4852.5 4868.1 4779.2 4841.6 4843.4 4874.0 5048.3 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5805.64 Median:6590.06 stddev:1852.89 (28.1%) stddev-sans-first:1811.93 08:29:16 INFO - PROCESS | 1663 | Values: 329.5 333.5 339.7 344.6 349.4 6593.2 6637.5 6651.1 6652.8 6658.1 5761.7 6041.8 6557.6 6565.6 6586.9 4695.8 4752.0 4793.3 4854.1 4892.2 4809.8 4868.4 4871.4 4872.7 4887.3 4786.5 4823.3 4827.9 4846.0 4852.8 4680.4 4694.6 4799.7 4855.4 4887.7 4134.4 4145.5 4150.1 4150.8 4161.9 6376.5 6399.2 6402.5 6414.8 6426.5 4880.4 5731.6 5748.7 5762.1 5768.9 5750.8 6740.3 7072.2 7074.0 7085.0 4547.5 4682.8 4735.6 4737.4 4742.7 7406.0 7449.4 7465.9 7468.1 7471.8 4785.5 4799.8 4802.7 4802.7 4805.4 4863.1 4864.0 4870.7 4872.6 4882.8 5765.5 5768.9 5773.2 5784.7 5784.8 6804.8 7068.1 7098.2 7120.4 7182.7 1620.8 1640.7 1652.9 1655.0 1655.0 979.4 1029.5 1030.6 1052.7 1056.2 6436.1 6486.4 6579.2 6669.7 6671.3 6235.7 7351.7 7442.0 7453.0 7459.8 6596.5 6618.4 6620.3 6621.9 6629.3 3870.6 3934.4 3937.3 3944.9 3945.1 7453.0 7711.4 7726.8 7735.3 7768.3 7580.5 7690.1 7748.5 7757.9 7758.3 7743.5 7744.4 7748.1 7753.0 7754.5 7190.0 7419.7 7493.0 7503.0 7512.9 5930.6 7262.5 7346.2 7351.9 7356.7 6809.2 7129.0 7742.5 7750.4 7755.5 4623.2 4677.6 4687.0 4689.4 4690.5 7097.2 7355.9 7369.8 7382.8 7411.6 7090.6 7122.9 7143.5 7163.7 7172.6 7123.3 7127.4 7128.2 7131.9 7149.0 6946.3 6954.0 6961.1 6961.9 6964.8 5750.3 6807.1 7070.5 7094.6 7140.1 6729.1 6863.0 6932.2 7014.9 7045.9 08:29:16 INFO - PROCESS | 1663 | 08:29:16 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17545.46 Median:32518.52 stddev:24038.17 (73.9%) stddev-sans-first:24072.17 08:29:16 INFO - PROCESS | 1663 | Values: 662.3 705.1 712.1 729.4 757.6 13729.5 16546.0 16882.8 16888.2 16890.8 10865.7 15455.9 15460.6 15552.5 16331.6 763.9 775.1 907.9 932.7 932.9 918.4 922.0 925.8 928.5 928.9 966.9 967.1 969.7 972.7 977.8 784.8 957.6 958.7 959.7 959.9 722.7 722.9 723.8 725.1 731.4 14886.3 14949.6 14957.1 15004.6 15006.6 12065.3 12184.8 12284.9 12356.0 12375.9 9895.1 9932.7 10012.2 10050.7 10118.6 3493.3 3519.3 3546.3 3588.2 3703.3 77275.2 78883.6 79316.0 79410.0 79632.0 8929.7 9364.8 9426.6 9472.0 9480.1 20867.5 21094.9 21153.6 21186.5 21238.3 7359.7 7481.9 7513.4 7672.2 7857.0 4146.8 4151.3 4186.3 4246.0 4375.3 3634.8 3657.4 3671.1 3676.8 3688.6 586.8 643.0 663.0 664.4 684.4 536.6 553.8 558.5 581.8 604.3 7948.3 7996.4 8102.8 8235.3 8262.7 535.4 547.2 566.0 596.2 600.6 3765.5 3768.4 3788.7 3791.4 3995.2 70819.0 83037.8 86051.6 87758.6 88871.6 68527.0 68638.6 71695.0 72632.0 74000.6 62831.4 63255.7 65757.3 66116.0 68381.0 7652.9 7662.7 7730.5 7751.1 7770.0 47599.8 48730.8 49178.0 49609.1 49856.0 64109.4 64394.0 66063.7 66341.0 66985.0 10455.5 10589.7 10650.6 11038.8 11053.4 9917.0 10316.8 11340.8 12213.2 12319.0 8965.7 10893.5 10959.0 11008.8 11109.6 11133.5 11390.0 11481.4 11503.8 11753.8 10865.2 10869.0 10975.9 11147.7 11707.1 9484.6 9569.3 9684.0 9883.1 9887.8 9355.1 9446.0 9496.7 9535.2 9611.7 08:29:16 INFO - PROCESS | 1663 | -------- Summary: end -------- 08:29:16 INFO - PROCESS | 1663 | 08:29:17 INFO - TEST-INFO | 1663: exit 0 08:29:17 INFO - TEST-OK | dromaeo_css | took 1114513ms 08:29:17 INFO - TEST-START | kraken 08:29:17 INFO - operating with platform_type : mac_ 08:29:17 INFO - Initialising browser for kraken test... 08:29:17 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpf5MGbf/profile http://localhost:49273/getInfo.html) 08:29:19 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 08:29:19 INFO - PROCESS | 1673 | colorDepth:24 08:29:19 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 08:29:19 INFO - PROCESS | 1673 | __metrics 08:29:19 INFO - PROCESS | 1673 | 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" 08:29:19 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:29:19 INFO - TEST-INFO | 1673: exit 0 08:29:19 INFO - Browser initialized. 08:29:19 INFO - Running cycle 1/1 for kraken test... 08:29:19 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/T/tmpf5MGbf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:29:31 INFO - PROCESS | 1675 | RSS: Main: 211054592 08:29:31 INFO - PROCESS | 1675 | 08:29:34 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 08:29:35 INFO - PROCESS | 1675 | RSS: Main: 271716352 08:29:35 INFO - PROCESS | 1675 | 08:29:38 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 08:29:38 INFO - PROCESS | 1675 | RSS: Main: 285810688 08:29:38 INFO - PROCESS | 1675 | 08:29:41 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 08:29:42 INFO - PROCESS | 1675 | RSS: Main: 375365632 08:29:42 INFO - PROCESS | 1675 | 08:29:44 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 08:29:44 INFO - PROCESS | 1675 | RSS: Main: 279408640 08:29:44 INFO - PROCESS | 1675 | 08:29:47 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:29:47 INFO - PROCESS | 1675 | RSS: Main: 279932928 08:29:47 INFO - PROCESS | 1675 | 08:29:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 08:29:51 INFO - PROCESS | 1675 | RSS: Main: 318189568 08:29:51 INFO - PROCESS | 1675 | 08:29:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 08:29:54 INFO - PROCESS | 1675 | RSS: Main: 318246912 08:29:54 INFO - PROCESS | 1675 | 08:29:57 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 08:29:58 INFO - PROCESS | 1675 | RSS: Main: 317657088 08:29:58 INFO - PROCESS | 1675 | 08:29:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:30:00 INFO - PROCESS | 1675 | RSS: Main: 309428224 08:30:00 INFO - PROCESS | 1675 | 08:30:02 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:30:02 INFO - PROCESS | 1675 | RSS: Main: 316579840 08:30:02 INFO - PROCESS | 1675 | 08:30:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:30:05 INFO - PROCESS | 1675 | RSS: Main: 318611456 08:30:05 INFO - PROCESS | 1675 | 08:30:08 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:30:09 INFO - PROCESS | 1675 | RSS: Main: 321392640 08:30:09 INFO - PROCESS | 1675 | 08:30:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:30:12 INFO - PROCESS | 1675 | RSS: Main: 321806336 08:30:12 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 08:30:14 INFO - PROCESS | 1675 | RSS: Main: 386097152 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | __start_tp_report 08:30:14 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 08:30:14 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 08:30:14 INFO - PROCESS | 1675 | |i|pagename|runs| 08:30:14 INFO - PROCESS | 1675 | |0;ai-astar;113;112;119;112;112;111;112;114;113;133 08:30:14 INFO - PROCESS | 1675 | |1;audio-beat-detection;157;119;154;155;121;166;148;187;157;118 08:30:14 INFO - PROCESS | 1675 | |2;audio-dft;186;183;187;184;186;183;185;184;187;186 08:30:14 INFO - PROCESS | 1675 | |3;audio-fft;68;80;74;147;56;56;59;65;135;56 08:30:14 INFO - PROCESS | 1675 | |4;audio-oscillator;96;87;142;77;79;89;124;79;77;124 08:30:14 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;86;84;89;83;85;83;86;83;86;83 08:30:14 INFO - PROCESS | 1675 | |6;imaging-darkroom;92;92;92;92;92;110;151;113;92;92 08:30:14 INFO - PROCESS | 1675 | |7;imaging-desaturate;147;109;88;89;71;128;144;99;94;84 08:30:14 INFO - PROCESS | 1675 | |8;json-parse-financial;61;64;63;64;63;64;66;82;61;58 08:30:14 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;64;65;71;63;68;71;74;70;72;69 08:30:14 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;81;81;81;80;81;80;81;78;82;84 08:30:14 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;131;130;129;126;125;126;124;127;185;142 08:30:14 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;165;180;217;166;166;166;163;164;165;164 08:30:14 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;67;66;69;67;67;65;67;68;68;66 08:30:14 INFO - PROCESS | 1675 | __end_tp_report 08:30:14 INFO - PROCESS | 1675 | __start_cc_report 08:30:14 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,1959 08:30:14 INFO - PROCESS | 1675 | __end_cc_report 08:30:14 INFO - PROCESS | 1675 | __startTimestamp1464622214426__endTimestamp 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | ------- Summary: start ------- 08:30:14 INFO - PROCESS | 1675 | Number of tests: 14 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.00 stddev:6.67 (5.9%) stddev-sans-first:7.04 08:30:14 INFO - PROCESS | 1675 | Values: 113.0 112.0 119.0 112.0 112.0 111.0 112.0 114.0 113.0 133.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:156.00 stddev:22.51 (14.4%) stddev-sans-first:23.65 08:30:14 INFO - PROCESS | 1675 | Values: 157.0 119.0 154.0 155.0 121.0 166.0 148.0 187.0 157.0 118.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:185.10 Median:186.00 stddev:1.52 (0.8%) stddev-sans-first:1.58 08:30:14 INFO - PROCESS | 1675 | Values: 186.0 183.0 187.0 184.0 186.0 183.0 185.0 184.0 187.0 186.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:79.60 Median:62.00 stddev:33.48 (54.0%) stddev-sans-first:35.24 08:30:14 INFO - PROCESS | 1675 | Values: 68.0 80.0 74.0 147.0 56.0 56.0 59.0 65.0 135.0 56.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:97.40 Median:79.00 stddev:23.79 (30.1%) stddev-sans-first:25.23 08:30:14 INFO - PROCESS | 1675 | Values: 96.0 87.0 142.0 77.0 79.0 89.0 124.0 79.0 77.0 124.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:85.50 stddev:1.99 (2.3%) stddev-sans-first:2.06 08:30:14 INFO - PROCESS | 1675 | Values: 86.0 84.0 89.0 83.0 85.0 83.0 86.0 83.0 86.0 83.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:101.80 Median:92.00 stddev:19.11 (20.8%) stddev-sans-first:19.93 08:30:14 INFO - PROCESS | 1675 | Values: 92.0 92.0 92.0 92.0 92.0 110.0 151.0 113.0 92.0 92.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:105.30 Median:86.00 stddev:26.09 (30.3%) stddev-sans-first:22.90 08:30:14 INFO - PROCESS | 1675 | Values: 147.0 109.0 88.0 89.0 71.0 128.0 144.0 99.0 94.0 84.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:6.50 (10.2%) stddev-sans-first:6.76 08:30:14 INFO - PROCESS | 1675 | Values: 61.0 64.0 63.0 64.0 63.0 64.0 66.0 82.0 61.0 58.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:70.50 stddev:3.65 (5.2%) stddev-sans-first:3.46 08:30:14 INFO - PROCESS | 1675 | Values: 64.0 65.0 71.0 63.0 68.0 71.0 74.0 70.0 72.0 69.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.52 (1.9%) stddev-sans-first:1.62 08:30:14 INFO - PROCESS | 1675 | Values: 81.0 81.0 81.0 80.0 81.0 80.0 81.0 78.0 82.0 84.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:134.50 Median:129.50 stddev:18.47 (14.3%) stddev-sans-first:19.55 08:30:14 INFO - PROCESS | 1675 | Values: 131.0 130.0 129.0 126.0 125.0 126.0 124.0 127.0 185.0 142.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:166.00 stddev:16.67 (10.0%) stddev-sans-first:17.51 08:30:14 INFO - PROCESS | 1675 | Values: 165.0 180.0 217.0 166.0 166.0 166.0 163.0 164.0 165.0 164.0 08:30:14 INFO - PROCESS | 1675 | 08:30:14 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:1.15 (1.7%) stddev-sans-first:1.22 08:30:14 INFO - PROCESS | 1675 | Values: 67.0 66.0 69.0 67.0 67.0 65.0 67.0 68.0 68.0 66.0 08:30:14 INFO - PROCESS | 1675 | -------- Summary: end -------- 08:30:14 INFO - PROCESS | 1675 | 08:30:15 INFO - TEST-INFO | 1675: exit 0 08:30:15 INFO - TEST-OK | kraken | took 58233ms 08:30:15 INFO - SUITE-END | took 1172s 08:30:15 INFO - Completed test suite (00:19:33) 08:30:15 INFO - javascript benchmark 08:30:15 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3145.858436370363, 3425.2978139481393, 3440.088376580208, 3445.2592692571093, 3495.6679115484035, 5637.267155269814, 5647.576431767624, 5650.576206784493, 5654.802081927133, 5668.263125793641, 5614.017546929292, 5666.914996275049, 5669.489745922866, 5672.886542269152, 5687.374388817227, 5073.137566613666, 5127.48725127488, 5204.401493828221, 5222.190560463135, 5225.0, 5203.55769759569, 5213.905079933219, 5214.0, 5217.060929032773, 5225.2162175673575, 4274.29474136767, 4499.1901457737595, 4926.630502712293, 5196.038732834438, 5208.713520756357, 5218.008578370135, 5221.869453263661, 5226.686398816083, 5227.085260079471, 5231.659942103751, 5055.671381360236, 5066.062778385974, 5157.200633901713, 5166.5866730661455, 5179.834537229131, 5523.420040895729, 5574.219609254707, 5593.52455041322, 5594.552435805126, 5610.915836262459, 6115.571909966304, 6119.847003824895, 6122.571420000601, 6140.171076904606, 6145.631262124287, 6109.419605137505, 6169.7532098716, 6186.443220110212, 6211.223597050369, 6304.211973503312, 5803.274590676166, 5831.329397119301, 5833.300003999547, 5844.9415505845245, 5867.149263356832, 6235.251769787655, 6277.466415354655, 6292.559520833589, 6294.0558916162945, 6318.241810982619, 5986.2217911671205, 6067.86188181536, 6070.575059745775, 6179.721912513947, 6191.876162476725, 6240.906386404207, 6275.811725648238, 6277.78027769035, 6286.4342209201395, 6330.955392360301, 6211.2857021442205, 6211.534134939898, 6219.502439804804, 6232.968835155795, 6244.562880598405, 6109.192117492145, 6115.724792384353, 6183.350748171467, 6192.473639740582, 6244.968775156095, 4831.9275210871865, 4846.903061938741, 4848.369711937426, 4895.804167833318, 4905.484924082992, 4410.448693913261, 4423.712458690175, 4449.1546606145375, 4475.4181956345465, 4495.078508905666, 5618.129189975561, 5624.831255062355, 5629.155626656035, 5644.74598643062, 5646.2377579027125, 5044.520770526794, 6151.800398922167, 6271.404216599416, 6273.592216505913, 6290.968545157337, 5600.915986260209, 5607.243022191952, 5627.155926610959, 5627.43725627434, 5633.8309850704545, 5325.467453254643, 5347.037533243975, 5354.437784032621, 5355.705436201047, 5365.7585408656705, 6108.328083910677, 6247.531435142473, 6247.9062814057825, 6251.218597675291, 6313.905291420632, 5550.972245138749, 6265.780697675559, 6287.874242515032, 6300.810975670737, 6329.398707122816, 6292.2134733158355, 6314.55798094129, 6316.936830631818, 6317.747290108528, 6326.905096423466, 6046.727897244723, 6113.358097399887, 6180.134781130555, 6200.534959878118, 6214.9689251553455, 6244.562880598314, 6249.593776404519, 6264.123022776906, 6269.373062693694, 6271.247450305992, 5781.364049954585, 6077.726502307319, 6086.786962456293, 6089.269287685506, 6105.236845394325, 5925.0, 6046.486048685911, 6108.51131909455, 6112.449879510687, 6119.502553941512, 5913.852153696192, 5928.733207005609, 5931.911021334769, 5938.940610593839, 5945.583809133319, 5908.527317814651, 6093.817185484442, 6109.969450152721, 6119.785807496716, 6139.938600614116, 5774.105013722879, 5864.120381942572, 5867.20792692981, 5956.0, 5963.9105413417965], "value": 5657.49955010988, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [523.8140460136651, 536.0672429971848, 564.700708624429, 570.8896196897036, 626.9091780302273, 24450.633240501385, 31530.211744706336, 31547.526787098213, 31562.0, 31631.0, 31955.84022079892, 41194.382084268684, 41918.0, 41940.79029604855, 41980.580194198126, 9502.477363745009, 9672.806543869154, 9680.951595242013, 9684.0, 9704.563294651722, 8660.956695216546, 8843.734687959372, 9561.235101191924, 9702.175315098226, 9704.078112579293, 8737.60680769367, 8806.911930880706, 8817.250533704642, 11655.941720291428, 11835.993940515023, 11428.08575313977, 11450.713732156679, 11451.770964580663, 11457.598984035601, 11488.712782180428, 11512.76974460506, 11646.59236926712, 11651.951324380847, 11655.475503602274, 11658.183927125105, 32677.673223267702, 41364.37953430701, 41873.581264187575, 41892.79053604743, 41914.37128443076, 3213.791103578283, 3338.315645292709, 3361.478970759536, 3788.299164654548, 3789.147441825567, 26512.469750605072, 26967.32581685454, 27714.307142321602, 28470.0, 28853.711462885312, 2798.8320700758018, 2826.660800703908, 2839.7586205172584, 2849.985750071257, 2888.393437378153, 358988.0, 362527.0, 362667.0, 363266.0, 364883.17558412306, 36198.19009049537, 36553.268934621155, 36664.08339791527, 36699.633003670155, 36734.265314693555, 36060.459093113626, 36222.45666315007, 36364.81817590895, 36399.0, 36476.63523364785, 15752.68494630101, 15957.361705531881, 15962.920185398998, 15969.840301597067, 15971.760423593656, 13351.0, 13412.731745365038, 13426.395812188475, 13596.796048059286, 13599.660008499866, 7752.186020467882, 8304.584770761438, 8317.584120793936, 8390.244877961013, 8418.873716894252, 3024.1985873743474, 3229.2249860033894, 3339.19859233787, 3354.4129777288877, 3470.514128022079, 983.4492684096929, 992.7617371830853, 1010.3937637417491, 1011.049613363436, 1138.481990694232, 28894.711052889623, 30620.54069188964, 30712.846435768126, 30902.69097309043, 31542.684573154434, 1088.967330980072, 1148.4372657397814, 1169.1932566529067, 1193.8030225012776, 1219.4146809531474, 15203.923980380248, 15539.766903496457, 15715.842841571437, 15783.289751961189, 15859.682806343808, 662650.0, 666617.0, 670481.0, 670571.0, 671041.0, 54045.45954540404, 55057.449425506034, 61399.0, 61682.38317616766, 62175.0, 1474.2997076388629, 1476.9926150369179, 1485.2128371962876, 1491.3065424577624, 1498.7826765118996, 23837.0, 27590.0, 29369.118926432242, 29530.114096577137, 30211.0, 51741.0, 51743.741281293354, 51992.480075198764, 52115.73942130265, 52219.73890130525, 398950.0, 487600.0, 634844.0, 646784.0, 849395.0, 27378.726212738413, 27522.0, 27701.307467312672, 27709.445811084068, 27730.86134569314, 44881.32678009898, 47184.0, 47292.52707472881, 47480.52519474761, 48994.0, 37348.0, 38766.80616597012, 38874.80562597169, 38890.02774930649, 39384.2123157541, 35724.64275357317, 38310.62153268443, 38523.80738096291, 38898.22203555857, 39512.802435988786, 36751.448728269635, 38332.50838695476, 38348.424773628976, 38734.2253154941, 39358.803205983786, 29323.266918327212, 33353.16617084592, 33898.661013390534, 34135.31729365449, 34776.9566912993, 22079.006444709707, 24868.967649596536, 25655.743442565334, 25820.612690809277, 26128.0], "value": 18534.28277884813, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.85692579893845, 401.07151943251387, 403.4956304619226, 406.0802282829392, 417.1198770594046, 18864.5283867903, 19608.70586941195, 20225.595488090246, 20227.797722022777, 21193.364199074014, 20118.59762804743, 20467.48831279219, 20774.06516706748, 20784.68822967653, 20794.48013799652, 4933.506649335065, 5566.109422492402, 5833.095870140135, 5896.587238893279, 5914.083317085837, 5797.072468405056, 5814.215080964058, 5827.067669172933, 5856.355800861902, 5862.091375836752, 5536.169574563807, 5790.7104644767805, 5791.942080579182, 5810.15752715856, 5817.738201780909, 5694.088945768678, 5718.59969802114, 5753.827385178452, 5755.079187330049, 5759.913601295984, 4102.405151253081, 5069.974650126763, 5070.923936140924, 5076.06092872816, 5077.26379674945, 24515.387115322083, 24947.87526062376, 25216.369590760194, 25254.873725631252, 25359.11243106501, 2206.9227577034726, 2214.966775498369, 2268.6937263469385, 2452.7424620414777, 2609.5302845487804, 14381.0, 14591.48929787463, 14593.051451655714, 14807.629809254748, 15061.322240499203, 1670.9415170468976, 1711.6063305439818, 1749.6413235286864, 1754.0528114817982, 1814.6280012597385, 109947.45026274897, 112655.43672281668, 113630.06884277197, 113884.43057784658, 114873.0, 10650.786984260272, 10958.068564171976, 11188.0, 11202.495887685074, 11292.209545331747, 14283.428662853577, 14284.357203925849, 14298.714025719428, 14302.070365426214, 14309.141451512734, 8758.781030474289, 8916.37585369031, 8923.687670931486, 9017.954910225408, 9025.458472491671, 9046.592903319366, 9047.638094476148, 9063.184313411824, 9138.223251023604, 9164.770880727901, 6625.3043430440075, 6767.796966091025, 6771.729130834711, 6779.661016949133, 6796.592204467748, 1589.2689362893198, 1720.9827901720823, 1883.2278765705994, 1912.6898074818794, 1927.2194761121768, 893.4817805672824, 967.67099186277, 1026.440589878518, 1056.9682909512726, 1062.5909025025421, 19763.60472790536, 19927.501812454513, 20505.38483845487, 20826.32490337057, 20999.580008399746, 956.9138777510058, 986.447589349952, 996.7807082441851, 1009.4498498318434, 1052.0584077250817, 11085.612003579836, 11156.386398748147, 11174.385408802595, 11304.886951130547, 11313.20807543464, 628952.0, 632518.0, 636979.0, 642051.0, 647435.0, 108316.0, 112668.0, 113036.0, 114582.42708786402, 116643.0, 1044.2116202267239, 1046.3669479964665, 1062.8140075486758, 1063.0591926145416, 1066.1896958311584, 17818.0, 17909.910450447664, 17990.910045449687, 18194.81805181931, 18889.90555047216, 49913.75043124761, 50173.0, 50531.0, 50976.0, 52182.739086304326, 71078.64460677664, 71869.56260874649, 73076.90384644341, 73376.89934650878, 74748.62625686837, 7515.398768098648, 7852.214778522102, 8135.349172066102, 8163.816246644322, 8427.241548260687, 9097.34831101973, 9878.753031174278, 12037.337946413032, 12212.145149839782, 12440.502379904703, 11022.614208502664, 11164.832527512257, 11221.66335009951, 11341.603043893754, 11358.94320528392, 8599.914000859912, 10388.961103889551, 10547.78904421892, 10778.622748204089, 11221.102311814871, 11037.779244415227, 11042.282251653616, 11227.719307017063, 11250.606228781953, 11654.825177622512, 7757.224277572198, 8074.192580741878, 8277.95861020691, 8332.916670833456, 8456.365772567206, 9131.954340228256, 9677.854832177381, 10944.0, 11074.723131921766, 11249.831252531056], "value": 9642.195571393435, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1432.9283535823206, 1562.4297131546984, 1589.9284532196045, 1781.1895587507686, 1919.539310565464, 7699.153093159748, 8489.91510084899, 8585.699500517467, 8593.742187734379, 8621.008584012841, 8392.118827523098, 8441.409101362908, 8512.446690965084, 8513.702020429286, 8526.317894568436, 1967.1246295398553, 2115.492281852352, 2426.9878650606765, 2453.092355828346, 2455.0793452455327, 2429.2347910408166, 2464.938376540592, 2472.418981539337, 2475.269795410349, 2475.2697954103583, 2397.9958826301577, 2417.033186725306, 2443.2548072837776, 2461.9261422157365, 2462.1259452894233, 2141.9250326238507, 2260.446190683293, 2426.8301218914685, 2429.757024297574, 2444.608862581987, 2364.609839376501, 2384.594618914771, 2385.9045638174625, 2390.270967354956, 2400.9639855402183, 8310.70912518059, 8359.373047021438, 8363.58182090899, 8364.121767214416, 8365.623546940342, 5201.323827902348, 5281.524662780368, 5288.2596436498925, 5300.867478313035, 5303.124984377573, 3386.7798593091616, 3434.347473979961, 3545.9290814183573, 3572.3034008368377, 3583.6416358364218, 1040.1886528507775, 1051.9158467322595, 1062.102523367753, 1065.440643662076, 1072.4423299884095, 11841.64475065758, 11863.347515886622, 11892.751261117632, 11896.524139034429, 11909.10681698876, 4157.854475093418, 4514.774261286922, 4638.281066434641, 4793.952060479421, 4842.055799119138, 9946.850797238047, 9962.701118966443, 9995.400275983464, 10009.799804004026, 10046.29675922693, 5390.9191362129595, 5391.299131113009, 5412.702301373462, 5428.945710542923, 5461.2354270402175, 4373.212821692063, 4475.597196252353, 4820.6143508519235, 4856.8785780355065, 4928.507149285043, 3961.6830653547645, 3973.443717879499, 4031.959680403217, 4059.573744756758, 4077.7757223352405, 1326.9137506062075, 1333.4199623163954, 1333.7399207154706, 1346.4344975110478, 1351.3175846197607, 834.7245409015011, 840.6133178737726, 907.5190149220923, 912.6988093928987, 913.6208473483563, 5558.055130627803, 5708.457696518824, 5746.798862039808, 5828.388019257917, 5892.292924849045, 958.9136977672043, 959.1271942532262, 967.7000129959595, 980.6959842448864, 992.5583115513527, 3777.036855601804, 3829.80850957451, 3856.2865869814173, 3880.8835734927998, 3930.6462418382484, 10321.483925803679, 10430.16558675304, 10758.354498730101, 10776.568937242422, 10784.460776961121, 10501.794623129075, 10627.202959778047, 10671.46642667879, 10678.62624808128, 10687.62593309246, 7708.537487750753, 7826.921730782733, 8098.676052957933, 8355.456895301786, 8355.874661880076, 4733.242681170997, 5508.91736623943, 5637.46444087811, 5671.347795003628, 5917.993941029949, 8958.641654333753, 9104.317176211678, 9164.587593558172, 9269.351145419756, 9282.257419406566, 9072.319576031956, 9217.631294748135, 9223.262139028728, 9253.306002049738, 9371.718848434557, 6730.125083739083, 6878.621675807879, 7295.2340004298785, 7341.70633174667, 7460.9927659765235, 8001.799955001172, 8008.439409241297, 8082.313003394542, 8086.231807978233, 8094.635741391534, 7056.223815380202, 7171.713131474683, 7176.641167941686, 7277.9272207277245, 7305.926940730525, 7047.260037695968, 7128.28717128283, 7173.426125910019, 7189.496735228693, 7253.746118885813, 6855.828604284933, 6920.30796920304, 6941.2364639888565, 6942.06282151903, 6951.096357473698, 4668.393108895822, 4704.529547045405, 4730.432348118248, 4852.539008794159, 4868.050730107595, 4779.235322348408, 4841.588464980376, 4843.418789745112, 4874.049560335842, 5048.293238946624], "value": 4531.601160191207, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [329.49258142460604, 333.5280577982155, 339.71124544137484, 344.58133367957925, 349.3536956630236, 6593.241777195624, 6637.502187335951, 6651.102101216335, 6652.800415987516, 6658.067870498128, 5761.654300741948, 6041.818745437633, 6557.606543607387, 6565.573237739543, 6586.8682626347445, 4695.8356457523905, 4752.0, 4793.32893394925, 4854.101991131652, 4892.241702536113, 4809.807607695706, 4868.367112275399, 4871.4397844248015, 4872.683275587093, 4887.3402090717655, 4786.545278198566, 4823.252395878644, 4827.927581086286, 4846.030793841246, 4852.805887764503, 4680.391549098613, 4694.554017368345, 4799.7360145192, 4855.441624213226, 4887.682300650464, 4134.400511925784, 4145.461090058273, 4150.107726838744, 4150.83396664133, 4161.875143745692, 6376.458001069915, 6399.200099987502, 6402.48780097591, 6414.839629009265, 6426.518011149183, 4880.389951256093, 5731.598788084836, 5748.683822389767, 5762.078067509178, 5768.913466298009, 5750.79872204475, 6740.325967403221, 7072.222055573883, 7074.0, 7085.0, 4547.522510136421, 4682.789274482622, 4735.644826638016, 4737.360456338385, 4742.668013239075, 7406.0, 7449.366803821682, 7465.925340746578, 7468.103827540675, 7471.813204669927, 4785.473597904228, 4799.760011999386, 4802.711837289704, 4802.711837289774, 4805.447373552016, 4863.076015557032, 4863.95136048635, 4870.70926204549, 4872.561469467765, 4882.829100981448, 5765.538756899438, 5768.913466298009, 5773.191753154478, 5784.71076446176, 5784.768609255667, 6804.829879253059, 7068.0811494506415, 7098.219195888448, 7120.430365570742, 7182.676779544933, 1620.7568864670159, 1640.7456844189169, 1652.9421470248483, 1655.0, 1655.0318063932493, 979.4417182206074, 1029.45953374479, 1030.6341248856697, 1052.7315534538643, 1056.2447849787438, 6436.098946147543, 6486.35136486357, 6579.17760279965, 6669.699863506057, 6671.299513551007, 6235.657038862906, 7351.713450146203, 7441.962790186014, 7452.962735186397, 7459.776206713807, 6596.538242323088, 6618.371254730793, 6620.271770105284, 6621.933780662228, 6629.3370662934285, 3870.612938706107, 3934.429507721364, 3937.291287568265, 3944.9408258876138, 3945.0531872351003, 7452.962735186289, 7711.383089352838, 7726.7681969541, 7735.265149810759, 7768.339691126317, 7580.54516728998, 7690.115636701739, 7748.496347737379, 7757.883631745528, 7758.340541053961, 7743.457957943003, 7744.41916856238, 7748.070231572247, 7752.999999999887, 7754.534727916343, 7189.99340092398, 7419.740309089156, 7492.9625351875075, 7503.024606801081, 7512.887306690294, 5930.614510056833, 7262.455315851226, 7346.228645992094, 7351.889721654073, 7356.705731770883, 6809.216940051958, 7128.964355178398, 7742.496737712256, 7750.379969602305, 7755.534667919943, 4623.237165867728, 4677.602403795579, 4687.015726697432, 4689.366935463669, 4690.484046754786, 7097.183823860322, 7355.852882942419, 7369.8157546061775, 7382.815429614087, 7411.629418528944, 7090.574565526085, 7122.85754284901, 7143.499955003308, 7163.677634506564, 7172.641367931687, 7123.252058533986, 7127.394171495273, 7128.215896251512, 7131.85736285261, 7149.0, 6946.305369463013, 6953.965230173817, 6961.095057642474, 6961.89557156633, 6964.79105626832, 5750.281214848144, 6807.115075040213, 7070.505064645426, 7094.6097964612445, 7140.143182818095, 6729.1252137221845, 6862.970554416677, 6932.202796678446, 7014.894776578458, 7045.859082818418], "value": 5184.822182049038, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [662.2648859765662, 705.1256442011907, 712.070747674285, 729.3763831923703, 757.579543353439, 13729.45082196711, 16546.0, 16882.831171688278, 16888.155592220384, 16890.831091689062, 10865.728356791084, 15455.918085734003, 15460.613484662888, 15552.53342399727, 16331.59171020725, 763.9083310002808, 775.0892701076241, 907.9001309855927, 932.6782260120282, 932.9253659707207, 918.3755046568364, 922.0042354257386, 925.796324808541, 928.475411392561, 928.8885333759958, 966.917811985982, 967.090934521551, 969.6606187834273, 972.6644307713863, 977.799551092028, 784.831261278825, 957.5834511987238, 958.6788425877339, 959.7456673981366, 959.8560215967591, 722.6820199112374, 722.9349358557755, 723.7973367457121, 725.1081170160732, 731.3783284208433, 14886.330115144845, 14949.551513454613, 14957.102573845495, 15004.624884377869, 15006.62483437912, 12065.33640649764, 12184.81722774159, 12284.877151228462, 12356.0, 12375.93812030943, 9895.109440150422, 9932.702018939442, 10012.199024078056, 10050.69847904564, 10118.645847395306, 3493.336266109431, 3519.2961407718303, 3546.2552863898613, 3588.1567831559564, 3703.2778608171416, 77275.22724772737, 78883.60558197173, 79316.0, 79410.0, 79632.0, 8929.73210803677, 9364.765880852898, 9426.575804088696, 9472.0, 9480.146786789222, 20867.478313042295, 21094.89452552758, 21153.576928461345, 21186.470338241666, 21238.25666101679, 7359.6688149033425, 7481.916704522267, 7513.436492263102, 7672.194419585975, 7857.0, 4146.771927544014, 4151.315033019579, 4186.330187170099, 4246.023414614594, 4375.321825117112, 3634.818259087035, 3657.4330978698345, 3671.1372827385544, 3676.7977761222855, 3688.5942546319884, 586.8180863932195, 643.0418676172562, 663.0404573788093, 664.4285914113858, 684.3806355248508, 536.5734241278193, 553.8310815201322, 558.4666643355586, 581.8138195777395, 604.2597817673314, 7948.284654381134, 7996.400269979775, 8102.797430064295, 8235.258826705625, 8262.669493220204, 535.4163961282221, 547.206550501774, 565.9632123911932, 596.2308621877816, 600.6336134957672, 3765.5104836371097, 3768.397056471006, 3788.734788564774, 3791.4123310886857, 3995.20095980805, 70819.0, 83037.75443368354, 86051.5697421509, 87758.56120719356, 88871.55564222137, 68527.0, 68638.62722745517, 71695.0, 72632.0, 74000.62999684968, 62831.37168628255, 63255.68372158202, 65757.34242657512, 66116.0, 68380.97428538477, 7652.853698945497, 7662.655180516778, 7730.497517661334, 7751.147373788992, 7770.0287464067, 47599.762001189774, 48730.75634621804, 49178.0, 49609.07831836587, 49856.0, 64109.358906412206, 64394.0, 66063.67872642617, 66341.00976970699, 66985.0, 10455.529501172618, 10589.735256618646, 10650.57397704083, 11038.779224415306, 11053.447327633747, 9917.0, 10316.845247321446, 11340.773184536429, 12213.206141600767, 12319.0, 8965.731028069169, 10893.455327233449, 10959.0, 11008.834867477155, 11109.611163609236, 11133.498992545194, 11389.974902258837, 11481.425928703364, 11503.769924601294, 11753.808967170662, 10865.239433239598, 10869.0, 10975.890241097486, 11147.665570032912, 11707.063434925356, 9484.573194206416, 9569.282303827102, 9683.983181766094, 9883.11052005323, 9887.802243954937, 9355.114540248354, 9445.952770236105, 9496.667616633385, 9535.23718102564, 9611.711648650551], "value": 6810.857054820874, "unit": "score"}], "name": "dromaeo_css", "value": 7381.872270141979, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 119.0, 112.0, 112.0, 111.0, 112.0, 114.0, 113.0, 133.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 119.0, 154.0, 155.0, 121.0, 166.0, 148.0, 187.0, 157.0, 118.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 187.0, 184.0, 186.0, 183.0, 185.0, 184.0, 187.0, 186.0], "value": 185.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 80.0, 74.0, 147.0, 56.0, 56.0, 59.0, 65.0, 135.0, 56.0], "value": 79.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 87.0, 142.0, 77.0, 79.0, 89.0, 124.0, 79.0, 77.0, 124.0], "value": 97.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 89.0, 83.0, 85.0, 83.0, 86.0, 83.0, 86.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 92.0, 92.0, 110.0, 151.0, 113.0, 92.0, 92.0], "value": 101.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 88.0, 89.0, 71.0, 128.0, 144.0, 99.0, 94.0, 84.0], "value": 105.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 63.0, 64.0, 63.0, 64.0, 66.0, 82.0, 61.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 71.0, 63.0, 68.0, 71.0, 74.0, 70.0, 72.0, 69.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 80.0, 81.0, 80.0, 81.0, 78.0, 82.0, 84.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 130.0, 129.0, 126.0, 125.0, 126.0, 124.0, 127.0, 185.0, 142.0], "value": 134.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 180.0, 217.0, 166.0, 166.0, 166.0, 163.0, 164.0, 165.0, 164.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 67.0, 67.0, 65.0, 67.0, 68.0, 68.0, 66.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1504.6, "alertThreshold": 2.0}]} 08:30:15 INFO - Return code: 0 08:30:15 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:30:15 ERROR - # TBPL SUCCESS # 08:30:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:30:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:30:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:30:15 INFO - 2016-05-30 08:30:15.733 screenresolution[1680:9663] starting screenresolution argv=screenresolution get 08:30:15 INFO - 2016-05-30 08:30:15.735 screenresolution[1680:9663] Display 0: 1600x1200x32@60 08:30:15 INFO - 2016-05-30 08:30:15.741 screenresolution[1681:9666] starting screenresolution argv=screenresolution list 08:30:15 INFO - Available Modes on Display 0 08:30:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:30:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:30:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:30:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:30:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:30:15 INFO - Intel Iris: 08:30:15 INFO - Chipset Model: Intel Iris 08:30:15 INFO - Type: GPU 08:30:15 INFO - Bus: Built-In 08:30:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:30:15 INFO - Vendor: Intel (0x8086) 08:30:15 INFO - Device ID: 0x0a2e 08:30:15 INFO - Revision ID: 0x0009 08:30:15 INFO - Displays: 08:30:15 INFO - Display: 08:30:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:30:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:30:15 INFO - Display Serial Number: 1600x1200 60 08:30:15 INFO - Main Display: Yes 08:30:15 INFO - Mirror: Off 08:30:15 INFO - Online: Yes 08:30:15 INFO - Rotation: Supported 08:30:15 INFO - Return code: 0 08:30:15 INFO - Running post-action listener: _resource_record_post_action 08:30:15 INFO - Running post-run listener: _resource_record_post_run 08:30:17 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 37897728; Write bytes: 651261440; Read time: 390; Write time: 1879 08:30:17 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345315840; Write bytes: 345954304; Read time: 26882; Write time: 655 08:30:17 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 36943360; Write bytes: 295349760; Read time: 376; Write time: 1194 08:30:17 INFO - Running post-run listener: _upload_blobber_files 08:30:17 INFO - Blob upload gear active. 08:30:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:30:17 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 08:30:17 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'] 08:30:17 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 08:30:17 INFO - (blobuploader) - INFO - Open directory for files ... 08:30:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:30:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:19 INFO - (blobuploader) - INFO - Done attempting. 08:30:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:30:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:30:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:30:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:30:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:30:19 INFO - (blobuploader) - INFO - Done attempting. 08:30:19 INFO - (blobuploader) - INFO - Iteration through files over. 08:30:19 INFO - Return code: 0 08:30:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:30:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:30:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e711e25429de1f814945215259c19a986804de56feaf4daa6e781f01c02241093b1054a0a70a6cf3bb7072411a4ea90119b8dd419e0634d1c0f52f72a87f86f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df7c253729a41a57109e6f092e57b83f9e5a03be632e8d8d5ec03522f576178a3f5dae00077049b492dad0dd7d1035c9f037dba2969a1737488e8d4d2fd0a9ec"} 08:30:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:30:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:30:19 INFO - Contents: 08:30:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8e711e25429de1f814945215259c19a986804de56feaf4daa6e781f01c02241093b1054a0a70a6cf3bb7072411a4ea90119b8dd419e0634d1c0f52f72a87f86f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df7c253729a41a57109e6f092e57b83f9e5a03be632e8d8d5ec03522f576178a3f5dae00077049b492dad0dd7d1035c9f037dba2969a1737488e8d4d2fd0a9ec"} 08:30:19 INFO - Running post-run listener: copy_logs_to_upload_dir 08:30:19 INFO - Copying logs to upload dir... 08:30:19 INFO - mkdir: /builds/slave/test/build/upload/logs 08:30:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.265401 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-30 08:30:20.010434) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:20.014538) ========= 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.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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/8e711e25429de1f814945215259c19a986804de56feaf4daa6e781f01c02241093b1054a0a70a6cf3bb7072411a4ea90119b8dd419e0634d1c0f52f72a87f86f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df7c253729a41a57109e6f092e57b83f9e5a03be632e8d8d5ec03522f576178a3f5dae00077049b492dad0dd7d1035c9f037dba2969a1737488e8d4d2fd0a9ec"} build_url:https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011537 build_url: 'https://queue.taskcluster.net/v1/task/eAhkcfpqSpmaq-FV_-pdbw/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/8e711e25429de1f814945215259c19a986804de56feaf4daa6e781f01c02241093b1054a0a70a6cf3bb7072411a4ea90119b8dd419e0634d1c0f52f72a87f86f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/df7c253729a41a57109e6f092e57b83f9e5a03be632e8d8d5ec03522f576178a3f5dae00077049b492dad0dd7d1035c9f037dba2969a1737488e8d4d2fd0a9ec"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:20.045417) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:20.045747) ========= 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.pghku4EK0F/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.XSavsvdCMk/Listeners TMPDIR=/var/folders/6k/fq_g2pq17rxc93pg__4q5kx800000w/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.005330 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:30:20.095503) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:30:20.095855) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:30:20.096206) ========= ========= Total master_lag: 0.16 =========