builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0300 starttime: 1464169578.22 results: success (0) buildid: 20160525004047 builduid: 07a7102eb5e9489dbdb378a34d8aac30 revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.224785) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.225166) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.225436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.264493) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.264762) ========= 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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-25 02:46:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 152M=0s 2016-05-25 02:46:18 (152 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.507238 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.792414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.792732) ========= 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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.029107 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.847356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:18.847707) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --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 14079be66b19b1fa8c60fa34239bcff57ca51ab0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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-25 02:46:18,931 truncating revision to first 12 chars 2016-05-25 02:46:18,931 Setting DEBUG logging. 2016-05-25 02:46:18,932 attempt 1/10 2016-05-25 02:46:18,932 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/14079be66b19?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 02:46:19,188 unpacking tar archive at: mozilla-aurora-14079be66b19/testing/mozharness/ program finished with exit code 0 elapsedTime=0.517394 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:19.387772) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:19.388099) ========= script_repo_revision: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:19.388483) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:19.388755) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 02:46:19.404427) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-25 02:46:19.404715) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:46:19 INFO - MultiFileLogger online at 20160525 02:46:19 in /builds/slave/test 02:46:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:46:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:46:19 INFO - {'append_to_log': False, 02:46:19 INFO - 'base_work_dir': '/builds/slave/test', 02:46:19 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:46:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:46:19 INFO - 'branch': 'Mozilla-Aurora', 02:46:19 INFO - 'buildbot_json_path': 'buildprops.json', 02:46:19 INFO - 'config_files': ('talos/mac_config.py',), 02:46:19 INFO - 'default_actions': ('clobber', 02:46:19 INFO - 'read-buildbot-config', 02:46:19 INFO - 'download-and-extract', 02:46:19 INFO - 'populate-webroot', 02:46:19 INFO - 'create-virtualenv', 02:46:19 INFO - 'install', 02:46:19 INFO - 'run-tests'), 02:46:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:46:19 INFO - 'download_minidump_stackwalk': True, 02:46:19 INFO - 'download_symbols': 'ondemand', 02:46:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:46:19 INFO - 'tooltool.py': '/tools/tooltool.py', 02:46:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:46:19 INFO - '/tools/misc-python/virtualenv.py')}, 02:46:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:46:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:46:19 INFO - 'installer_path': 'installer.exe', 02:46:19 INFO - 'log_level': 'info', 02:46:19 INFO - 'log_name': 'talos', 02:46:19 INFO - 'log_to_console': True, 02:46:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:46:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:46:19 INFO - 'opt_config_files': (), 02:46:19 INFO - 'pip_index': False, 02:46:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:19 INFO - 'cmd': ('bash', 02:46:19 INFO - '-c', 02:46:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:19 INFO - 'enabled': True, 02:46:19 INFO - 'halt_on_failure': False, 02:46:19 INFO - 'name': 'check_screen_resolution'},), 02:46:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:46:19 INFO - 'cmd': ('bash', 02:46:19 INFO - '-c', 02:46:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:46:19 INFO - 'enabled': True, 02:46:19 INFO - 'halt_on_failure': False, 02:46:19 INFO - 'name': 'check_screen_resolution'},), 02:46:19 INFO - 'run_cmd_checks_enabled': True, 02:46:19 INFO - 'sps_profile': False, 02:46:19 INFO - 'sps_profile_interval': 0, 02:46:19 INFO - 'suite': 'dromaeojs', 02:46:19 INFO - 'system_bits': '32', 02:46:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:46:19 INFO - 'title': 't-yosemite-r7-0300', 02:46:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:46:19 INFO - 'use_talos_json': True, 02:46:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:46:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:46:19 INFO - 'work_dir': 'build'} 02:46:19 INFO - ##### 02:46:19 INFO - ##### Running clobber step. 02:46:19 INFO - ##### 02:46:19 INFO - Running pre-action listener: _resource_record_pre_action 02:46:19 INFO - Running main action method: clobber 02:46:19 INFO - rmtree: /builds/slave/test/build 02:46:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:46:21 INFO - Running post-action listener: _resource_record_post_action 02:46:21 INFO - ##### 02:46:21 INFO - ##### Running read-buildbot-config step. 02:46:21 INFO - ##### 02:46:21 INFO - Running pre-action listener: _resource_record_pre_action 02:46:21 INFO - Running main action method: read_buildbot_config 02:46:21 INFO - Using buildbot properties: 02:46:21 INFO - { 02:46:21 INFO - "project": "", 02:46:21 INFO - "product": "firefox", 02:46:21 INFO - "script_repo_revision": "production", 02:46:21 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 02:46:21 INFO - "repository": "", 02:46:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 02:46:21 INFO - "buildid": "20160525004047", 02:46:21 INFO - "stage_platform": "macosx64", 02:46:21 INFO - "basedir": "/builds/slave/test", 02:46:21 INFO - "nightly_build": "True", 02:46:21 INFO - "buildnumber": 11, 02:46:21 INFO - "slavename": "t-yosemite-r7-0300", 02:46:21 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 02:46:21 INFO - "platform": "yosemite_r7", 02:46:21 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:46:21 INFO - "branch": "mozilla-aurora", 02:46:21 INFO - "repo_path": "releases/mozilla-aurora", 02:46:21 INFO - "revision": "14079be66b19b1fa8c60fa34239bcff57ca51ab0", 02:46:21 INFO - "pgo_build": "False", 02:46:21 INFO - "builduid": "07a7102eb5e9489dbdb378a34d8aac30", 02:46:21 INFO - "slavebuilddir": "test" 02:46:21 INFO - } 02:46:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:46:21 INFO - Running post-action listener: _resource_record_post_action 02:46:21 INFO - ##### 02:46:21 INFO - ##### Running download-and-extract step. 02:46:21 INFO - ##### 02:46:21 INFO - Running pre-action listener: _resource_record_pre_action 02:46:21 INFO - Running main action method: download_and_extract 02:46:21 INFO - mkdir: /builds/slave/test/build/tests 02:46: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')]} 02:46:21 INFO - https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:46:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:46:44 INFO - Downloaded 1199 bytes. 02:46:44 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:46:44 INFO - Using the following test package requirements: 02:46:44 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:44 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:46:44 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:46:44 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'jsshell-mac.zip'], 02:46:44 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:46:44 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:46:44 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:46:44 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:46:44 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:46:44 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:46:44 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:46:44 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:46:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:44 INFO - https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:46:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:46:51 INFO - Downloaded 18425529 bytes. 02:46:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:46:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:46:54 INFO - Return code: 0 02:46:54 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:46:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:54 INFO - https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:46:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:46:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:46:59 INFO - Downloaded 18425529 bytes. 02:46: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'] 02:46: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 02:47:02 INFO - Return code: 0 02:47: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')]} 02:47:02 INFO - https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:47:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:47:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:47:03 INFO - Downloaded 10840145 bytes. 02:47:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:47:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:47:04 INFO - Return code: 0 02:47:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:04 INFO - https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:47:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:47:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:47:07 INFO - Downloaded 114243413 bytes. 02:47:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:07 INFO - mkdir: /builds/slave/test/properties 02:47:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:47:07 INFO - Writing to file /builds/slave/test/properties/build_url 02:47:07 INFO - Contents: 02:47:07 INFO - build_url:https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:47:08 INFO - Running post-action listener: _resource_record_post_action 02:47:08 INFO - Running post-action listener: set_extra_try_arguments 02:47:08 INFO - ##### 02:47:08 INFO - ##### Running populate-webroot step. 02:47:08 INFO - ##### 02:47:08 INFO - Running pre-action listener: _resource_record_pre_action 02:47:08 INFO - Running main action method: populate_webroot 02:47:08 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:47:08 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:47:08 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:47:08 INFO - '--tppagecycles', 02:47:08 INFO - '7'], 02:47:08 INFO - 'tests': ['tsvgm']}}, 02:47:08 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:47:08 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:47:08 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:47:08 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:47:08 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:08 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:47:08 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['damp', 'tps']}, 02:47:08 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'tests': ['damp', 'tps']}, 02:47:08 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['dromaeo_dom']}, 02:47:08 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:47:08 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'other-e10s': {'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['a11yr', 02:47:08 INFO - 'ts_paint', 02:47:08 INFO - 'tpaint', 02:47:08 INFO - 'sessionrestore', 02:47:08 INFO - 'sessionrestore_no_auto_restore', 02:47:08 INFO - 'tabpaint']}, 02:47:08 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['tsvgx', 02:47:08 INFO - 'tsvgr_opacity', 02:47:08 INFO - 'tart', 02:47:08 INFO - 'tscrollx', 02:47:08 INFO - 'cart']}, 02:47:08 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:47:08 INFO - 'tsvgr_opacity', 02:47:08 INFO - 'tart', 02:47:08 INFO - 'tscrollx', 02:47:08 INFO - 'cart']}, 02:47:08 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'talos_options': ['--disable-e10s'], 02:47:08 INFO - 'tests': ['tp5o']}, 02:47:08 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'tests': ['tp5o']}, 02:47:08 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'talos_options': ['--disable-e10s', 02:47:08 INFO - '--xperf_path', 02:47:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:08 INFO - 'tests': ['tp5n']}, 02:47:08 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:47:08 INFO - 'talos_options': ['--xperf_path', 02:47:08 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:47:08 INFO - 'tests': ['tp5n']}}, 02:47:08 INFO - 'talos.zip': {'path': '', 02:47:08 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:47:08 INFO - Running post-action listener: _resource_record_post_action 02:47:08 INFO - ##### 02:47:08 INFO - ##### Running create-virtualenv step. 02:47:08 INFO - ##### 02:47:08 INFO - Running pre-action listener: _resource_record_pre_action 02:47:08 INFO - Running main action method: create_virtualenv 02:47:08 INFO - Creating virtualenv /builds/slave/test/build/venv 02:47:08 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:47:08 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:47:08 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:47:08 INFO - Using real prefix '/tools/python27' 02:47:08 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:47:09 INFO - Installing distribute.............................................................................................................................................................................................done. 02:47:11 INFO - Installing pip.................done. 02:47:11 INFO - Return code: 0 02:47:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:11 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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:47:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:47:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:11 INFO - 'HOME': '/Users/cltbld', 02:47:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:11 INFO - 'LOGNAME': 'cltbld', 02:47:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:11 INFO - 'MOZ_NO_REMOTE': '1', 02:47:11 INFO - 'NO_EM_RESTART': '1', 02:47:11 INFO - 'PAGER': '/bin/cat', 02:47:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:11 INFO - 'PWD': '/builds/slave/test', 02:47:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:11 INFO - 'SHELL': '/bin/bash', 02:47:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:11 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:11 INFO - 'USER': 'cltbld', 02:47:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:11 INFO - 'XPC_FLAGS': '0x0', 02:47:11 INFO - 'XPC_SERVICE_NAME': '0', 02:47:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:12 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:47:12 INFO - Cleaning up... 02:47:12 INFO - Return code: 0 02:47:12 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:47:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:47:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:12 INFO - 'HOME': '/Users/cltbld', 02:47:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:12 INFO - 'LOGNAME': 'cltbld', 02:47:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:12 INFO - 'MOZ_NO_REMOTE': '1', 02:47:12 INFO - 'NO_EM_RESTART': '1', 02:47:12 INFO - 'PAGER': '/bin/cat', 02:47:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:12 INFO - 'PWD': '/builds/slave/test', 02:47:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:12 INFO - 'SHELL': '/bin/bash', 02:47:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:12 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:12 INFO - 'USER': 'cltbld', 02:47:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:12 INFO - 'XPC_FLAGS': '0x0', 02:47:12 INFO - 'XPC_SERVICE_NAME': '0', 02:47:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:12 INFO - Downloading/unpacking psutil>=0.7.1 02:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:47:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:15 INFO - Installing collected packages: psutil 02:47:15 INFO - Running setup.py install for psutil 02:47:15 INFO - building 'psutil._psutil_osx' extension 02:47:15 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:47:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:47:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:47:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:47:16 INFO - building 'psutil._psutil_posix' extension 02:47:16 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:47:16 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:47:16 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:47:16 INFO - ^ 02:47:16 INFO - 1 warning generated. 02:47:16 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:47:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:47:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:47:16 INFO - Successfully installed psutil 02:47:16 INFO - Cleaning up... 02:47:16 INFO - Return code: 0 02:47:16 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:16 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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:47:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:47:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:16 INFO - 'HOME': '/Users/cltbld', 02:47:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:16 INFO - 'LOGNAME': 'cltbld', 02:47:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:16 INFO - 'MOZ_NO_REMOTE': '1', 02:47:16 INFO - 'NO_EM_RESTART': '1', 02:47:16 INFO - 'PAGER': '/bin/cat', 02:47:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:16 INFO - 'PWD': '/builds/slave/test', 02:47:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:16 INFO - 'SHELL': '/bin/bash', 02:47:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:16 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:16 INFO - 'USER': 'cltbld', 02:47:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:16 INFO - 'XPC_FLAGS': '0x0', 02:47:16 INFO - 'XPC_SERVICE_NAME': '0', 02:47:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47:19 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:47:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:47:19 INFO - Installing collected packages: mozsystemmonitor 02:47:19 INFO - Running setup.py install for mozsystemmonitor 02:47:19 INFO - Successfully installed mozsystemmonitor 02:47:19 INFO - Cleaning up... 02:47:19 INFO - Return code: 0 02:47:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:19 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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:19 INFO - 'HOME': '/Users/cltbld', 02:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:19 INFO - 'LOGNAME': 'cltbld', 02:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:19 INFO - 'MOZ_NO_REMOTE': '1', 02:47:19 INFO - 'NO_EM_RESTART': '1', 02:47:19 INFO - 'PAGER': '/bin/cat', 02:47:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:19 INFO - 'PWD': '/builds/slave/test', 02:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:19 INFO - 'SHELL': '/bin/bash', 02:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:19 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:19 INFO - 'USER': 'cltbld', 02:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:19 INFO - 'XPC_FLAGS': '0x0', 02:47:19 INFO - 'XPC_SERVICE_NAME': '0', 02:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:20 INFO - Downloading/unpacking blobuploader==1.2.4 02:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:23 INFO - Downloading blobuploader-1.2.4.tar.gz 02:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:47:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:47:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:24 INFO - Downloading docopt-0.6.1.tar.gz 02:47:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:47:24 INFO - Installing collected packages: blobuploader, requests, docopt 02:47:24 INFO - Running setup.py install for blobuploader 02:47:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:47:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:47:24 INFO - Running setup.py install for requests 02:47:24 INFO - Running setup.py install for docopt 02:47:25 INFO - Successfully installed blobuploader requests docopt 02:47:25 INFO - Cleaning up... 02:47:25 INFO - Return code: 0 02:47:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:25 INFO - 'HOME': '/Users/cltbld', 02:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:25 INFO - 'LOGNAME': 'cltbld', 02:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:25 INFO - 'MOZ_NO_REMOTE': '1', 02:47:25 INFO - 'NO_EM_RESTART': '1', 02:47:25 INFO - 'PAGER': '/bin/cat', 02:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:25 INFO - 'PWD': '/builds/slave/test', 02:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:25 INFO - 'SHELL': '/bin/bash', 02:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:25 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:25 INFO - 'USER': 'cltbld', 02:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:25 INFO - 'XPC_FLAGS': '0x0', 02:47:25 INFO - 'XPC_SERVICE_NAME': '0', 02:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:25 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-eQgeqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-xu0A_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-4mXvTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-XZ3DCo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-J0grst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nIUxPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-oMQgvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-medKHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-eQd3Bb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-zmQBdQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:26 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-4U5ODz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-13yfsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-mCIwJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Yhu4n5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-KlShGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-TLf36b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:27 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-h_jtxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:47:27 INFO - Running setup.py install for manifestparser 02:47:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:47:27 INFO - Running setup.py install for mozcrash 02:47:27 INFO - Running setup.py install for mozdebug 02:47:28 INFO - Running setup.py install for mozdevice 02:47:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Running setup.py install for mozfile 02:47:28 INFO - Running setup.py install for mozhttpd 02:47:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Running setup.py install for mozinfo 02:47:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Running setup.py install for mozInstall 02:47:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:47:28 INFO - Running setup.py install for mozleak 02:47:28 INFO - Running setup.py install for mozlog 02:47:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for moznetwork 02:47:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozprocess 02:47:29 INFO - Running setup.py install for mozprofile 02:47:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozrunner 02:47:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:47:29 INFO - Running setup.py install for mozscreenshot 02:47:29 INFO - Running setup.py install for moztest 02:47:30 INFO - Running setup.py install for mozversion 02:47:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:47:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:47:30 INFO - Cleaning up... 02:47:30 INFO - Return code: 0 02:47:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:30 INFO - 'HOME': '/Users/cltbld', 02:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:30 INFO - 'LOGNAME': 'cltbld', 02:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:30 INFO - 'MOZ_NO_REMOTE': '1', 02:47:30 INFO - 'NO_EM_RESTART': '1', 02:47:30 INFO - 'PAGER': '/bin/cat', 02:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:30 INFO - 'PWD': '/builds/slave/test', 02:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:30 INFO - 'SHELL': '/bin/bash', 02:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:30 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:30 INFO - 'USER': 'cltbld', 02:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:30 INFO - 'XPC_FLAGS': '0x0', 02:47:30 INFO - 'XPC_SERVICE_NAME': '0', 02:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:47:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-cWLRdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:47:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-9eRjod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:47:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-21pIvA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:47:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-IQz86r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:47:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:47:30 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-TuAfLw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-07D1c0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-nm4oNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-fCyMXp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-U8jhUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-MZvWsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-va8R98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-Gdh4LD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-fwmuaZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-EtDzl5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:47:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:47:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:31 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-66FB3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:47:32 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-x1ScS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:47:32 INFO - Running setup.py (path:/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/pip-czdnkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:47:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:35 INFO - Downloading blessings-1.6.tar.gz 02:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:47:35 INFO - Installing collected packages: blessings 02:47:35 INFO - Running setup.py install for blessings 02:47:35 INFO - Successfully installed blessings 02:47:35 INFO - Cleaning up... 02:47:35 INFO - Return code: 0 02:47:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:47:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:35 INFO - Reading from file tmpfile_stdout 02:47:35 INFO - Current package versions: 02:47:35 INFO - blessings == 1.6 02:47:35 INFO - blobuploader == 1.2.4 02:47:35 INFO - docopt == 0.6.1 02:47:35 INFO - manifestparser == 1.1 02:47:35 INFO - mozInstall == 1.12 02:47:35 INFO - mozcrash == 0.17 02:47:35 INFO - mozdebug == 0.1 02:47:35 INFO - mozdevice == 0.48 02:47:35 INFO - mozfile == 1.2 02:47:35 INFO - mozhttpd == 0.7 02:47:35 INFO - mozinfo == 0.9 02:47:35 INFO - mozleak == 0.1 02:47:35 INFO - mozlog == 3.1 02:47:35 INFO - moznetwork == 0.27 02:47:35 INFO - mozprocess == 0.22 02:47:35 INFO - mozprofile == 0.28 02:47:35 INFO - mozrunner == 6.11 02:47:35 INFO - mozscreenshot == 0.1 02:47:35 INFO - mozsystemmonitor == 0.0 02:47:35 INFO - moztest == 0.7 02:47:35 INFO - mozversion == 1.4 02:47:35 INFO - psutil == 3.1.1 02:47:35 INFO - requests == 1.2.3 02:47:35 INFO - wsgiref == 0.1.2 02:47:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:35 INFO - 'HOME': '/Users/cltbld', 02:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:35 INFO - 'LOGNAME': 'cltbld', 02:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:35 INFO - 'MOZ_NO_REMOTE': '1', 02:47:35 INFO - 'NO_EM_RESTART': '1', 02:47:35 INFO - 'PAGER': '/bin/cat', 02:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:35 INFO - 'PWD': '/builds/slave/test', 02:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:35 INFO - 'SHELL': '/bin/bash', 02:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:35 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:35 INFO - 'USER': 'cltbld', 02:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:35 INFO - 'XPC_FLAGS': '0x0', 02:47:35 INFO - 'XPC_SERVICE_NAME': '0', 02:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:47:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:47:35 INFO - Cleaning up... 02:47:35 INFO - Return code: 0 02:47:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:47:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--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 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:47:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:47:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:35 INFO - 'HOME': '/Users/cltbld', 02:47:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:35 INFO - 'LOGNAME': 'cltbld', 02:47:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:35 INFO - 'MOZ_NO_REMOTE': '1', 02:47:35 INFO - 'NO_EM_RESTART': '1', 02:47:35 INFO - 'PAGER': '/bin/cat', 02:47:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:35 INFO - 'PWD': '/builds/slave/test', 02:47:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:35 INFO - 'SHELL': '/bin/bash', 02:47:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:35 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:35 INFO - 'USER': 'cltbld', 02:47:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:35 INFO - 'XPC_FLAGS': '0x0', 02:47:35 INFO - 'XPC_SERVICE_NAME': '0', 02:47:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:36 INFO - Downloading/unpacking jsonschema 02:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:47:38 INFO - Downloading/unpacking functools32 (from jsonschema) 02:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:38 INFO - Downloading functools32-3.2.3-2.tar.gz 02:47:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:47:39 INFO - warning: no files found matching '*.txt' 02:47:39 INFO - no previously-included directories found matching 'build' 02:47:39 INFO - no previously-included directories found matching 'dist' 02:47:39 INFO - no previously-included directories found matching '.git*' 02:47:39 INFO - Installing collected packages: jsonschema, functools32 02:47:39 INFO - Running setup.py install for functools32 02:47:39 INFO - warning: no files found matching '*.txt' 02:47:39 INFO - no previously-included directories found matching 'build' 02:47:39 INFO - no previously-included directories found matching 'dist' 02:47:39 INFO - no previously-included directories found matching '.git*' 02:47:39 INFO - Successfully installed jsonschema functools32 02:47:39 INFO - Cleaning up... 02:47:39 INFO - Return code: 0 02:47:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:47: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')]} 02:47:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:47:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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')]} 02:47:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:47:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:47: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2f4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d304420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa502d6b890>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, '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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:47: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', 'flake8'] in /builds/slave/test/build 02:47: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 flake8 02:47:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:47:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:47:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:47:39 INFO - 'HOME': '/Users/cltbld', 02:47:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:47:39 INFO - 'LOGNAME': 'cltbld', 02:47:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:47:39 INFO - 'MOZ_NO_REMOTE': '1', 02:47:39 INFO - 'NO_EM_RESTART': '1', 02:47:39 INFO - 'PAGER': '/bin/cat', 02:47:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:47:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:47:39 INFO - 'PWD': '/builds/slave/test', 02:47:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:47:39 INFO - 'SHELL': '/bin/bash', 02:47:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:47:39 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:47:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:47:39 INFO - 'USER': 'cltbld', 02:47:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:47:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:47:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:47:39 INFO - 'XPC_FLAGS': '0x0', 02:47:39 INFO - 'XPC_SERVICE_NAME': '0', 02:47:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:47:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:47:39 INFO - Downloading/unpacking flake8 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47: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 02:47:42 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:47:42 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:47:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:47:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:47:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:47:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:43 INFO - no previously-included directories found matching 'docs/_build' 02:47:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:47:43 INFO - Running setup.py install for pep8 02:47:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:47:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:47:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:47:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:47:43 INFO - no previously-included directories found matching 'docs/_build' 02:47:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:47:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:47:43 INFO - Cleaning up... 02:47:43 INFO - Return code: 0 02:47:43 INFO - Running post-action listener: _resource_record_post_action 02:47:43 INFO - Running post-action listener: _start_resource_monitoring 02:47:43 INFO - Starting resource monitoring. 02:47:43 INFO - ##### 02:47:43 INFO - ##### Running install step. 02:47:43 INFO - ##### 02:47:43 INFO - Running pre-action listener: _resource_record_pre_action 02:47:43 INFO - Running main action method: install 02:47:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:47:43 INFO - Reading from file tmpfile_stdout 02:47:43 INFO - Detecting whether we're running mozinstall >=1.0... 02:47:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:47:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:47:44 INFO - Reading from file tmpfile_stdout 02:47:44 INFO - Output received: 02:47:44 INFO - Usage: mozinstall [options] installer 02:47:44 INFO - Options: 02:47:44 INFO - -h, --help show this help message and exit 02:47:44 INFO - -d DEST, --destination=DEST 02:47:44 INFO - Directory to install application into. [default: 02:47:44 INFO - "/builds/slave/test"] 02:47:44 INFO - --app=APP Application being installed. [default: firefox] 02:47:44 INFO - mkdir: /builds/slave/test/build/application 02:47:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:47:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:48:15 INFO - Reading from file tmpfile_stdout 02:48:15 INFO - Output received: 02:48:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:48:15 INFO - Running post-action listener: _resource_record_post_action 02:48:15 INFO - ##### 02:48:15 INFO - ##### Running run-tests step. 02:48:15 INFO - ##### 02:48:15 INFO - Running pre-action listener: _resource_record_pre_action 02:48:15 INFO - Running main action method: run_tests 02:48:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:48:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:48:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:48:15 INFO - 2016-05-25 02:48:15.545 screenresolution[1641:8106] starting screenresolution argv=screenresolution get 02:48:15 INFO - 2016-05-25 02:48:15.547 screenresolution[1641:8106] Display 0: 1600x1200x32@60 02:48:15 INFO - 2016-05-25 02:48:15.553 screenresolution[1642:8109] starting screenresolution argv=screenresolution list 02:48:15 INFO - Available Modes on Display 0 02:48:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:48:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:48:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:48:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:48:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:48:15 INFO - Intel Iris: 02:48:15 INFO - Chipset Model: Intel Iris 02:48:15 INFO - Type: GPU 02:48:15 INFO - Bus: Built-In 02:48:15 INFO - VRAM (Dynamic, Max): 1536 MB 02:48:15 INFO - Vendor: Intel (0x8086) 02:48:15 INFO - Device ID: 0x0a2e 02:48:15 INFO - Revision ID: 0x0009 02:48:15 INFO - Displays: 02:48:15 INFO - Display: 02:48:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:48:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:48:15 INFO - Display Serial Number: 1600x1200 60 02:48:15 INFO - Main Display: Yes 02:48:15 INFO - Mirror: Off 02:48:15 INFO - Online: Yes 02:48:15 INFO - Rotation: Supported 02:48:15 INFO - Return code: 0 02:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:48:15 INFO - Python 2.7.3 02:48:15 INFO - Return code: 0 02:48:15 INFO - grabbing minidump binary from tooltool 02:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:48:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d193e00>, '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 0x10cffcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d18eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:48:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:48:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:48:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:48:15 INFO - Return code: 0 02:48:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:48:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:48:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:48:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:48:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:48:16 INFO - Return code: 0 02:48:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 02:48:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0300 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 02:48:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.1REbc0BHZl/Render', 02:48:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:48:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:48:16 INFO - 'HOME': '/Users/cltbld', 02:48:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:48:16 INFO - 'LOGNAME': 'cltbld', 02:48:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:48:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:48:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:48:16 INFO - 'MOZ_NO_REMOTE': '1', 02:48:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:48:16 INFO - 'NO_EM_RESTART': '1', 02:48:16 INFO - 'PAGER': '/bin/cat', 02:48:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:48:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:48:16 INFO - 'PWD': '/builds/slave/test', 02:48:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:48:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:48:16 INFO - 'SHELL': '/bin/bash', 02:48:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3CFcDmYxAZ/Listeners', 02:48:16 INFO - 'TMPDIR': '/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/', 02:48:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:48:16 INFO - 'USER': 'cltbld', 02:48:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:48:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:48:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:48:16 INFO - 'XPC_FLAGS': '0x0', 02:48:16 INFO - 'XPC_SERVICE_NAME': '0', 02:48:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:48:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0300', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 02:48:17 INFO - mozversion application_buildid: 20160525004047 02:48:17 INFO - mozversion application_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 02:48:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:48:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:48:17 INFO - mozversion application_name: Firefox 02:48:17 INFO - mozversion application_remotingname: firefox-dev 02:48:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:17 INFO - mozversion application_vendor: Mozilla 02:48:17 INFO - mozversion application_version: 48.0a2 02:48:17 INFO - mozversion platform_buildid: 20160525004047 02:48:17 INFO - mozversion platform_changeset: 14079be66b19b1fa8c60fa34239bcff57ca51ab0 02:48:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:48:17 INFO - mozversion platform_version: 48.0a2 02:48:17 INFO - using testdate: 1464169697 02:48:17 INFO - actual date: 1464169697 02:48:17 INFO - starting webserver on 'localhost:49270' 02:48:17 INFO - SUITE-START | Running 2 tests 02:48:17 INFO - TEST-START | dromaeo_css 02:48:17 INFO - operating with platform_type : mac_ 02:48:17 INFO - Initialising browser for dromaeo_css test... 02:48:17 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp9A0Osk/profile http://localhost:49270/getInfo.html) 02:48:19 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 02:48:19 INFO - PROCESS | 1660 | colorDepth:24 02:48:19 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 02:48:19 INFO - PROCESS | 1660 | __metrics 02:48:19 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:48:19 ERROR - PROCESS | 1660 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 02:48:20 INFO - TEST-INFO | 1660: exit 0 02:48:20 INFO - Browser initialized. 02:48:20 INFO - Running cycle 1/1 for dromaeo_css test... 02:48:20 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmp9A0Osk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:48:32 INFO - PROCESS | 1664 | RSS: Main: 211251200 02:48:32 INFO - PROCESS | 1664 | 02:49:24 INFO - PROCESS | 1664 | 1464169764067 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:51:34 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 02:51:35 INFO - PROCESS | 1664 | RSS: Main: 324288512 02:51:35 INFO - PROCESS | 1664 | 02:54:38 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 02:54:39 INFO - PROCESS | 1664 | RSS: Main: 355471360 02:54:39 INFO - PROCESS | 1664 | 02:57:41 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 02:57:42 INFO - PROCESS | 1664 | RSS: Main: 363429888 02:57:42 INFO - PROCESS | 1664 | 03:00:44 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 03:00:45 INFO - PROCESS | 1664 | RSS: Main: 361459712 03:00:45 INFO - PROCESS | 1664 | 03:03:48 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 03:03:48 INFO - PROCESS | 1664 | RSS: Main: 328478720 03:03:48 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 03:06:51 INFO - PROCESS | 1664 | RSS: Main: 366243840 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | __start_tp_report 03:06:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 03:06:51 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 03:06:51 INFO - PROCESS | 1664 | |i|pagename|runs| 03:06:51 INFO - PROCESS | 1664 | |0;dojo.html;3386.8306584670763;3455.568053993251;3467.3758723429773;3489.1102768793953;3523.3129539739743;5670.4896559309655;5716.685582292972;5717.2281741964825;5741.425857414257;5760.683162426065;5666.178404131399;5746.770129194838;5746.885062298751;5747.655140691551;5769.740361683724;5155.355580552431;5206.375234971809;5208.114620514502;5305.126765601642;5402.432744561824;5275.182346736262;5277.419483856773;5284.471552844704;5287.814926477574;5294.338207724035;5185.092608793465;5243.344581927259;5280.128778751501;5288.682679039251;5312.681239125645;5299.867503312429;5339.733013349356;5343.732813359316;5344.412114667383;5369.731513424353;5136.049830781318;5174.534291913746;5195.896082078375;5280.656757310762;5282.550983166436;5547.362053363834;5588.776448942038;5622.943770562324;5637.577181711347;5652.717364131777;6248.593841400295;6250.531210159256;6273.12176295319;6281.434670879643;6289.685515724195;6130.111133885606;6229.563930524865;6321.367863213688;6340.873182536369;6369.4585960193435;5807.912881306784;5822.214001109796;5824.504917082011;5832.766689332421;5843.912341314884;6288.088227207121;6308.022256550242;6310.684465776692;6311.779087731908;6349.428551430394;6020.187274717945;6073.29738266476;6093.451589356979;6101.942208431344;6183.659898705614;5336.386315573759;6004.7598096076545;6151.138840562236;6156.26124865019;6206.348333425016;6213.099100630383;6220.409061139185;6240.5319601030105;6256.468200202943;6275.152854364603;6221.160143380585;6265.8120256392385;6265.937340626627;6275.623462592259;6288.52836037299;4830.806767729322;4887;4915.95084049155;4918.754062296871;4959.975200123977;4099.938500922489;4388.166248412791;4396.802143903532;4441;4462.263726485158;5643.435656434406;5670.858228544319;5677.829665110054;5685.943140568624;5706.572007099484;6135.048937646369;6229.844253893689;6234.594751341239;6333.398327158912;6349.904751428732;5707.857303567361;5709.429057094258;5710.71446427677;5713.285839270091;5723.427657234327;5398.163284690879;5431;5433.728313584384;5436.130219164915;5443.673379597158;6240.063990401476;6341.207349081365;6407.7757278495965;6409.198850143732;6419.935800642027;6293.150424692608;6334;6336.7465301389275;6344.048392741126;6346.650934198478;6364.204474440695;6388.712507937247;6391.073294372197;6401.679916004275;6413.903791443039;6071.058985857199;6109.647596799152;6169.96915015422;6189.84525386869;6240.625562466266;6156.107364432221;6243.875122497616;6251.718672659832;6314.589551679126;6328.398802113792;6149.508039356931;6150;6168.537359697951;6176.382361763787;6179.134921111138;6046.486048685911;6136.693165341804;6137.3862613738265;6145.84635384119;6150.354212807591;5674.375818659862;5781.21953536284;5869.383714709894;5966.017991646318;5978.133170690138;6036.9698151508965;6044.395560443921;6049.455549000611;6053.130929791166;6090.390960903874;5822.73797679097;5911.201987731602;5920.023196172583;5929.881402371842;5933.288005439203 03:06:51 INFO - PROCESS | 1664 | |1;ext.html;537.666646679059;540.4757385336223;579.2296245992829;585.797899724595;594.2809200866956;31843;32607.347853042953;37472.81263593678;38331;38568.6500972466;41075.35698572054;41532.58467415325;41628.791856040756;41639.167216655725;41650;9430;9648.5175741213;9649.228061755042;9655.710328690151;9660.903390966107;8670.783230419258;8862.158094980967;11387.373694446802;11574.42127893606;11604.419779011057;9814.754631134243;11450.198486105975;11487.138464615145;11500.82748758765;11501.07991360689;8583.699570515064;8772.254358379545;11354.71613209668;11408.258463199949;11425.08599312053;9809.460479673615;11634.941825290904;11655.708607284885;11660.475278612483;11667.299962002307;37922.43116353257;38292.08539573038;38357;38363.616363836285;38528.036799079964;3201.9039428817173;3243.1567792373917;3525.2596954639557;3684.7420680552373;3806.809659517041;27363.726362736317;27411.451770964468;27706;28405.57391639127;28833.27916802076;2771.0855417712105;2774.4728501584636;2795.3989892173076;2804.060639685708;2815.7325054119824;369017;369852;370193;489872;489938.99999999645;62304;62649;62672;62681;62777.68611156915;63680.36319636744;67277.66361168261;68387.65806171036;68454.65772671104;68515.31484685189;17282.740758888627;17327.56681082983;17493;17523.474295771146;17555.21001554933;12500.687482813002;12754.979601631847;13392.196468211938;13481;13677.931610342084;17001.744973825404;18505.35231266899;19157.233710651697;19166.71249931252;19198.520036999187;7824.334931530883;8147.877781833277;8260.802183683332;8296.626651800683;8327.333813294921;970.257752819094;991.3704797453669;997.910188083076;1000.5547531348626;1160.3849959521467;27464;27788;28256;28335.43329133447;28431.71568284332;1003.392947266893;1005.7787286796893;1113.2151832957745;1116.8883111688765;1149.4999675141341;14573.708525829425;15273.236338183046;15618;15964.92017539905;16270.349186032663;589278;597393.1042758328;597990;601219;601642;59856;60287.69856150691;60346.396536034954;60519;60555;1236.5795629486017;1355.8711922367359;1481.8444063373622;1516.6056825225521;1532.8620424161657;28537.714622854335;28543.42913141767;28692.856535717186;29158.41683166367;29363.85318073396;50497.49502504928;50730.731731707005;51400;51417.742911285204;51489;438953;518208;519987;520727;521148.39425802627;23214.535709286058;26026.60960085638;26318.736812632396;26351.60472592951;26702;39397.409038865015;41017;41522.96192595088;41544.58455415407;41893.37159942542;32743.508847366833;34478.48282275818;34947.825260873535;35219.47170792389;35633.46549801807;34351;34673.47989780105;34762.65237347695;35031.598736051295;36686.63313366832;35407;35602;35872.02703851313;35970.10074748152;36409;31829;31924;31950.881719139717;32039.6796032046;32530.837345814067;20038.398848034583;23663.763362366157;24017.759822401553;24183.78356777443;24354.02583896624 03:06:51 INFO - PROCESS | 1664 | |2;jquery.html;332.8931303416141;409.9795010249488;410.0507325541373;415.21109891206703;423.4896949176243;18983.33558325459;19737;20392.69410958835;20423.795762042377;20469.771813691154;20166.395008149742;20248.69626955593;20488.487787805312;22041.448963775914;22089.116435342567;5766.106253530709;5874.236349274588;5954.315253745832;5954.851128721773;5981.641101533922;5923.022701254309;5923.526117910578;5974.970125149368;5982.670953097578;5992.221011268529;5708.343540492834;5782.710864456782;5892.764289428418;5906.025505791539;6109.633421994672;6015.218021657179;6025.15647809307;6062.090686397076;6070.150178974947;6070.969645151768;5054.216596427523;5848.239728835224;5900.439458251502;5907.379725128842;5913.763449462016;13815.723685526335;14110.717785644332;14204.360803763855;14216;23294;2020.6160829442358;2318.2465698648007;2536.9746302537105;2569.4218800769863;2900.6809250982374;9901.801963960752;10454.947725261325;10767.56929722818;12783.360831958458;14959.551213463614;1527.6868242010473;1760.7270873014704;1821.1349608935782;1827.5339788353886;1895.232430865488;105187;110540.34189487084;110619.44690276578;115338;116156.41921790421;11908.583199587973;11914.285142891455;11944;11957.70105747346;11969.760604787856;12862.421191046426;12897.290649014394;15376.538703838904;15494.457693980656;15535;7470.962645186739;9032;9032.232260257952;9070.229030532479;9147.725568232963;7445.8883116753295;8314.667413303521;9081.954590227138;9213.078692130868;9230.723078307661;4703.83536576225;4758.666893317504;4775.5224477552665;4784.234522476387;4791.712497250176;1927.7397551330391;1985.612805502942;2004.7293615362032;2026.7940575357638;2047.62119007984;920.2591913509555;990.782027953849;1016.2378216337746;1029.8609687692215;1083.91870609703;19951.401457956286;20328.186872525228;20555.4861128473;20615.58768824645;20838.270660527112;980.2256217588168;1008.4755926917961;1016;1032.4270030133348;1034.8085604163255;10740.570377184982;10888.074513666426;10911.618093366695;10919.890801092046;10924.672259832218;568650;601185;604702.9764851235;619932;625989;110627.44686276678;113629;115969.42015290038;121207;123053;980.7499087732582;995.2933417273672;1000.68478429296;1001.759577701361;1035.2753072848884;14936.925315373353;17577;18154;18216.544586385447;18494.445166645022;50370.244446334065;51363.48636513737;51838.481615183366;52040;52582.21126683179;68882;71074.86775396747;73114;74862.26239638458;75939.24060759542;8297.668093276201;8352.791180220544;8376.49741015541;8481.27909127731;8512.246674126569;7816.921830781619;12041.939790300992;12582.496700131893;12696.746065078461;12918.289494077915;11019.889801101886;11180.888191117985;11210.383428911487;11230.3261804292;11248.550057997589;9112.453252804853;10711.678649640524;11108;11147.72130696739;11320.433978301216;11165.106791456501;11301.43492825339;11316.26444281119;11632.31331456917;11688.18182727201;6827.829304267433;8322.916770832215;8406.915930840612;8432.03031651368;8652.826943461221;9856.655017074369;11225.158113141384;11441.313521188422;11464.541418343173;11518.07855371585 03:06:51 INFO - PROCESS | 1664 | |3;mootools.html;1459.0443259664924;1604.149800605679;1619.7084524785537;1621.9026858388497;1953.3065761654611;7489.775306740793;7940.523568585882;8780.560971951392;8807.515586642732;8814.823703525934;8506.6172022259;8752.29981601472;8769.079246679103;8769.693060742875;8782.516961567111;2179.030331502482;2381.428457170275;2417.0815090265764;2452;2461.027893981876;2475.220305603738;2483.205374280231;2483.776460118589;2487.2911220302217;2492.289697436231;2305.4697419593504;2350.5533948549805;2370.9923282604823;2371.620540713486;2372.9406764830933;2174.7716489768663;2394.748551402095;2452.0927256914965;2455.5457240410583;2460.20043485866;2420.2739178246457;2454.2023842251338;2454.631805229212;2456.2876765737915;2458.4714286428502;8632.482051076955;8870.955645221733;8871.423357481808;8873.733787986306;8884.20042196199;5345.412004679482;5556.833295001156;5570.86072848178;5572.0527510323345;5584.972075139639;3489.7382696297627;3555.3600351936643;3616.18635806944;3637.4543818427182;3647.74465787395;1018.45003698003;1044.6552637629563;1044.69703785902;1053.020690757595;1069.9893001069968;12055.33695646739;12112.939435302855;12124.939375303155;12139.271643701319;12154.149209555335;4815.662903596784;4897.534734200317;4935.506449355107;4944.258361245842;4975.228839529878;10297.845532317022;10302;10304.330218535772;10306.12397946168;10315.381077135396;5522.0336441122645;5556.861078473071;5557.861053473695;5564.944350556523;5611.438856114438;4469.351943968171;4518.1415531049;4581.65637577183;4582.198115329804;4584.106099310602;3477.51314815926;3993.3011722948368;4086.2849001424634;4136.96575856036;4143.56492568282;1342.597220833746;1356.5455572383362;1362.1146254934372;1362.9659258518418;1374.2785037855206;730.777112980546;771.9886948098009;867.4405008769435;930.8184903943666;953.5518306396121;5874.089516125007;5879.647221166743;5996.5202783777195;6063.029915213545;6087.939120608826;924.1451657217182;936.8360536906014;938.2869019545195;967.685502211784;989.9901000990042;3652.415613501827;3987.003249187703;3991.7405368650943;4001.8999525011527;4060.796960151981;10449.634262800766;10933.070688991365;10959.945200273947;10970.012698857141;11009.339439633648;10512.580801591841;10767.515461804238;10944.671659850217;10972.615958441576;10981.066609338135;7558.773236802905;8240.340772738167;8620.051794302623;8634.395592308603;8644.438111522857;5727.599068065362;5734.139879018014;5821.534277257724;5862.12068189775;5862.706864656665;9188.035256297993;9499.240060795259;9691.982341854004;9724.70825875225;9765.609375624896;9451.905480945103;9478.715638530855;9791.902080979384;9984.450855203033;9998.250131240331;6954.965225173842;7387.8153046174275;8283.502989820388;8295.543745094077;8316.251537361666;7385.818269076922;7680.308772210527;8073.475224110414;8167.14245004266;8333.916660833314;7178.533395329287;7299.124105107421;7345.77962661121;7440.181580026301;7475.028246327943;6934.479914006571;7034.577925324497;7128.394086502705;7267.527610705287;7305.890411643936;6847.794566163023;6945.548539344926;6951.965240173767;6980.965095174492;7033.120859892513;4861.927071094007;4912.631552633496;4960.627952903475;5028.245763135559;5106.693598384109;4869.732164730974;4963.875903102451;5009.223570346602;5029.59763218949;5188.377394712658 03:06:51 INFO - PROCESS | 1664 | |4;prototype.html;324.98212598307094;327.2293748222935;334.52664479761137;334.7790458297524;341.35483935362186;6559.77040803572;6584.506162037848;6632.137822083122;6685.832854178636;6712.362325579074;6554.803355899318;6593.670316484168;6619.966900165505;6625.403713665769;6628.900566491507;4702.459217190016;4814.06125805468;4816.566509014188;4894.975525122387;4951.455339912624;4354.804033818486;4379.496357918848;4898.657094003421;4917.926231106536;4919.926201106986;4854.417469903598;4869.172240719086;4873.1228378891965;4878.0731660984475;4890.682105663138;4048.251073551388;4707.505711900252;4794.256890182028;4827.879303017453;4852.660313778037;4133.090720041586;4139.234241665302;4148.149629326011;4148.91702165958;4156.771377574232;5290.973545132289;6128.938710612926;6243.282022567418;6254.249490061176;6291.087792270141;5684.8010319639025;5700.002499562602;5701.4013528579735;5711.571632127607;5721.399253078408;6375.266844312917;6974.407175390098;6979.267176946399;6992.1259842519685;6992.160940687099;4074.9796251018556;4511.909761804745;4529.32060190974;4576.771161441948;4577.862664120082;7372.963135184344;7488.101427828641;7493.100827900686;7498.962505187494;7513.248675132498;4578.313253012075;4615.26155815068;4625.190591646448;4626.930596041062;4628.745419001987;4666.743329116931;4667.836625718083;4670.602998745077;4677.18149323867;4682.08034705769;5568.805091821767;5575.470330318613;5581.162825576196;5582.33012038558;5595.580331475156;6781.254062053269;7011.5793052416;7017.824554386079;7031.964840175767;7084.36240738336;1607.718649236379;1615.8626516746208;1620.1899050474763;1622.3916031488193;1627.6826018926433;804.4851295171095;983.095552092077;1023.1098943918838;1025.7845852370936;1046.8641523946603;6461.967690161519;6603.438707709803;6606.537542372084;6622.470202383798;6646.534742567974;7407.481476296607;7416;7425.777226683208;7428.85142297151;7443.813904652318;6600.075989361493;6621.50338724588;6654.301298363699;6660.83347916306;6661.500387470959;3851.614838516126;3889.3582558878034;3896.3960586109197;3900.258950799339;3901.43429202764;6518.631087471684;7470.551766894004;7597.480503899132;7753.991981042428;7778.988731464873;7573;7622.31399174077;7622.390208783395;7632.732854350071;7637.083549973928;7768.689252429953;7769.417293702995;7772.45592808498;7781.727639532589;7787.247869229378;7083.539569927938;7338.596377199306;7352.448566357438;7366.889496657662;7373;7240.8913866290995;7266.019087423341;7283.817904552428;7289.927100728924;7291.343779059698;6281.560290779602;7767.805804854698;7773.18381569927;7788.766337009899;7789.883151752615;4599.563041511051;4632.073585283024;4645.651576131736;4649.86050418488;4656.650751193606;7050.400715939202;7311.268873112646;7314.707411703473;7317.5609463432365;7319.377852882351;7052.717891284291;7053.4004609608755;7062.823429414306;7084.681189346388;7087.043249161297;7094.467914906506;7097.219305876452;7105.928940710527;7122.074130363019;7124.786256412316;6914.481413894078;6915.308469153045;6917.654117294014;6921.411680007256;6957.199922009034;6855.348741869514;7083.468739844429;7091.787246382617;7100.644967751488;7116;6640.103586016019;6785.7624983127325;6880.58716477013;6918.965405172942;6958.304169583002 03:06:51 INFO - PROCESS | 1664 | |5;yui.html;706.7420391557082;710.9111361079862;733.3033618062839;738.5605564689015;756.3986630628651;14806.925965370172;14942.252887355617;15145.92427037866;15190.936634435593;15197.392104315828;13679.589612311647;13943.511977080805;13981.930090349559;14024.228667423313;14043;760.2321655128333;770.6108415250291;872.7905302727363;965.8502932045544;966.9274804389664;828.8549503836834;963.903609639034;966.1160038564705;970.0978090375947;972.1445128287132;999.5002498750624;1012.0335079998621;1012.8632634594346;1014.1126514299988;1015.9593616255341;786.3709032773787;976.6728146071075;994.1996692662403;1007.7480629842539;1008.2236677758085;738.1216352540513;742.8477162181794;745.4856149256996;746.9066366704162;748.5733132114697;12995.350232488337;13152.210867347894;13161.473541058345;13165;13175.407106680223;12493.312867792269;12572.559960401342;12722.173058751245;12866.292353920531;12888.935555322258;10111.797764044752;10256.435896025716;10271.126954208901;10551.366917984946;11052.171087168414;3456.2396272819938;3514.7539672222956;3518.9648103518894;3563.2695297464215;3754.5682246541724;81138.59430702809;82285;82759.9999999994;82850;83000.58499707463;9372.812543749087;9680.806383872283;9734.95132524347;9764.707058788248;10417.9479102604;22726.431839203822;22743.203987860677;22748.09007639709;22761.430964226027;22774.65838012431;7385.519941203805;7526.09686837572;7696.538207707556;7744.806379840549;7821.413393995473;3998.420229066769;4091.0386059276047;4121.773302468333;4170.35359519275;4179.791010449465;3676.669099780979;3737.3085979093953;3740.2519496100344;3745.831437585315;3746.400575907842;600.891839468891;643.327722529948;652.292262894755;665.6871270502855;678.6233640329566;523.9109351410269;564.2777245126244;595.3272801734013;602.2351613450893;606.9681541379584;7903.802404939923;7940.091295137897;8044.356451483867;8077.232662897015;8311.542865142475;578.398465595784;595.2737660054727;599.7537434960469;606.7087797856965;607.6536374266714;3145.370925814846;3168.002079344999;3368.1747971747077;3370.4944258361443;3471.2536804587135;92812.07187928169;94537.527312363;95798;95995.04004960001;96162.26864119584;62301.68849155725;81081.59459202785;81220.18779812244;83579;83719;67594;69393;69571.95642065373;70119.64940175267;71385;6654.667266636746;6657.933420665925;6701.932980670131;6783.186017677713;6823.1812182538415;45604;45909.31136032895;46823.53176468192;48144.7592762034;51772.74113629558;65704;68401.65799170973;68680.6565967167;70360.29639703537;72175.27824721686;11000.174986876176;11323.660290191307;11461.150573107023;11488.253263537843;11522.711932201762;11798.646040618794;12136.453859576517;12159.45282462309;12457.813132802834;12565;11153.55385784592;11545.307281563133;11553.826692599785;11807.704807379885;11893.702657433634;9356.95321523388;11274.718132046764;11476.598319058792;11603.535858565563;12392.070594705252;10912.508937098008;11152.275102118336;11325.660230193107;11363.602273920373;11739.354335511629;9566.760830979281;9571.343236425464;9741.756456088655;9930.106290433896;10144.442055687008;9469.810603787748;9642.469664168257;9662.3719458235;9871.605135794774;9887.653932112342 03:06:51 INFO - PROCESS | 1664 | __end_tp_report 03:06:51 INFO - PROCESS | 1664 | __start_cc_report 03:06:51 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1008 03:06:51 INFO - PROCESS | 1664 | __end_cc_report 03:06:51 INFO - PROCESS | 1664 | __startTimestamp1464170811774__endTimestamp 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | ------- Summary: start ------- 03:06:51 INFO - PROCESS | 1664 | Number of tests: 6 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5759.99 Median:5929.88 stddev:633.07 (10.7%) stddev-sans-first:607.02 03:06:51 INFO - PROCESS | 1664 | Values: 3386.8 3455.6 3467.4 3489.1 3523.3 5670.5 5716.7 5717.2 5741.4 5760.7 5666.2 5746.8 5746.9 5747.7 5769.7 5155.4 5206.4 5208.1 5305.1 5402.4 5275.2 5277.4 5284.5 5287.8 5294.3 5185.1 5243.3 5280.1 5288.7 5312.7 5299.9 5339.7 5343.7 5344.4 5369.7 5136.0 5174.5 5195.9 5280.7 5282.6 5547.4 5588.8 5622.9 5637.6 5652.7 6248.6 6250.5 6273.1 6281.4 6289.7 6130.1 6229.6 6321.4 6340.9 6369.5 5807.9 5822.2 5824.5 5832.8 5843.9 6288.1 6308.0 6310.7 6311.8 6349.4 6020.2 6073.3 6093.5 6101.9 6183.7 5336.4 6004.8 6151.1 6156.3 6206.3 6213.1 6220.4 6240.5 6256.5 6275.2 6221.2 6265.8 6265.9 6275.6 6288.5 4830.8 4887.0 4916.0 4918.8 4960.0 4099.9 4388.2 4396.8 4441.0 4462.3 5643.4 5670.9 5677.8 5685.9 5706.6 6135.0 6229.8 6234.6 6333.4 6349.9 5707.9 5709.4 5710.7 5713.3 5723.4 5398.2 5431.0 5433.7 5436.1 5443.7 6240.1 6341.2 6407.8 6409.2 6419.9 6293.2 6334.0 6336.7 6344.0 6346.7 6364.2 6388.7 6391.1 6401.7 6413.9 6071.1 6109.6 6170.0 6189.8 6240.6 6156.1 6243.9 6251.7 6314.6 6328.4 6149.5 6150.0 6168.5 6176.4 6179.1 6046.5 6136.7 6137.4 6145.8 6150.4 5674.4 5781.2 5869.4 5966.0 5978.1 6037.0 6044.4 6049.5 6053.1 6090.4 5822.7 5911.2 5920.0 5929.9 5933.3 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:64570.37 Median:34989.71 stddev:136879.40 (391.2%) stddev-sans-first:137178.95 03:06:51 INFO - PROCESS | 1664 | Values: 537.7 540.5 579.2 585.8 594.3 31843.0 32607.3 37472.8 38331.0 38568.7 41075.4 41532.6 41628.8 41639.2 41650.0 9430.0 9648.5 9649.2 9655.7 9660.9 8670.8 8862.2 11387.4 11574.4 11604.4 9814.8 11450.2 11487.1 11500.8 11501.1 8583.7 8772.3 11354.7 11408.3 11425.1 9809.5 11634.9 11655.7 11660.5 11667.3 37922.4 38292.1 38357.0 38363.6 38528.0 3201.9 3243.2 3525.3 3684.7 3806.8 27363.7 27411.5 27706.0 28405.6 28833.3 2771.1 2774.5 2795.4 2804.1 2815.7 369017.0 369852.0 370193.0 489872.0 489939.0 62304.0 62649.0 62672.0 62681.0 62777.7 63680.4 67277.7 68387.7 68454.7 68515.3 17282.7 17327.6 17493.0 17523.5 17555.2 12500.7 12755.0 13392.2 13481.0 13677.9 17001.7 18505.4 19157.2 19166.7 19198.5 7824.3 8147.9 8260.8 8296.6 8327.3 970.3 991.4 997.9 1000.6 1160.4 27464.0 27788.0 28256.0 28335.4 28431.7 1003.4 1005.8 1113.2 1116.9 1149.5 14573.7 15273.2 15618.0 15964.9 16270.3 589278.0 597393.1 597990.0 601219.0 601642.0 59856.0 60287.7 60346.4 60519.0 60555.0 1236.6 1355.9 1481.8 1516.6 1532.9 28537.7 28543.4 28692.9 29158.4 29363.9 50497.5 50730.7 51400.0 51417.7 51489.0 438953.0 518208.0 519987.0 520727.0 521148.4 23214.5 26026.6 26318.7 26351.6 26702.0 39397.4 41017.0 41523.0 41544.6 41893.4 32743.5 34478.5 34947.8 35219.5 35633.5 34351.0 34673.5 34762.7 35031.6 36686.6 35407.0 35602.0 35872.0 35970.1 36409.0 31829.0 31924.0 31950.9 32039.7 32530.8 20038.4 23663.8 24017.8 24183.8 24354.0 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:34298.17 Median:20521.99 stddev:100373.59 (489.1%) stddev-sans-first:100622.77 03:06:51 INFO - PROCESS | 1664 | Values: 332.9 410.0 410.1 415.2 423.5 18983.3 19737.0 20392.7 20423.8 20469.8 20166.4 20248.7 20488.5 22041.4 22089.1 5766.1 5874.2 5954.3 5954.9 5981.6 5923.0 5923.5 5975.0 5982.7 5992.2 5708.3 5782.7 5892.8 5906.0 6109.6 6015.2 6025.2 6062.1 6070.2 6071.0 5054.2 5848.2 5900.4 5907.4 5913.8 13815.7 14110.7 14204.4 14216.0 23294.0 2020.6 2318.2 2537.0 2569.4 2900.7 9901.8 10454.9 10767.6 12783.4 14959.6 1527.7 1760.7 1821.1 1827.5 1895.2 105187.0 110540.3 110619.4 115338.0 116156.4 11908.6 11914.3 11944.0 11957.7 11969.8 12862.4 12897.3 15376.5 15494.5 15535.0 7471.0 9032.0 9032.2 9070.2 9147.7 7445.9 8314.7 9082.0 9213.1 9230.7 4703.8 4758.7 4775.5 4784.2 4791.7 1927.7 1985.6 2004.7 2026.8 2047.6 920.3 990.8 1016.2 1029.9 1083.9 19951.4 20328.2 20555.5 20615.6 20838.3 980.2 1008.5 1016.0 1032.4 1034.8 10740.6 10888.1 10911.6 10919.9 10924.7 568650.0 601185.0 604703.0 619932.0 625989.0 110627.4 113629.0 115969.4 121207.0 123053.0 980.7 995.3 1000.7 1001.8 1035.3 14936.9 17577.0 18154.0 18216.5 18494.4 50370.2 51363.5 51838.5 52040.0 52582.2 68882.0 71074.9 73114.0 74862.3 75939.2 8297.7 8352.8 8376.5 8481.3 8512.2 7816.9 12041.9 12582.5 12696.7 12918.3 11019.9 11180.9 11210.4 11230.3 11248.6 9112.5 10711.7 11108.0 11147.7 11320.4 11165.1 11301.4 11316.3 11632.3 11688.2 6827.8 8322.9 8406.9 8432.0 8652.8 9856.7 11225.2 11441.3 11464.5 11518.1 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5739.49 Median:5018.73 stddev:3204.54 (63.9%) stddev-sans-first:3197.39 03:06:51 INFO - PROCESS | 1664 | Values: 1459.0 1604.1 1619.7 1621.9 1953.3 7489.8 7940.5 8780.6 8807.5 8814.8 8506.6 8752.3 8769.1 8769.7 8782.5 2179.0 2381.4 2417.1 2452.0 2461.0 2475.2 2483.2 2483.8 2487.3 2492.3 2305.5 2350.6 2371.0 2371.6 2372.9 2174.8 2394.7 2452.1 2455.5 2460.2 2420.3 2454.2 2454.6 2456.3 2458.5 8632.5 8871.0 8871.4 8873.7 8884.2 5345.4 5556.8 5570.9 5572.1 5585.0 3489.7 3555.4 3616.2 3637.5 3647.7 1018.5 1044.7 1044.7 1053.0 1070.0 12055.3 12112.9 12124.9 12139.3 12154.1 4815.7 4897.5 4935.5 4944.3 4975.2 10297.8 10302.0 10304.3 10306.1 10315.4 5522.0 5556.9 5557.9 5564.9 5611.4 4469.4 4518.1 4581.7 4582.2 4584.1 3477.5 3993.3 4086.3 4137.0 4143.6 1342.6 1356.5 1362.1 1363.0 1374.3 730.8 772.0 867.4 930.8 953.6 5874.1 5879.6 5996.5 6063.0 6087.9 924.1 936.8 938.3 967.7 990.0 3652.4 3987.0 3991.7 4001.9 4060.8 10449.6 10933.1 10959.9 10970.0 11009.3 10512.6 10767.5 10944.7 10972.6 10981.1 7558.8 8240.3 8620.1 8634.4 8644.4 5727.6 5734.1 5821.5 5862.1 5862.7 9188.0 9499.2 9692.0 9724.7 9765.6 9451.9 9478.7 9791.9 9984.5 9998.3 6955.0 7387.8 8283.5 8295.5 8316.3 7385.8 7680.3 8073.5 8167.1 8333.9 7178.5 7299.1 7345.8 7440.2 7475.0 6934.5 7034.6 7128.4 7267.5 7305.9 6847.8 6945.5 6952.0 6981.0 7033.1 4861.9 4912.6 4960.6 5028.2 5106.7 4869.7 4963.9 5009.2 5029.6 5188.4 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5779.28 Median:6621.99 stddev:1872.25 (28.3%) stddev-sans-first:1832.20 03:06:51 INFO - PROCESS | 1664 | Values: 325.0 327.2 334.5 334.8 341.4 6559.8 6584.5 6632.1 6685.8 6712.4 6554.8 6593.7 6620.0 6625.4 6628.9 4702.5 4814.1 4816.6 4895.0 4951.5 4354.8 4379.5 4898.7 4917.9 4919.9 4854.4 4869.2 4873.1 4878.1 4890.7 4048.3 4707.5 4794.3 4827.9 4852.7 4133.1 4139.2 4148.1 4148.9 4156.8 5291.0 6128.9 6243.3 6254.2 6291.1 5684.8 5700.0 5701.4 5711.6 5721.4 6375.3 6974.4 6979.3 6992.1 6992.2 4075.0 4511.9 4529.3 4576.8 4577.9 7373.0 7488.1 7493.1 7499.0 7513.2 4578.3 4615.3 4625.2 4626.9 4628.7 4666.7 4667.8 4670.6 4677.2 4682.1 5568.8 5575.5 5581.2 5582.3 5595.6 6781.3 7011.6 7017.8 7032.0 7084.4 1607.7 1615.9 1620.2 1622.4 1627.7 804.5 983.1 1023.1 1025.8 1046.9 6462.0 6603.4 6606.5 6622.5 6646.5 7407.5 7416.0 7425.8 7428.9 7443.8 6600.1 6621.5 6654.3 6660.8 6661.5 3851.6 3889.4 3896.4 3900.3 3901.4 6518.6 7470.6 7597.5 7754.0 7779.0 7573.0 7622.3 7622.4 7632.7 7637.1 7768.7 7769.4 7772.5 7781.7 7787.2 7083.5 7338.6 7352.4 7366.9 7373.0 7240.9 7266.0 7283.8 7289.9 7291.3 6281.6 7767.8 7773.2 7788.8 7789.9 4599.6 4632.1 4645.7 4649.9 4656.7 7050.4 7311.3 7314.7 7317.6 7319.4 7052.7 7053.4 7062.8 7084.7 7087.0 7094.5 7097.2 7105.9 7122.1 7124.8 6914.5 6915.3 6917.7 6921.4 6957.2 6855.3 7083.5 7091.8 7100.6 7116.0 6640.1 6785.8 6880.6 6919.0 6958.3 03:06:51 INFO - PROCESS | 1664 | 03:06:51 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18371.75 Median:595.30 stddev:26080.00 (4381.0%) stddev-sans-first:26119.43 03:06:51 INFO - PROCESS | 1664 | Values: 706.7 710.9 733.3 738.6 756.4 14806.9 14942.3 15145.9 15190.9 15197.4 13679.6 13943.5 13981.9 14024.2 14043.0 760.2 770.6 872.8 965.9 966.9 828.9 963.9 966.1 970.1 972.1 999.5 1012.0 1012.9 1014.1 1016.0 786.4 976.7 994.2 1007.7 1008.2 738.1 742.8 745.5 746.9 748.6 12995.4 13152.2 13161.5 13165.0 13175.4 12493.3 12572.6 12722.2 12866.3 12888.9 10111.8 10256.4 10271.1 10551.4 11052.2 3456.2 3514.8 3519.0 3563.3 3754.6 81138.6 82285.0 82760.0 82850.0 83000.6 9372.8 9680.8 9735.0 9764.7 10417.9 22726.4 22743.2 22748.1 22761.4 22774.7 7385.5 7526.1 7696.5 7744.8 7821.4 3998.4 4091.0 4121.8 4170.4 4179.8 3676.7 3737.3 3740.3 3745.8 3746.4 600.9 643.3 652.3 665.7 678.6 523.9 564.3 595.3 602.2 607.0 7903.8 7940.1 8044.4 8077.2 8311.5 578.4 595.3 599.8 606.7 607.7 3145.4 3168.0 3368.2 3370.5 3471.3 92812.1 94537.5 95798.0 95995.0 96162.3 62301.7 81081.6 81220.2 83579.0 83719.0 67594.0 69393.0 69572.0 70119.6 71385.0 6654.7 6657.9 6701.9 6783.2 6823.2 45604.0 45909.3 46823.5 48144.8 51772.7 65704.0 68401.7 68680.7 70360.3 72175.3 11000.2 11323.7 11461.2 11488.3 11522.7 11798.6 12136.5 12159.5 12457.8 12565.0 11153.6 11545.3 11553.8 11807.7 11893.7 9357.0 11274.7 11476.6 11603.5 12392.1 10912.5 11152.3 11325.7 11363.6 11739.4 9566.8 9571.3 9741.8 9930.1 10144.4 9469.8 9642.5 9662.4 9871.6 9887.7 03:06:51 INFO - PROCESS | 1664 | -------- Summary: end -------- 03:06:51 INFO - PROCESS | 1664 | 03:06:52 INFO - TEST-INFO | 1664: exit 0 03:06:52 INFO - TEST-OK | dromaeo_css | took 1115137ms 03:06:52 INFO - TEST-START | kraken 03:06:52 INFO - operating with platform_type : mac_ 03:06:52 INFO - Initialising browser for kraken test... 03:06:52 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpHLm5Wq/profile http://localhost:49270/getInfo.html) 03:06:54 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 03:06:54 INFO - PROCESS | 1669 | colorDepth:24 03:06:54 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 03:06:54 INFO - PROCESS | 1669 | __metrics 03:06:54 INFO - PROCESS | 1669 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:06:54 ERROR - PROCESS | 1669 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:06:55 INFO - TEST-INFO | 1669: exit 0 03:06:55 INFO - Browser initialized. 03:06:55 INFO - Running cycle 1/1 for kraken test... 03:06:55 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/tmpHLm5Wq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:07:07 INFO - PROCESS | 1672 | RSS: Main: 211107840 03:07:07 INFO - PROCESS | 1672 | 03:07:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 03:07:10 INFO - PROCESS | 1672 | RSS: Main: 271761408 03:07:10 INFO - PROCESS | 1672 | 03:07:13 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 03:07:14 INFO - PROCESS | 1672 | RSS: Main: 286203904 03:07:14 INFO - PROCESS | 1672 | 03:07:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 03:07:17 INFO - PROCESS | 1672 | RSS: Main: 372871168 03:07:17 INFO - PROCESS | 1672 | 03:07:19 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 03:07:20 INFO - PROCESS | 1672 | RSS: Main: 279769088 03:07:20 INFO - PROCESS | 1672 | 03:07:22 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:07:23 INFO - PROCESS | 1672 | RSS: Main: 279187456 03:07:23 INFO - PROCESS | 1672 | 03:07:25 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 03:07:26 INFO - PROCESS | 1672 | RSS: Main: 317300736 03:07:26 INFO - PROCESS | 1672 | 03:07:29 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 03:07:30 INFO - PROCESS | 1672 | RSS: Main: 316792832 03:07:30 INFO - PROCESS | 1672 | 03:07:32 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 03:07:33 INFO - PROCESS | 1672 | RSS: Main: 315736064 03:07:33 INFO - PROCESS | 1672 | 03:07:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:07:35 INFO - PROCESS | 1672 | RSS: Main: 308453376 03:07:35 INFO - PROCESS | 1672 | 03:07:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:07:38 INFO - PROCESS | 1672 | RSS: Main: 316391424 03:07:38 INFO - PROCESS | 1672 | 03:07:40 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:07:40 INFO - PROCESS | 1672 | RSS: Main: 319455232 03:07:40 INFO - PROCESS | 1672 | 03:07:43 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:07:44 INFO - PROCESS | 1672 | RSS: Main: 324096000 03:07:44 INFO - PROCESS | 1672 | 03:07:46 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:07:47 INFO - PROCESS | 1672 | RSS: Main: 322322432 03:07:47 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 03:07:49 INFO - PROCESS | 1672 | RSS: Main: 386318336 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | __start_tp_report 03:07:49 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 03:07:49 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 03:07:49 INFO - PROCESS | 1672 | |i|pagename|runs| 03:07:49 INFO - PROCESS | 1672 | |0;ai-astar;113;113;118;112;112;113;114;114;114;192 03:07:49 INFO - PROCESS | 1672 | |1;audio-beat-detection;153;120;147;198;148;147;143;140;167;119 03:07:49 INFO - PROCESS | 1672 | |2;audio-dft;181;184;184;182;183;183;185;186;183;183 03:07:49 INFO - PROCESS | 1672 | |3;audio-fft;69;79;75;147;57;55;58;65;132;55 03:07:49 INFO - PROCESS | 1672 | |4;audio-oscillator;95;94;143;76;78;85;126;80;79;124 03:07:49 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;88;84;88;84;87;84;88;85;87;83 03:07:49 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;92;92;93;96;92;93;92;92;93 03:07:49 INFO - PROCESS | 1672 | |7;imaging-desaturate;86;86;85;86;85;85;85;83;85;86 03:07:49 INFO - PROCESS | 1672 | |8;json-parse-financial;61;64;64;65;64;64;65;80;60;58 03:07:49 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;65;66;63;80;74;70;67;69;67;68 03:07:49 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;80;78;79;82;78;80;82;79;82;84 03:07:49 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;129;141;143;146;125;123;117;123;147;133 03:07:49 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;168;166;164;167;168;161;163;191;147;191 03:07:49 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;68;67;72;93;96;99;76;69;66;66 03:07:49 INFO - PROCESS | 1672 | __end_tp_report 03:07:49 INFO - PROCESS | 1672 | __start_cc_report 03:07:49 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,1969 03:07:49 INFO - PROCESS | 1672 | __end_cc_report 03:07:49 INFO - PROCESS | 1672 | __startTimestamp1464170869721__endTimestamp 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | ------- Summary: start ------- 03:07:49 INFO - PROCESS | 1672 | Number of tests: 14 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:121.50 Median:114.00 stddev:24.83 (21.8%) stddev-sans-first:26.14 03:07:49 INFO - PROCESS | 1672 | Values: 113.0 113.0 118.0 112.0 112.0 113.0 114.0 114.0 114.0 192.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:148.20 Median:147.50 stddev:22.61 (15.3%) stddev-sans-first:23.92 03:07:49 INFO - PROCESS | 1672 | Values: 153.0 120.0 147.0 198.0 148.0 147.0 143.0 140.0 167.0 119.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:183.40 Median:183.50 stddev:1.43 (0.8%) stddev-sans-first:1.22 03:07:49 INFO - PROCESS | 1672 | Values: 181.0 184.0 184.0 182.0 183.0 183.0 185.0 186.0 183.0 183.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:79.20 Median:61.50 stddev:33.03 (53.7%) stddev-sans-first:34.83 03:07:49 INFO - PROCESS | 1672 | Values: 69.0 79.0 75.0 147.0 57.0 55.0 58.0 65.0 132.0 55.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:98.00 Median:79.50 stddev:24.15 (30.4%) stddev-sans-first:25.59 03:07:49 INFO - PROCESS | 1672 | Values: 95.0 94.0 143.0 76.0 78.0 85.0 126.0 80.0 79.0 124.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.99 (2.3%) stddev-sans-first:1.94 03:07:49 INFO - PROCESS | 1672 | Values: 88.0 84.0 88.0 84.0 87.0 84.0 88.0 85.0 87.0 83.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:1.23 (1.3%) stddev-sans-first:1.30 03:07:49 INFO - PROCESS | 1672 | Values: 93.0 92.0 92.0 93.0 96.0 92.0 93.0 92.0 92.0 93.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:85.20 Median:85.50 stddev:0.92 (1.1%) stddev-sans-first:0.93 03:07:49 INFO - PROCESS | 1672 | Values: 86.0 86.0 85.0 86.0 85.0 85.0 85.0 83.0 85.0 86.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:64.50 Median:64.00 stddev:5.93 (9.3%) stddev-sans-first:6.15 03:07:49 INFO - PROCESS | 1672 | Values: 61.0 64.0 64.0 65.0 64.0 64.0 65.0 80.0 60.0 58.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:68.90 Median:68.50 stddev:4.91 (7.2%) stddev-sans-first:5.00 03:07:49 INFO - PROCESS | 1672 | Values: 65.0 66.0 63.0 80.0 74.0 70.0 67.0 69.0 67.0 68.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:81.00 stddev:2.01 (2.5%) stddev-sans-first:2.13 03:07:49 INFO - PROCESS | 1672 | Values: 80.0 78.0 79.0 82.0 78.0 80.0 82.0 79.0 82.0 84.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:132.70 Median:137.00 stddev:10.87 (7.9%) stddev-sans-first:11.45 03:07:49 INFO - PROCESS | 1672 | Values: 129.0 141.0 143.0 146.0 125.0 123.0 117.0 123.0 147.0 133.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:167.50 stddev:13.29 (7.9%) stddev-sans-first:14.10 03:07:49 INFO - PROCESS | 1672 | Values: 168.0 166.0 164.0 167.0 168.0 161.0 163.0 191.0 147.0 191.0 03:07:49 INFO - PROCESS | 1672 | 03:07:49 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:74.00 stddev:13.39 (18.1%) stddev-sans-first:13.78 03:07:49 INFO - PROCESS | 1672 | Values: 68.0 67.0 72.0 93.0 96.0 99.0 76.0 69.0 66.0 66.0 03:07:49 INFO - PROCESS | 1672 | -------- Summary: end -------- 03:07:49 INFO - PROCESS | 1672 | 03:07:50 INFO - TEST-INFO | 1672: exit 0 03:07:50 INFO - TEST-OK | kraken | took 58180ms 03:07:50 INFO - SUITE-END | took 1173s 03:07:50 INFO - Completed test suite (00:19:33) 03:07:50 INFO - javascript benchmark 03:07:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3386.8306584670763, 3455.568053993251, 3467.3758723429773, 3489.1102768793953, 3523.3129539739743, 5670.4896559309655, 5716.685582292972, 5717.2281741964825, 5741.425857414257, 5760.683162426065, 5666.178404131399, 5746.770129194838, 5746.885062298751, 5747.655140691551, 5769.740361683724, 5155.355580552431, 5206.375234971809, 5208.114620514502, 5305.126765601642, 5402.432744561824, 5275.182346736262, 5277.419483856773, 5284.471552844704, 5287.814926477574, 5294.338207724035, 5185.092608793465, 5243.344581927259, 5280.128778751501, 5288.682679039251, 5312.681239125645, 5299.867503312429, 5339.733013349356, 5343.732813359316, 5344.412114667383, 5369.731513424353, 5136.049830781318, 5174.534291913746, 5195.896082078375, 5280.656757310762, 5282.550983166436, 5547.362053363834, 5588.776448942038, 5622.943770562324, 5637.577181711347, 5652.717364131777, 6248.593841400295, 6250.531210159256, 6273.12176295319, 6281.434670879643, 6289.685515724195, 6130.111133885606, 6229.563930524865, 6321.367863213688, 6340.873182536369, 6369.4585960193435, 5807.912881306784, 5822.214001109796, 5824.504917082011, 5832.766689332421, 5843.912341314884, 6288.088227207121, 6308.022256550242, 6310.684465776692, 6311.779087731908, 6349.428551430394, 6020.187274717945, 6073.29738266476, 6093.451589356979, 6101.942208431344, 6183.659898705614, 5336.386315573759, 6004.7598096076545, 6151.138840562236, 6156.26124865019, 6206.348333425016, 6213.099100630383, 6220.409061139185, 6240.5319601030105, 6256.468200202943, 6275.152854364603, 6221.160143380585, 6265.8120256392385, 6265.937340626627, 6275.623462592259, 6288.52836037299, 4830.806767729322, 4887.0, 4915.95084049155, 4918.754062296871, 4959.975200123977, 4099.938500922489, 4388.166248412791, 4396.802143903532, 4441.0, 4462.263726485158, 5643.435656434406, 5670.858228544319, 5677.829665110054, 5685.943140568624, 5706.572007099484, 6135.048937646369, 6229.844253893689, 6234.594751341239, 6333.398327158912, 6349.904751428732, 5707.857303567361, 5709.429057094258, 5710.71446427677, 5713.285839270091, 5723.427657234327, 5398.163284690879, 5431.0, 5433.728313584384, 5436.130219164915, 5443.673379597158, 6240.063990401476, 6341.207349081365, 6407.7757278495965, 6409.198850143732, 6419.935800642027, 6293.150424692608, 6334.0, 6336.7465301389275, 6344.048392741126, 6346.650934198478, 6364.204474440695, 6388.712507937247, 6391.073294372197, 6401.679916004275, 6413.903791443039, 6071.058985857199, 6109.647596799152, 6169.96915015422, 6189.84525386869, 6240.625562466266, 6156.107364432221, 6243.875122497616, 6251.718672659832, 6314.589551679126, 6328.398802113792, 6149.508039356931, 6150.0, 6168.537359697951, 6176.382361763787, 6179.134921111138, 6046.486048685911, 6136.693165341804, 6137.3862613738265, 6145.84635384119, 6150.354212807591, 5674.375818659862, 5781.21953536284, 5869.383714709894, 5966.017991646318, 5978.133170690138, 6036.9698151508965, 6044.395560443921, 6049.455549000611, 6053.130929791166, 6090.390960903874, 5822.73797679097, 5911.201987731602, 5920.023196172583, 5929.881402371842, 5933.288005439203], "value": 5719.820289358147, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.666646679059, 540.4757385336223, 579.2296245992829, 585.797899724595, 594.2809200866956, 31843.0, 32607.347853042953, 37472.81263593678, 38331.0, 38568.6500972466, 41075.35698572054, 41532.58467415325, 41628.791856040756, 41639.167216655725, 41650.0, 9430.0, 9648.5175741213, 9649.228061755042, 9655.710328690151, 9660.903390966107, 8670.783230419258, 8862.158094980967, 11387.373694446802, 11574.42127893606, 11604.419779011057, 9814.754631134243, 11450.198486105975, 11487.138464615145, 11500.82748758765, 11501.07991360689, 8583.699570515064, 8772.254358379545, 11354.71613209668, 11408.258463199949, 11425.08599312053, 9809.460479673615, 11634.941825290904, 11655.708607284885, 11660.475278612483, 11667.299962002307, 37922.43116353257, 38292.08539573038, 38357.0, 38363.616363836285, 38528.036799079964, 3201.9039428817173, 3243.1567792373917, 3525.2596954639557, 3684.7420680552373, 3806.809659517041, 27363.726362736317, 27411.451770964468, 27706.0, 28405.57391639127, 28833.27916802076, 2771.0855417712105, 2774.4728501584636, 2795.3989892173076, 2804.060639685708, 2815.7325054119824, 369017.0, 369852.0, 370193.0, 489872.0, 489938.99999999645, 62304.0, 62649.0, 62672.0, 62681.0, 62777.68611156915, 63680.36319636744, 67277.66361168261, 68387.65806171036, 68454.65772671104, 68515.31484685189, 17282.740758888627, 17327.56681082983, 17493.0, 17523.474295771146, 17555.21001554933, 12500.687482813002, 12754.979601631847, 13392.196468211938, 13481.0, 13677.931610342084, 17001.744973825404, 18505.35231266899, 19157.233710651697, 19166.71249931252, 19198.520036999187, 7824.334931530883, 8147.877781833277, 8260.802183683332, 8296.626651800683, 8327.333813294921, 970.257752819094, 991.3704797453669, 997.910188083076, 1000.5547531348626, 1160.3849959521467, 27464.0, 27788.0, 28256.0, 28335.43329133447, 28431.71568284332, 1003.392947266893, 1005.7787286796893, 1113.2151832957745, 1116.8883111688765, 1149.4999675141341, 14573.708525829425, 15273.236338183046, 15618.0, 15964.92017539905, 16270.349186032663, 589278.0, 597393.1042758328, 597990.0, 601219.0, 601642.0, 59856.0, 60287.69856150691, 60346.396536034954, 60519.0, 60555.0, 1236.5795629486017, 1355.8711922367359, 1481.8444063373622, 1516.6056825225521, 1532.8620424161657, 28537.714622854335, 28543.42913141767, 28692.856535717186, 29158.41683166367, 29363.85318073396, 50497.49502504928, 50730.731731707005, 51400.0, 51417.742911285204, 51489.0, 438953.0, 518208.0, 519987.0, 520727.0, 521148.39425802627, 23214.535709286058, 26026.60960085638, 26318.736812632396, 26351.60472592951, 26702.0, 39397.409038865015, 41017.0, 41522.96192595088, 41544.58455415407, 41893.37159942542, 32743.508847366833, 34478.48282275818, 34947.825260873535, 35219.47170792389, 35633.46549801807, 34351.0, 34673.47989780105, 34762.65237347695, 35031.598736051295, 36686.63313366832, 35407.0, 35602.0, 35872.02703851313, 35970.10074748152, 36409.0, 31829.0, 31924.0, 31950.881719139717, 32039.6796032046, 32530.837345814067, 20038.398848034583, 23663.763362366157, 24017.759822401553, 24183.78356777443, 24354.02583896624], "value": 19757.906807639818, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [332.8931303416141, 409.9795010249488, 410.0507325541373, 415.21109891206703, 423.4896949176243, 18983.33558325459, 19737.0, 20392.69410958835, 20423.795762042377, 20469.771813691154, 20166.395008149742, 20248.69626955593, 20488.487787805312, 22041.448963775914, 22089.116435342567, 5766.106253530709, 5874.236349274588, 5954.315253745832, 5954.851128721773, 5981.641101533922, 5923.022701254309, 5923.526117910578, 5974.970125149368, 5982.670953097578, 5992.221011268529, 5708.343540492834, 5782.710864456782, 5892.764289428418, 5906.025505791539, 6109.633421994672, 6015.218021657179, 6025.15647809307, 6062.090686397076, 6070.150178974947, 6070.969645151768, 5054.216596427523, 5848.239728835224, 5900.439458251502, 5907.379725128842, 5913.763449462016, 13815.723685526335, 14110.717785644332, 14204.360803763855, 14216.0, 23294.0, 2020.6160829442358, 2318.2465698648007, 2536.9746302537105, 2569.4218800769863, 2900.6809250982374, 9901.801963960752, 10454.947725261325, 10767.56929722818, 12783.360831958458, 14959.551213463614, 1527.6868242010473, 1760.7270873014704, 1821.1349608935782, 1827.5339788353886, 1895.232430865488, 105187.0, 110540.34189487084, 110619.44690276578, 115338.0, 116156.41921790421, 11908.583199587973, 11914.285142891455, 11944.0, 11957.70105747346, 11969.760604787856, 12862.421191046426, 12897.290649014394, 15376.538703838904, 15494.457693980656, 15535.0, 7470.962645186739, 9032.0, 9032.232260257952, 9070.229030532479, 9147.725568232963, 7445.8883116753295, 8314.667413303521, 9081.954590227138, 9213.078692130868, 9230.723078307661, 4703.83536576225, 4758.666893317504, 4775.5224477552665, 4784.234522476387, 4791.712497250176, 1927.7397551330391, 1985.612805502942, 2004.7293615362032, 2026.7940575357638, 2047.62119007984, 920.2591913509555, 990.782027953849, 1016.2378216337746, 1029.8609687692215, 1083.91870609703, 19951.401457956286, 20328.186872525228, 20555.4861128473, 20615.58768824645, 20838.270660527112, 980.2256217588168, 1008.4755926917961, 1016.0, 1032.4270030133348, 1034.8085604163255, 10740.570377184982, 10888.074513666426, 10911.618093366695, 10919.890801092046, 10924.672259832218, 568650.0, 601185.0, 604702.9764851235, 619932.0, 625989.0, 110627.44686276678, 113629.0, 115969.42015290038, 121207.0, 123053.0, 980.7499087732582, 995.2933417273672, 1000.68478429296, 1001.759577701361, 1035.2753072848884, 14936.925315373353, 17577.0, 18154.0, 18216.544586385447, 18494.445166645022, 50370.244446334065, 51363.48636513737, 51838.481615183366, 52040.0, 52582.21126683179, 68882.0, 71074.86775396747, 73114.0, 74862.26239638458, 75939.24060759542, 8297.668093276201, 8352.791180220544, 8376.49741015541, 8481.27909127731, 8512.246674126569, 7816.921830781619, 12041.939790300992, 12582.496700131893, 12696.746065078461, 12918.289494077915, 11019.889801101886, 11180.888191117985, 11210.383428911487, 11230.3261804292, 11248.550057997589, 9112.453252804853, 10711.678649640524, 11108.0, 11147.72130696739, 11320.433978301216, 11165.106791456501, 11301.43492825339, 11316.26444281119, 11632.31331456917, 11688.18182727201, 6827.829304267433, 8322.916770832215, 8406.915930840612, 8432.03031651368, 8652.826943461221, 9856.655017074369, 11225.158113141384, 11441.313521188422, 11464.541418343173, 11518.07855371585], "value": 9525.551490482845, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1459.0443259664924, 1604.149800605679, 1619.7084524785537, 1621.9026858388497, 1953.3065761654611, 7489.775306740793, 7940.523568585882, 8780.560971951392, 8807.515586642732, 8814.823703525934, 8506.6172022259, 8752.29981601472, 8769.079246679103, 8769.693060742875, 8782.516961567111, 2179.030331502482, 2381.428457170275, 2417.0815090265764, 2452.0, 2461.027893981876, 2475.220305603738, 2483.205374280231, 2483.776460118589, 2487.2911220302217, 2492.289697436231, 2305.4697419593504, 2350.5533948549805, 2370.9923282604823, 2371.620540713486, 2372.9406764830933, 2174.7716489768663, 2394.748551402095, 2452.0927256914965, 2455.5457240410583, 2460.20043485866, 2420.2739178246457, 2454.2023842251338, 2454.631805229212, 2456.2876765737915, 2458.4714286428502, 8632.482051076955, 8870.955645221733, 8871.423357481808, 8873.733787986306, 8884.20042196199, 5345.412004679482, 5556.833295001156, 5570.86072848178, 5572.0527510323345, 5584.972075139639, 3489.7382696297627, 3555.3600351936643, 3616.18635806944, 3637.4543818427182, 3647.74465787395, 1018.45003698003, 1044.6552637629563, 1044.69703785902, 1053.020690757595, 1069.9893001069968, 12055.33695646739, 12112.939435302855, 12124.939375303155, 12139.271643701319, 12154.149209555335, 4815.662903596784, 4897.534734200317, 4935.506449355107, 4944.258361245842, 4975.228839529878, 10297.845532317022, 10302.0, 10304.330218535772, 10306.12397946168, 10315.381077135396, 5522.0336441122645, 5556.861078473071, 5557.861053473695, 5564.944350556523, 5611.438856114438, 4469.351943968171, 4518.1415531049, 4581.65637577183, 4582.198115329804, 4584.106099310602, 3477.51314815926, 3993.3011722948368, 4086.2849001424634, 4136.96575856036, 4143.56492568282, 1342.597220833746, 1356.5455572383362, 1362.1146254934372, 1362.9659258518418, 1374.2785037855206, 730.777112980546, 771.9886948098009, 867.4405008769435, 930.8184903943666, 953.5518306396121, 5874.089516125007, 5879.647221166743, 5996.5202783777195, 6063.029915213545, 6087.939120608826, 924.1451657217182, 936.8360536906014, 938.2869019545195, 967.685502211784, 989.9901000990042, 3652.415613501827, 3987.003249187703, 3991.7405368650943, 4001.8999525011527, 4060.796960151981, 10449.634262800766, 10933.070688991365, 10959.945200273947, 10970.012698857141, 11009.339439633648, 10512.580801591841, 10767.515461804238, 10944.671659850217, 10972.615958441576, 10981.066609338135, 7558.773236802905, 8240.340772738167, 8620.051794302623, 8634.395592308603, 8644.438111522857, 5727.599068065362, 5734.139879018014, 5821.534277257724, 5862.12068189775, 5862.706864656665, 9188.035256297993, 9499.240060795259, 9691.982341854004, 9724.70825875225, 9765.609375624896, 9451.905480945103, 9478.715638530855, 9791.902080979384, 9984.450855203033, 9998.250131240331, 6954.965225173842, 7387.8153046174275, 8283.502989820388, 8295.543745094077, 8316.251537361666, 7385.818269076922, 7680.308772210527, 8073.475224110414, 8167.14245004266, 8333.916660833314, 7178.533395329287, 7299.124105107421, 7345.77962661121, 7440.181580026301, 7475.028246327943, 6934.479914006571, 7034.577925324497, 7128.394086502705, 7267.527610705287, 7305.890411643936, 6847.794566163023, 6945.548539344926, 6951.965240173767, 6980.965095174492, 7033.120859892513, 4861.927071094007, 4912.631552633496, 4960.627952903475, 5028.245763135559, 5106.693598384109, 4869.732164730974, 4963.875903102451, 5009.223570346602, 5029.59763218949, 5188.377394712658], "value": 4622.532126131081, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.98212598307094, 327.2293748222935, 334.52664479761137, 334.7790458297524, 341.35483935362186, 6559.77040803572, 6584.506162037848, 6632.137822083122, 6685.832854178636, 6712.362325579074, 6554.803355899318, 6593.670316484168, 6619.966900165505, 6625.403713665769, 6628.900566491507, 4702.459217190016, 4814.06125805468, 4816.566509014188, 4894.975525122387, 4951.455339912624, 4354.804033818486, 4379.496357918848, 4898.657094003421, 4917.926231106536, 4919.926201106986, 4854.417469903598, 4869.172240719086, 4873.1228378891965, 4878.0731660984475, 4890.682105663138, 4048.251073551388, 4707.505711900252, 4794.256890182028, 4827.879303017453, 4852.660313778037, 4133.090720041586, 4139.234241665302, 4148.149629326011, 4148.91702165958, 4156.771377574232, 5290.973545132289, 6128.938710612926, 6243.282022567418, 6254.249490061176, 6291.087792270141, 5684.8010319639025, 5700.002499562602, 5701.4013528579735, 5711.571632127607, 5721.399253078408, 6375.266844312917, 6974.407175390098, 6979.267176946399, 6992.1259842519685, 6992.160940687099, 4074.9796251018556, 4511.909761804745, 4529.32060190974, 4576.771161441948, 4577.862664120082, 7372.963135184344, 7488.101427828641, 7493.100827900686, 7498.962505187494, 7513.248675132498, 4578.313253012075, 4615.26155815068, 4625.190591646448, 4626.930596041062, 4628.745419001987, 4666.743329116931, 4667.836625718083, 4670.602998745077, 4677.18149323867, 4682.08034705769, 5568.805091821767, 5575.470330318613, 5581.162825576196, 5582.33012038558, 5595.580331475156, 6781.254062053269, 7011.5793052416, 7017.824554386079, 7031.964840175767, 7084.36240738336, 1607.718649236379, 1615.8626516746208, 1620.1899050474763, 1622.3916031488193, 1627.6826018926433, 804.4851295171095, 983.095552092077, 1023.1098943918838, 1025.7845852370936, 1046.8641523946603, 6461.967690161519, 6603.438707709803, 6606.537542372084, 6622.470202383798, 6646.534742567974, 7407.481476296607, 7416.0, 7425.777226683208, 7428.85142297151, 7443.813904652318, 6600.075989361493, 6621.50338724588, 6654.301298363699, 6660.83347916306, 6661.500387470959, 3851.614838516126, 3889.3582558878034, 3896.3960586109197, 3900.258950799339, 3901.43429202764, 6518.631087471684, 7470.551766894004, 7597.480503899132, 7753.991981042428, 7778.988731464873, 7573.0, 7622.31399174077, 7622.390208783395, 7632.732854350071, 7637.083549973928, 7768.689252429953, 7769.417293702995, 7772.45592808498, 7781.727639532589, 7787.247869229378, 7083.539569927938, 7338.596377199306, 7352.448566357438, 7366.889496657662, 7373.0, 7240.8913866290995, 7266.019087423341, 7283.817904552428, 7289.927100728924, 7291.343779059698, 6281.560290779602, 7767.805804854698, 7773.18381569927, 7788.766337009899, 7789.883151752615, 4599.563041511051, 4632.073585283024, 4645.651576131736, 4649.86050418488, 4656.650751193606, 7050.400715939202, 7311.268873112646, 7314.707411703473, 7317.5609463432365, 7319.377852882351, 7052.717891284291, 7053.4004609608755, 7062.823429414306, 7084.681189346388, 7087.043249161297, 7094.467914906506, 7097.219305876452, 7105.928940710527, 7122.074130363019, 7124.786256412316, 6914.481413894078, 6915.308469153045, 6917.654117294014, 6921.411680007256, 6957.199922009034, 6855.348741869514, 7083.468739844429, 7091.787246382617, 7100.644967751488, 7116.0, 6640.103586016019, 6785.7624983127325, 6880.58716477013, 6918.965405172942, 6958.304169583002], "value": 5144.588025194713, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.7420391557082, 710.9111361079862, 733.3033618062839, 738.5605564689015, 756.3986630628651, 14806.925965370172, 14942.252887355617, 15145.92427037866, 15190.936634435593, 15197.392104315828, 13679.589612311647, 13943.511977080805, 13981.930090349559, 14024.228667423313, 14043.0, 760.2321655128333, 770.6108415250291, 872.7905302727363, 965.8502932045544, 966.9274804389664, 828.8549503836834, 963.903609639034, 966.1160038564705, 970.0978090375947, 972.1445128287132, 999.5002498750624, 1012.0335079998621, 1012.8632634594346, 1014.1126514299988, 1015.9593616255341, 786.3709032773787, 976.6728146071075, 994.1996692662403, 1007.7480629842539, 1008.2236677758085, 738.1216352540513, 742.8477162181794, 745.4856149256996, 746.9066366704162, 748.5733132114697, 12995.350232488337, 13152.210867347894, 13161.473541058345, 13165.0, 13175.407106680223, 12493.312867792269, 12572.559960401342, 12722.173058751245, 12866.292353920531, 12888.935555322258, 10111.797764044752, 10256.435896025716, 10271.126954208901, 10551.366917984946, 11052.171087168414, 3456.2396272819938, 3514.7539672222956, 3518.9648103518894, 3563.2695297464215, 3754.5682246541724, 81138.59430702809, 82285.0, 82759.9999999994, 82850.0, 83000.58499707463, 9372.812543749087, 9680.806383872283, 9734.95132524347, 9764.707058788248, 10417.9479102604, 22726.431839203822, 22743.203987860677, 22748.09007639709, 22761.430964226027, 22774.65838012431, 7385.519941203805, 7526.09686837572, 7696.538207707556, 7744.806379840549, 7821.413393995473, 3998.420229066769, 4091.0386059276047, 4121.773302468333, 4170.35359519275, 4179.791010449465, 3676.669099780979, 3737.3085979093953, 3740.2519496100344, 3745.831437585315, 3746.400575907842, 600.891839468891, 643.327722529948, 652.292262894755, 665.6871270502855, 678.6233640329566, 523.9109351410269, 564.2777245126244, 595.3272801734013, 602.2351613450893, 606.9681541379584, 7903.802404939923, 7940.091295137897, 8044.356451483867, 8077.232662897015, 8311.542865142475, 578.398465595784, 595.2737660054727, 599.7537434960469, 606.7087797856965, 607.6536374266714, 3145.370925814846, 3168.002079344999, 3368.1747971747077, 3370.4944258361443, 3471.2536804587135, 92812.07187928169, 94537.527312363, 95798.0, 95995.04004960001, 96162.26864119584, 62301.68849155725, 81081.59459202785, 81220.18779812244, 83579.0, 83719.0, 67594.0, 69393.0, 69571.95642065373, 70119.64940175267, 71385.0, 6654.667266636746, 6657.933420665925, 6701.932980670131, 6783.186017677713, 6823.1812182538415, 45604.0, 45909.31136032895, 46823.53176468192, 48144.7592762034, 51772.74113629558, 65704.0, 68401.65799170973, 68680.6565967167, 70360.29639703537, 72175.27824721686, 11000.174986876176, 11323.660290191307, 11461.150573107023, 11488.253263537843, 11522.711932201762, 11798.646040618794, 12136.453859576517, 12159.45282462309, 12457.813132802834, 12565.0, 11153.55385784592, 11545.307281563133, 11553.826692599785, 11807.704807379885, 11893.702657433634, 9356.95321523388, 11274.718132046764, 11476.598319058792, 11603.535858565563, 12392.070594705252, 10912.508937098008, 11152.275102118336, 11325.660230193107, 11363.602273920373, 11739.354335511629, 9566.760830979281, 9571.343236425464, 9741.756456088655, 9930.106290433896, 10144.442055687008, 9469.810603787748, 9642.469664168257, 9662.3719458235, 9871.605135794774, 9887.653932112342], "value": 6902.872906548014, "unit": "score"}], "name": "dromaeo_css", "value": 7491.207027787101, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 118.0, 112.0, 112.0, 113.0, 114.0, 114.0, 114.0, 192.0], "value": 121.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 120.0, 147.0, 198.0, 148.0, 147.0, 143.0, 140.0, 167.0, 119.0], "value": 148.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 184.0, 184.0, 182.0, 183.0, 183.0, 185.0, 186.0, 183.0, 183.0], "value": 183.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 75.0, 147.0, 57.0, 55.0, 58.0, 65.0, 132.0, 55.0], "value": 79.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 143.0, 76.0, 78.0, 85.0, 126.0, 80.0, 79.0, 124.0], "value": 98.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 88.0, 84.0, 87.0, 84.0, 88.0, 85.0, 87.0, 83.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 92.0, 93.0, 96.0, 92.0, 93.0, 92.0, 92.0, 93.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 86.0, 85.0, 85.0, 85.0, 83.0, 85.0, 86.0], "value": 85.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 65.0, 64.0, 64.0, 65.0, 80.0, 60.0, 58.0], "value": 64.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 63.0, 80.0, 74.0, 70.0, 67.0, 69.0, 67.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 79.0, 82.0, 78.0, 80.0, 82.0, 79.0, 82.0, 84.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 141.0, 143.0, 146.0, 125.0, 123.0, 117.0, 123.0, 147.0, 133.0], "value": 132.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 164.0, 167.0, 168.0, 161.0, 163.0, 191.0, 147.0, 191.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 72.0, 93.0, 96.0, 99.0, 76.0, 69.0, 66.0, 66.0], "value": 77.2, "unit": "score"}], "name": "kraken", "value": 1486.4, "alertThreshold": 2.0}]} 03:07:50 INFO - Return code: 0 03:07:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:07:51 ERROR - # TBPL SUCCESS # 03:07:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:07:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:07:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:07:51 INFO - 2016-05-25 03:07:51.040 screenresolution[1676:10330] starting screenresolution argv=screenresolution get 03:07:51 INFO - 2016-05-25 03:07:51.042 screenresolution[1676:10330] Display 0: 1600x1200x32@60 03:07:51 INFO - 2016-05-25 03:07:51.048 screenresolution[1677:10333] starting screenresolution argv=screenresolution list 03:07:51 INFO - Available Modes on Display 0 03:07:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:07:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:07:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:07:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:07:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:07:51 INFO - Intel Iris: 03:07:51 INFO - Chipset Model: Intel Iris 03:07:51 INFO - Type: GPU 03:07:51 INFO - Bus: Built-In 03:07:51 INFO - VRAM (Dynamic, Max): 1536 MB 03:07:51 INFO - Vendor: Intel (0x8086) 03:07:51 INFO - Device ID: 0x0a2e 03:07:51 INFO - Revision ID: 0x0009 03:07:51 INFO - Displays: 03:07:51 INFO - Display: 03:07:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:07:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:07:51 INFO - Display Serial Number: 1600x1200 60 03:07:51 INFO - Main Display: Yes 03:07:51 INFO - Mirror: Off 03:07:51 INFO - Online: Yes 03:07:51 INFO - Rotation: Supported 03:07:51 INFO - Return code: 0 03:07:51 INFO - Running post-action listener: _resource_record_post_action 03:07:51 INFO - Running post-run listener: _resource_record_post_run 03:07:52 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 31124992; Write bytes: 654549504; Read time: 194; Write time: 1858 03:07:52 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 345768448; Write bytes: 345667072; Read time: 26493; Write time: 726 03:07:52 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30199296; Write bytes: 297584128; Read time: 183; Write time: 1089 03:07:52 INFO - Running post-run listener: _upload_blobber_files 03:07:52 INFO - Blob upload gear active. 03:07:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:07:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:07:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:07:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:07:52 INFO - (blobuploader) - INFO - Open directory for files ... 03:07:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:07:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:07:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:53 INFO - (blobuploader) - INFO - Done attempting. 03:07:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:07:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:07:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:07:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:07:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:07:54 INFO - (blobuploader) - INFO - Done attempting. 03:07:54 INFO - (blobuploader) - INFO - Iteration through files over. 03:07:54 INFO - Return code: 0 03:07:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:07:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:07:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8535af684a93ca451f9b2d05516fb405326c506a82e31b70ceb7977a1fc49ec0acd245cf21c0d2c2356c9684295ba5ff6544dfb0f8ccb8a3fb117243a758c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8f9ac6b11a4297efe5d2cfd71e9b5af33ee2e08b7835ec1e03da16c46607fdc05b50158ccbc6a808e0261d620083841d512e37b024741b736831ef2cc1ae95c"} 03:07:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:07:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:07:54 INFO - Contents: 03:07:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8535af684a93ca451f9b2d05516fb405326c506a82e31b70ceb7977a1fc49ec0acd245cf21c0d2c2356c9684295ba5ff6544dfb0f8ccb8a3fb117243a758c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8f9ac6b11a4297efe5d2cfd71e9b5af33ee2e08b7835ec1e03da16c46607fdc05b50158ccbc6a808e0261d620083841d512e37b024741b736831ef2cc1ae95c"} 03:07:54 INFO - Running post-run listener: copy_logs_to_upload_dir 03:07:54 INFO - Copying logs to upload dir... 03:07:54 INFO - mkdir: /builds/slave/test/build/upload/logs 03:07:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.530191 ========= master_lag: 0.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-05-25 03:07:55.022459) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 03:07:55.026338) ========= 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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8535af684a93ca451f9b2d05516fb405326c506a82e31b70ceb7977a1fc49ec0acd245cf21c0d2c2356c9684295ba5ff6544dfb0f8ccb8a3fb117243a758c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8f9ac6b11a4297efe5d2cfd71e9b5af33ee2e08b7835ec1e03da16c46607fdc05b50158ccbc6a808e0261d620083841d512e37b024741b736831ef2cc1ae95c"} build_url:https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011164 build_url: 'https://queue.taskcluster.net/v1/task/By2q9BHVRjabxxJiqXopDw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e8535af684a93ca451f9b2d05516fb405326c506a82e31b70ceb7977a1fc49ec0acd245cf21c0d2c2356c9684295ba5ff6544dfb0f8ccb8a3fb117243a758c16", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f8f9ac6b11a4297efe5d2cfd71e9b5af33ee2e08b7835ec1e03da16c46607fdc05b50158ccbc6a808e0261d620083841d512e37b024741b736831ef2cc1ae95c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 03:07:55.057514) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:07:55.057853) ========= 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.1REbc0BHZl/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.3CFcDmYxAZ/Listeners TMPDIR=/var/folders/rv/3yqh8vjn0nx53n2r8sx5mb2w00000w/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.005082 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 03:07:55.106948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 03:07:55.107258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 03:07:55.107594) ========= ========= Total master_lag: 0.22 =========