builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0333 starttime: 1464626693.36 results: success (0) buildid: 20160530074337 builduid: 8a9fc6d750a2412ca82a5108db4ad8cb revision: 9cc06cd6038eed9f12df93428b8f02fb6478f3ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.355362) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.355793) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.356113) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.376187) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.376568) ========= 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.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 09:44:53-- 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% 140M=0s 2016-05-30 09:44:53 (140 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121740 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.517704) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.518029) ========= 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.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.020799 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:53.566377) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 09:44:53.566722) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9cc06cd6038eed9f12df93428b8f02fb6478f3ef --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 9cc06cd6038eed9f12df93428b8f02fb6478f3ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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 09:44:53,651 truncating revision to first 12 chars 2016-05-30 09:44:53,652 Setting DEBUG logging. 2016-05-30 09:44:53,652 attempt 1/10 2016-05-30 09:44:53,652 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9cc06cd6038e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 09:44:54,900 unpacking tar archive at: mozilla-aurora-9cc06cd6038e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.517758 ========= master_lag: 1.23 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 09:44:56.311780) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:56.312125) ========= script_repo_revision: 9cc06cd6038eed9f12df93428b8f02fb6478f3ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:56.312532) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:56.312796) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 09:44:56.346875) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-30 09:44:56.347244) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:44:56 INFO - MultiFileLogger online at 20160530 09:44:56 in /builds/slave/test 09:44:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:44:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:44:56 INFO - {'append_to_log': False, 09:44:56 INFO - 'base_work_dir': '/builds/slave/test', 09:44:56 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:44:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:44:56 INFO - 'branch': 'Mozilla-Aurora', 09:44:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:44:56 INFO - 'config_files': ('talos/mac_config.py',), 09:44:56 INFO - 'default_actions': ('clobber', 09:44:56 INFO - 'read-buildbot-config', 09:44:56 INFO - 'download-and-extract', 09:44:56 INFO - 'populate-webroot', 09:44:56 INFO - 'create-virtualenv', 09:44:56 INFO - 'install', 09:44:56 INFO - 'run-tests'), 09:44:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:44:56 INFO - 'download_minidump_stackwalk': True, 09:44:56 INFO - 'download_symbols': 'ondemand', 09:44:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:44:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:44:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:44:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:44:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:44:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:44:56 INFO - 'installer_path': 'installer.exe', 09:44:56 INFO - 'log_level': 'info', 09:44:56 INFO - 'log_name': 'talos', 09:44:56 INFO - 'log_to_console': True, 09:44:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:44:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:44:56 INFO - 'opt_config_files': (), 09:44:56 INFO - 'pip_index': False, 09:44:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:56 INFO - 'cmd': ('bash', 09:44:56 INFO - '-c', 09:44:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:56 INFO - 'enabled': True, 09:44:56 INFO - 'halt_on_failure': False, 09:44:56 INFO - 'name': 'check_screen_resolution'},), 09:44:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:44:56 INFO - 'cmd': ('bash', 09:44:56 INFO - '-c', 09:44:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:44:56 INFO - 'enabled': True, 09:44:56 INFO - 'halt_on_failure': False, 09:44:56 INFO - 'name': 'check_screen_resolution'},), 09:44:56 INFO - 'run_cmd_checks_enabled': True, 09:44:56 INFO - 'sps_profile': False, 09:44:56 INFO - 'sps_profile_interval': 0, 09:44:56 INFO - 'suite': 'dromaeojs-e10s', 09:44:56 INFO - 'system_bits': '32', 09:44:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:44:56 INFO - 'title': 't-yosemite-r7-0333', 09:44:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:44:56 INFO - 'use_talos_json': True, 09:44:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:44:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:44:56 INFO - 'work_dir': 'build'} 09:44:56 INFO - ##### 09:44:56 INFO - ##### Running clobber step. 09:44:56 INFO - ##### 09:44:56 INFO - Running pre-action listener: _resource_record_pre_action 09:44:56 INFO - Running main action method: clobber 09:44:56 INFO - rmtree: /builds/slave/test/build 09:44:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:44:57 INFO - Running post-action listener: _resource_record_post_action 09:44:57 INFO - ##### 09:44:57 INFO - ##### Running read-buildbot-config step. 09:44:57 INFO - ##### 09:44:57 INFO - Running pre-action listener: _resource_record_pre_action 09:44:57 INFO - Running main action method: read_buildbot_config 09:44:57 INFO - Using buildbot properties: 09:44:57 INFO - { 09:44:57 INFO - "project": "", 09:44:57 INFO - "product": "firefox", 09:44:57 INFO - "script_repo_revision": "production", 09:44:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:44:57 INFO - "repository": "", 09:44:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:44:57 INFO - "buildid": "20160530074337", 09:44:57 INFO - "pgo_build": "False", 09:44:57 INFO - "basedir": "/builds/slave/test", 09:44:57 INFO - "buildnumber": 27, 09:44:57 INFO - "slavename": "t-yosemite-r7-0333", 09:44:57 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:44:57 INFO - "platform": "yosemite_r7", 09:44:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:44:57 INFO - "branch": "mozilla-aurora", 09:44:57 INFO - "repo_path": "releases/mozilla-aurora", 09:44:57 INFO - "revision": "9cc06cd6038eed9f12df93428b8f02fb6478f3ef", 09:44:57 INFO - "stage_platform": "macosx64", 09:44:57 INFO - "builduid": "8a9fc6d750a2412ca82a5108db4ad8cb", 09:44:57 INFO - "slavebuilddir": "test" 09:44:57 INFO - } 09:44:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 09:44:57 INFO - Running post-action listener: _resource_record_post_action 09:44:57 INFO - ##### 09:44:57 INFO - ##### Running download-and-extract step. 09:44:57 INFO - ##### 09:44:57 INFO - Running pre-action listener: _resource_record_pre_action 09:44:57 INFO - Running main action method: download_and_extract 09:44:57 INFO - mkdir: /builds/slave/test/build/tests 09:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:57 INFO - https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:44:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 09:44:58 INFO - Downloaded 1199 bytes. 09:44:58 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 09:44:58 INFO - Using the following test package requirements: 09:44:58 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:44:58 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 09:44:58 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 09:44:58 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'jsshell-mac.zip'], 09:44:58 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 09:44:58 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 09:44:58 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 09:44:58 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 09:44:58 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 09:44:58 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 09:44:58 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 09:44:58 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:44:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:58 INFO - https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:44:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:44:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:44:59 INFO - Downloaded 18425610 bytes. 09:44:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:44:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:45:02 INFO - Return code: 0 09:45:02 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:45:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:02 INFO - https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:45:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:45:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:45:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 09:45:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:45:03 INFO - Downloaded 18425610 bytes. 09:45:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:45:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 09:45:07 INFO - Return code: 0 09:45:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:07 INFO - https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:45:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:45:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:45:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 09:45:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:45:08 INFO - Downloaded 10840145 bytes. 09:45:08 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:45:08 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 09:45:08 INFO - Return code: 0 09:45:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:08 INFO - https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 09:45:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 09:45:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 09:45:11 INFO - Downloaded 114106097 bytes. 09:45:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:11 INFO - mkdir: /builds/slave/test/properties 09:45:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:45:11 INFO - Writing to file /builds/slave/test/properties/build_url 09:45:11 INFO - Contents: 09:45:11 INFO - build_url:https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 09:45:13 INFO - Running post-action listener: _resource_record_post_action 09:45:13 INFO - Running post-action listener: set_extra_try_arguments 09:45:13 INFO - ##### 09:45:13 INFO - ##### Running populate-webroot step. 09:45:13 INFO - ##### 09:45:13 INFO - Running pre-action listener: _resource_record_pre_action 09:45:13 INFO - Running main action method: populate_webroot 09:45:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:45:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:45:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:45:13 INFO - '--tppagecycles', 09:45:13 INFO - '7'], 09:45:13 INFO - 'tests': ['tsvgm']}}, 09:45:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:45:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:45:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:45:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:45:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:45:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['damp', 'tps']}, 09:45:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'tests': ['damp', 'tps']}, 09:45:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['dromaeo_dom']}, 09:45:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:45:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'other-e10s': {'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['a11yr', 09:45:13 INFO - 'ts_paint', 09:45:13 INFO - 'tpaint', 09:45:13 INFO - 'sessionrestore', 09:45:13 INFO - 'sessionrestore_no_auto_restore', 09:45:13 INFO - 'tabpaint']}, 09:45:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['tsvgx', 09:45:13 INFO - 'tsvgr_opacity', 09:45:13 INFO - 'tart', 09:45:13 INFO - 'tscrollx', 09:45:13 INFO - 'cart']}, 09:45:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:45:13 INFO - 'tsvgr_opacity', 09:45:13 INFO - 'tart', 09:45:13 INFO - 'tscrollx', 09:45:13 INFO - 'cart']}, 09:45:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'talos_options': ['--disable-e10s'], 09:45:13 INFO - 'tests': ['tp5o']}, 09:45:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'tests': ['tp5o']}, 09:45:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'talos_options': ['--disable-e10s', 09:45:13 INFO - '--xperf_path', 09:45:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:13 INFO - 'tests': ['tp5n']}, 09:45:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:45:13 INFO - 'talos_options': ['--xperf_path', 09:45:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:45:13 INFO - 'tests': ['tp5n']}}, 09:45:13 INFO - 'talos.zip': {'path': '', 09:45:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:45:13 INFO - Running post-action listener: _resource_record_post_action 09:45:13 INFO - ##### 09:45:13 INFO - ##### Running create-virtualenv step. 09:45:13 INFO - ##### 09:45:13 INFO - Running pre-action listener: _resource_record_pre_action 09:45:13 INFO - Running main action method: create_virtualenv 09:45:13 INFO - Creating virtualenv /builds/slave/test/build/venv 09:45:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:45:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:45:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:45:13 INFO - Using real prefix '/tools/python27' 09:45:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:45:14 INFO - Installing distribute.............................................................................................................................................................................................done. 09:45:17 INFO - Installing pip.................done. 09:45:17 INFO - Return code: 0 09:45:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:17 INFO - 'HOME': '/Users/cltbld', 09:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:17 INFO - 'LOGNAME': 'cltbld', 09:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:17 INFO - 'MOZ_NO_REMOTE': '1', 09:45:17 INFO - 'NO_EM_RESTART': '1', 09:45:17 INFO - 'PAGER': '/bin/cat', 09:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:17 INFO - 'PWD': '/builds/slave/test', 09:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:17 INFO - 'SHELL': '/bin/bash', 09:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:17 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:17 INFO - 'USER': 'cltbld', 09:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:17 INFO - 'XPC_FLAGS': '0x0', 09:45:17 INFO - 'XPC_SERVICE_NAME': '0', 09:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:45:17 INFO - Cleaning up... 09:45:17 INFO - Return code: 0 09:45:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 09:45:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 09:45:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:17 INFO - 'HOME': '/Users/cltbld', 09:45:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:17 INFO - 'LOGNAME': 'cltbld', 09:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:17 INFO - 'MOZ_NO_REMOTE': '1', 09:45:17 INFO - 'NO_EM_RESTART': '1', 09:45:17 INFO - 'PAGER': '/bin/cat', 09:45:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:17 INFO - 'PWD': '/builds/slave/test', 09:45:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:17 INFO - 'SHELL': '/bin/bash', 09:45:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:17 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:17 INFO - 'USER': 'cltbld', 09:45:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:17 INFO - 'XPC_FLAGS': '0x0', 09:45:17 INFO - 'XPC_SERVICE_NAME': '0', 09:45:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:17 INFO - Downloading/unpacking psutil>=0.7.1 09:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:45:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:20 INFO - Installing collected packages: psutil 09:45:20 INFO - Running setup.py install for psutil 09:45:20 INFO - building 'psutil._psutil_osx' extension 09:45:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:45:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:45:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:45:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:45:20 INFO - building 'psutil._psutil_posix' extension 09:45:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:45:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:45:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:45:21 INFO - ^ 09:45:21 INFO - 1 warning generated. 09:45:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:45:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:45:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:45:21 INFO - Successfully installed psutil 09:45:21 INFO - Cleaning up... 09:45:21 INFO - Return code: 0 09:45:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:21 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:45:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:21 INFO - 'HOME': '/Users/cltbld', 09:45:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:21 INFO - 'LOGNAME': 'cltbld', 09:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:21 INFO - 'MOZ_NO_REMOTE': '1', 09:45:21 INFO - 'NO_EM_RESTART': '1', 09:45:21 INFO - 'PAGER': '/bin/cat', 09:45:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:21 INFO - 'PWD': '/builds/slave/test', 09:45:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:21 INFO - 'SHELL': '/bin/bash', 09:45:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:21 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:21 INFO - 'USER': 'cltbld', 09:45:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:21 INFO - 'XPC_FLAGS': '0x0', 09:45:21 INFO - 'XPC_SERVICE_NAME': '0', 09:45:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:21 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:45:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:45:24 INFO - Installing collected packages: mozsystemmonitor 09:45:24 INFO - Running setup.py install for mozsystemmonitor 09:45:24 INFO - Successfully installed mozsystemmonitor 09:45:24 INFO - Cleaning up... 09:45:24 INFO - Return code: 0 09:45:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:24 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:45:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:24 INFO - 'HOME': '/Users/cltbld', 09:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:24 INFO - 'LOGNAME': 'cltbld', 09:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:24 INFO - 'MOZ_NO_REMOTE': '1', 09:45:24 INFO - 'NO_EM_RESTART': '1', 09:45:24 INFO - 'PAGER': '/bin/cat', 09:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:24 INFO - 'PWD': '/builds/slave/test', 09:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:24 INFO - 'SHELL': '/bin/bash', 09:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:24 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:24 INFO - 'USER': 'cltbld', 09:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:24 INFO - 'XPC_FLAGS': '0x0', 09:45:24 INFO - 'XPC_SERVICE_NAME': '0', 09:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:24 INFO - Downloading/unpacking blobuploader==1.2.4 09:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:26 INFO - Downloading blobuploader-1.2.4.tar.gz 09:45:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:45:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:45:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:28 INFO - Downloading docopt-0.6.1.tar.gz 09:45:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:45:28 INFO - Installing collected packages: blobuploader, requests, docopt 09:45:28 INFO - Running setup.py install for blobuploader 09:45:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:45:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:45:28 INFO - Running setup.py install for requests 09:45:28 INFO - Running setup.py install for docopt 09:45:28 INFO - Successfully installed blobuploader requests docopt 09:45:28 INFO - Cleaning up... 09:45:29 INFO - Return code: 0 09:45:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45: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')]} 09:45:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45: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')]} 09:45:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:29 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:45:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:45:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:29 INFO - 'HOME': '/Users/cltbld', 09:45:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:29 INFO - 'LOGNAME': 'cltbld', 09:45:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:29 INFO - 'MOZ_NO_REMOTE': '1', 09:45:29 INFO - 'NO_EM_RESTART': '1', 09:45:29 INFO - 'PAGER': '/bin/cat', 09:45:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:29 INFO - 'PWD': '/builds/slave/test', 09:45:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:29 INFO - 'SHELL': '/bin/bash', 09:45:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:29 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:29 INFO - 'USER': 'cltbld', 09:45:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:29 INFO - 'XPC_FLAGS': '0x0', 09:45:29 INFO - 'XPC_SERVICE_NAME': '0', 09:45:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-pD0Ye3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-DwjRzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-SxJM5G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-USAoo6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-aukbH5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-O8sKEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:29 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-14In9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-NXygDY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-ALGie8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-lTdiDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-0KFOiz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-bRIRFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-o5_53K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-SSdVLp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-tocz0d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-oNTWgl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:30 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9faEv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:45:31 INFO - Running setup.py install for manifestparser 09:45:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:45:31 INFO - Running setup.py install for mozcrash 09:45:31 INFO - Running setup.py install for mozdebug 09:45:31 INFO - Running setup.py install for mozdevice 09:45:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:45:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:45:31 INFO - Running setup.py install for mozfile 09:45:31 INFO - Running setup.py install for mozhttpd 09:45:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:45:31 INFO - Running setup.py install for mozinfo 09:45:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Running setup.py install for mozInstall 09:45:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Running setup.py install for mozleak 09:45:32 INFO - Running setup.py install for mozlog 09:45:32 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Running setup.py install for moznetwork 09:45:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Running setup.py install for mozprocess 09:45:32 INFO - Running setup.py install for mozprofile 09:45:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:45:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:45:33 INFO - Running setup.py install for mozrunner 09:45:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:45:33 INFO - Running setup.py install for mozscreenshot 09:45:33 INFO - Running setup.py install for moztest 09:45:33 INFO - Running setup.py install for mozversion 09:45:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:45:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:45:33 INFO - Cleaning up... 09:45:33 INFO - Return code: 0 09:45:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:33 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:45:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:45:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:33 INFO - 'HOME': '/Users/cltbld', 09:45:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:33 INFO - 'LOGNAME': 'cltbld', 09:45:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:33 INFO - 'MOZ_NO_REMOTE': '1', 09:45:33 INFO - 'NO_EM_RESTART': '1', 09:45:33 INFO - 'PAGER': '/bin/cat', 09:45:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:33 INFO - 'PWD': '/builds/slave/test', 09:45:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:33 INFO - 'SHELL': '/bin/bash', 09:45:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:33 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:33 INFO - 'USER': 'cltbld', 09:45:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:33 INFO - 'XPC_FLAGS': '0x0', 09:45:33 INFO - 'XPC_SERVICE_NAME': '0', 09:45:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:45:33 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-PPiEQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-MlBoGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-9fJMsN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-i1mMh0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-wKSPl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Bg103W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-h6qwAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-Gtlnat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-GTnYzQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:45:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:45:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:45:34 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-oNSfoT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-mNvH_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-7knabc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-i0gPLy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-QXxZI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-htzcky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-VTmVur-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:45:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:45:35 INFO - Running setup.py (path:/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/pip-WUTPJT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:45:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:45:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:38 INFO - Downloading blessings-1.6.tar.gz 09:45:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:45:38 INFO - Installing collected packages: blessings 09:45:38 INFO - Running setup.py install for blessings 09:45:38 INFO - Successfully installed blessings 09:45:38 INFO - Cleaning up... 09:45:38 INFO - Return code: 0 09:45:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:45:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:38 INFO - Reading from file tmpfile_stdout 09:45:38 INFO - Current package versions: 09:45:38 INFO - blessings == 1.6 09:45:38 INFO - blobuploader == 1.2.4 09:45:38 INFO - docopt == 0.6.1 09:45:38 INFO - manifestparser == 1.1 09:45:38 INFO - mozInstall == 1.12 09:45:38 INFO - mozcrash == 0.17 09:45:38 INFO - mozdebug == 0.1 09:45:38 INFO - mozdevice == 0.48 09:45:38 INFO - mozfile == 1.2 09:45:38 INFO - mozhttpd == 0.7 09:45:38 INFO - mozinfo == 0.9 09:45:38 INFO - mozleak == 0.1 09:45:38 INFO - mozlog == 3.1 09:45:38 INFO - moznetwork == 0.27 09:45:38 INFO - mozprocess == 0.22 09:45:38 INFO - mozprofile == 0.28 09:45:38 INFO - mozrunner == 6.11 09:45:38 INFO - mozscreenshot == 0.1 09:45:38 INFO - mozsystemmonitor == 0.0 09:45:38 INFO - moztest == 0.7 09:45:38 INFO - mozversion == 1.4 09:45:38 INFO - psutil == 3.1.1 09:45:38 INFO - requests == 1.2.3 09:45:38 INFO - wsgiref == 0.1.2 09:45:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:38 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:45:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:38 INFO - 'HOME': '/Users/cltbld', 09:45:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:38 INFO - 'LOGNAME': 'cltbld', 09:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:38 INFO - 'MOZ_NO_REMOTE': '1', 09:45:38 INFO - 'NO_EM_RESTART': '1', 09:45:38 INFO - 'PAGER': '/bin/cat', 09:45:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:38 INFO - 'PWD': '/builds/slave/test', 09:45:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:38 INFO - 'SHELL': '/bin/bash', 09:45:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:38 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:38 INFO - 'USER': 'cltbld', 09:45:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:38 INFO - 'XPC_FLAGS': '0x0', 09:45:38 INFO - 'XPC_SERVICE_NAME': '0', 09:45:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:45:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:45:39 INFO - Cleaning up... 09:45:39 INFO - Return code: 0 09:45:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:39 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 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:45:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:45:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:39 INFO - 'HOME': '/Users/cltbld', 09:45:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:39 INFO - 'LOGNAME': 'cltbld', 09:45:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:39 INFO - 'MOZ_NO_REMOTE': '1', 09:45:39 INFO - 'NO_EM_RESTART': '1', 09:45:39 INFO - 'PAGER': '/bin/cat', 09:45:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:39 INFO - 'PWD': '/builds/slave/test', 09:45:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:39 INFO - 'SHELL': '/bin/bash', 09:45:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:39 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:39 INFO - 'USER': 'cltbld', 09:45:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:39 INFO - 'XPC_FLAGS': '0x0', 09:45:39 INFO - 'XPC_SERVICE_NAME': '0', 09:45:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:39 INFO - Downloading/unpacking jsonschema 09:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:45:41 INFO - Downloading/unpacking functools32 (from jsonschema) 09:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:42 INFO - Downloading functools32-3.2.3-2.tar.gz 09:45:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:45:42 INFO - warning: no files found matching '*.txt' 09:45:42 INFO - no previously-included directories found matching 'build' 09:45:42 INFO - no previously-included directories found matching 'dist' 09:45:42 INFO - no previously-included directories found matching '.git*' 09:45:42 INFO - Installing collected packages: jsonschema, functools32 09:45:42 INFO - Running setup.py install for functools32 09:45:42 INFO - warning: no files found matching '*.txt' 09:45:42 INFO - no previously-included directories found matching 'build' 09:45:42 INFO - no previously-included directories found matching 'dist' 09:45:42 INFO - no previously-included directories found matching '.git*' 09:45:42 INFO - Successfully installed jsonschema functools32 09:45:42 INFO - Cleaning up... 09:45:42 INFO - Return code: 0 09:45:42 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:45:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:45:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:45:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:45:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102e40a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102e50420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc3d347dc90>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, '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.zbX4B1gKqR/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.NBcM7VlewD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:45:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:45:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:45:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:45:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:45:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:45:42 INFO - 'HOME': '/Users/cltbld', 09:45:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:45:42 INFO - 'LOGNAME': 'cltbld', 09:45:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:45:42 INFO - 'MOZ_NO_REMOTE': '1', 09:45:42 INFO - 'NO_EM_RESTART': '1', 09:45:42 INFO - 'PAGER': '/bin/cat', 09:45:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:45:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:45:42 INFO - 'PWD': '/builds/slave/test', 09:45:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:45:42 INFO - 'SHELL': '/bin/bash', 09:45:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:45:42 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:45:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:45:42 INFO - 'USER': 'cltbld', 09:45:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:45:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:45:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:45:42 INFO - 'XPC_FLAGS': '0x0', 09:45:42 INFO - 'XPC_SERVICE_NAME': '0', 09:45:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:45:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:45:42 INFO - Downloading/unpacking flake8 09:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:45:45 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:45:45 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:45:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:45:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:45:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:45:46 INFO - no previously-included directories found matching 'docs/_build' 09:45:46 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:45:46 INFO - Running setup.py install for pep8 09:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:45:46 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:45:46 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:45:46 INFO - no previously-included directories found matching 'docs/_build' 09:45:46 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:45:46 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:45:46 INFO - Cleaning up... 09:45:46 INFO - Return code: 0 09:45:46 INFO - Running post-action listener: _resource_record_post_action 09:45:46 INFO - Running post-action listener: _start_resource_monitoring 09:45:46 INFO - Starting resource monitoring. 09:45:46 INFO - ##### 09:45:46 INFO - ##### Running install step. 09:45:46 INFO - ##### 09:45:46 INFO - Running pre-action listener: _resource_record_pre_action 09:45:46 INFO - Running main action method: install 09:45:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:45:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:45:47 INFO - Reading from file tmpfile_stdout 09:45:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:45:47 INFO - Reading from file tmpfile_stdout 09:45:47 INFO - Output received: 09:45:47 INFO - Usage: mozinstall [options] installer 09:45:47 INFO - Options: 09:45:47 INFO - -h, --help show this help message and exit 09:45:47 INFO - -d DEST, --destination=DEST 09:45:47 INFO - Directory to install application into. [default: 09:45:47 INFO - "/builds/slave/test"] 09:45:47 INFO - --app=APP Application being installed. [default: firefox] 09:45:47 INFO - mkdir: /builds/slave/test/build/application 09:45:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:45:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:46:18 INFO - Reading from file tmpfile_stdout 09:46:18 INFO - Output received: 09:46:18 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:46:18 INFO - Running post-action listener: _resource_record_post_action 09:46:18 INFO - ##### 09:46:18 INFO - ##### Running run-tests step. 09:46:18 INFO - ##### 09:46:18 INFO - Running pre-action listener: _resource_record_pre_action 09:46:18 INFO - Running main action method: run_tests 09:46:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:46:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:46:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:46:18 INFO - 2016-05-30 09:46:18.541 screenresolution[2327:19881] starting screenresolution argv=screenresolution get 09:46:18 INFO - 2016-05-30 09:46:18.543 screenresolution[2327:19881] Display 0: 1600x1200x32@60 09:46:18 INFO - 2016-05-30 09:46:18.550 screenresolution[2328:19885] starting screenresolution argv=screenresolution list 09:46:18 INFO - Available Modes on Display 0 09:46:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:46:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:46:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:46:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:46:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:46:18 INFO - Intel Iris: 09:46:18 INFO - Chipset Model: Intel Iris 09:46:18 INFO - Type: GPU 09:46:18 INFO - Bus: Built-In 09:46:18 INFO - VRAM (Dynamic, Max): 1536 MB 09:46:18 INFO - Vendor: Intel (0x8086) 09:46:18 INFO - Device ID: 0x0a2e 09:46:18 INFO - Revision ID: 0x0009 09:46:18 INFO - Displays: 09:46:18 INFO - Display: 09:46:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:46:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:46:18 INFO - Display Serial Number: 1600x1200 60 09:46:18 INFO - Main Display: Yes 09:46:18 INFO - Mirror: Off 09:46:18 INFO - Online: Yes 09:46:18 INFO - Rotation: Supported 09:46:18 INFO - Return code: 0 09:46:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:46:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:46:18 INFO - Python 2.7.3 09:46:18 INFO - Return code: 0 09:46:18 INFO - grabbing minidump binary from tooltool 09:46: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')]} 09:46:18 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 0x102cdfe00>, '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 0x102b48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cdab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:46:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:46:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:46:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:46:18 INFO - Return code: 0 09:46:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:46:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:46:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:46:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:46:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:46:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:46:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:46:20 INFO - Return code: 0 09:46:20 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-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:46:20 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-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0333 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:46:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zbX4B1gKqR/Render', 09:46:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:46:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:46:20 INFO - 'HOME': '/Users/cltbld', 09:46:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:46:20 INFO - 'LOGNAME': 'cltbld', 09:46:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:46:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:46:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:46:20 INFO - 'MOZ_NO_REMOTE': '1', 09:46:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:46:20 INFO - 'NO_EM_RESTART': '1', 09:46:20 INFO - 'PAGER': '/bin/cat', 09:46:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:46:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:46:20 INFO - 'PWD': '/builds/slave/test', 09:46:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:46:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:46:20 INFO - 'SHELL': '/bin/bash', 09:46:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NBcM7VlewD/Listeners', 09:46:20 INFO - 'TMPDIR': '/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/', 09:46:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:46:20 INFO - 'USER': 'cltbld', 09:46:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:46:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:46:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:46:20 INFO - 'XPC_FLAGS': '0x0', 09:46:20 INFO - 'XPC_SERVICE_NAME': '0', 09:46:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:46:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0333', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:46:20 INFO - mozversion application_buildid: 20160530074337 09:46:20 INFO - mozversion application_changeset: 9cc06cd6038eed9f12df93428b8f02fb6478f3ef 09:46:20 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:46:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:46:20 INFO - mozversion application_name: Firefox 09:46:20 INFO - mozversion application_remotingname: firefox-dev 09:46:20 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:46:20 INFO - mozversion application_vendor: Mozilla 09:46:20 INFO - mozversion application_version: 48.0a2 09:46:20 INFO - mozversion platform_buildid: 20160530074337 09:46:20 INFO - mozversion platform_changeset: 9cc06cd6038eed9f12df93428b8f02fb6478f3ef 09:46:20 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:46:20 INFO - mozversion platform_version: 48.0a2 09:46:20 INFO - using testdate: 1464626780 09:46:20 INFO - actual date: 1464626780 09:46:20 INFO - starting webserver on 'localhost:51785' 09:46:20 INFO - SUITE-START | Running 2 tests 09:46:20 INFO - TEST-START | dromaeo_css 09:46:20 INFO - operating with platform_type : mac_ 09:46:20 INFO - Initialising browser for dromaeo_css test... 09:46:20 INFO - TEST-INFO | started process 2346 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp3KV7bw/profile http://localhost:51785/getInfo.html) 09:46:23 INFO - PROCESS | 2346 | __metrics Screen width/height:1600/1200 09:46:23 INFO - PROCESS | 2346 | colorDepth:24 09:46:23 INFO - PROCESS | 2346 | Browser inner width/height: 1024/689 09:46:23 INFO - PROCESS | 2346 | __metrics 09:46:23 ERROR - PROCESS | 2346 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 09:46:23 INFO - TEST-INFO | 2346: exit 0 09:46:23 INFO - Browser initialized. 09:46:23 INFO - Running cycle 1/1 for dromaeo_css test... 09:46:23 INFO - TEST-INFO | started process 2349 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmp3KV7bw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:46:35 INFO - PROCESS | 2349 | RSS: Main: 221261824 09:46:35 INFO - PROCESS | 2349 | 09:47:25 INFO - PROCESS | 2349 | 1464626845576 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:49:36 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51785/tests/dromaeo/cssquery-ext.html) 09:49:37 INFO - PROCESS | 2349 | RSS: Main: 231084032 09:49:37 INFO - PROCESS | 2349 | 09:52:39 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-ext.html (next: http://localhost:51785/tests/dromaeo/cssquery-jquery.html) 09:52:40 INFO - PROCESS | 2349 | RSS: Main: 243396608 09:52:40 INFO - PROCESS | 2349 | 09:55:42 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51785/tests/dromaeo/cssquery-mootools.html) 09:55:42 INFO - PROCESS | 2349 | RSS: Main: 245432320 09:55:42 INFO - PROCESS | 2349 | 09:58:44 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51785/tests/dromaeo/cssquery-prototype.html) 09:58:45 INFO - PROCESS | 2349 | RSS: Main: 247279616 09:58:45 INFO - PROCESS | 2349 | 10:01:47 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51785/tests/dromaeo/cssquery-yui.html) 10:01:48 INFO - PROCESS | 2349 | RSS: Main: 250068992 10:01:48 INFO - PROCESS | 2349 | 10:04:50 INFO - PROCESS | 2349 | Cycle 1(1): loaded http://localhost:51785/tests/dromaeo/cssquery-yui.html (next: http://localhost:51785/tests/dromaeo/cssquery-dojo.html) 10:04:51 INFO - PROCESS | 2349 | RSS: Main: 250974208 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | __start_tp_report 10:04:51 INFO - PROCESS | 2349 | _x_x_mozilla_page_load 10:04:51 INFO - PROCESS | 2349 | _x_x_mozilla_page_load_details 10:04:51 INFO - PROCESS | 2349 | |i|pagename|runs| 10:04:51 INFO - PROCESS | 2349 | |0;dojo.html;3433.3305005523916;3481.3037392521487;3505.7545971781988;3528.4530897710856;3533.3463309287768;5850.531957443405;5871;5878.323992740836;5880.059190529516;5901.085331773576;5785.450382213693;5851;5866.354700982899;5871.148683440899;5927.88144237115;5271.815486457966;5323;5345.064613692608;5384.3538775347015;5449.618526703112;5270.051390749663;5291.047611429941;5378.731063446831;5405.297311349519;5411.864703382407;5305.814296499623;5341.866453338678;5345.4120046795015;5348.037353276409;5349.652272602288;4915.680480768757;4988.700677959316;5332.840014799563;5334.333208348957;5367.597430192712;5092.363454568179;5174.24973378858;5224.216367544898;5270.49930256625;5273.997940391313;4405.867823965286;5609;5717.771289148429;5734.971325143348;5793.913091303634;6054.939450605481;6114.113453549255;6154.476869466056;6165.969170154165;6168.321484636686;6113.050656958994;6142.86713775973;6167.845803854894;6180.84547886302;6220;5918.970405147989;5960.672163031075;5968.955432799286;5972.134040564094;5973.1936188614845;5468.179773034077;6270.467010304175;6292.244930608356;6313.40022697843;6332.841678958063;6137.714085983976;6186.721597528122;6212.285587157535;6220.471259942865;6231.065340199007;6206.472449841724;6223.502119830403;6224.875502489925;6238.625682459067;6252.562320637512;5013.874653133628;6124.686092398479;6164.6917654117115;6227;6228.34602366754;5301.973490132525;6205.348438413992;6206.503479721611;6215.595986260879;6228.5951413158;4906.607471402279;4993.300937868701;5005.299258103868;5009.273655319958;5045.369328833895;4433.445819272591;4448.088141930896;4492.258777301774;4498.752568608758;4510.007798284372;4849.927251091166;5671.376148623648;5822.505087067562;5841.153032810219;5848.181254624356;6148.4773794227995;6160.44555989963;6163.7842675506145;6212.409821066992;6215.098810672427;4888.951110488921;5707.3721890592005;5764.452377024176;5851.765929362863;5875.853103672357;4665.813367465331;5420.159875219348;5425.484578964992;5432.7555260012605;5468.4531546845;6399.232092148972;6399.55203135776;6406.327335629785;6424;6436.388543088399;5044.344235249541;6129.358790882775;6198;6202.689865506616;6251.937480625135;6356.110144579855;6378.202724659418;6389.744410223539;6402;6442.871142577216;5968.970155149196;6208.844778880564;6211.534134939988;6212.751489940532;6234.220722409699;6152.415520525543;6193;6202.255729312511;6205.937940620536;6216.160818289473;5132.30713853625;6031.60794548353;6105.84735381619;6168.074788781719;6215.378462153749;6092.75628974836;6143.232095988002;6193.6903154841175;6195.380461953949;6198.907016394667;5925.200097986889;6009.609375390585;6032.034874420072;6064.878702425838;6072.635641861502;6161.322254552086;6168.8766224674355;6188.535859810442;6209.720562574786;6229.657368844576;5831.35855055952;6037.305709843425;6068.817935461943;6084.847878803065;6089.756409743561 10:04:51 INFO - PROCESS | 2349 | |1;ext.html;498.1208167584212;510.6527561258344;582.9183914252003;596.6897213449005;627.5199472403611;27854;39270.01824954378;41122;41177.58822411775;42157.7892110539;42476.36285455713;42670.933226669345;42927.785361073154;42985.570144298545;43056.3541546877;8430.53632050237;9441.103095205961;9648.131668149865;9650.613975440974;9653.806923861519;9553.283503737213;9729.756756081084;9789.167920726746;9790.5594248259;9802.921678615347;8863.335249856254;9807.852882206773;9817.754556136082;9824.410535367864;9838.311318207729;8469.19542643454;9734.610615575368;9763;9792.608295668164;9868.802623947553;9354.111359420913;9675.516224188832;9802.215822734164;9833.26250531213;9891.406515609087;41576.37635435471;41626;41954.79022604867;41999.7900010498;42160.789196053825;2739.904103356393;2814.6763122241;3202.1354234356827;3292.193412613901;3306.7850589711834;25924.74075259242;27245.45509089827;27697.169084927486;27712.03007894734;27993.86003069992;2293.8853057347064;2703.6620422447195;2706.6751989761156;2730.494858451193;2814.6200262964444;384656;387894;389479;389850.05074974726;391015;36707;36728.63271367252;36899.81550092233;36907.44638830366;36930;39377.212455751294;74786.62606686931;75156.24843751601;75802.62098689581;76124.61937690276;17696.115194240236;17735.556611084827;17906.731399029024;17912.10439478047;17967.371142009968;13602.455901764017;13615;13705.177689338672;13707.657308567366;13739.519116830863;20444.897775511028;20686;20707.482312941996;20785.792142078386;20800.27199048026;7641.808954776175;8504.574771261536;8656.350773691998;8694.652213911499;8710.172533609297;925.9166674999148;964.1370972979145;1034.0900007993068;1124.2636073371955;1145.8453108830367;30915.690843091732;31083.067507974796;31323.703197690673;31700.682993170234;31885.362292754016;952.4095061062186;1100.3892839474008;1102.922795404314;1137.4312843578211;1142.9885701143048;14862.479813206486;15239.314230859638;16084.839151608568;16270.593235169215;16698.83301166997;640266;647083;649284.7535762291;651828;660028;54865.451345486035;66178;67023;67096.6645166771;67209;1336.9131006484547;1517.1124891938118;1529.2888806704714;1533.5706002319369;1551.5500504853692;31380.744770208938;32062;32117.518237226926;32300.515492268103;32354.67645323611;58064.4193558059;58290;58972.41027589669;59008;59175.81648367095;591446;613702;616531;616698;621266;28321.43357132887;28368.57447138336;28679.139625811258;28719.569206462336;28787.28031799222;37751.62248377481;48029;48438.51561484481;49366.50633493619;49644;35172.82413587916;37521.81239093787;39320;39833.2033359337;40358.99102522343;39026.024349390354;39405.605943941344;40052;40193.59806401899;40380.798096009334;38134;38719.419208712454;39187.82436526909;39526.209475809745;40070.1985960285;33437.99686009424;34286.65713342834;34628.82685586556;35199.82400087983;35450.93647190589;25898.611520826827;25940.70296485206;26145.869270654286;26164;26165 10:04:51 INFO - PROCESS | 2349 | |2;jquery.html;397.87069202509184;400.2455371624489;402.90330320723035;402.93955906614013;412.7874144815419;19932.701009484852;20446.795532044674;20654.863982480958;20690.068946897387;22042.88978555105;11859.644210673672;12231.877681223184;12291.508339666425;21462.89268553659;21911.67132493014;5305.681659100456;5468.781248750065;5480.671159730429;5501.614886957895;5515.669059856401;4563.703359281636;5631.521320687746;5850.941490585104;5950.226470558843;5961;5832.154337621064;5930.051191809312;5941.554383421239;6578.802635920906;6706.463482921379;6632.469402447792;6660.733570657168;6684.933150668479;6686.899696504556;6697.095892054559;5770.16332631766;5914.645121292736;5993.4006599340155;6022;6026.216591843032;26059.9576016961;26126.60810087831;26516.071937482287;26590.86704566484;26779.062732804454;2108.609907167179;2138.903749331284;2151.688005239247;2168.490404754881;2251.3921241264766;8436.535990520519;9371.625134994592;9732.90267097327;10069.54687039085;10140.492975351202;1694.102125873277;1837.9632407351778;1862.7764668239897;1870.5884705364933;1870.9812901870944;115239.42380288128;116829.41585292104;122008;124869;125356.37321813423;11706.478157839596;11813.527458901719;11994.280343179436;12033.939830300793;12084.395780210954;15286.006409583342;15309.540713778604;15330.770038449433;15333.539993800205;15336;9000.59497322622;9008.684696035607;9031.27749780016;9098.499582523025;9136.49749263797;8957.059508751618;8989.10108989109;8994.820103597891;9126.041765614515;9128.680496182602;3857.9807100964317;3950.2692001979726;4720.315554244616;4753.500882407367;4812.807487700523;1801.639672065592;1832.4227868221467;1932.8885890612953;1957.9608407831763;2021.3632705697657;815.0748899998426;940.7554035950702;987.6197663899306;1014.0062738516295;1016.1466493354932;19561.021948902784;20580.970951452367;21006;21676.674849877265;21854;932.7341707613298;962.7159987803615;968.5835090910836;1001.019001378653;1041.8437234414746;10453.84319235212;11045.447727613588;11109.222354435113;11136.276142050741;11172.832407513893;560393;583981.5606453754;634923;646940;651016;74524.5095098095;108921;125828.37085814512;131337.34331328474;135830.32084839512;1063.2875973098044;1075.6557901471454;1132.5243397772956;1149.816029435303;1153.3425947210178;15219.239038048274;18301.54246143857;18345.541361465537;19095.809041909957;19408.51478712987;32589;37852.43221351737;50092.49907500878;50994;55956;73207.63396182985;74837.62581187059;76927.23072769424;78239;78400.82398764137;7866.72466463671;8061.4760040597175;8186.7953301167945;8244.216799404046;8341.082480927214;9030;12178.695532611755;12387;12734.87265127374;12819.679508012374;10713.892861071601;11006.504707288352;11117.11063114965;11182.832257516306;11264.83102753443;10700.197485188486;10742;10837.891621084003;10872.945635271773;11139.275947063416;11207.887921120684;11430.314181149157;11473.885261147281;11493.310401375944;11639.6508104757;7446.367058800062;8170.550619715973;8213.876791848008;8271.917280827356;8357.414980951275;9742.95128524353;10849.294795838245;11055.281406708538;11224.607138750105;11329 10:04:51 INFO - PROCESS | 2349 | |3;mootools.html;1575.0943207655598;1594.449914779401;1828.4514645606323;1898.2027548429658;1961.9215231390745;7587.658555365001;7975.92024079759;8558.400911936167;8684.392092553524;8988.505632190227;8701.477911325324;8732.51971141587;8850;8859;8869.20177184055;2023.8886861222627;2036.470517665403;2376.2514807835564;2463.1009681466317;2481.6401621764835;2399.3281881073326;2433.8904749286326;2439.1584903208363;2452.6566280720713;2464.7165575958725;1976.5256338478735;2373.4422410733464;2397.652340410639;2447.9265622031367;2449.081594402099;2411.3007227903886;2435.074671624789;2437.025189924027;2439.5364880672614;2450.767177118171;2413.5776239158254;2456.066694656019;2463.3841539615096;2465.457599328145;2478;9010.909890901072;9018.729438116867;9026.954865225698;9033.548322583845;9041.3671043027;5397.136458166714;5443.59173062018;5565.3599836018975;5574.024545704486;5587.944120558783;3456.084137703511;3578.3022310649435;3590.138366791977;3615.385384484644;3617.620149884277;929.0570071377557;1018.0379541333443;1030.587764894041;1036.3781730961439;1046.2676126711256;11833.408329583572;11952.163348565604;11985.041196704242;12023.39883005855;12025.037996960222;4249.235137675249;4415.227335216325;4734.621230301567;4896.069746748107;4916.0905232532095;10147.289689721796;10254.6410875619;10258.846117308247;10273.537690803932;10290.639827605997;5387.4073851876265;5476.534494568006;5495.917561236584;5502.0371434998715;5510.9448905511235;4408.426904502394;4826.975865120722;4936.531029552187;4943.505649435099;5007.223880298559;4044.797760111983;4061.289274376972;4133.710640255214;4145.91708165835;4153.792310384469;1309.0901823232953;1313.4746101559263;1329.2091205732575;1338.3709656461447;1341.9798703019462;933.3699752666922;946.5503885654259;955.9235261179089;970.3595626886221;981.6318880419842;5864.413558644187;5930.466258036798;6092.238470191226;6101.176341193882;6151.046587778901;447.1928169653741;465.5786513205554;466.5591016489453;470.6658272626474;475.34165181224256;3952.446657467957;4021.4571032910185;4033.153037862082;4046.109855831712;4094.7952602369764;8956.731298061068;10764.94617526923;10989.725256868642;10999.945000274947;11066.833997490045;10409;10540.894591054144;10778.73053173677;10849.891501085045;10883.020528152347;8378.413511054168;8447.53538555373;8486.363522735819;8498.107698691796;8566.3146948244;5808.970955145197;5845.853853653693;5864.648121112747;5932.110183472513;5978;9201.03389144157;9613.663521776984;9745.26910481731;9855.310128291238;9855.950720246354;8178.877316840371;9008.59461324255;9899.802003959736;9969.551370188214;10083.193344532274;6873.828154295982;7320.743773968099;7453.813654658677;7516.924830751623;7584.886226706714;8218.958905205436;8272.793180170544;8283;8341.666333346599;8362.163783621589;6465.353464653497;7315.707371705285;7485;7506.9249307506225;7634.160242373446;5709.314882214161;6088.8782224356155;7170.713171473083;7388.482806203514;7400.222976587596;6831.077804496329;6993.650317484207;7019.929800702132;7022.016917631434;7032.367086962198;4919.0407870464915;4919.385076865392;4943.8516844494725;5028.974855125701;5062.82280120194;4965.503449655005;4983.750812459435;5016.473270306711;5023.849284521324;5212.087884620252 10:04:51 INFO - PROCESS | 2349 | |4;prototype.html;324.6510001748121;326.598284110544;330.92885029718616;331.45310238107135;336.2015213867066;6930.9306906930915;6938.583684978898;6953.478489113318;6954.44364450844;6973.651317434121;6801.591904485735;6811.1826580810375;6814.841476948918;6874.553154044984;6905.585664860112;4872.610191184705;4967.552920237178;5085;5097.847064588068;5103.157978933472;5025.623078269126;5078.568321692661;5088.669236499634;5110.1312776827845;5131.486851314876;4129.566395528472;5026;5038.470960549126;5049.570786483153;5071.1632580624155;5051.570616497602;5056.266841308026;5059.848204553888;5072.644914855961;5108.438071812097;4355.651547876162;4370.191514569815;4373.103513779667;4374.49693285273;4379.890502737425;5481.479259470344;6363.3954774296835;6703.597784132968;6716.361945615159;6740.494462915301;5822.1266809978415;5850.912236316459;5855.590108692393;5872.383399743008;5872.647641141545;7386.891966205058;7389.4457915656;7397.371223446014;7400.814979625498;7432;3603.765755225902;4731.479537250934;4741.739204343794;4744.312074749142;4749.41157069398;6397.816403965281;6747.426468750162;6754.628495432749;7127.251638577926;7456.962715186443;3986.1828325193264;4783.234682450791;4790.976045119752;4797.520247975244;4807.687500312469;4851.636127290467;4874.902501949941;4879.56083952446;4885.145099607555;4893.070316639827;5006.524380183877;5698.458646428583;5827.271591051119;5834.883302333929;5863.853403664943;7398.926010739931;7437.925620743723;7440.7395741149885;7455.7017719290625;7461.738839140604;1670.3402156148254;1701.2089378439084;1704.1479260369815;1706.4027590343528;1706.505113517066;1048.3133547526381;1061.87257529097;1070.2775626452114;1076.9030787229187;1082.832161015044;5718.199452076713;6903.551269167488;6916.6541672916155;6961.269066747918;6982;7497.212792656693;7571.3942884569105;7682.884756728654;7724.536527808237;7745.961270193613;6955.095837541087;6962.930370696329;7021.473389495808;7022.578645281299;7041.366277035091;4082.9183416332103;4131.5248746393945;4140.875773726793;4141.7722025288895;4145.97927010363;8210.096889342167;8212.222377706203;8220.712275070344;8249.917500825035;8255.958720206361;7686.116096648957;8038.7186448475195;8060.952076230053;8086.636101375452;8107.391945604103;7376.926230737731;7524.887126693104;8128.39037072222;8178.5092894426525;8191.344692424473;7739.7678069658;7755.418343624364;7757.3794096473275;7761.534307941542;7788.727394541163;7317.707291708272;7623.809404764926;7645.585566670184;7650.73222437212;7657.617119144132;6606.570572912938;8154.388420868578;8169.142240064945;8176.182381761776;8214.548199849065;4760.000399916056;4799.784009719501;4807.495213002584;4808.711477311373;4837.588804951619;7675;7685.23147685227;7686.730964416219;7712.113106992768;7733.381329493514;7185.964070179615;7459.216782237787;7467.551946883204;7468.925310746823;7478.850422991619;6122.663253521099;7402;7453.925460745323;7456.96271518639;7472.178060413242;7237.240089790496;7241.094863142107;7241.5293005954445;7267.890981635385;7284.890726638999;7471.103467583925;7471.626418678936;7475;7476.177620461636;7477.700891964478;6033.849153771191;7299.817504562428;7315.597642129734;7322.3775979042375;7356.301151390609 10:04:51 INFO - PROCESS | 2349 | |5;yui.html;677.292229620047;690.299346163644;708.4332533972821;722.9566226026438;752.766642340874;14599.708005839877;14805.777913331296;15023.774643380344;15071.39714411425;15132.092074475539;13758.724825503508;13936.651583710387;13972.510962116328;14044.64888377791;14060.64848378791;739.8557281330143;744.7542311037345;909.2634965677822;918.8392031394512;931.2456909902967;884.4295429448002;915.2266334946993;919.3105171121658;919.6597259014175;925.8379783537888;933.6545478173085;964.3587014635294;965.6378857928277;971.4948226921996;983.1053741095604;944.6221511395497;952.1145334838598;960.9135177833958;970.6457143142773;984.8818141822991;705.3686950179612;713.6289129652552;715.2597062040815;724.916634587024;726.4914559808155;12631.673674264253;14526.927365363106;14636.853631463655;14840.777388339184;14895.702085958328;12518.74962500754;12608.936955315165;12631.305278209695;12641.115121941468;12781.233126012468;9887.159591434736;10016.849747253797;10131.936146704564;10146.594136234542;10148.137408320228;3465.7671040276596;3514.121469632592;3537.2218112015316;3540.840662170183;3563.322968635977;76393;76454;76529.46941061203;78085.43829123386;78385.60807195984;9172.8624070639;9423.952880235554;9467.573959171854;9471.715848524555;9586.089321514444;21342.253021144497;21348.146074157172;21375.57248855014;21411.82234977091;21483.78516214849;7334.889976650355;7517.962410187914;7536.585487798114;7577.659005344773;7637.389008879276;4039.6768258539832;4112.485939257593;4118.114605359862;4127.2570937230985;4142.357934520154;3573.4639804029603;3590.730695197871;3638.8180590970874;3650.1239702471216;3652.9634703653155;654.3848782144769;667.2160211751172;668.355037388922;669.494531628627;671.8253254153857;497.3335730121582;591.5662112467002;598.937111603284;602.5209958083334;615.566025951703;8190.426670133033;8245.79385515355;8284.792880177924;8451.535165565952;8459.830803383898;536.8469986053956;595.484905556699;606.5117580347862;608.7017361492836;623.6476390839161;3808.5620153682125;3865.666345110932;3898.5126859142606;3955.0903292242947;4093.549709532009;84580;97630.02369976351;97632.51183744035;97900;100220;78019;80132.59933700295;80188;81909.59045204855;83517.32965340781;75234.24765752272;77216.61391693118;77259.61370193113;78641.82037269445;79098.60450697824;8530.274926631306;8554.31565474773;8577.656893724181;8748.737537873874;8771.254443372129;38195.61804381921;47504.2874356878;52557.474425255255;52626;52880.94238115139;67976.6601166991;70101.29898700948;70261;71619.5676086465;71833.6408317955;10079.496025198858;11340.319580825177;11658.358790266617;11689.123315751183;11914;11966;11969.341686206993;12112.75774484523;12446.25322480654;12471.189372690746;9731.56207970629;10734.463276836283;11292.943535282271;11702.590409335633;11893.643190704293;11361.431928403712;11525.077993760648;11585.768284634092;11656.125790565911;12202.694932626755;10446.738831529272;11154.776904462027;11477.139214559109;11505.942470287595;12123.151379403356;9503.239740820856;9739.561719722771;9781.902180978099;10042.447665378475;10081.747956301151;9636.75908102303;9662.08210220028;9757.316987810786;9782.021797820162;9801.901980980385 10:04:51 INFO - PROCESS | 2349 | __end_tp_report 10:04:51 INFO - PROCESS | 2349 | __start_cc_report 10:04:51 INFO - PROCESS | 2349 | _x_x_mozilla_cycle_collect,420 10:04:51 INFO - PROCESS | 2349 | __end_cc_report 10:04:51 INFO - PROCESS | 2349 | __startTimestamp1464627891016__endTimestamp 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | ------- Summary: start ------- 10:04:51 INFO - PROCESS | 2349 | Number of tests: 6 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#0] dojo.html Cycles:165 Average:5743.77 Median:5973.19 stddev:639.02 (10.7%) stddev-sans-first:614.74 10:04:51 INFO - PROCESS | 2349 | Values: 3433.3 3481.3 3505.8 3528.5 3533.3 5850.5 5871.0 5878.3 5880.1 5901.1 5785.5 5851.0 5866.4 5871.1 5927.9 5271.8 5323.0 5345.1 5384.4 5449.6 5270.1 5291.0 5378.7 5405.3 5411.9 5305.8 5341.9 5345.4 5348.0 5349.7 4915.7 4988.7 5332.8 5334.3 5367.6 5092.4 5174.2 5224.2 5270.5 5274.0 4405.9 5609.0 5717.8 5735.0 5793.9 6054.9 6114.1 6154.5 6166.0 6168.3 6113.1 6142.9 6167.8 6180.8 6220.0 5919.0 5960.7 5969.0 5972.1 5973.2 5468.2 6270.5 6292.2 6313.4 6332.8 6137.7 6186.7 6212.3 6220.5 6231.1 6206.5 6223.5 6224.9 6238.6 6252.6 5013.9 6124.7 6164.7 6227.0 6228.3 5302.0 6205.3 6206.5 6215.6 6228.6 4906.6 4993.3 5005.3 5009.3 5045.4 4433.4 4448.1 4492.3 4498.8 4510.0 4849.9 5671.4 5822.5 5841.2 5848.2 6148.5 6160.4 6163.8 6212.4 6215.1 4889.0 5707.4 5764.5 5851.8 5875.9 4665.8 5420.2 5425.5 5432.8 5468.5 6399.2 6399.6 6406.3 6424.0 6436.4 5044.3 6129.4 6198.0 6202.7 6251.9 6356.1 6378.2 6389.7 6402.0 6442.9 5969.0 6208.8 6211.5 6212.8 6234.2 6152.4 6193.0 6202.3 6205.9 6216.2 5132.3 6031.6 6105.8 6168.1 6215.4 6092.8 6143.2 6193.7 6195.4 6198.9 5925.2 6009.6 6032.0 6064.9 6072.6 6161.3 6168.9 6188.5 6209.7 6229.7 5831.4 6037.3 6068.8 6084.8 6089.8 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#1] ext.html Cycles:180 Average:68883.84 Median:39295.01 stddev:150400.93 (382.7%) stddev-sans-first:150735.21 10:04:51 INFO - PROCESS | 2349 | Values: 498.1 510.7 582.9 596.7 627.5 27854.0 39270.0 41122.0 41177.6 42157.8 42476.4 42670.9 42927.8 42985.6 43056.4 8430.5 9441.1 9648.1 9650.6 9653.8 9553.3 9729.8 9789.2 9790.6 9802.9 8863.3 9807.9 9817.8 9824.4 9838.3 8469.2 9734.6 9763.0 9792.6 9868.8 9354.1 9675.5 9802.2 9833.3 9891.4 41576.4 41626.0 41954.8 41999.8 42160.8 2739.9 2814.7 3202.1 3292.2 3306.8 25924.7 27245.5 27697.2 27712.0 27993.9 2293.9 2703.7 2706.7 2730.5 2814.6 384656.0 387894.0 389479.0 389850.1 391015.0 36707.0 36728.6 36899.8 36907.4 36930.0 39377.2 74786.6 75156.2 75802.6 76124.6 17696.1 17735.6 17906.7 17912.1 17967.4 13602.5 13615.0 13705.2 13707.7 13739.5 20444.9 20686.0 20707.5 20785.8 20800.3 7641.8 8504.6 8656.4 8694.7 8710.2 925.9 964.1 1034.1 1124.3 1145.8 30915.7 31083.1 31323.7 31700.7 31885.4 952.4 1100.4 1102.9 1137.4 1143.0 14862.5 15239.3 16084.8 16270.6 16698.8 640266.0 647083.0 649284.8 651828.0 660028.0 54865.5 66178.0 67023.0 67096.7 67209.0 1336.9 1517.1 1529.3 1533.6 1551.6 31380.7 32062.0 32117.5 32300.5 32354.7 58064.4 58290.0 58972.4 59008.0 59175.8 591446.0 613702.0 616531.0 616698.0 621266.0 28321.4 28368.6 28679.1 28719.6 28787.3 37751.6 48029.0 48438.5 49366.5 49644.0 35172.8 37521.8 39320.0 39833.2 40359.0 39026.0 39405.6 40052.0 40193.6 40380.8 38134.0 38719.4 39187.8 39526.2 40070.2 33438.0 34286.7 34628.8 35199.8 35450.9 25898.6 25940.7 26145.9 26164.0 26165.0 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#2] jquery.html Cycles:180 Average:34925.66 Median:21977.28 stddev:102550.22 (466.6%) stddev-sans-first:102805.13 10:04:51 INFO - PROCESS | 2349 | Values: 397.9 400.2 402.9 402.9 412.8 19932.7 20446.8 20654.9 20690.1 22042.9 11859.6 12231.9 12291.5 21462.9 21911.7 5305.7 5468.8 5480.7 5501.6 5515.7 4563.7 5631.5 5850.9 5950.2 5961.0 5832.2 5930.1 5941.6 6578.8 6706.5 6632.5 6660.7 6684.9 6686.9 6697.1 5770.2 5914.6 5993.4 6022.0 6026.2 26060.0 26126.6 26516.1 26590.9 26779.1 2108.6 2138.9 2151.7 2168.5 2251.4 8436.5 9371.6 9732.9 10069.5 10140.5 1694.1 1838.0 1862.8 1870.6 1871.0 115239.4 116829.4 122008.0 124869.0 125356.4 11706.5 11813.5 11994.3 12033.9 12084.4 15286.0 15309.5 15330.8 15333.5 15336.0 9000.6 9008.7 9031.3 9098.5 9136.5 8957.1 8989.1 8994.8 9126.0 9128.7 3858.0 3950.3 4720.3 4753.5 4812.8 1801.6 1832.4 1932.9 1958.0 2021.4 815.1 940.8 987.6 1014.0 1016.1 19561.0 20581.0 21006.0 21676.7 21854.0 932.7 962.7 968.6 1001.0 1041.8 10453.8 11045.4 11109.2 11136.3 11172.8 560393.0 583981.6 634923.0 646940.0 651016.0 74524.5 108921.0 125828.4 131337.3 135830.3 1063.3 1075.7 1132.5 1149.8 1153.3 15219.2 18301.5 18345.5 19095.8 19408.5 32589.0 37852.4 50092.5 50994.0 55956.0 73207.6 74837.6 76927.2 78239.0 78400.8 7866.7 8061.5 8186.8 8244.2 8341.1 9030.0 12178.7 12387.0 12734.9 12819.7 10713.9 11006.5 11117.1 11182.8 11264.8 10700.2 10742.0 10837.9 10872.9 11139.3 11207.9 11430.3 11473.9 11493.3 11639.7 7446.4 8170.6 8213.9 8271.9 8357.4 9743.0 10849.3 11055.3 11224.6 11329.0 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#3] mootools.html Cycles:180 Average:5706.09 Median:4954.68 stddev:3192.79 (64.4%) stddev-sans-first:3186.65 10:04:51 INFO - PROCESS | 2349 | Values: 1575.1 1594.4 1828.5 1898.2 1961.9 7587.7 7975.9 8558.4 8684.4 8988.5 8701.5 8732.5 8850.0 8859.0 8869.2 2023.9 2036.5 2376.3 2463.1 2481.6 2399.3 2433.9 2439.2 2452.7 2464.7 1976.5 2373.4 2397.7 2447.9 2449.1 2411.3 2435.1 2437.0 2439.5 2450.8 2413.6 2456.1 2463.4 2465.5 2478.0 9010.9 9018.7 9027.0 9033.5 9041.4 5397.1 5443.6 5565.4 5574.0 5587.9 3456.1 3578.3 3590.1 3615.4 3617.6 929.1 1018.0 1030.6 1036.4 1046.3 11833.4 11952.2 11985.0 12023.4 12025.0 4249.2 4415.2 4734.6 4896.1 4916.1 10147.3 10254.6 10258.8 10273.5 10290.6 5387.4 5476.5 5495.9 5502.0 5510.9 4408.4 4827.0 4936.5 4943.5 5007.2 4044.8 4061.3 4133.7 4145.9 4153.8 1309.1 1313.5 1329.2 1338.4 1342.0 933.4 946.6 955.9 970.4 981.6 5864.4 5930.5 6092.2 6101.2 6151.0 447.2 465.6 466.6 470.7 475.3 3952.4 4021.5 4033.2 4046.1 4094.8 8956.7 10764.9 10989.7 10999.9 11066.8 10409.0 10540.9 10778.7 10849.9 10883.0 8378.4 8447.5 8486.4 8498.1 8566.3 5809.0 5845.9 5864.6 5932.1 5978.0 9201.0 9613.7 9745.3 9855.3 9856.0 8178.9 9008.6 9899.8 9969.6 10083.2 6873.8 7320.7 7453.8 7516.9 7584.9 8219.0 8272.8 8283.0 8341.7 8362.2 6465.4 7315.7 7485.0 7506.9 7634.2 5709.3 6088.9 7170.7 7388.5 7400.2 6831.1 6993.7 7019.9 7022.0 7032.4 4919.0 4919.4 4943.9 5029.0 5062.8 4965.5 4983.8 5016.5 5023.8 5212.1 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#4] prototype.html Cycles:180 Average:6017.64 Median:6889.05 stddev:1962.00 (28.5%) stddev-sans-first:1920.41 10:04:51 INFO - PROCESS | 2349 | Values: 324.7 326.6 330.9 331.5 336.2 6930.9 6938.6 6953.5 6954.4 6973.7 6801.6 6811.2 6814.8 6874.6 6905.6 4872.6 4967.6 5085.0 5097.8 5103.2 5025.6 5078.6 5088.7 5110.1 5131.5 4129.6 5026.0 5038.5 5049.6 5071.2 5051.6 5056.3 5059.8 5072.6 5108.4 4355.7 4370.2 4373.1 4374.5 4379.9 5481.5 6363.4 6703.6 6716.4 6740.5 5822.1 5850.9 5855.6 5872.4 5872.6 7386.9 7389.4 7397.4 7400.8 7432.0 3603.8 4731.5 4741.7 4744.3 4749.4 6397.8 6747.4 6754.6 7127.3 7457.0 3986.2 4783.2 4791.0 4797.5 4807.7 4851.6 4874.9 4879.6 4885.1 4893.1 5006.5 5698.5 5827.3 5834.9 5863.9 7398.9 7437.9 7440.7 7455.7 7461.7 1670.3 1701.2 1704.1 1706.4 1706.5 1048.3 1061.9 1070.3 1076.9 1082.8 5718.2 6903.6 6916.7 6961.3 6982.0 7497.2 7571.4 7682.9 7724.5 7746.0 6955.1 6962.9 7021.5 7022.6 7041.4 4082.9 4131.5 4140.9 4141.8 4146.0 8210.1 8212.2 8220.7 8249.9 8256.0 7686.1 8038.7 8061.0 8086.6 8107.4 7376.9 7524.9 8128.4 8178.5 8191.3 7739.8 7755.4 7757.4 7761.5 7788.7 7317.7 7623.8 7645.6 7650.7 7657.6 6606.6 8154.4 8169.1 8176.2 8214.5 4760.0 4799.8 4807.5 4808.7 4837.6 7675.0 7685.2 7686.7 7712.1 7733.4 7186.0 7459.2 7467.6 7468.9 7478.9 6122.7 7402.0 7453.9 7457.0 7472.2 7237.2 7241.1 7241.5 7267.9 7284.9 7471.1 7471.6 7475.0 7476.2 7477.7 6033.8 7299.8 7315.6 7322.4 7356.3 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - PROCESS | 2349 | [#5] yui.html Cycles:180 Average:18628.01 Median:639.02 stddev:26466.32 (4141.7%) stddev-sans-first:26506.24 10:04:51 INFO - PROCESS | 2349 | Values: 677.3 690.3 708.4 723.0 752.8 14599.7 14805.8 15023.8 15071.4 15132.1 13758.7 13936.7 13972.5 14044.6 14060.6 739.9 744.8 909.3 918.8 931.2 884.4 915.2 919.3 919.7 925.8 933.7 964.4 965.6 971.5 983.1 944.6 952.1 960.9 970.6 984.9 705.4 713.6 715.3 724.9 726.5 12631.7 14526.9 14636.9 14840.8 14895.7 12518.7 12608.9 12631.3 12641.1 12781.2 9887.2 10016.8 10131.9 10146.6 10148.1 3465.8 3514.1 3537.2 3540.8 3563.3 76393.0 76454.0 76529.5 78085.4 78385.6 9172.9 9424.0 9467.6 9471.7 9586.1 21342.3 21348.1 21375.6 21411.8 21483.8 7334.9 7518.0 7536.6 7577.7 7637.4 4039.7 4112.5 4118.1 4127.3 4142.4 3573.5 3590.7 3638.8 3650.1 3653.0 654.4 667.2 668.4 669.5 671.8 497.3 591.6 598.9 602.5 615.6 8190.4 8245.8 8284.8 8451.5 8459.8 536.8 595.5 606.5 608.7 623.6 3808.6 3865.7 3898.5 3955.1 4093.5 84580.0 97630.0 97632.5 97900.0 100220.0 78019.0 80132.6 80188.0 81909.6 83517.3 75234.2 77216.6 77259.6 78641.8 79098.6 8530.3 8554.3 8577.7 8748.7 8771.3 38195.6 47504.3 52557.5 52626.0 52880.9 67976.7 70101.3 70261.0 71619.6 71833.6 10079.5 11340.3 11658.4 11689.1 11914.0 11966.0 11969.3 12112.8 12446.3 12471.2 9731.6 10734.5 11292.9 11702.6 11893.6 11361.4 11525.1 11585.8 11656.1 12202.7 10446.7 11154.8 11477.1 11505.9 12123.2 9503.2 9739.6 9781.9 10042.4 10081.7 9636.8 9662.1 9757.3 9782.0 9801.9 10:04:51 INFO - PROCESS | 2349 | -------- Summary: end -------- 10:04:51 INFO - PROCESS | 2349 | 10:04:51 INFO - TEST-INFO | 2349: exit 0 10:04:51 INFO - TEST-OK | dromaeo_css | took 1111338ms 10:04:51 INFO - TEST-START | kraken 10:04:51 INFO - operating with platform_type : mac_ 10:04:51 INFO - Initialising browser for kraken test... 10:04:51 INFO - TEST-INFO | started process 2353 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpaggYRp/profile http://localhost:51785/getInfo.html) 10:04:54 INFO - PROCESS | 2353 | __metrics Screen width/height:1600/1200 10:04:54 INFO - PROCESS | 2353 | colorDepth:24 10:04:54 INFO - PROCESS | 2353 | Browser inner width/height: 1024/689 10:04:54 INFO - PROCESS | 2353 | __metrics 10:04:54 ERROR - PROCESS | 2353 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 10:04:55 INFO - TEST-INFO | 2353: exit 0 10:04:55 INFO - Browser initialized. 10:04:55 INFO - Running cycle 1/1 for kraken test... 10:04:55 INFO - TEST-INFO | started process 2356 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/T/tmpaggYRp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:05:06 INFO - PROCESS | 2356 | RSS: Main: 221200384 10:05:06 INFO - PROCESS | 2356 | 10:05:09 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51785/tests/kraken/driver.html?testName=audio-beat-detection) 10:05:10 INFO - PROCESS | 2356 | RSS: Main: 232943616 10:05:10 INFO - PROCESS | 2356 | 10:05:13 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51785/tests/kraken/driver.html?testName=audio-dft) 10:05:13 INFO - PROCESS | 2356 | RSS: Main: 241844224 10:05:13 INFO - PROCESS | 2356 | 10:05:17 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51785/tests/kraken/driver.html?testName=audio-fft) 10:05:17 INFO - PROCESS | 2356 | RSS: Main: 245260288 10:05:17 INFO - PROCESS | 2356 | 10:05:19 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51785/tests/kraken/driver.html?testName=audio-oscillator) 10:05:20 INFO - PROCESS | 2356 | RSS: Main: 247205888 10:05:20 INFO - PROCESS | 2356 | 10:05:22 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51785/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:05:23 INFO - PROCESS | 2356 | RSS: Main: 248156160 10:05:23 INFO - PROCESS | 2356 | 10:05:26 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51785/tests/kraken/driver.html?testName=imaging-darkroom) 10:05:26 INFO - PROCESS | 2356 | RSS: Main: 247443456 10:05:26 INFO - PROCESS | 2356 | 10:05:29 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51785/tests/kraken/driver.html?testName=imaging-desaturate) 10:05:30 INFO - PROCESS | 2356 | RSS: Main: 247562240 10:05:30 INFO - PROCESS | 2356 | 10:05:33 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51785/tests/kraken/driver.html?testName=json-parse-financial) 10:05:34 INFO - PROCESS | 2356 | RSS: Main: 247644160 10:05:34 INFO - PROCESS | 2356 | 10:05:35 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51785/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:05:36 INFO - PROCESS | 2356 | RSS: Main: 247164928 10:05:36 INFO - PROCESS | 2356 | 10:05:38 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:05:38 INFO - PROCESS | 2356 | RSS: Main: 247242752 10:05:38 INFO - PROCESS | 2356 | 10:05:40 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:05:41 INFO - PROCESS | 2356 | RSS: Main: 247603200 10:05:41 INFO - PROCESS | 2356 | 10:05:44 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:05:45 INFO - PROCESS | 2356 | RSS: Main: 247607296 10:05:45 INFO - PROCESS | 2356 | 10:05:47 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:05:48 INFO - PROCESS | 2356 | RSS: Main: 247562240 10:05:48 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | Cycle 1(1): loaded http://localhost:51785/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51785/tests/kraken/driver.html?testName=ai-astar) 10:05:50 INFO - PROCESS | 2356 | RSS: Main: 249479168 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | __start_tp_report 10:05:50 INFO - PROCESS | 2356 | _x_x_mozilla_page_load 10:05:50 INFO - PROCESS | 2356 | _x_x_mozilla_page_load_details 10:05:50 INFO - PROCESS | 2356 | |i|pagename|runs| 10:05:50 INFO - PROCESS | 2356 | |0;ai-astar;116;120;109;116;119;140;106;119;116;116 10:05:50 INFO - PROCESS | 2356 | |1;audio-beat-detection;152;124;157;156;102;172;145;183;158;146 10:05:50 INFO - PROCESS | 2356 | |2;audio-dft;200;198;202;196;194;195;200;199;198;218 10:05:50 INFO - PROCESS | 2356 | |3;audio-fft;62;72;108;160;57;56;56;61;150;54 10:05:50 INFO - PROCESS | 2356 | |4;audio-oscillator;77;82;138;113;111;83;123;77;105;117 10:05:50 INFO - PROCESS | 2356 | |5;imaging-gaussian-blur;100;113;89;87;89;93;135;86;132;127 10:05:50 INFO - PROCESS | 2356 | |6;imaging-darkroom;98;139;147;119;131;146;146;96;95;95 10:05:50 INFO - PROCESS | 2356 | |7;imaging-desaturate;108;139;91;89;95;90;88;90;91;92 10:05:50 INFO - PROCESS | 2356 | |8;json-parse-financial;59;62;65;66;65;65;64;81;63;59 10:05:50 INFO - PROCESS | 2356 | |9;json-stringify-tinderbox;62;66;67;74;69;81;65;84;97;103 10:05:50 INFO - PROCESS | 2356 | |10;stanford-crypto-aes;82;96;103;93;83;82;82;81;84;81 10:05:50 INFO - PROCESS | 2356 | |11;stanford-crypto-ccm;139;165;151;148;137;128;133;131;168;167 10:05:50 INFO - PROCESS | 2356 | |12;stanford-crypto-pbkdf2;168;166;164;164;172;173;165;168;164;165 10:05:50 INFO - PROCESS | 2356 | |13;stanford-crypto-sha256-iterative;66;72;76;69;57;84;94;93;94;72 10:05:50 INFO - PROCESS | 2356 | __end_tp_report 10:05:50 INFO - PROCESS | 2356 | __start_cc_report 10:05:50 INFO - PROCESS | 2356 | _x_x_mozilla_cycle_collect,1086 10:05:50 INFO - PROCESS | 2356 | __end_cc_report 10:05:50 INFO - PROCESS | 2356 | __startTimestamp1464627950580__endTimestamp 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | ------- Summary: start ------- 10:05:50 INFO - PROCESS | 2356 | Number of tests: 14 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#0] ai-astar Cycles:10 Average:117.70 Median:117.50 stddev:9.01 (7.7%) stddev-sans-first:9.53 10:05:50 INFO - PROCESS | 2356 | Values: 116.0 120.0 109.0 116.0 119.0 140.0 106.0 119.0 116.0 116.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#1] audio-beat-detection Cycles:10 Average:149.50 Median:156.50 stddev:22.96 (14.7%) stddev-sans-first:24.34 10:05:50 INFO - PROCESS | 2356 | Values: 152.0 124.0 157.0 156.0 102.0 172.0 145.0 183.0 158.0 146.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#2] audio-dft Cycles:10 Average:200.00 Median:199.50 stddev:6.78 (3.4%) stddev-sans-first:7.19 10:05:50 INFO - PROCESS | 2356 | Values: 200.0 198.0 202.0 196.0 194.0 195.0 200.0 199.0 198.0 218.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#3] audio-fft Cycles:10 Average:83.60 Median:56.50 stddev:40.91 (72.4%) stddev-sans-first:42.64 10:05:50 INFO - PROCESS | 2356 | Values: 62.0 72.0 108.0 160.0 57.0 56.0 56.0 61.0 150.0 54.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#4] audio-oscillator Cycles:10 Average:102.60 Median:107.50 stddev:21.55 (20.0%) stddev-sans-first:20.77 10:05:50 INFO - PROCESS | 2356 | Values: 77.0 82.0 138.0 113.0 111.0 83.0 123.0 77.0 105.0 117.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#5] imaging-gaussian-blur Cycles:10 Average:105.10 Median:86.50 stddev:19.84 (22.9%) stddev-sans-first:20.96 10:05:50 INFO - PROCESS | 2356 | Values: 100.0 113.0 89.0 87.0 89.0 93.0 135.0 86.0 132.0 127.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#6] imaging-darkroom Cycles:10 Average:121.20 Median:121.00 stddev:23.24 (19.2%) stddev-sans-first:23.08 10:05:50 INFO - PROCESS | 2356 | Values: 98.0 139.0 147.0 119.0 131.0 146.0 146.0 96.0 95.0 95.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#7] imaging-desaturate Cycles:10 Average:97.30 Median:90.50 stddev:15.73 (17.4%) stddev-sans-first:16.21 10:05:50 INFO - PROCESS | 2356 | Values: 108.0 139.0 91.0 89.0 95.0 90.0 88.0 90.0 91.0 92.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:65.00 stddev:6.17 (9.5%) stddev-sans-first:6.17 10:05:50 INFO - PROCESS | 2356 | Values: 59.0 62.0 65.0 66.0 65.0 65.0 64.0 81.0 63.0 59.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#9] json-stringify-tinderbox Cycles:10 Average:76.80 Median:71.50 stddev:14.16 (19.8%) stddev-sans-first:13.97 10:05:50 INFO - PROCESS | 2356 | Values: 62.0 66.0 67.0 74.0 69.0 81.0 65.0 84.0 97.0 103.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#10] stanford-crypto-aes Cycles:10 Average:86.70 Median:82.50 stddev:7.78 (9.4%) stddev-sans-first:8.06 10:05:50 INFO - PROCESS | 2356 | Values: 82.0 96.0 103.0 93.0 83.0 82.0 82.0 81.0 84.0 81.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#11] stanford-crypto-ccm Cycles:10 Average:146.70 Median:149.50 stddev:15.49 (10.4%) stddev-sans-first:16.17 10:05:50 INFO - PROCESS | 2356 | Values: 139.0 165.0 151.0 148.0 137.0 128.0 133.0 131.0 168.0 167.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.00 stddev:3.31 (2.0%) stddev-sans-first:3.49 10:05:50 INFO - PROCESS | 2356 | Values: 168.0 166.0 164.0 164.0 172.0 173.0 165.0 168.0 164.0 165.0 10:05:50 INFO - PROCESS | 2356 | 10:05:50 INFO - PROCESS | 2356 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.70 Median:80.00 stddev:12.97 (16.2%) stddev-sans-first:13.05 10:05:50 INFO - PROCESS | 2356 | Values: 66.0 72.0 76.0 69.0 57.0 84.0 94.0 93.0 94.0 72.0 10:05:50 INFO - PROCESS | 2356 | -------- Summary: end -------- 10:05:50 INFO - PROCESS | 2356 | 10:05:51 INFO - TEST-INFO | 2356: exit 0 10:05:51 INFO - TEST-OK | kraken | took 59758ms 10:05:51 INFO - SUITE-END | took 1171s 10:05:51 INFO - Completed test suite (00:19:31) 10:05:51 INFO - javascript benchmark 10:05:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3433.3305005523916, 3481.3037392521487, 3505.7545971781988, 3528.4530897710856, 3533.3463309287768, 5850.531957443405, 5871.0, 5878.323992740836, 5880.059190529516, 5901.085331773576, 5785.450382213693, 5851.0, 5866.354700982899, 5871.148683440899, 5927.88144237115, 5271.815486457966, 5323.0, 5345.064613692608, 5384.3538775347015, 5449.618526703112, 5270.051390749663, 5291.047611429941, 5378.731063446831, 5405.297311349519, 5411.864703382407, 5305.814296499623, 5341.866453338678, 5345.4120046795015, 5348.037353276409, 5349.652272602288, 4915.680480768757, 4988.700677959316, 5332.840014799563, 5334.333208348957, 5367.597430192712, 5092.363454568179, 5174.24973378858, 5224.216367544898, 5270.49930256625, 5273.997940391313, 4405.867823965286, 5609.0, 5717.771289148429, 5734.971325143348, 5793.913091303634, 6054.939450605481, 6114.113453549255, 6154.476869466056, 6165.969170154165, 6168.321484636686, 6113.050656958994, 6142.86713775973, 6167.845803854894, 6180.84547886302, 6220.0, 5918.970405147989, 5960.672163031075, 5968.955432799286, 5972.134040564094, 5973.1936188614845, 5468.179773034077, 6270.467010304175, 6292.244930608356, 6313.40022697843, 6332.841678958063, 6137.714085983976, 6186.721597528122, 6212.285587157535, 6220.471259942865, 6231.065340199007, 6206.472449841724, 6223.502119830403, 6224.875502489925, 6238.625682459067, 6252.562320637512, 5013.874653133628, 6124.686092398479, 6164.6917654117115, 6227.0, 6228.34602366754, 5301.973490132525, 6205.348438413992, 6206.503479721611, 6215.595986260879, 6228.5951413158, 4906.607471402279, 4993.300937868701, 5005.299258103868, 5009.273655319958, 5045.369328833895, 4433.445819272591, 4448.088141930896, 4492.258777301774, 4498.752568608758, 4510.007798284372, 4849.927251091166, 5671.376148623648, 5822.505087067562, 5841.153032810219, 5848.181254624356, 6148.4773794227995, 6160.44555989963, 6163.7842675506145, 6212.409821066992, 6215.098810672427, 4888.951110488921, 5707.3721890592005, 5764.452377024176, 5851.765929362863, 5875.853103672357, 4665.813367465331, 5420.159875219348, 5425.484578964992, 5432.7555260012605, 5468.4531546845, 6399.232092148972, 6399.55203135776, 6406.327335629785, 6424.0, 6436.388543088399, 5044.344235249541, 6129.358790882775, 6198.0, 6202.689865506616, 6251.937480625135, 6356.110144579855, 6378.202724659418, 6389.744410223539, 6402.0, 6442.871142577216, 5968.970155149196, 6208.844778880564, 6211.534134939988, 6212.751489940532, 6234.220722409699, 6152.415520525543, 6193.0, 6202.255729312511, 6205.937940620536, 6216.160818289473, 5132.30713853625, 6031.60794548353, 6105.84735381619, 6168.074788781719, 6215.378462153749, 6092.75628974836, 6143.232095988002, 6193.6903154841175, 6195.380461953949, 6198.907016394667, 5925.200097986889, 6009.609375390585, 6032.034874420072, 6064.878702425838, 6072.635641861502, 6161.322254552086, 6168.8766224674355, 6188.535859810442, 6209.720562574786, 6229.657368844576, 5831.35855055952, 6037.305709843425, 6068.817935461943, 6084.847878803065, 6089.756409743561], "value": 5707.110390211739, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.1208167584212, 510.6527561258344, 582.9183914252003, 596.6897213449005, 627.5199472403611, 27854.0, 39270.01824954378, 41122.0, 41177.58822411775, 42157.7892110539, 42476.36285455713, 42670.933226669345, 42927.785361073154, 42985.570144298545, 43056.3541546877, 8430.53632050237, 9441.103095205961, 9648.131668149865, 9650.613975440974, 9653.806923861519, 9553.283503737213, 9729.756756081084, 9789.167920726746, 9790.5594248259, 9802.921678615347, 8863.335249856254, 9807.852882206773, 9817.754556136082, 9824.410535367864, 9838.311318207729, 8469.19542643454, 9734.610615575368, 9763.0, 9792.608295668164, 9868.802623947553, 9354.111359420913, 9675.516224188832, 9802.215822734164, 9833.26250531213, 9891.406515609087, 41576.37635435471, 41626.0, 41954.79022604867, 41999.7900010498, 42160.789196053825, 2739.904103356393, 2814.6763122241, 3202.1354234356827, 3292.193412613901, 3306.7850589711834, 25924.74075259242, 27245.45509089827, 27697.169084927486, 27712.03007894734, 27993.86003069992, 2293.8853057347064, 2703.6620422447195, 2706.6751989761156, 2730.494858451193, 2814.6200262964444, 384656.0, 387894.0, 389479.0, 389850.05074974726, 391015.0, 36707.0, 36728.63271367252, 36899.81550092233, 36907.44638830366, 36930.0, 39377.212455751294, 74786.62606686931, 75156.24843751601, 75802.62098689581, 76124.61937690276, 17696.115194240236, 17735.556611084827, 17906.731399029024, 17912.10439478047, 17967.371142009968, 13602.455901764017, 13615.0, 13705.177689338672, 13707.657308567366, 13739.519116830863, 20444.897775511028, 20686.0, 20707.482312941996, 20785.792142078386, 20800.27199048026, 7641.808954776175, 8504.574771261536, 8656.350773691998, 8694.652213911499, 8710.172533609297, 925.9166674999148, 964.1370972979145, 1034.0900007993068, 1124.2636073371955, 1145.8453108830367, 30915.690843091732, 31083.067507974796, 31323.703197690673, 31700.682993170234, 31885.362292754016, 952.4095061062186, 1100.3892839474008, 1102.922795404314, 1137.4312843578211, 1142.9885701143048, 14862.479813206486, 15239.314230859638, 16084.839151608568, 16270.593235169215, 16698.83301166997, 640266.0, 647083.0, 649284.7535762291, 651828.0, 660028.0, 54865.451345486035, 66178.0, 67023.0, 67096.6645166771, 67209.0, 1336.9131006484547, 1517.1124891938118, 1529.2888806704714, 1533.5706002319369, 1551.5500504853692, 31380.744770208938, 32062.0, 32117.518237226926, 32300.515492268103, 32354.67645323611, 58064.4193558059, 58290.0, 58972.41027589669, 59008.0, 59175.81648367095, 591446.0, 613702.0, 616531.0, 616698.0, 621266.0, 28321.43357132887, 28368.57447138336, 28679.139625811258, 28719.569206462336, 28787.28031799222, 37751.62248377481, 48029.0, 48438.51561484481, 49366.50633493619, 49644.0, 35172.82413587916, 37521.81239093787, 39320.0, 39833.2033359337, 40358.99102522343, 39026.024349390354, 39405.605943941344, 40052.0, 40193.59806401899, 40380.798096009334, 38134.0, 38719.419208712454, 39187.82436526909, 39526.209475809745, 40070.1985960285, 33437.99686009424, 34286.65713342834, 34628.82685586556, 35199.82400087983, 35450.93647190589, 25898.611520826827, 25940.70296485206, 26145.869270654286, 26164.0, 26165.0], "value": 20117.954266891105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.87069202509184, 400.2455371624489, 402.90330320723035, 402.93955906614013, 412.7874144815419, 19932.701009484852, 20446.795532044674, 20654.863982480958, 20690.068946897387, 22042.88978555105, 11859.644210673672, 12231.877681223184, 12291.508339666425, 21462.89268553659, 21911.67132493014, 5305.681659100456, 5468.781248750065, 5480.671159730429, 5501.614886957895, 5515.669059856401, 4563.703359281636, 5631.521320687746, 5850.941490585104, 5950.226470558843, 5961.0, 5832.154337621064, 5930.051191809312, 5941.554383421239, 6578.802635920906, 6706.463482921379, 6632.469402447792, 6660.733570657168, 6684.933150668479, 6686.899696504556, 6697.095892054559, 5770.16332631766, 5914.645121292736, 5993.4006599340155, 6022.0, 6026.216591843032, 26059.9576016961, 26126.60810087831, 26516.071937482287, 26590.86704566484, 26779.062732804454, 2108.609907167179, 2138.903749331284, 2151.688005239247, 2168.490404754881, 2251.3921241264766, 8436.535990520519, 9371.625134994592, 9732.90267097327, 10069.54687039085, 10140.492975351202, 1694.102125873277, 1837.9632407351778, 1862.7764668239897, 1870.5884705364933, 1870.9812901870944, 115239.42380288128, 116829.41585292104, 122008.0, 124869.0, 125356.37321813423, 11706.478157839596, 11813.527458901719, 11994.280343179436, 12033.939830300793, 12084.395780210954, 15286.006409583342, 15309.540713778604, 15330.770038449433, 15333.539993800205, 15336.0, 9000.59497322622, 9008.684696035607, 9031.27749780016, 9098.499582523025, 9136.49749263797, 8957.059508751618, 8989.10108989109, 8994.820103597891, 9126.041765614515, 9128.680496182602, 3857.9807100964317, 3950.2692001979726, 4720.315554244616, 4753.500882407367, 4812.807487700523, 1801.639672065592, 1832.4227868221467, 1932.8885890612953, 1957.9608407831763, 2021.3632705697657, 815.0748899998426, 940.7554035950702, 987.6197663899306, 1014.0062738516295, 1016.1466493354932, 19561.021948902784, 20580.970951452367, 21006.0, 21676.674849877265, 21854.0, 932.7341707613298, 962.7159987803615, 968.5835090910836, 1001.019001378653, 1041.8437234414746, 10453.84319235212, 11045.447727613588, 11109.222354435113, 11136.276142050741, 11172.832407513893, 560393.0, 583981.5606453754, 634923.0, 646940.0, 651016.0, 74524.5095098095, 108921.0, 125828.37085814512, 131337.34331328474, 135830.32084839512, 1063.2875973098044, 1075.6557901471454, 1132.5243397772956, 1149.816029435303, 1153.3425947210178, 15219.239038048274, 18301.54246143857, 18345.541361465537, 19095.809041909957, 19408.51478712987, 32589.0, 37852.43221351737, 50092.49907500878, 50994.0, 55956.0, 73207.63396182985, 74837.62581187059, 76927.23072769424, 78239.0, 78400.82398764137, 7866.72466463671, 8061.4760040597175, 8186.7953301167945, 8244.216799404046, 8341.082480927214, 9030.0, 12178.695532611755, 12387.0, 12734.87265127374, 12819.679508012374, 10713.892861071601, 11006.504707288352, 11117.11063114965, 11182.832257516306, 11264.83102753443, 10700.197485188486, 10742.0, 10837.891621084003, 10872.945635271773, 11139.275947063416, 11207.887921120684, 11430.314181149157, 11473.885261147281, 11493.310401375944, 11639.6508104757, 7446.367058800062, 8170.550619715973, 8213.876791848008, 8271.917280827356, 8357.414980951275, 9742.95128524353, 10849.294795838245, 11055.281406708538, 11224.607138750105, 11329.0], "value": 9549.52667720713, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1575.0943207655598, 1594.449914779401, 1828.4514645606323, 1898.2027548429658, 1961.9215231390745, 7587.658555365001, 7975.92024079759, 8558.400911936167, 8684.392092553524, 8988.505632190227, 8701.477911325324, 8732.51971141587, 8850.0, 8859.0, 8869.20177184055, 2023.8886861222627, 2036.470517665403, 2376.2514807835564, 2463.1009681466317, 2481.6401621764835, 2399.3281881073326, 2433.8904749286326, 2439.1584903208363, 2452.6566280720713, 2464.7165575958725, 1976.5256338478735, 2373.4422410733464, 2397.652340410639, 2447.9265622031367, 2449.081594402099, 2411.3007227903886, 2435.074671624789, 2437.025189924027, 2439.5364880672614, 2450.767177118171, 2413.5776239158254, 2456.066694656019, 2463.3841539615096, 2465.457599328145, 2478.0, 9010.909890901072, 9018.729438116867, 9026.954865225698, 9033.548322583845, 9041.3671043027, 5397.136458166714, 5443.59173062018, 5565.3599836018975, 5574.024545704486, 5587.944120558783, 3456.084137703511, 3578.3022310649435, 3590.138366791977, 3615.385384484644, 3617.620149884277, 929.0570071377557, 1018.0379541333443, 1030.587764894041, 1036.3781730961439, 1046.2676126711256, 11833.408329583572, 11952.163348565604, 11985.041196704242, 12023.39883005855, 12025.037996960222, 4249.235137675249, 4415.227335216325, 4734.621230301567, 4896.069746748107, 4916.0905232532095, 10147.289689721796, 10254.6410875619, 10258.846117308247, 10273.537690803932, 10290.639827605997, 5387.4073851876265, 5476.534494568006, 5495.917561236584, 5502.0371434998715, 5510.9448905511235, 4408.426904502394, 4826.975865120722, 4936.531029552187, 4943.505649435099, 5007.223880298559, 4044.797760111983, 4061.289274376972, 4133.710640255214, 4145.91708165835, 4153.792310384469, 1309.0901823232953, 1313.4746101559263, 1329.2091205732575, 1338.3709656461447, 1341.9798703019462, 933.3699752666922, 946.5503885654259, 955.9235261179089, 970.3595626886221, 981.6318880419842, 5864.413558644187, 5930.466258036798, 6092.238470191226, 6101.176341193882, 6151.046587778901, 447.1928169653741, 465.5786513205554, 466.5591016489453, 470.6658272626474, 475.34165181224256, 3952.446657467957, 4021.4571032910185, 4033.153037862082, 4046.109855831712, 4094.7952602369764, 8956.731298061068, 10764.94617526923, 10989.725256868642, 10999.945000274947, 11066.833997490045, 10409.0, 10540.894591054144, 10778.73053173677, 10849.891501085045, 10883.020528152347, 8378.413511054168, 8447.53538555373, 8486.363522735819, 8498.107698691796, 8566.3146948244, 5808.970955145197, 5845.853853653693, 5864.648121112747, 5932.110183472513, 5978.0, 9201.03389144157, 9613.663521776984, 9745.26910481731, 9855.310128291238, 9855.950720246354, 8178.877316840371, 9008.59461324255, 9899.802003959736, 9969.551370188214, 10083.193344532274, 6873.828154295982, 7320.743773968099, 7453.813654658677, 7516.924830751623, 7584.886226706714, 8218.958905205436, 8272.793180170544, 8283.0, 8341.666333346599, 8362.163783621589, 6465.353464653497, 7315.707371705285, 7485.0, 7506.9249307506225, 7634.160242373446, 5709.314882214161, 6088.8782224356155, 7170.713171473083, 7388.482806203514, 7400.222976587596, 6831.077804496329, 6993.650317484207, 7019.929800702132, 7022.016917631434, 7032.367086962198, 4919.0407870464915, 4919.385076865392, 4943.8516844494725, 5028.974855125701, 5062.82280120194, 4965.503449655005, 4983.750812459435, 5016.473270306711, 5023.849284521324, 5212.087884620252], "value": 4534.9255448911335, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.6510001748121, 326.598284110544, 330.92885029718616, 331.45310238107135, 336.2015213867066, 6930.9306906930915, 6938.583684978898, 6953.478489113318, 6954.44364450844, 6973.651317434121, 6801.591904485735, 6811.1826580810375, 6814.841476948918, 6874.553154044984, 6905.585664860112, 4872.610191184705, 4967.552920237178, 5085.0, 5097.847064588068, 5103.157978933472, 5025.623078269126, 5078.568321692661, 5088.669236499634, 5110.1312776827845, 5131.486851314876, 4129.566395528472, 5026.0, 5038.470960549126, 5049.570786483153, 5071.1632580624155, 5051.570616497602, 5056.266841308026, 5059.848204553888, 5072.644914855961, 5108.438071812097, 4355.651547876162, 4370.191514569815, 4373.103513779667, 4374.49693285273, 4379.890502737425, 5481.479259470344, 6363.3954774296835, 6703.597784132968, 6716.361945615159, 6740.494462915301, 5822.1266809978415, 5850.912236316459, 5855.590108692393, 5872.383399743008, 5872.647641141545, 7386.891966205058, 7389.4457915656, 7397.371223446014, 7400.814979625498, 7432.0, 3603.765755225902, 4731.479537250934, 4741.739204343794, 4744.312074749142, 4749.41157069398, 6397.816403965281, 6747.426468750162, 6754.628495432749, 7127.251638577926, 7456.962715186443, 3986.1828325193264, 4783.234682450791, 4790.976045119752, 4797.520247975244, 4807.687500312469, 4851.636127290467, 4874.902501949941, 4879.56083952446, 4885.145099607555, 4893.070316639827, 5006.524380183877, 5698.458646428583, 5827.271591051119, 5834.883302333929, 5863.853403664943, 7398.926010739931, 7437.925620743723, 7440.7395741149885, 7455.7017719290625, 7461.738839140604, 1670.3402156148254, 1701.2089378439084, 1704.1479260369815, 1706.4027590343528, 1706.505113517066, 1048.3133547526381, 1061.87257529097, 1070.2775626452114, 1076.9030787229187, 1082.832161015044, 5718.199452076713, 6903.551269167488, 6916.6541672916155, 6961.269066747918, 6982.0, 7497.212792656693, 7571.3942884569105, 7682.884756728654, 7724.536527808237, 7745.961270193613, 6955.095837541087, 6962.930370696329, 7021.473389495808, 7022.578645281299, 7041.366277035091, 4082.9183416332103, 4131.5248746393945, 4140.875773726793, 4141.7722025288895, 4145.97927010363, 8210.096889342167, 8212.222377706203, 8220.712275070344, 8249.917500825035, 8255.958720206361, 7686.116096648957, 8038.7186448475195, 8060.952076230053, 8086.636101375452, 8107.391945604103, 7376.926230737731, 7524.887126693104, 8128.39037072222, 8178.5092894426525, 8191.344692424473, 7739.7678069658, 7755.418343624364, 7757.3794096473275, 7761.534307941542, 7788.727394541163, 7317.707291708272, 7623.809404764926, 7645.585566670184, 7650.73222437212, 7657.617119144132, 6606.570572912938, 8154.388420868578, 8169.142240064945, 8176.182381761776, 8214.548199849065, 4760.000399916056, 4799.784009719501, 4807.495213002584, 4808.711477311373, 4837.588804951619, 7675.0, 7685.23147685227, 7686.730964416219, 7712.113106992768, 7733.381329493514, 7185.964070179615, 7459.216782237787, 7467.551946883204, 7468.925310746823, 7478.850422991619, 6122.663253521099, 7402.0, 7453.925460745323, 7456.96271518639, 7472.178060413242, 7237.240089790496, 7241.094863142107, 7241.5293005954445, 7267.890981635385, 7284.890726638999, 7471.103467583925, 7471.626418678936, 7475.0, 7476.177620461636, 7477.700891964478, 6033.849153771191, 7299.817504562428, 7315.597642129734, 7322.3775979042375, 7356.301151390609], "value": 5355.40549589903, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [677.292229620047, 690.299346163644, 708.4332533972821, 722.9566226026438, 752.766642340874, 14599.708005839877, 14805.777913331296, 15023.774643380344, 15071.39714411425, 15132.092074475539, 13758.724825503508, 13936.651583710387, 13972.510962116328, 14044.64888377791, 14060.64848378791, 739.8557281330143, 744.7542311037345, 909.2634965677822, 918.8392031394512, 931.2456909902967, 884.4295429448002, 915.2266334946993, 919.3105171121658, 919.6597259014175, 925.8379783537888, 933.6545478173085, 964.3587014635294, 965.6378857928277, 971.4948226921996, 983.1053741095604, 944.6221511395497, 952.1145334838598, 960.9135177833958, 970.6457143142773, 984.8818141822991, 705.3686950179612, 713.6289129652552, 715.2597062040815, 724.916634587024, 726.4914559808155, 12631.673674264253, 14526.927365363106, 14636.853631463655, 14840.777388339184, 14895.702085958328, 12518.74962500754, 12608.936955315165, 12631.305278209695, 12641.115121941468, 12781.233126012468, 9887.159591434736, 10016.849747253797, 10131.936146704564, 10146.594136234542, 10148.137408320228, 3465.7671040276596, 3514.121469632592, 3537.2218112015316, 3540.840662170183, 3563.322968635977, 76393.0, 76454.0, 76529.46941061203, 78085.43829123386, 78385.60807195984, 9172.8624070639, 9423.952880235554, 9467.573959171854, 9471.715848524555, 9586.089321514444, 21342.253021144497, 21348.146074157172, 21375.57248855014, 21411.82234977091, 21483.78516214849, 7334.889976650355, 7517.962410187914, 7536.585487798114, 7577.659005344773, 7637.389008879276, 4039.6768258539832, 4112.485939257593, 4118.114605359862, 4127.2570937230985, 4142.357934520154, 3573.4639804029603, 3590.730695197871, 3638.8180590970874, 3650.1239702471216, 3652.9634703653155, 654.3848782144769, 667.2160211751172, 668.355037388922, 669.494531628627, 671.8253254153857, 497.3335730121582, 591.5662112467002, 598.937111603284, 602.5209958083334, 615.566025951703, 8190.426670133033, 8245.79385515355, 8284.792880177924, 8451.535165565952, 8459.830803383898, 536.8469986053956, 595.484905556699, 606.5117580347862, 608.7017361492836, 623.6476390839161, 3808.5620153682125, 3865.666345110932, 3898.5126859142606, 3955.0903292242947, 4093.549709532009, 84580.0, 97630.02369976351, 97632.51183744035, 97900.0, 100220.0, 78019.0, 80132.59933700295, 80188.0, 81909.59045204855, 83517.32965340781, 75234.24765752272, 77216.61391693118, 77259.61370193113, 78641.82037269445, 79098.60450697824, 8530.274926631306, 8554.31565474773, 8577.656893724181, 8748.737537873874, 8771.254443372129, 38195.61804381921, 47504.2874356878, 52557.474425255255, 52626.0, 52880.94238115139, 67976.6601166991, 70101.29898700948, 70261.0, 71619.5676086465, 71833.6408317955, 10079.496025198858, 11340.319580825177, 11658.358790266617, 11689.123315751183, 11914.0, 11966.0, 11969.341686206993, 12112.75774484523, 12446.25322480654, 12471.189372690746, 9731.56207970629, 10734.463276836283, 11292.943535282271, 11702.590409335633, 11893.643190704293, 11361.431928403712, 11525.077993760648, 11585.768284634092, 11656.125790565911, 12202.694932626755, 10446.738831529272, 11154.776904462027, 11477.139214559109, 11505.942470287595, 12123.151379403356, 9503.239740820856, 9739.561719722771, 9781.902180978099, 10042.447665378475, 10081.747956301151, 9636.75908102303, 9662.08210220028, 9757.316987810786, 9782.021797820162, 9801.901980980385], "value": 6965.312005337499, "unit": "score"}], "name": "dromaeo_css", "value": 7551.85642497966, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 109.0, 116.0, 119.0, 140.0, 106.0, 119.0, 116.0, 116.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 157.0, 156.0, 102.0, 172.0, 145.0, 183.0, 158.0, 146.0], "value": 149.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 198.0, 202.0, 196.0, 194.0, 195.0, 200.0, 199.0, 198.0, 218.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 72.0, 108.0, 160.0, 57.0, 56.0, 56.0, 61.0, 150.0, 54.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 82.0, 138.0, 113.0, 111.0, 83.0, 123.0, 77.0, 105.0, 117.0], "value": 102.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 89.0, 87.0, 89.0, 93.0, 135.0, 86.0, 132.0, 127.0], "value": 105.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 139.0, 147.0, 119.0, 131.0, 146.0, 146.0, 96.0, 95.0, 95.0], "value": 121.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 139.0, 91.0, 89.0, 95.0, 90.0, 88.0, 90.0, 91.0, 92.0], "value": 97.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 65.0, 66.0, 65.0, 65.0, 64.0, 81.0, 63.0, 59.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 66.0, 67.0, 74.0, 69.0, 81.0, 65.0, 84.0, 97.0, 103.0], "value": 76.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 96.0, 103.0, 93.0, 83.0, 82.0, 82.0, 81.0, 84.0, 81.0], "value": 86.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 165.0, 151.0, 148.0, 137.0, 128.0, 133.0, 131.0, 168.0, 167.0], "value": 146.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 164.0, 164.0, 172.0, 173.0, 165.0, 168.0, 164.0, 165.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 76.0, 69.0, 57.0, 84.0, 94.0, 93.0, 94.0, 72.0], "value": 77.7, "unit": "score"}], "name": "kraken", "value": 1596.7000000000003, "alertThreshold": 2.0}]} 10:05:51 INFO - Return code: 0 10:05:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:05:51 ERROR - # TBPL SUCCESS # 10:05:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:05:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:05:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:05:51 INFO - 2016-05-30 10:05:51.871 screenresolution[2361:22449] starting screenresolution argv=screenresolution get 10:05:51 INFO - 2016-05-30 10:05:51.874 screenresolution[2361:22449] Display 0: 1600x1200x32@60 10:05:51 INFO - 2016-05-30 10:05:51.880 screenresolution[2362:22452] starting screenresolution argv=screenresolution list 10:05:51 INFO - Available Modes on Display 0 10:05:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:05:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:05:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:05:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:05:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:05:52 INFO - Intel Iris: 10:05:52 INFO - Chipset Model: Intel Iris 10:05:52 INFO - Type: GPU 10:05:52 INFO - Bus: Built-In 10:05:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:05:52 INFO - Vendor: Intel (0x8086) 10:05:52 INFO - Device ID: 0x0a2e 10:05:52 INFO - Revision ID: 0x0009 10:05:52 INFO - Displays: 10:05:52 INFO - Display: 10:05:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:05:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:05:52 INFO - Display Serial Number: 1600x1200 60 10:05:52 INFO - Main Display: Yes 10:05:52 INFO - Mirror: Off 10:05:52 INFO - Online: Yes 10:05:52 INFO - Rotation: Supported 10:05:52 INFO - Return code: 0 10:05:52 INFO - Running post-action listener: _resource_record_post_action 10:05:52 INFO - Running post-run listener: _resource_record_post_run 10:05:53 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 233472; Write bytes: 653881856; Read time: 1; Write time: 1830 10:05:53 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345668096; Write bytes: 345970176; Read time: 26514; Write time: 692 10:05:53 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 233472; Write bytes: 297954304; Read time: 1; Write time: 1101 10:05:53 INFO - Running post-run listener: _upload_blobber_files 10:05:53 INFO - Blob upload gear active. 10:05:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:05:53 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 10:05:53 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'] 10:05:53 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 10:05:53 INFO - (blobuploader) - INFO - Open directory for files ... 10:05:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:05:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:05:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:54 INFO - (blobuploader) - INFO - Done attempting. 10:05:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:05:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:05:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:05:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:05:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:05:55 INFO - (blobuploader) - INFO - Done attempting. 10:05:55 INFO - (blobuploader) - INFO - Iteration through files over. 10:05:55 INFO - Return code: 0 10:05:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:05:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:05:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/115a96449361f89a407e1f0c64088fbeea7814fb489294549016a2a04e217590c952293ea8d2eb23b0bd6c0c911ebf145ab4b0eaa1cf56a789e4a55fc23cd5cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcba261f0f66c596e7e94be36da5b53c6d0b5bcb1c9620fd1ab6306de140173fb6d02d7ef0c2b16759d977b168a1ec799f3fd23e25b9d7104a53162a8356976f"} 10:05:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:05:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:05:55 INFO - Contents: 10:05:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/115a96449361f89a407e1f0c64088fbeea7814fb489294549016a2a04e217590c952293ea8d2eb23b0bd6c0c911ebf145ab4b0eaa1cf56a789e4a55fc23cd5cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcba261f0f66c596e7e94be36da5b53c6d0b5bcb1c9620fd1ab6306de140173fb6d02d7ef0c2b16759d977b168a1ec799f3fd23e25b9d7104a53162a8356976f"} 10:05:55 INFO - Running post-run listener: copy_logs_to_upload_dir 10:05:55 INFO - Copying logs to upload dir... 10:05:55 INFO - mkdir: /builds/slave/test/build/upload/logs 10:05:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1259.198217 ========= master_lag: 1.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-05-30 10:05:56.567648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 10:05:56.572301) ========= 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.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/115a96449361f89a407e1f0c64088fbeea7814fb489294549016a2a04e217590c952293ea8d2eb23b0bd6c0c911ebf145ab4b0eaa1cf56a789e4a55fc23cd5cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcba261f0f66c596e7e94be36da5b53c6d0b5bcb1c9620fd1ab6306de140173fb6d02d7ef0c2b16759d977b168a1ec799f3fd23e25b9d7104a53162a8356976f"} build_url:https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011350 build_url: 'https://queue.taskcluster.net/v1/task/LW4pjFIfTMCQmUBHW4FT0A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/115a96449361f89a407e1f0c64088fbeea7814fb489294549016a2a04e217590c952293ea8d2eb23b0bd6c0c911ebf145ab4b0eaa1cf56a789e4a55fc23cd5cb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bcba261f0f66c596e7e94be36da5b53c6d0b5bcb1c9620fd1ab6306de140173fb6d02d7ef0c2b16759d977b168a1ec799f3fd23e25b9d7104a53162a8356976f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 10:05:56.608623) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:05:56.611943) ========= 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.zbX4B1gKqR/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.NBcM7VlewD/Listeners TMPDIR=/var/folders/_6/xqyggwrj3_z5x7zs3gplhbgr00000w/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.005212 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 10:05:56.660404) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:05:56.660684) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 10:05:56.664006) ========= ========= Total master_lag: 2.36 =========