builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0385 starttime: 1463190625.64 results: success (0) buildid: 20160513171545 builduid: 8e3062ca518243a3b04482eedd5332c3 revision: aea53454e86ff1f3572ad224c2a08161592d43e1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.639031) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.639451) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.639734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.712773) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.713019) ========= 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.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-13 18:50:25-- 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% 7.93M=0.001s 2016-05-13 18:50:25 (7.93 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.104291 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.837230) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.837506) ========= 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.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.019044 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:25.884988) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 18:50:25.885288) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev aea53454e86ff1f3572ad224c2a08161592d43e1 --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 aea53454e86ff1f3572ad224c2a08161592d43e1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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-13 18:50:25,921 truncating revision to first 12 chars 2016-05-13 18:50:25,921 Setting DEBUG logging. 2016-05-13 18:50:25,921 attempt 1/10 2016-05-13 18:50:25,921 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/aea53454e86f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-13 18:50:26,663 unpacking tar archive at: mozilla-aurora-aea53454e86f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.999369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-13 18:50:26.904027) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:26.904913) ========= script_repo_revision: aea53454e86ff1f3572ad224c2a08161592d43e1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:26.905943) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:26.906500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-13 18:50:26.920134) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-13 18:50:26.920451) ========= /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.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:50:27 INFO - MultiFileLogger online at 20160513 18:50:27 in /builds/slave/test 18:50:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:50:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:50:27 INFO - {'append_to_log': False, 18:50:27 INFO - 'base_work_dir': '/builds/slave/test', 18:50:27 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:50:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:50:27 INFO - 'branch': 'Mozilla-Aurora', 18:50:27 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:27 INFO - 'config_files': ('talos/mac_config.py',), 18:50:27 INFO - 'default_actions': ('clobber', 18:50:27 INFO - 'read-buildbot-config', 18:50:27 INFO - 'download-and-extract', 18:50:27 INFO - 'populate-webroot', 18:50:27 INFO - 'create-virtualenv', 18:50:27 INFO - 'install', 18:50:27 INFO - 'run-tests'), 18:50:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:27 INFO - 'download_minidump_stackwalk': True, 18:50:27 INFO - 'download_symbols': 'ondemand', 18:50:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:27 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:27 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:27 INFO - 'installer_path': 'installer.exe', 18:50:27 INFO - 'log_level': 'info', 18:50:27 INFO - 'log_name': 'talos', 18:50:27 INFO - 'log_to_console': True, 18:50:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:50:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:50:27 INFO - 'opt_config_files': (), 18:50:27 INFO - 'pip_index': False, 18:50:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:27 INFO - 'cmd': ('bash', 18:50:27 INFO - '-c', 18:50:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:27 INFO - 'enabled': True, 18:50:27 INFO - 'halt_on_failure': False, 18:50:27 INFO - 'name': 'check_screen_resolution'},), 18:50:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:27 INFO - 'cmd': ('bash', 18:50:27 INFO - '-c', 18:50:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:27 INFO - 'enabled': True, 18:50:27 INFO - 'halt_on_failure': False, 18:50:27 INFO - 'name': 'check_screen_resolution'},), 18:50:27 INFO - 'run_cmd_checks_enabled': True, 18:50:27 INFO - 'sps_profile': False, 18:50:27 INFO - 'sps_profile_interval': 0, 18:50:27 INFO - 'suite': 'dromaeojs', 18:50:27 INFO - 'system_bits': '32', 18:50:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:27 INFO - 'title': 't-yosemite-r7-0385', 18:50:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:27 INFO - 'use_talos_json': True, 18:50:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:50:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:27 INFO - 'work_dir': 'build'} 18:50:27 INFO - ##### 18:50:27 INFO - ##### Running clobber step. 18:50:27 INFO - ##### 18:50:27 INFO - Running pre-action listener: _resource_record_pre_action 18:50:27 INFO - Running main action method: clobber 18:50:27 INFO - rmtree: /builds/slave/test/build 18:50:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:50:28 INFO - Running post-action listener: _resource_record_post_action 18:50:28 INFO - ##### 18:50:28 INFO - ##### Running read-buildbot-config step. 18:50:28 INFO - ##### 18:50:28 INFO - Running pre-action listener: _resource_record_pre_action 18:50:28 INFO - Running main action method: read_buildbot_config 18:50:28 INFO - Using buildbot properties: 18:50:28 INFO - { 18:50:28 INFO - "project": "", 18:50:28 INFO - "product": "firefox", 18:50:28 INFO - "script_repo_revision": "production", 18:50:28 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:50:28 INFO - "repository": "", 18:50:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:50:28 INFO - "buildid": "20160513171545", 18:50:28 INFO - "pgo_build": "False", 18:50:28 INFO - "basedir": "/builds/slave/test", 18:50:28 INFO - "buildnumber": 7, 18:50:28 INFO - "slavename": "t-yosemite-r7-0385", 18:50:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:50:28 INFO - "platform": "yosemite_r7", 18:50:28 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:50:28 INFO - "branch": "mozilla-aurora", 18:50:28 INFO - "repo_path": "releases/mozilla-aurora", 18:50:28 INFO - "revision": "aea53454e86ff1f3572ad224c2a08161592d43e1", 18:50:28 INFO - "stage_platform": "macosx64", 18:50:28 INFO - "builduid": "8e3062ca518243a3b04482eedd5332c3", 18:50:28 INFO - "slavebuilddir": "test" 18:50:28 INFO - } 18:50:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:50:28 INFO - Running post-action listener: _resource_record_post_action 18:50:28 INFO - ##### 18:50:28 INFO - ##### Running download-and-extract step. 18:50:28 INFO - ##### 18:50:28 INFO - Running pre-action listener: _resource_record_pre_action 18:50:28 INFO - Running main action method: download_and_extract 18:50:28 INFO - mkdir: /builds/slave/test/build/tests 18:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:28 INFO - https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:50:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:50:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:50:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:50:30 INFO - Downloaded 1199 bytes. 18:50:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:50:30 INFO - Using the following test package requirements: 18:50:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:50:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:50:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:50:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'jsshell-mac.zip'], 18:50:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:50:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:50:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:50:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:50:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:50:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:50:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:50:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:50:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:30 INFO - https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:50:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:31 INFO - Downloaded 18102041 bytes. 18:50:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:50:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:50:34 INFO - Return code: 0 18:50:34 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:50:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:34 INFO - https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:50:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:50:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:50:36 INFO - Downloaded 18102041 bytes. 18:50:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:50:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:50:40 INFO - Return code: 0 18:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:40 INFO - https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:50:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:50:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:50:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:50:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:50:41 INFO - Downloaded 10840145 bytes. 18:50:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:50:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:50:41 INFO - Return code: 0 18:50:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:41 INFO - https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:50:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:50:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:50:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:50:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:50:47 INFO - Downloaded 114129243 bytes. 18:50:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:50:47 INFO - mkdir: /builds/slave/test/properties 18:50:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:50:47 INFO - Writing to file /builds/slave/test/properties/build_url 18:50:47 INFO - Contents: 18:50:47 INFO - build_url:https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:50:48 INFO - Running post-action listener: _resource_record_post_action 18:50:48 INFO - Running post-action listener: set_extra_try_arguments 18:50:48 INFO - ##### 18:50:48 INFO - ##### Running populate-webroot step. 18:50:48 INFO - ##### 18:50:48 INFO - Running pre-action listener: _resource_record_pre_action 18:50:48 INFO - Running main action method: populate_webroot 18:50:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:50:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:50:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:50:48 INFO - '--tppagecycles', 18:50:48 INFO - '7'], 18:50:48 INFO - 'tests': ['tsvgm']}}, 18:50:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:50:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:50:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:50:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:50:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:50:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['damp', 'tps']}, 18:50:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'tests': ['damp', 'tps']}, 18:50:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['dromaeo_dom']}, 18:50:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:50:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'other-e10s': {'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['a11yr', 18:50:48 INFO - 'ts_paint', 18:50:48 INFO - 'tpaint', 18:50:48 INFO - 'sessionrestore', 18:50:48 INFO - 'sessionrestore_no_auto_restore', 18:50:48 INFO - 'tabpaint']}, 18:50:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['tsvgx', 18:50:48 INFO - 'tsvgr_opacity', 18:50:48 INFO - 'tart', 18:50:48 INFO - 'tscrollx', 18:50:48 INFO - 'cart']}, 18:50:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:50:48 INFO - 'tsvgr_opacity', 18:50:48 INFO - 'tart', 18:50:48 INFO - 'tscrollx', 18:50:48 INFO - 'cart']}, 18:50:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'talos_options': ['--disable-e10s'], 18:50:48 INFO - 'tests': ['tp5o']}, 18:50:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'tests': ['tp5o']}, 18:50:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'talos_options': ['--disable-e10s', 18:50:48 INFO - '--xperf_path', 18:50:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:48 INFO - 'tests': ['tp5n']}, 18:50:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:50:48 INFO - 'talos_options': ['--xperf_path', 18:50:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:50:48 INFO - 'tests': ['tp5n']}}, 18:50:48 INFO - 'talos.zip': {'path': '', 18:50:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:50:48 INFO - Running post-action listener: _resource_record_post_action 18:50:48 INFO - ##### 18:50:48 INFO - ##### Running create-virtualenv step. 18:50:48 INFO - ##### 18:50:48 INFO - Running pre-action listener: _resource_record_pre_action 18:50:48 INFO - Running main action method: create_virtualenv 18:50:48 INFO - Creating virtualenv /builds/slave/test/build/venv 18:50:48 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:50:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:50:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:50:48 INFO - Using real prefix '/tools/python27' 18:50:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:50:49 INFO - Installing distribute.............................................................................................................................................................................................done. 18:50:52 INFO - Installing pip.................done. 18:50:52 INFO - Return code: 0 18:50:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:52 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:52 INFO - 'HOME': '/Users/cltbld', 18:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:52 INFO - 'LOGNAME': 'cltbld', 18:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:52 INFO - 'MOZ_NO_REMOTE': '1', 18:50:52 INFO - 'NO_EM_RESTART': '1', 18:50:52 INFO - 'PAGER': '/bin/cat', 18:50:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:52 INFO - 'PWD': '/builds/slave/test', 18:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:52 INFO - 'SHELL': '/bin/bash', 18:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:50:52 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:52 INFO - 'USER': 'cltbld', 18:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:52 INFO - 'XPC_FLAGS': '0x0', 18:50:52 INFO - 'XPC_SERVICE_NAME': '0', 18:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:52 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:50:52 INFO - Cleaning up... 18:50:52 INFO - Return code: 0 18:50:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:52 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 18:50:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 18:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:52 INFO - 'HOME': '/Users/cltbld', 18:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:52 INFO - 'LOGNAME': 'cltbld', 18:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:52 INFO - 'MOZ_NO_REMOTE': '1', 18:50:52 INFO - 'NO_EM_RESTART': '1', 18:50:52 INFO - 'PAGER': '/bin/cat', 18:50:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:52 INFO - 'PWD': '/builds/slave/test', 18:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:52 INFO - 'SHELL': '/bin/bash', 18:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:50:52 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:52 INFO - 'USER': 'cltbld', 18:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:52 INFO - 'XPC_FLAGS': '0x0', 18:50:52 INFO - 'XPC_SERVICE_NAME': '0', 18:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:52 INFO - Downloading/unpacking psutil>=0.7.1 18:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:50:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:55 INFO - Installing collected packages: psutil 18:50:55 INFO - Running setup.py install for psutil 18:50:55 INFO - building 'psutil._psutil_osx' extension 18:50:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:50:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:50:55 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:50:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:50:56 INFO - building 'psutil._psutil_posix' extension 18:50:56 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:50:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:50:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:50:56 INFO - ^ 18:50:56 INFO - 1 warning generated. 18:50:56 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:50:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:50:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:50:56 INFO - Successfully installed psutil 18:50:56 INFO - Cleaning up... 18:50:56 INFO - Return code: 0 18:50:56 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:50:56 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:50:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:50:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:50:56 INFO - 'HOME': '/Users/cltbld', 18:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:50:56 INFO - 'LOGNAME': 'cltbld', 18:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:50:56 INFO - 'MOZ_NO_REMOTE': '1', 18:50:56 INFO - 'NO_EM_RESTART': '1', 18:50:56 INFO - 'PAGER': '/bin/cat', 18:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:50:56 INFO - 'PWD': '/builds/slave/test', 18:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:50:56 INFO - 'SHELL': '/bin/bash', 18:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:50:56 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:50:56 INFO - 'USER': 'cltbld', 18:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:50:56 INFO - 'XPC_FLAGS': '0x0', 18:50:56 INFO - 'XPC_SERVICE_NAME': '0', 18:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:50:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:50:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:50:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:50:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:50:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:51:00 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:51:00 INFO - Installing collected packages: mozsystemmonitor 18:51:00 INFO - Running setup.py install for mozsystemmonitor 18:51:00 INFO - Successfully installed mozsystemmonitor 18:51:00 INFO - Cleaning up... 18:51:00 INFO - Return code: 0 18:51:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:00 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:51:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:51:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:00 INFO - 'HOME': '/Users/cltbld', 18:51:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:00 INFO - 'LOGNAME': 'cltbld', 18:51:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:00 INFO - 'MOZ_NO_REMOTE': '1', 18:51:00 INFO - 'NO_EM_RESTART': '1', 18:51:00 INFO - 'PAGER': '/bin/cat', 18:51:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:00 INFO - 'PWD': '/builds/slave/test', 18:51:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:00 INFO - 'SHELL': '/bin/bash', 18:51:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:00 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:00 INFO - 'USER': 'cltbld', 18:51:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:00 INFO - 'XPC_FLAGS': '0x0', 18:51:00 INFO - 'XPC_SERVICE_NAME': '0', 18:51:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:00 INFO - Downloading/unpacking blobuploader==1.2.4 18:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:02 INFO - Downloading blobuploader-1.2.4.tar.gz 18:51:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:51:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:51:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:04 INFO - Downloading docopt-0.6.1.tar.gz 18:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:51:04 INFO - Installing collected packages: blobuploader, requests, docopt 18:51:04 INFO - Running setup.py install for blobuploader 18:51:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:51:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:51:04 INFO - Running setup.py install for requests 18:51:04 INFO - Running setup.py install for docopt 18:51:04 INFO - Successfully installed blobuploader requests docopt 18:51:04 INFO - Cleaning up... 18:51:05 INFO - Return code: 0 18:51:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:05 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:51:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:51:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:05 INFO - 'HOME': '/Users/cltbld', 18:51:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:05 INFO - 'LOGNAME': 'cltbld', 18:51:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:05 INFO - 'MOZ_NO_REMOTE': '1', 18:51:05 INFO - 'NO_EM_RESTART': '1', 18:51:05 INFO - 'PAGER': '/bin/cat', 18:51:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:05 INFO - 'PWD': '/builds/slave/test', 18:51:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:05 INFO - 'SHELL': '/bin/bash', 18:51:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:05 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:05 INFO - 'USER': 'cltbld', 18:51:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:05 INFO - 'XPC_FLAGS': '0x0', 18:51:05 INFO - 'XPC_SERVICE_NAME': '0', 18:51:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-AEwr2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-EKi5Yf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-4Dj1nq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-PZUHXr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-6S0KdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-bT36Zi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:05 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-5Evy7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-DcrnwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-iYvCHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-F7yhMN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-e2f8gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-z8IOnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-h0V75p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-1D9w9i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-jGryKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Yc42lL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:06 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-kz6HT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:07 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:51:07 INFO - Running setup.py install for manifestparser 18:51:07 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Running setup.py install for mozcrash 18:51:07 INFO - Running setup.py install for mozdebug 18:51:07 INFO - Running setup.py install for mozdevice 18:51:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Running setup.py install for mozfile 18:51:07 INFO - Running setup.py install for mozhttpd 18:51:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:51:07 INFO - Running setup.py install for mozinfo 18:51:08 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozInstall 18:51:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozleak 18:51:08 INFO - Running setup.py install for mozlog 18:51:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for moznetwork 18:51:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozprocess 18:51:08 INFO - Running setup.py install for mozprofile 18:51:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:51:08 INFO - Running setup.py install for mozrunner 18:51:09 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Running setup.py install for mozscreenshot 18:51:09 INFO - Running setup.py install for moztest 18:51:09 INFO - Running setup.py install for mozversion 18:51:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:51:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:51:09 INFO - Cleaning up... 18:51:09 INFO - Return code: 0 18:51:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:09 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:51:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:51:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:09 INFO - 'HOME': '/Users/cltbld', 18:51:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:09 INFO - 'LOGNAME': 'cltbld', 18:51:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:09 INFO - 'MOZ_NO_REMOTE': '1', 18:51:09 INFO - 'NO_EM_RESTART': '1', 18:51:09 INFO - 'PAGER': '/bin/cat', 18:51:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:09 INFO - 'PWD': '/builds/slave/test', 18:51:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:09 INFO - 'SHELL': '/bin/bash', 18:51:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:09 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:09 INFO - 'USER': 'cltbld', 18:51:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:09 INFO - 'XPC_FLAGS': '0x0', 18:51:09 INFO - 'XPC_SERVICE_NAME': '0', 18:51:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:09 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-KWEAPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:09 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:51:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:09 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-zXO10T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-EOua9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rTqyXs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-uKWk8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-FyfMss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-Caz0jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-vylHjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9FjYoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-robPgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:10 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:51:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:10 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-95rPcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-XSKFUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-D8XY2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-9ZbBaV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-gyGEf4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-0qsZx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 18:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:11 INFO - Running setup.py (path:/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/pip-rsrUVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:51:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:51:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:14 INFO - Downloading blessings-1.6.tar.gz 18:51:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:51:14 INFO - Installing collected packages: blessings 18:51:14 INFO - Running setup.py install for blessings 18:51:14 INFO - Successfully installed blessings 18:51:14 INFO - Cleaning up... 18:51:14 INFO - Return code: 0 18:51:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:51:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:14 INFO - Reading from file tmpfile_stdout 18:51:14 INFO - Current package versions: 18:51:14 INFO - blessings == 1.6 18:51:14 INFO - blobuploader == 1.2.4 18:51:14 INFO - docopt == 0.6.1 18:51:14 INFO - manifestparser == 1.1 18:51:14 INFO - mozInstall == 1.12 18:51:14 INFO - mozcrash == 0.17 18:51:14 INFO - mozdebug == 0.1 18:51:14 INFO - mozdevice == 0.48 18:51:14 INFO - mozfile == 1.2 18:51:14 INFO - mozhttpd == 0.7 18:51:14 INFO - mozinfo == 0.9 18:51:14 INFO - mozleak == 0.1 18:51:14 INFO - mozlog == 3.1 18:51:14 INFO - moznetwork == 0.27 18:51:14 INFO - mozprocess == 0.22 18:51:14 INFO - mozprofile == 0.28 18:51:14 INFO - mozrunner == 6.11 18:51:14 INFO - mozscreenshot == 0.1 18:51:14 INFO - mozsystemmonitor == 0.0 18:51:14 INFO - moztest == 0.7 18:51:14 INFO - mozversion == 1.4 18:51:14 INFO - psutil == 3.1.1 18:51:14 INFO - requests == 1.2.3 18:51:14 INFO - wsgiref == 0.1.2 18:51:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:14 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:51:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:51:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:14 INFO - 'HOME': '/Users/cltbld', 18:51:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:14 INFO - 'LOGNAME': 'cltbld', 18:51:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:14 INFO - 'MOZ_NO_REMOTE': '1', 18:51:14 INFO - 'NO_EM_RESTART': '1', 18:51:14 INFO - 'PAGER': '/bin/cat', 18:51:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:14 INFO - 'PWD': '/builds/slave/test', 18:51:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:14 INFO - 'SHELL': '/bin/bash', 18:51:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:14 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:14 INFO - 'USER': 'cltbld', 18:51:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:14 INFO - 'XPC_FLAGS': '0x0', 18:51:14 INFO - 'XPC_SERVICE_NAME': '0', 18:51:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:51:15 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:51:15 INFO - Cleaning up... 18:51:15 INFO - Return code: 0 18:51:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:51: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')]} 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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')]} 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:15 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:51:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:51:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:15 INFO - 'HOME': '/Users/cltbld', 18:51:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:15 INFO - 'LOGNAME': 'cltbld', 18:51:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:15 INFO - 'MOZ_NO_REMOTE': '1', 18:51:15 INFO - 'NO_EM_RESTART': '1', 18:51:15 INFO - 'PAGER': '/bin/cat', 18:51:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:15 INFO - 'PWD': '/builds/slave/test', 18:51:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:15 INFO - 'SHELL': '/bin/bash', 18:51:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:15 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:15 INFO - 'USER': 'cltbld', 18:51:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:15 INFO - 'XPC_FLAGS': '0x0', 18:51:15 INFO - 'XPC_SERVICE_NAME': '0', 18:51:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:15 INFO - Downloading/unpacking jsonschema 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:51:17 INFO - Downloading/unpacking functools32 (from jsonschema) 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51:18 INFO - Downloading functools32-3.2.3-2.tar.gz 18:51:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:51:18 INFO - warning: no files found matching '*.txt' 18:51:18 INFO - no previously-included directories found matching 'build' 18:51:18 INFO - no previously-included directories found matching 'dist' 18:51:18 INFO - no previously-included directories found matching '.git*' 18:51:18 INFO - Installing collected packages: jsonschema, functools32 18:51:18 INFO - Running setup.py install for functools32 18:51:18 INFO - warning: no files found matching '*.txt' 18:51:18 INFO - no previously-included directories found matching 'build' 18:51:18 INFO - no previously-included directories found matching 'dist' 18:51:18 INFO - no previously-included directories found matching '.git*' 18:51:18 INFO - Successfully installed jsonschema functools32 18:51:18 INFO - Cleaning up... 18:51:18 INFO - Return code: 0 18:51:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:51:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:18 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 0x10b7f3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b803420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9b6158ed50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, '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.5NIZhjOk4X/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.ypSlsazOKj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:51:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:51:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:51:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:18 INFO - 'HOME': '/Users/cltbld', 18:51:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:18 INFO - 'LOGNAME': 'cltbld', 18:51:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:18 INFO - 'MOZ_NO_REMOTE': '1', 18:51:18 INFO - 'NO_EM_RESTART': '1', 18:51:18 INFO - 'PAGER': '/bin/cat', 18:51:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:18 INFO - 'PWD': '/builds/slave/test', 18:51:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:18 INFO - 'SHELL': '/bin/bash', 18:51:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:18 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:18 INFO - 'USER': 'cltbld', 18:51:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:18 INFO - 'XPC_FLAGS': '0x0', 18:51:18 INFO - 'XPC_SERVICE_NAME': '0', 18:51:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:18 INFO - Downloading/unpacking flake8 18:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:51:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:51:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:51:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:51:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:51:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:51:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:51:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:51:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:51:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:51:22 INFO - no previously-included directories found matching 'docs/_build' 18:51:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:51:22 INFO - Running setup.py install for pep8 18:51:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:51:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:51:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:51:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:51:22 INFO - no previously-included directories found matching 'docs/_build' 18:51:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:51:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:51:22 INFO - Cleaning up... 18:51:22 INFO - Return code: 0 18:51:22 INFO - Running post-action listener: _resource_record_post_action 18:51:22 INFO - Running post-action listener: _start_resource_monitoring 18:51:22 INFO - Starting resource monitoring. 18:51:22 INFO - ##### 18:51:22 INFO - ##### Running install step. 18:51:22 INFO - ##### 18:51:22 INFO - Running pre-action listener: _resource_record_pre_action 18:51:22 INFO - Running main action method: install 18:51:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:51:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:51:23 INFO - Reading from file tmpfile_stdout 18:51:23 INFO - Detecting whether we're running mozinstall >=1.0... 18:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:51:23 INFO - Reading from file tmpfile_stdout 18:51:23 INFO - Output received: 18:51:23 INFO - Usage: mozinstall [options] installer 18:51:23 INFO - Options: 18:51:23 INFO - -h, --help show this help message and exit 18:51:23 INFO - -d DEST, --destination=DEST 18:51:23 INFO - Directory to install application into. [default: 18:51:23 INFO - "/builds/slave/test"] 18:51:23 INFO - --app=APP Application being installed. [default: firefox] 18:51:23 INFO - mkdir: /builds/slave/test/build/application 18:51:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:51:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:51:54 INFO - Reading from file tmpfile_stdout 18:51:54 INFO - Output received: 18:51:54 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:51:54 INFO - Running post-action listener: _resource_record_post_action 18:51:54 INFO - ##### 18:51:54 INFO - ##### Running run-tests step. 18:51:54 INFO - ##### 18:51:54 INFO - Running pre-action listener: _resource_record_pre_action 18:51:54 INFO - Running main action method: run_tests 18:51:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:51:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:51:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:51:54 INFO - 2016-05-13 18:51:54.300 screenresolution[1923:14215] starting screenresolution argv=screenresolution get 18:51:54 INFO - 2016-05-13 18:51:54.302 screenresolution[1923:14215] Display 0: 1600x1200x32@60 18:51:54 INFO - 2016-05-13 18:51:54.308 screenresolution[1924:14217] starting screenresolution argv=screenresolution list 18:51:54 INFO - Available Modes on Display 0 18:51:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:51:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:51:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:51:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:51:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:51:54 INFO - Intel Iris: 18:51:54 INFO - Chipset Model: Intel Iris 18:51:54 INFO - Type: GPU 18:51:54 INFO - Bus: Built-In 18:51:54 INFO - VRAM (Dynamic, Max): 1536 MB 18:51:54 INFO - Vendor: Intel (0x8086) 18:51:54 INFO - Device ID: 0x0a2e 18:51:54 INFO - Revision ID: 0x0009 18:51:54 INFO - Displays: 18:51:54 INFO - Display: 18:51:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:51:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:51:54 INFO - Display Serial Number: 1600x1200 60 18:51:54 INFO - Main Display: Yes 18:51:54 INFO - Mirror: Off 18:51:54 INFO - Online: Yes 18:51:54 INFO - Rotation: Supported 18:51:54 INFO - Return code: 0 18:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:51:54 INFO - Python 2.7.3 18:51:54 INFO - Return code: 0 18:51:54 INFO - grabbing minidump binary from tooltool 18:51: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')]} 18:51:54 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 0x10b692e00>, '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 0x10b4fbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b68db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:51:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:51:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:51:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:51:54 INFO - Return code: 0 18:51:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:51:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:51:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:51:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:51:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:51:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:51:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:51:55 INFO - Return code: 0 18:51:55 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/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:51:55 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/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0385 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NIZhjOk4X/Render', 18:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:55 INFO - 'HOME': '/Users/cltbld', 18:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:55 INFO - 'LOGNAME': 'cltbld', 18:51:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:51:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:55 INFO - 'MOZ_NO_REMOTE': '1', 18:51:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:51:55 INFO - 'NO_EM_RESTART': '1', 18:51:55 INFO - 'PAGER': '/bin/cat', 18:51:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:55 INFO - 'PWD': '/builds/slave/test', 18:51:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:55 INFO - 'SHELL': '/bin/bash', 18:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ypSlsazOKj/Listeners', 18:51:55 INFO - 'TMPDIR': '/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/', 18:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:55 INFO - 'USER': 'cltbld', 18:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:55 INFO - 'XPC_FLAGS': '0x0', 18:51:55 INFO - 'XPC_SERVICE_NAME': '0', 18:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:55 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/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0385', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:51:55 INFO - mozversion application_buildid: 20160513171545 18:51:55 INFO - mozversion application_changeset: aea53454e86ff1f3572ad224c2a08161592d43e1 18:51:55 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:51:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:51:55 INFO - mozversion application_name: Firefox 18:51:55 INFO - mozversion application_remotingname: firefox-dev 18:51:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:51:55 INFO - mozversion application_vendor: Mozilla 18:51:55 INFO - mozversion application_version: 48.0a2 18:51:55 INFO - mozversion platform_buildid: 20160513171545 18:51:55 INFO - mozversion platform_changeset: aea53454e86ff1f3572ad224c2a08161592d43e1 18:51:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:51:55 INFO - mozversion platform_version: 48.0a2 18:51:55 INFO - using testdate: 1463190715 18:51:55 INFO - actual date: 1463190715 18:51:55 INFO - starting webserver on 'localhost:52457' 18:51:55 INFO - SUITE-START | Running 2 tests 18:51:55 INFO - TEST-START | dromaeo_css 18:51:55 INFO - operating with platform_type : mac_ 18:51:55 INFO - Initialising browser for dromaeo_css test... 18:51:55 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpQyBfbt/profile http://localhost:52457/getInfo.html) 18:51:58 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 18:51:58 INFO - PROCESS | 1942 | colorDepth:24 18:51:58 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 18:51:58 INFO - PROCESS | 1942 | __metrics 18:51:58 INFO - PROCESS | 1942 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:51:58 ERROR - PROCESS | 1942 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:51:59 INFO - TEST-INFO | 1942: exit 0 18:51:59 INFO - Browser initialized. 18:51:59 INFO - Running cycle 1/1 for dromaeo_css test... 18:51:59 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpQyBfbt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:52:11 INFO - PROCESS | 1944 | RSS: Main: 211070976 18:52:11 INFO - PROCESS | 1944 | 18:53:02 INFO - PROCESS | 1944 | 1463190782776 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 18:55:13 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52457/tests/dromaeo/cssquery-ext.html) 18:55:14 INFO - PROCESS | 1944 | RSS: Main: 319688704 18:55:14 INFO - PROCESS | 1944 | 18:58:17 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-ext.html (next: http://localhost:52457/tests/dromaeo/cssquery-jquery.html) 18:58:18 INFO - PROCESS | 1944 | RSS: Main: 353943552 18:58:18 INFO - PROCESS | 1944 | 19:01:20 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52457/tests/dromaeo/cssquery-mootools.html) 19:01:20 INFO - PROCESS | 1944 | RSS: Main: 359415808 19:01:20 INFO - PROCESS | 1944 | 19:04:23 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52457/tests/dromaeo/cssquery-prototype.html) 19:04:23 INFO - PROCESS | 1944 | RSS: Main: 360923136 19:04:23 INFO - PROCESS | 1944 | 19:07:27 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52457/tests/dromaeo/cssquery-yui.html) 19:07:27 INFO - PROCESS | 1944 | RSS: Main: 323354624 19:07:27 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:52457/tests/dromaeo/cssquery-yui.html (next: http://localhost:52457/tests/dromaeo/cssquery-dojo.html) 19:10:30 INFO - PROCESS | 1944 | RSS: Main: 370036736 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | __start_tp_report 19:10:30 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 19:10:30 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 19:10:30 INFO - PROCESS | 1944 | |i|pagename|runs| 19:10:30 INFO - PROCESS | 1944 | |0;dojo.html;3297.6042874855016;3391.915202119946;3393.779404338718;3426.0407086015925;3455.5162277281183;5566.638168519044;5613.158026296058;5624.381318055016;5633.211350410936;5649.322081350244;5586.273784408021;5615.354234263061;5628.887422251552;5639.182318563806;5639.830805075844;4993;5036.697798132105;5105.259737338093;5153.046686363016;5194.896102077956;5140.408852981899;5144.717040562768;5147.9704059188125;5155.845324640267;5157.819476318329;5090.109230884599;5127.128388174001;5150.124478838607;5173.430922598511;5194.8441546753465;5164.147915593941;5197.064528384889;5211.869703257411;5218.373795144569;5273.050850846846;4966.329545511346;5009.998000399898;5018.573421259197;5105.463926287724;5122.538971492583;5427.294451721251;5449.836504904859;5479.917801232985;5497.092979658351;5520.06158952975;5850.180974663551;5856.677882716449;5865.58940874139;5878.353381128072;5894.145348924382;5559.27729395176;5918.112283157518;5957;6121.510279177655;6144.463884028993;5699.544036477072;5733.3406658234835;5748.109043098327;5764.625299355528;5784.68184249866;6283.183186185858;6313.08460273267;6329.303776584572;6333.809985700436;6346.238451385863;5524.3370795504;5899.380565040695;6004.039353703386;6028.457438830526;6079.240094988127;6173.826972875229;6178.227721534809;6179;6194.5044396448175;6199.442050215503;6091.573589848668;6095.329513753483;6102.206713127353;6102.69486525672;6106.4809491192855;5862.589618726648;5957.821265362046;5965.224520812267;6129.417705317987;6144.662043587557;4783.808647654125;4796.136695394793;4823.686460380064;4827.951720482821;4830.227163653799;3748.775073495599;4319.870403887889;4381.430414046154;4407.27279998803;4409.757463339483;5635.380108188096;5636.436356364331;5652.095664693629;5658;5674.631148975303;6188.504919606421;6222.284437289679;6243.406876346739;6248.687565621701;6272.968635156795;5563.777448902079;5582.469665381782;5619.43805619443;5639.718014099279;5643.125315576092;5306.787728490817;5312.707801070901;5321.521063104339;5334.5732341412595;5354.571634269249;6238.563300568917;6292.244930608264;6297;6318.715657795318;6324.747010119636;6093.086037094294;6143.907841382472;6164.815055548341;6208.068789681584;6211.472024877936;6258.593191442542;6288.968555157195;6291.245050593958;6300.9684951574945;6311.936880631135;6084.087386892002;6097.451229389376;6098.725557350019;6107.053406721965;6118.388161184024;4558.544145585548;6112.1387513176505;6131.804871721949;6194.504439644727;6196;5945.227120474311;5965.821025369246;5995.790147344822;6003.459688628044;6032.849178770566;5939.67331796738;5955.67243801595;5968.641881487125;5975.492083172979;5976.46211840919;5704.914426283526;5795.449432303924;5803.7388317524965;5818.76724930998;5834.18321435008;5483.533899618577;5697.917395694804;6004.579679422399;6009.609375390585;6039.063945088518;4501.864944051684;4640.164770341371;5710.143478478095;5850.151727999331;5886.087656413263 19:10:30 INFO - PROCESS | 1944 | |1;ext.html;504.70979186967503;505.55258596142403;505.630889450701;559.6642014791124;600.3066458240734;24609.876950615246;38316.850494485174;39066.60933390672;39173.21653566927;39206.803965980165;42191;42710.7864460678;42723.78638106813;42766;42805.57194428055;9487.525623718804;9684.273679474032;9686.46724430156;9695.515224238796;9701.902980970206;9478.241740660766;9596.712098637052;9608.183304419133;9615.519224038804;9657.95171024144;8356.2897153742;9629;9704;9713.368631038995;9716.174125199366;9640.951795241013;9649.710508684786;9673.564689588986;9683.90316096837;9709.757256068584;9536.332456728032;9638.42169469827;9638.662646807328;9641.662541811073;9669.323147379686;41427;41684.58315416837;41744.74765757032;41792.582074179176;41931.79034104841;3126.1403114143563;3682.723795715305;3717.8141092945516;3839.827207775657;3863.787491687956;28315.71684283172;28349.14952551427;28405;28596.571051434035;28853.56719649207;2772.4177922636068;2796.8182068165506;2829.9292517687018;2848.4445533121047;2873.7126287371098;465324;468719;469617;469790.651046746;470111;59249.70375148096;63954;64172;64179.03731444032;64282;66305.673886522;66467.6706465868;66500;66526.33473665298;66731.66634166896;17399.73900391495;17590.736138957927;17600.94394336344;17625.911870440566;17626.735598966025;13505.864941350657;13585.456581736818;13593.86406135926;13604.251766152758;13636;15887.364505419884;16859.072750998814;16950.49148525546;16953.745693814602;16985.575360616083;5461.1808228766;6375.2987171411105;6589.143411356493;6658.86682266352;6662;798.876174193001;1030.7423144213947;1046.9005444482762;1074.2211896375065;1096.572336788653;22892.88553557255;27498.862505687342;28593.857030714713;28953.565696514568;29002.709972900422;723.8769409200448;949.8052899155656;1045.257866914484;1192.3203773848995;1196.8264601632713;13481.2585307809;15081.019733717274;15874.52376428686;15878.444254451038;16184.676306473804;440532;440646;444788;445904;446422;54967.725161373935;55502;55574;55616.44383556113;55681.60795980133;1425.0879437160227;1513.4702854001011;1523.7181121492558;1526.2902750220978;1585.2549301828121;24472.75527244776;26285.474290514467;27726.306842329104;28500.002499912403;29269.41461170807;49165.75417122892;49525.504744952086;49644.75177624089;49669.503304966485;49685.75157124336;437123;439496;440636;440655;441335;21945.231916882833;23084.192053278053;25923.481530369663;26324.868375658767;26730;41286.79356603198;41409.58590414178;42519;42564.787176063925;44242;34745.47881781826;36889.815550922074;37018.44472332971;37288;37806.62193378031;35203.64796352004;35412;35471.93584192478;36032.27935441329;36063;35104.64895351116;35482.645173547935;35646;36072.63927360798;36161.27677446384;31501.212469688442;31904.883074636284;32406.18984525406;32642.51036234506;32866.67133328636;20700.374420244825;21783.34649960504;24469.632955506037;24642.383940400916;24777 19:10:30 INFO - PROCESS | 1944 | |2;jquery.html;399.29723686312093;402.0892678084138;403.826354667493;404.3409242934017;412.8864562245384;14991.47529836456;18331.08344582772;19611.901940490294;20272.986350682462;20278.69581956272;16340.75488867668;17631.38290159845;20297.898510507464;20609.793902060974;20636.69044964327;5188.066148093351;5531.2532808071;5533.22534845122;5534.584906132036;5538.723063846792;3980;5407;5503.752331145088;5503.7798488060425;5515.062439385294;5847.707614619273;6110.786122485714;6126.989046807272;6129;6131.47882429994;6046.3349031606485;6113.174721412597;6119.724612392431;6127.47916427102;6659.134312539387;5930.673812940287;5952.9702351488395;5963.701814909281;5966.433188847096;5974.61165024272;13254.801177982337;13488.19070855742;13735.24456154911;14089.577312680534;14089.718205635932;2255.853369530975;2490.8754562271815;2833.4049849531625;2834.1780883543747;2868.3976364963382;10359.585616575328;11138.721531961684;11142.665720028412;12032.879671203264;15396.692066158726;1646.777685012532;1668.3493437559362;1730.4376077774773;1746.6594014167242;1769.0623969296294;97777.5111124447;118294;120586;124187.379063105;124475.13287300698;9583.616655333848;11772.293662380285;11798.705032374088;11819.290842549475;11938.820917686242;15205.771913421308;15361.769573456408;15386.692266154614;15437.996530225499;15438.691226175639;6469.741210351627;8320.46380288375;8573;8815.647374105092;8982.955085224663;8812.206901378906;8867.556622168866;8869.556522173865;8937.06160853101;8955.865662015074;4280.571942805756;4634.745089020069;4691.179043667345;4697.718136911796;4735.952640473552;1796.766405735601;1896.4310706787908;1987.8111579399933;1991.8804871707744;1992.3524854422371;860.1226748716274;934.4860326820221;936.7096200177966;970.335320305593;999.9000099990088;19474;20069.899650501655;20178.596428071356;20197.495062623257;20702.482437939172;996.8953259907751;1003.19744204636;1014.4065721552827;1031.8194315994672;1032.1381646325253;10603.992620701021;11039.72400689973;11111.944440277908;11125.554977800959;11125.777484450266;596024.0397596055;617616;630995;640715.1856962835;640860;100327.99268476822;116038;119893;120603;123971;935.5322338830584;944.9149576538144;945.1446440970932;946.3281070439928;1028.4600584692946;18046;18136.727949080512;18317;18599;18985;32287.51568726424;39698.80150599228;44526.218951241586;52627.94744105173;54655.72672136614;51220.48779512306;63857.72284554376;74547.62726186334;76441.61779191068;76803.61598192196;7834.529928204326;8173.67305307781;8212.589370531568;8270.37972152079;8470.068292487698;11318.4906679198;11367.317960922372;11774.587889423829;12110.818337724764;12451.626451206479;10498.317609355367;10728.624498142528;10886.129109671365;10916.454177291262;10961.232713709964;10543.736406589896;10606.893931060898;10669.946650266698;10777.299475534066;10816.837747433638;9172.770680733034;10878.238523303293;10916;11118.888811112109;11273.887261127611;7862.2923936845955;8103.797405064921;8116.837663246584;8241.093479717107;8469;7954.960225198837;9845.45849978258;10587.523561439908;11054.170937179582;11125.833112503156 19:10:30 INFO - PROCESS | 1944 | |3;mootools.html;1421.8293804743419;1460.3647413375184;1495.925203739813;1753.8684598655095;1924.0860591219173;7752.418568607356;8454.87317690235;8613.397062205648;8620.396572239946;8622.396432249745;8459;8621.956890215555;8630.741077767661;8632.697855575056;8645;2408.7109546854404;2430.8541487510715;2432.74456182101;2436.8050555955483;2437.3297343230574;2241.711016165711;2286.977130228693;2394.820388470863;2414.611598330958;2444.8533088014774;2400.411899084727;2434.525267572824;2436.902523899042;2437.390652336916;2438.3660248335473;2397.5924092904247;2423.042898055267;2423.53952748978;2428.538577670246;2442.3527765142253;2321.2339927823778;2406.073661640281;2407.6749638798715;2407.843490173133;2412.8552286862846;7540.8114797129965;7722.150563438017;8654.394192406535;8686;8692.261157801595;4293.720908140961;4766.142094423002;4918.680285781448;4945.579625731817;5339.065663508909;2995.8052726572705;3489.755717099804;3528.5060091587197;3538.8230588470474;3583;1048.9527971241312;1051.4217180550665;1082.2965072702727;1618.660081382911;1709.427341840485;8041.6381262843315;9573.425594464285;11433.485493152828;11462.369569673749;11473.254238474556;4286.39990401344;4548.385967894292;4565.178267911808;4647.34937108805;4649.163150632851;8894.843670322816;8940.597673104727;8955.865662015074;8961.686340978034;8970.775730606656;5192.299039629677;5211.661242019332;5221.008008478377;5225.764840582183;5238.2404551340605;4715.386999690087;4884.584810291094;4899.093667671421;4955.058538877602;4968.552830245223;3798.3352913240624;3816.5801761806733;3819.8854034379015;3850.8652197172964;3927.9018024549614;1190.678516800459;1193.856737191525;1199.184554502929;1207.9335636540075;1208.8428504294561;854.918782715641;863.2144748279036;913.9954300228457;920.9907900921039;925.8657494663236;5757.337906140764;5801.7099145042575;5885.382034886361;5938.287405511366;6004.309504407049;802.5064585280128;929.7117893453051;967.4195482710318;983.1593987140892;1026.4970164619283;3752.868649597251;3822.9044273893373;3852.1332700142802;3873.7482063666334;3934.9803250983564;10269.229807764448;10662.093722033715;10718.303310284806;10743.08683761889;10844.295120817122;10695.679129626124;10697.518611662494;10712.035916767529;10712.94643526793;10724.624638137628;7749.263819937097;8090.352771778362;8226.177382261725;8383.706570270011;8387.454815436977;4754.762261886961;4803.927941080817;5689.459501347365;5704.0588302929555;5856.707164641665;9008.234300084567;9302;9434.81130377375;9442.575084121094;9459.952700236454;9018.09819018093;9469.10043545862;9532.237421006164;9545.904540954501;9711.66009189675;8066.23370779775;8068.7175948841505;8172.182781722018;8172.387070969582;8176.182381761776;8106;8154.551499667576;8188.590570471333;8188.5905704715715;8194.795130121795;7349.228331025379;7392.852142957003;7444.478886477895;7460.104787425544;7578.924210757822;7259.310365515267;7264.854702905806;7304.598247096248;7390.9260907390235;7446;6829;6931.584104953719;6984.126984126984;6985.3713165815325;6989.790306290819;4025.7987100645437;4243.321068629005;4871.439784424837;4896.2410826321975;4975.154223782039;4760.523947605212;4776.952230477651;4803.855884323476;4852.587530059985;5014.87462813418 19:10:30 INFO - PROCESS | 1944 | |4;prototype.html;322.5419903736694;325.46948473987396;331.805893552272;334.2729563200039;337.6066882082374;5599.524040456566;6290.685465726712;6451.290358060615;6495.675216239193;6507.3167317431635;6410.519211059178;6420.486361091113;6426.967865160667;6461.353864613537;6493.4805215582755;4536.8185272589135;4632.768361581924;4633.2586786114225;4741.952580474185;4782.665213435061;4661.3707149534885;4750.121227572893;4754.168020596399;4757.333973243749;4763.976180119094;4674.90650186996;4758.45277793053;4773.403324584427;4774.116788394141;4778.378810754597;3688.1885985083372;4611.9077618447445;4615.953840461586;4616.76916154191;4621.399218101659;3042.1025797389825;3965.325894597925;4054.8175332110127;4062.309407400749;4069.267531844267;5947.702614869239;6023.096535519663;6152.3847615238565;6159.23009623797;6203.596766210227;5600.1599760035915;5601.159826026088;5604.607677462579;5607.990561698893;5667.178259152441;5769.624974376652;6770.72917083316;6885.621290829003;6885.896711549331;6900.3444672756;4455.391784229906;4559.247724125516;4625.791839367236;4637.420322459692;4642;5966;7129.3583577478275;7261.2012678605315;7291.708331666727;7300.269973002657;3774.3960966245268;4707.5057119002695;4711.575958163782;4721.881952951204;4729.266963620645;4022.3162062449455;4736.934189807334;4768.427788665383;4769.546893045155;4792.448868380111;4936.6297527685565;5319.787208511616;5589.776408943678;5611.691356975405;5613.101903695389;6623.470122390294;6892.896606550906;6921.584704917721;6928.930710692828;6929.133858267716;1578.7079390312829;1590.109538658355;1599.160440768606;1606.4136590144512;1608.035178892655;848.4824257203101;1008.294194064158;1025.3850185957147;1026.461107918349;1032.4734385463469;6171.814845554641;6290.811275661737;6343.5876668016435;6403.551751377358;6467.676616169173;7233.782986510413;7233.782986510413;7241.782746517613;7252.492325537161;7254.782356529313;5941.841340938562;6501.609903405811;6507.349265073455;6509.056186852879;6513.413792758674;3464.601570819388;3819.9809000955374;3821.8662346818282;3824.0248736572;3826.196498735241;7238.963805180941;7518.661660225303;7528;7591.924080759121;7596.430267729942;5310.9734451327495;6599.868002640016;7199.568025918462;7362.815929601696;7404.222556631584;7503.5497870127965;7512.962435187898;7522.059742532184;7526.548407095592;7529.623518824146;7243.384312333511;7261.201267860425;7272.527285726411;7284.307990740871;7294.343509084208;6864.24493305746;7098.929010709827;7107;7156.033935418652;7167.1757747859665;7423.703051877865;7539.509931854412;7550.811229719301;7559.924400755922;7588;4545.59083685634;4600.5629465200755;4608.377868987778;4608.400907881954;4617.907641847211;6142.324344322032;7002.774514460051;7204.0994875640545;7232.42140628759;7233.638318083969;6656.369189548592;6974.9537569365;6980.7207711693;6990.161180658353;6998.265182156004;6903.1716194057035;6904.963035904319;6949.270326615632;6964.164300283998;6987.371136597731;5632.633878797865;6721.8572842615895;6797.218319893275;6810.523263371517;6820.488463365168;6137.3248942617165;6951.6871740770775;6963.651817409008;6965.96517017432;6976;6403.967980160256;6484.740610375532;6539.313372095861;6643.136392268974;6803.353681400259 19:10:30 INFO - PROCESS | 1944 | |5;yui.html;729.5731996781882;737.1596380126655;742.910850697916;749.5989645539637;787.0358810457188;14642.633934151652;14647.340869660864;15067.62330941727;15082.472113476017;15084.94405391623;13966.860331396709;14063.859361406383;14099.78850317246;14101.294935253221;14101.717965640706;734.229059487536;740.0712106306578;743.5835931878152;916.7799728065249;933.2394098809483;910.913463220993;918.3709159225951;922.6540047482194;926.1664501948233;927.2535608834885;961.7739831139712;962.5283611030615;963.1620490173524;964.5370222293303;976.6191185437685;764.8240904591919;946.6781294359881;958.7986522830214;967.400211868639;971.9902800972006;712.1027505343253;715.7137145141933;718.2530168624625;718.9820254493627;721.2138768742096;14371.353289102015;14450.566483005527;14455.855441445556;14460.783088253685;14485.855141448556;12478.563250286286;12524.937375313157;12770.233785972778;12794.296313702742;12968.221906685629;9785.26610504215;9858.950705246429;9974.301798874081;10039.29724919256;10107.241956853266;3501.562304711911;3505.964940350615;3537.4340105582983;3600.9279814403567;3613.5121758562523;70788.2921170792;71207.64396178038;71403.64298178475;72083;72472.63763681201;9422;9437.669681561112;9482.194013508792;9505;9549.379290346105;21395.25116620911;21456.57086858254;21465;21472.570548589254;21513.89243053806;7404.333609975128;7500;7540.660670269851;7550.886736698954;7774.2614451627005;4002.0995276063236;4005.0788318686864;4019.8995025124023;4088.7546747195265;4135.875923722233;3158.0210134858266;3215.694509021639;3216.195951012247;3218.839058047088;3228.6771322867994;588.4204059001879;590.7181416326582;659.475716805141;687.621808005595;689.6241548356157;429.50821309600957;550.9889802203934;577.6043410263983;593.2880543348;613.1752792494088;7249.970127852798;7777.72777952769;8196.221358970888;8362.749117526484;8425.536595487307;548.4844246408363;596.6062398816847;602.5932495565476;611.7614130489113;620.7579044172776;3718.6095459976855;3880.631340022693;3890.144168282973;3929;3941;83850;84264.57867710623;84666;84750.57624711837;85456.57271713726;57939;62259;69607.56373526902;73918.63040684762;76550.85173722399;67405.66297168483;68204.65897670579;68819.96770048453;69320.26699332557;69867;8141.307988821017;8319.292860106958;8349.12334204924;8456.196661317164;8524.360672949677;46305.84235394142;48285.5171448281;48832.51167488279;50198;50355.496445036544;51362;64137.67931160314;65518.67240663766;68065;68671.65664171647;11321.830172547583;11440.199186056896;11512.654620361403;11775.823362649739;11908.52365905354;11790.292582445081;11805.23265987708;12070.75858482843;12179.634610961686;12650.873491264969;9765.658201962897;11198.048165905664;11474.196806223817;11779;12012.579559715368;11461.770764584828;11481.483333250191;11500.137489688474;11754.706132346417;11763.647090587296;11340.489677964346;11395.202335836411;11429.828552571551;11514.021308188898;11813.468393922121;9455.19630831387;9463.621455141718;9498.287628427757;9615.375000624937;9845.556949937414;9540.189083927944;9617.51912404363;9671.226301895691;9713.174380177765;9716.076972687584 19:10:30 INFO - PROCESS | 1944 | __end_tp_report 19:10:30 INFO - PROCESS | 1944 | __start_cc_report 19:10:30 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1039 19:10:30 INFO - PROCESS | 1944 | __end_cc_report 19:10:30 INFO - PROCESS | 1944 | __startTimestamp1463191830472__endTimestamp 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | ------- Summary: start ------- 19:10:30 INFO - PROCESS | 1944 | Number of tests: 6 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5615.82 Median:5764.63 stddev:643.19 (11.2%) stddev-sans-first:618.91 19:10:30 INFO - PROCESS | 1944 | Values: 3297.6 3391.9 3393.8 3426.0 3455.5 5566.6 5613.2 5624.4 5633.2 5649.3 5586.3 5615.4 5628.9 5639.2 5639.8 4993.0 5036.7 5105.3 5153.0 5194.9 5140.4 5144.7 5148.0 5155.8 5157.8 5090.1 5127.1 5150.1 5173.4 5194.8 5164.1 5197.1 5211.9 5218.4 5273.1 4966.3 5010.0 5018.6 5105.5 5122.5 5427.3 5449.8 5479.9 5497.1 5520.1 5850.2 5856.7 5865.6 5878.4 5894.1 5559.3 5918.1 5957.0 6121.5 6144.5 5699.5 5733.3 5748.1 5764.6 5784.7 6283.2 6313.1 6329.3 6333.8 6346.2 5524.3 5899.4 6004.0 6028.5 6079.2 6173.8 6178.2 6179.0 6194.5 6199.4 6091.6 6095.3 6102.2 6102.7 6106.5 5862.6 5957.8 5965.2 6129.4 6144.7 4783.8 4796.1 4823.7 4828.0 4830.2 3748.8 4319.9 4381.4 4407.3 4409.8 5635.4 5636.4 5652.1 5658.0 5674.6 6188.5 6222.3 6243.4 6248.7 6273.0 5563.8 5582.5 5619.4 5639.7 5643.1 5306.8 5312.7 5321.5 5334.6 5354.6 6238.6 6292.2 6297.0 6318.7 6324.7 6093.1 6143.9 6164.8 6208.1 6211.5 6258.6 6289.0 6291.2 6301.0 6311.9 6084.1 6097.5 6098.7 6107.1 6118.4 4558.5 6112.1 6131.8 6194.5 6196.0 5945.2 5965.8 5995.8 6003.5 6032.8 5939.7 5955.7 5968.6 5975.5 5976.5 5704.9 5795.4 5803.7 5818.8 5834.2 5483.5 5697.9 6004.6 6009.6 6039.1 4501.9 4640.2 5710.1 5850.2 5886.1 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:59703.95 Median:39190.01 stddev:119620.91 (305.2%) stddev-sans-first:119873.90 19:10:30 INFO - PROCESS | 1944 | Values: 504.7 505.6 505.6 559.7 600.3 24609.9 38316.9 39066.6 39173.2 39206.8 42191.0 42710.8 42723.8 42766.0 42805.6 9487.5 9684.3 9686.5 9695.5 9701.9 9478.2 9596.7 9608.2 9615.5 9658.0 8356.3 9629.0 9704.0 9713.4 9716.2 9641.0 9649.7 9673.6 9683.9 9709.8 9536.3 9638.4 9638.7 9641.7 9669.3 41427.0 41684.6 41744.7 41792.6 41931.8 3126.1 3682.7 3717.8 3839.8 3863.8 28315.7 28349.1 28405.0 28596.6 28853.6 2772.4 2796.8 2829.9 2848.4 2873.7 465324.0 468719.0 469617.0 469790.7 470111.0 59249.7 63954.0 64172.0 64179.0 64282.0 66305.7 66467.7 66500.0 66526.3 66731.7 17399.7 17590.7 17600.9 17625.9 17626.7 13505.9 13585.5 13593.9 13604.3 13636.0 15887.4 16859.1 16950.5 16953.7 16985.6 5461.2 6375.3 6589.1 6658.9 6662.0 798.9 1030.7 1046.9 1074.2 1096.6 22892.9 27498.9 28593.9 28953.6 29002.7 723.9 949.8 1045.3 1192.3 1196.8 13481.3 15081.0 15874.5 15878.4 16184.7 440532.0 440646.0 444788.0 445904.0 446422.0 54967.7 55502.0 55574.0 55616.4 55681.6 1425.1 1513.5 1523.7 1526.3 1585.3 24472.8 26285.5 27726.3 28500.0 29269.4 49165.8 49525.5 49644.8 49669.5 49685.8 437123.0 439496.0 440636.0 440655.0 441335.0 21945.2 23084.2 25923.5 26324.9 26730.0 41286.8 41409.6 42519.0 42564.8 44242.0 34745.5 36889.8 37018.4 37288.0 37806.6 35203.6 35412.0 35471.9 36032.3 36063.0 35104.6 35482.6 35646.0 36072.6 36161.3 31501.2 31904.9 32406.2 32642.5 32866.7 20700.4 21783.3 24469.6 24642.4 24777.0 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:34508.45 Median:2373.36 stddev:103829.85 (4374.8%) stddev-sans-first:104089.53 19:10:30 INFO - PROCESS | 1944 | Values: 399.3 402.1 403.8 404.3 412.9 14991.5 18331.1 19611.9 20273.0 20278.7 16340.8 17631.4 20297.9 20609.8 20636.7 5188.1 5531.3 5533.2 5534.6 5538.7 3980.0 5407.0 5503.8 5503.8 5515.1 5847.7 6110.8 6127.0 6129.0 6131.5 6046.3 6113.2 6119.7 6127.5 6659.1 5930.7 5953.0 5963.7 5966.4 5974.6 13254.8 13488.2 13735.2 14089.6 14089.7 2255.9 2490.9 2833.4 2834.2 2868.4 10359.6 11138.7 11142.7 12032.9 15396.7 1646.8 1668.3 1730.4 1746.7 1769.1 97777.5 118294.0 120586.0 124187.4 124475.1 9583.6 11772.3 11798.7 11819.3 11938.8 15205.8 15361.8 15386.7 15438.0 15438.7 6469.7 8320.5 8573.0 8815.6 8983.0 8812.2 8867.6 8869.6 8937.1 8955.9 4280.6 4634.7 4691.2 4697.7 4736.0 1796.8 1896.4 1987.8 1991.9 1992.4 860.1 934.5 936.7 970.3 999.9 19474.0 20069.9 20178.6 20197.5 20702.5 996.9 1003.2 1014.4 1031.8 1032.1 10604.0 11039.7 11111.9 11125.6 11125.8 596024.0 617616.0 630995.0 640715.2 640860.0 100328.0 116038.0 119893.0 120603.0 123971.0 935.5 944.9 945.1 946.3 1028.5 18046.0 18136.7 18317.0 18599.0 18985.0 32287.5 39698.8 44526.2 52627.9 54655.7 51220.5 63857.7 74547.6 76441.6 76803.6 7834.5 8173.7 8212.6 8270.4 8470.1 11318.5 11367.3 11774.6 12110.8 12451.6 10498.3 10728.6 10886.1 10916.5 10961.2 10543.7 10606.9 10669.9 10777.3 10816.8 9172.8 10878.2 10916.0 11118.9 11273.9 7862.3 8103.8 8116.8 8241.1 8469.0 7955.0 9845.5 10587.5 11054.2 11125.8 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5561.53 Median:4950.32 stddev:3049.88 (61.6%) stddev-sans-first:3042.57 19:10:30 INFO - PROCESS | 1944 | Values: 1421.8 1460.4 1495.9 1753.9 1924.1 7752.4 8454.9 8613.4 8620.4 8622.4 8459.0 8622.0 8630.7 8632.7 8645.0 2408.7 2430.9 2432.7 2436.8 2437.3 2241.7 2287.0 2394.8 2414.6 2444.9 2400.4 2434.5 2436.9 2437.4 2438.4 2397.6 2423.0 2423.5 2428.5 2442.4 2321.2 2406.1 2407.7 2407.8 2412.9 7540.8 7722.2 8654.4 8686.0 8692.3 4293.7 4766.1 4918.7 4945.6 5339.1 2995.8 3489.8 3528.5 3538.8 3583.0 1049.0 1051.4 1082.3 1618.7 1709.4 8041.6 9573.4 11433.5 11462.4 11473.3 4286.4 4548.4 4565.2 4647.3 4649.2 8894.8 8940.6 8955.9 8961.7 8970.8 5192.3 5211.7 5221.0 5225.8 5238.2 4715.4 4884.6 4899.1 4955.1 4968.6 3798.3 3816.6 3819.9 3850.9 3927.9 1190.7 1193.9 1199.2 1207.9 1208.8 854.9 863.2 914.0 921.0 925.9 5757.3 5801.7 5885.4 5938.3 6004.3 802.5 929.7 967.4 983.2 1026.5 3752.9 3822.9 3852.1 3873.7 3935.0 10269.2 10662.1 10718.3 10743.1 10844.3 10695.7 10697.5 10712.0 10712.9 10724.6 7749.3 8090.4 8226.2 8383.7 8387.5 4754.8 4803.9 5689.5 5704.1 5856.7 9008.2 9302.0 9434.8 9442.6 9460.0 9018.1 9469.1 9532.2 9545.9 9711.7 8066.2 8068.7 8172.2 8172.4 8176.2 8106.0 8154.6 8188.6 8188.6 8194.8 7349.2 7392.9 7444.5 7460.1 7578.9 7259.3 7264.9 7304.6 7390.9 7446.0 6829.0 6931.6 6984.1 6985.4 6989.8 4025.8 4243.3 4871.4 4896.2 4975.2 4760.5 4777.0 4803.9 4852.6 5014.9 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:5615.23 Median:6373.57 stddev:1814.85 (28.5%) stddev-sans-first:1775.93 19:10:30 INFO - PROCESS | 1944 | Values: 322.5 325.5 331.8 334.3 337.6 5599.5 6290.7 6451.3 6495.7 6507.3 6410.5 6420.5 6427.0 6461.4 6493.5 4536.8 4632.8 4633.3 4742.0 4782.7 4661.4 4750.1 4754.2 4757.3 4764.0 4674.9 4758.5 4773.4 4774.1 4778.4 3688.2 4611.9 4616.0 4616.8 4621.4 3042.1 3965.3 4054.8 4062.3 4069.3 5947.7 6023.1 6152.4 6159.2 6203.6 5600.2 5601.2 5604.6 5608.0 5667.2 5769.6 6770.7 6885.6 6885.9 6900.3 4455.4 4559.2 4625.8 4637.4 4642.0 5966.0 7129.4 7261.2 7291.7 7300.3 3774.4 4707.5 4711.6 4721.9 4729.3 4022.3 4736.9 4768.4 4769.5 4792.4 4936.6 5319.8 5589.8 5611.7 5613.1 6623.5 6892.9 6921.6 6928.9 6929.1 1578.7 1590.1 1599.2 1606.4 1608.0 848.5 1008.3 1025.4 1026.5 1032.5 6171.8 6290.8 6343.6 6403.6 6467.7 7233.8 7233.8 7241.8 7252.5 7254.8 5941.8 6501.6 6507.3 6509.1 6513.4 3464.6 3820.0 3821.9 3824.0 3826.2 7239.0 7518.7 7528.0 7591.9 7596.4 5311.0 6599.9 7199.6 7362.8 7404.2 7503.5 7513.0 7522.1 7526.5 7529.6 7243.4 7261.2 7272.5 7284.3 7294.3 6864.2 7098.9 7107.0 7156.0 7167.2 7423.7 7539.5 7550.8 7559.9 7588.0 4545.6 4600.6 4608.4 4608.4 4617.9 6142.3 7002.8 7204.1 7232.4 7233.6 6656.4 6975.0 6980.7 6990.2 6998.3 6903.2 6905.0 6949.3 6964.2 6987.4 5632.6 6721.9 6797.2 6810.5 6820.5 6137.3 6951.7 6963.7 6966.0 6976.0 6404.0 6484.7 6539.3 6643.1 6803.4 19:10:30 INFO - PROCESS | 1944 | 19:10:30 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:17362.85 Median:63198.34 stddev:23544.76 (37.3%) stddev-sans-first:23577.68 19:10:30 INFO - PROCESS | 1944 | Values: 729.6 737.2 742.9 749.6 787.0 14642.6 14647.3 15067.6 15082.5 15084.9 13966.9 14063.9 14099.8 14101.3 14101.7 734.2 740.1 743.6 916.8 933.2 910.9 918.4 922.7 926.2 927.3 961.8 962.5 963.2 964.5 976.6 764.8 946.7 958.8 967.4 972.0 712.1 715.7 718.3 719.0 721.2 14371.4 14450.6 14455.9 14460.8 14485.9 12478.6 12524.9 12770.2 12794.3 12968.2 9785.3 9859.0 9974.3 10039.3 10107.2 3501.6 3506.0 3537.4 3600.9 3613.5 70788.3 71207.6 71403.6 72083.0 72472.6 9422.0 9437.7 9482.2 9505.0 9549.4 21395.3 21456.6 21465.0 21472.6 21513.9 7404.3 7500.0 7540.7 7550.9 7774.3 4002.1 4005.1 4019.9 4088.8 4135.9 3158.0 3215.7 3216.2 3218.8 3228.7 588.4 590.7 659.5 687.6 689.6 429.5 551.0 577.6 593.3 613.2 7250.0 7777.7 8196.2 8362.7 8425.5 548.5 596.6 602.6 611.8 620.8 3718.6 3880.6 3890.1 3929.0 3941.0 83850.0 84264.6 84666.0 84750.6 85456.6 57939.0 62259.0 69607.6 73918.6 76550.9 67405.7 68204.7 68820.0 69320.3 69867.0 8141.3 8319.3 8349.1 8456.2 8524.4 46305.8 48285.5 48832.5 50198.0 50355.5 51362.0 64137.7 65518.7 68065.0 68671.7 11321.8 11440.2 11512.7 11775.8 11908.5 11790.3 11805.2 12070.8 12179.6 12650.9 9765.7 11198.0 11474.2 11779.0 12012.6 11461.8 11481.5 11500.1 11754.7 11763.6 11340.5 11395.2 11429.8 11514.0 11813.5 9455.2 9463.6 9498.3 9615.4 9845.6 9540.2 9617.5 9671.2 9713.2 9716.1 19:10:30 INFO - PROCESS | 1944 | -------- Summary: end -------- 19:10:30 INFO - PROCESS | 1944 | 19:10:31 INFO - TEST-INFO | 1944: exit 0 19:10:31 INFO - TEST-OK | dromaeo_css | took 1115101ms 19:10:31 INFO - TEST-START | kraken 19:10:31 INFO - operating with platform_type : mac_ 19:10:31 INFO - Initialising browser for kraken test... 19:10:31 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpKdj7VL/profile http://localhost:52457/getInfo.html) 19:10:33 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 19:10:33 INFO - PROCESS | 1947 | colorDepth:24 19:10:33 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 19:10:33 INFO - PROCESS | 1947 | __metrics 19:10:33 INFO - PROCESS | 1947 | 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" 19:10:33 ERROR - PROCESS | 1947 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:10:34 INFO - TEST-INFO | 1947: exit 0 19:10:34 INFO - Browser initialized. 19:10:34 INFO - Running cycle 1/1 for kraken test... 19:10:34 INFO - TEST-INFO | started process 1949 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/T/tmpKdj7VL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:10:45 INFO - PROCESS | 1949 | RSS: Main: 210952192 19:10:45 INFO - PROCESS | 1949 | 19:10:48 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52457/tests/kraken/driver.html?testName=audio-beat-detection) 19:10:49 INFO - PROCESS | 1949 | RSS: Main: 271482880 19:10:49 INFO - PROCESS | 1949 | 19:10:52 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52457/tests/kraken/driver.html?testName=audio-dft) 19:10:52 INFO - PROCESS | 1949 | RSS: Main: 286044160 19:10:52 INFO - PROCESS | 1949 | 19:10:56 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52457/tests/kraken/driver.html?testName=audio-fft) 19:10:56 INFO - PROCESS | 1949 | RSS: Main: 373563392 19:10:56 INFO - PROCESS | 1949 | 19:10:58 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52457/tests/kraken/driver.html?testName=audio-oscillator) 19:10:59 INFO - PROCESS | 1949 | RSS: Main: 278298624 19:10:59 INFO - PROCESS | 1949 | 19:11:01 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52457/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:11:02 INFO - PROCESS | 1949 | RSS: Main: 280641536 19:11:02 INFO - PROCESS | 1949 | 19:11:04 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52457/tests/kraken/driver.html?testName=imaging-darkroom) 19:11:05 INFO - PROCESS | 1949 | RSS: Main: 319471616 19:11:05 INFO - PROCESS | 1949 | 19:11:08 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52457/tests/kraken/driver.html?testName=imaging-desaturate) 19:11:08 INFO - PROCESS | 1949 | RSS: Main: 319074304 19:11:08 INFO - PROCESS | 1949 | 19:11:11 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52457/tests/kraken/driver.html?testName=json-parse-financial) 19:11:12 INFO - PROCESS | 1949 | RSS: Main: 317460480 19:11:12 INFO - PROCESS | 1949 | 19:11:14 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52457/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:11:14 INFO - PROCESS | 1949 | RSS: Main: 309039104 19:11:14 INFO - PROCESS | 1949 | 19:11:16 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:11:17 INFO - PROCESS | 1949 | RSS: Main: 316182528 19:11:17 INFO - PROCESS | 1949 | 19:11:19 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:11:20 INFO - PROCESS | 1949 | RSS: Main: 318947328 19:11:20 INFO - PROCESS | 1949 | 19:11:22 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:11:23 INFO - PROCESS | 1949 | RSS: Main: 320770048 19:11:23 INFO - PROCESS | 1949 | 19:11:26 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:11:27 INFO - PROCESS | 1949 | RSS: Main: 317775872 19:11:27 INFO - PROCESS | 1949 | 19:11:28 INFO - PROCESS | 1949 | Cycle 1(1): loaded http://localhost:52457/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52457/tests/kraken/driver.html?testName=ai-astar) 19:11:29 INFO - PROCESS | 1949 | RSS: Main: 381374464 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | __start_tp_report 19:11:29 INFO - PROCESS | 1949 | _x_x_mozilla_page_load 19:11:29 INFO - PROCESS | 1949 | _x_x_mozilla_page_load_details 19:11:29 INFO - PROCESS | 1949 | |i|pagename|runs| 19:11:29 INFO - PROCESS | 1949 | |0;ai-astar;115;114;119;113;115;113;114;113;114;135 19:11:29 INFO - PROCESS | 1949 | |1;audio-beat-detection;155;120;157;153;119;141;139;125;196;147 19:11:29 INFO - PROCESS | 1949 | |2;audio-dft;185;183;186;185;182;184;184;186;184;187 19:11:29 INFO - PROCESS | 1949 | |3;audio-fft;72;76;74;148;55;53;57;63;135;56 19:11:29 INFO - PROCESS | 1949 | |4;audio-oscillator;90;88;148;77;80;83;129;81;103;168 19:11:29 INFO - PROCESS | 1949 | |5;imaging-gaussian-blur;87;84;88;85;87;83;88;85;87;85 19:11:29 INFO - PROCESS | 1949 | |6;imaging-darkroom;92;97;94;95;92;93;94;94;93;94 19:11:29 INFO - PROCESS | 1949 | |7;imaging-desaturate;68;139;141;84;86;85;87;98;141;141 19:11:29 INFO - PROCESS | 1949 | |8;json-parse-financial;61;64;65;64;65;64;64;80;58;58 19:11:29 INFO - PROCESS | 1949 | |9;json-stringify-tinderbox;64;64;64;73;78;73;67;69;70;69 19:11:29 INFO - PROCESS | 1949 | |10;stanford-crypto-aes;81;81;80;81;80;79;81;81;82;86 19:11:29 INFO - PROCESS | 1949 | |11;stanford-crypto-ccm;128;138;116;133;142;147;130;128;174;170 19:11:29 INFO - PROCESS | 1949 | |12;stanford-crypto-pbkdf2;168;187;223;187;171;168;166;167;166;169 19:11:29 INFO - PROCESS | 1949 | |13;stanford-crypto-sha256-iterative;67;67;68;67;61;88;93;96;96;66 19:11:29 INFO - PROCESS | 1949 | __end_tp_report 19:11:29 INFO - PROCESS | 1949 | __start_cc_report 19:11:29 INFO - PROCESS | 1949 | _x_x_mozilla_cycle_collect,1980 19:11:29 INFO - PROCESS | 1949 | __end_cc_report 19:11:29 INFO - PROCESS | 1949 | __startTimestamp1463191889126__endTimestamp 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | ------- Summary: start ------- 19:11:29 INFO - PROCESS | 1949 | Number of tests: 14 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#0] ai-astar Cycles:10 Average:116.50 Median:114.50 stddev:6.74 (5.9%) stddev-sans-first:7.12 19:11:29 INFO - PROCESS | 1949 | Values: 115.0 114.0 119.0 113.0 115.0 113.0 114.0 113.0 114.0 135.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#1] audio-beat-detection Cycles:10 Average:145.20 Median:150.00 stddev:22.77 (15.2%) stddev-sans-first:23.87 19:11:29 INFO - PROCESS | 1949 | Values: 155.0 120.0 157.0 153.0 119.0 141.0 139.0 125.0 196.0 147.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.51 (0.8%) stddev-sans-first:1.59 19:11:29 INFO - PROCESS | 1949 | Values: 185.0 183.0 186.0 185.0 182.0 184.0 184.0 186.0 184.0 187.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#3] audio-fft Cycles:10 Average:78.90 Median:60.00 stddev:34.16 (56.9%) stddev-sans-first:36.14 19:11:29 INFO - PROCESS | 1949 | Values: 72.0 76.0 74.0 148.0 55.0 53.0 57.0 63.0 135.0 56.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#4] audio-oscillator Cycles:10 Average:104.70 Median:80.50 stddev:32.28 (40.1%) stddev-sans-first:33.80 19:11:29 INFO - PROCESS | 1949 | Values: 90.0 88.0 148.0 77.0 80.0 83.0 129.0 81.0 103.0 168.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 19:11:29 INFO - PROCESS | 1949 | Values: 87.0 84.0 88.0 85.0 87.0 83.0 88.0 85.0 87.0 85.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:1.48 (1.6%) stddev-sans-first:1.41 19:11:29 INFO - PROCESS | 1949 | Values: 92.0 97.0 94.0 95.0 92.0 93.0 94.0 94.0 93.0 94.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#7] imaging-desaturate Cycles:10 Average:107.00 Median:84.50 stddev:29.72 (35.2%) stddev-sans-first:27.97 19:11:29 INFO - PROCESS | 1949 | Values: 68.0 139.0 141.0 84.0 86.0 85.0 87.0 98.0 141.0 141.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:6.13 (9.6%) stddev-sans-first:6.38 19:11:29 INFO - PROCESS | 1949 | Values: 61.0 64.0 65.0 64.0 65.0 64.0 64.0 80.0 58.0 58.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#9] json-stringify-tinderbox Cycles:10 Average:69.10 Median:69.50 stddev:4.63 (6.7%) stddev-sans-first:4.53 19:11:29 INFO - PROCESS | 1949 | Values: 64.0 64.0 64.0 73.0 78.0 73.0 67.0 69.0 70.0 69.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:1.87 (2.3%) stddev-sans-first:1.99 19:11:29 INFO - PROCESS | 1949 | Values: 81.0 81.0 80.0 81.0 80.0 79.0 81.0 81.0 82.0 86.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#11] stanford-crypto-ccm Cycles:10 Average:140.60 Median:140.00 stddev:18.63 (13.3%) stddev-sans-first:19.19 19:11:29 INFO - PROCESS | 1949 | Values: 128.0 138.0 116.0 133.0 142.0 147.0 130.0 128.0 174.0 170.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.20 Median:170.00 stddev:18.01 (10.6%) stddev-sans-first:18.79 19:11:29 INFO - PROCESS | 1949 | Values: 168.0 187.0 223.0 187.0 171.0 168.0 166.0 167.0 166.0 169.0 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - PROCESS | 1949 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:78.00 stddev:14.36 (18.4%) stddev-sans-first:14.78 19:11:29 INFO - PROCESS | 1949 | Values: 67.0 67.0 68.0 67.0 61.0 88.0 93.0 96.0 96.0 66.0 19:11:29 INFO - PROCESS | 1949 | -------- Summary: end -------- 19:11:29 INFO - PROCESS | 1949 | 19:11:29 INFO - TEST-INFO | 1949: exit 0 19:11:29 INFO - TEST-OK | kraken | took 58868ms 19:11:29 INFO - SUITE-END | took 1173s 19:11:30 INFO - Completed test suite (00:19:34) 19:11:30 INFO - javascript benchmark 19:11:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.6042874855016, 3391.915202119946, 3393.779404338718, 3426.0407086015925, 3455.5162277281183, 5566.638168519044, 5613.158026296058, 5624.381318055016, 5633.211350410936, 5649.322081350244, 5586.273784408021, 5615.354234263061, 5628.887422251552, 5639.182318563806, 5639.830805075844, 4993.0, 5036.697798132105, 5105.259737338093, 5153.046686363016, 5194.896102077956, 5140.408852981899, 5144.717040562768, 5147.9704059188125, 5155.845324640267, 5157.819476318329, 5090.109230884599, 5127.128388174001, 5150.124478838607, 5173.430922598511, 5194.8441546753465, 5164.147915593941, 5197.064528384889, 5211.869703257411, 5218.373795144569, 5273.050850846846, 4966.329545511346, 5009.998000399898, 5018.573421259197, 5105.463926287724, 5122.538971492583, 5427.294451721251, 5449.836504904859, 5479.917801232985, 5497.092979658351, 5520.06158952975, 5850.180974663551, 5856.677882716449, 5865.58940874139, 5878.353381128072, 5894.145348924382, 5559.27729395176, 5918.112283157518, 5957.0, 6121.510279177655, 6144.463884028993, 5699.544036477072, 5733.3406658234835, 5748.109043098327, 5764.625299355528, 5784.68184249866, 6283.183186185858, 6313.08460273267, 6329.303776584572, 6333.809985700436, 6346.238451385863, 5524.3370795504, 5899.380565040695, 6004.039353703386, 6028.457438830526, 6079.240094988127, 6173.826972875229, 6178.227721534809, 6179.0, 6194.5044396448175, 6199.442050215503, 6091.573589848668, 6095.329513753483, 6102.206713127353, 6102.69486525672, 6106.4809491192855, 5862.589618726648, 5957.821265362046, 5965.224520812267, 6129.417705317987, 6144.662043587557, 4783.808647654125, 4796.136695394793, 4823.686460380064, 4827.951720482821, 4830.227163653799, 3748.775073495599, 4319.870403887889, 4381.430414046154, 4407.27279998803, 4409.757463339483, 5635.380108188096, 5636.436356364331, 5652.095664693629, 5658.0, 5674.631148975303, 6188.504919606421, 6222.284437289679, 6243.406876346739, 6248.687565621701, 6272.968635156795, 5563.777448902079, 5582.469665381782, 5619.43805619443, 5639.718014099279, 5643.125315576092, 5306.787728490817, 5312.707801070901, 5321.521063104339, 5334.5732341412595, 5354.571634269249, 6238.563300568917, 6292.244930608264, 6297.0, 6318.715657795318, 6324.747010119636, 6093.086037094294, 6143.907841382472, 6164.815055548341, 6208.068789681584, 6211.472024877936, 6258.593191442542, 6288.968555157195, 6291.245050593958, 6300.9684951574945, 6311.936880631135, 6084.087386892002, 6097.451229389376, 6098.725557350019, 6107.053406721965, 6118.388161184024, 4558.544145585548, 6112.1387513176505, 6131.804871721949, 6194.504439644727, 6196.0, 5945.227120474311, 5965.821025369246, 5995.790147344822, 6003.459688628044, 6032.849178770566, 5939.67331796738, 5955.67243801595, 5968.641881487125, 5975.492083172979, 5976.46211840919, 5704.914426283526, 5795.449432303924, 5803.7388317524965, 5818.76724930998, 5834.18321435008, 5483.533899618577, 5697.917395694804, 6004.579679422399, 6009.609375390585, 6039.063945088518, 4501.864944051684, 4640.164770341371, 5710.143478478095, 5850.151727999331, 5886.087656413263], "value": 5576.268499860051, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [504.70979186967503, 505.55258596142403, 505.630889450701, 559.6642014791124, 600.3066458240734, 24609.876950615246, 38316.850494485174, 39066.60933390672, 39173.21653566927, 39206.803965980165, 42191.0, 42710.7864460678, 42723.78638106813, 42766.0, 42805.57194428055, 9487.525623718804, 9684.273679474032, 9686.46724430156, 9695.515224238796, 9701.902980970206, 9478.241740660766, 9596.712098637052, 9608.183304419133, 9615.519224038804, 9657.95171024144, 8356.2897153742, 9629.0, 9704.0, 9713.368631038995, 9716.174125199366, 9640.951795241013, 9649.710508684786, 9673.564689588986, 9683.90316096837, 9709.757256068584, 9536.332456728032, 9638.42169469827, 9638.662646807328, 9641.662541811073, 9669.323147379686, 41427.0, 41684.58315416837, 41744.74765757032, 41792.582074179176, 41931.79034104841, 3126.1403114143563, 3682.723795715305, 3717.8141092945516, 3839.827207775657, 3863.787491687956, 28315.71684283172, 28349.14952551427, 28405.0, 28596.571051434035, 28853.56719649207, 2772.4177922636068, 2796.8182068165506, 2829.9292517687018, 2848.4445533121047, 2873.7126287371098, 465324.0, 468719.0, 469617.0, 469790.651046746, 470111.0, 59249.70375148096, 63954.0, 64172.0, 64179.03731444032, 64282.0, 66305.673886522, 66467.6706465868, 66500.0, 66526.33473665298, 66731.66634166896, 17399.73900391495, 17590.736138957927, 17600.94394336344, 17625.911870440566, 17626.735598966025, 13505.864941350657, 13585.456581736818, 13593.86406135926, 13604.251766152758, 13636.0, 15887.364505419884, 16859.072750998814, 16950.49148525546, 16953.745693814602, 16985.575360616083, 5461.1808228766, 6375.2987171411105, 6589.143411356493, 6658.86682266352, 6662.0, 798.876174193001, 1030.7423144213947, 1046.9005444482762, 1074.2211896375065, 1096.572336788653, 22892.88553557255, 27498.862505687342, 28593.857030714713, 28953.565696514568, 29002.709972900422, 723.8769409200448, 949.8052899155656, 1045.257866914484, 1192.3203773848995, 1196.8264601632713, 13481.2585307809, 15081.019733717274, 15874.52376428686, 15878.444254451038, 16184.676306473804, 440532.0, 440646.0, 444788.0, 445904.0, 446422.0, 54967.725161373935, 55502.0, 55574.0, 55616.44383556113, 55681.60795980133, 1425.0879437160227, 1513.4702854001011, 1523.7181121492558, 1526.2902750220978, 1585.2549301828121, 24472.75527244776, 26285.474290514467, 27726.306842329104, 28500.002499912403, 29269.41461170807, 49165.75417122892, 49525.504744952086, 49644.75177624089, 49669.503304966485, 49685.75157124336, 437123.0, 439496.0, 440636.0, 440655.0, 441335.0, 21945.231916882833, 23084.192053278053, 25923.481530369663, 26324.868375658767, 26730.0, 41286.79356603198, 41409.58590414178, 42519.0, 42564.787176063925, 44242.0, 34745.47881781826, 36889.815550922074, 37018.44472332971, 37288.0, 37806.62193378031, 35203.64796352004, 35412.0, 35471.93584192478, 36032.27935441329, 36063.0, 35104.64895351116, 35482.645173547935, 35646.0, 36072.63927360798, 36161.27677446384, 31501.212469688442, 31904.883074636284, 32406.18984525406, 32642.51036234506, 32866.67133328636, 20700.374420244825, 21783.34649960504, 24469.632955506037, 24642.383940400916, 24777.0], "value": 19153.815145521847, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.29723686312093, 402.0892678084138, 403.826354667493, 404.3409242934017, 412.8864562245384, 14991.47529836456, 18331.08344582772, 19611.901940490294, 20272.986350682462, 20278.69581956272, 16340.75488867668, 17631.38290159845, 20297.898510507464, 20609.793902060974, 20636.69044964327, 5188.066148093351, 5531.2532808071, 5533.22534845122, 5534.584906132036, 5538.723063846792, 3980.0, 5407.0, 5503.752331145088, 5503.7798488060425, 5515.062439385294, 5847.707614619273, 6110.786122485714, 6126.989046807272, 6129.0, 6131.47882429994, 6046.3349031606485, 6113.174721412597, 6119.724612392431, 6127.47916427102, 6659.134312539387, 5930.673812940287, 5952.9702351488395, 5963.701814909281, 5966.433188847096, 5974.61165024272, 13254.801177982337, 13488.19070855742, 13735.24456154911, 14089.577312680534, 14089.718205635932, 2255.853369530975, 2490.8754562271815, 2833.4049849531625, 2834.1780883543747, 2868.3976364963382, 10359.585616575328, 11138.721531961684, 11142.665720028412, 12032.879671203264, 15396.692066158726, 1646.777685012532, 1668.3493437559362, 1730.4376077774773, 1746.6594014167242, 1769.0623969296294, 97777.5111124447, 118294.0, 120586.0, 124187.379063105, 124475.13287300698, 9583.616655333848, 11772.293662380285, 11798.705032374088, 11819.290842549475, 11938.820917686242, 15205.771913421308, 15361.769573456408, 15386.692266154614, 15437.996530225499, 15438.691226175639, 6469.741210351627, 8320.46380288375, 8573.0, 8815.647374105092, 8982.955085224663, 8812.206901378906, 8867.556622168866, 8869.556522173865, 8937.06160853101, 8955.865662015074, 4280.571942805756, 4634.745089020069, 4691.179043667345, 4697.718136911796, 4735.952640473552, 1796.766405735601, 1896.4310706787908, 1987.8111579399933, 1991.8804871707744, 1992.3524854422371, 860.1226748716274, 934.4860326820221, 936.7096200177966, 970.335320305593, 999.9000099990088, 19474.0, 20069.899650501655, 20178.596428071356, 20197.495062623257, 20702.482437939172, 996.8953259907751, 1003.19744204636, 1014.4065721552827, 1031.8194315994672, 1032.1381646325253, 10603.992620701021, 11039.72400689973, 11111.944440277908, 11125.554977800959, 11125.777484450266, 596024.0397596055, 617616.0, 630995.0, 640715.1856962835, 640860.0, 100327.99268476822, 116038.0, 119893.0, 120603.0, 123971.0, 935.5322338830584, 944.9149576538144, 945.1446440970932, 946.3281070439928, 1028.4600584692946, 18046.0, 18136.727949080512, 18317.0, 18599.0, 18985.0, 32287.51568726424, 39698.80150599228, 44526.218951241586, 52627.94744105173, 54655.72672136614, 51220.48779512306, 63857.72284554376, 74547.62726186334, 76441.61779191068, 76803.61598192196, 7834.529928204326, 8173.67305307781, 8212.589370531568, 8270.37972152079, 8470.068292487698, 11318.4906679198, 11367.317960922372, 11774.587889423829, 12110.818337724764, 12451.626451206479, 10498.317609355367, 10728.624498142528, 10886.129109671365, 10916.454177291262, 10961.232713709964, 10543.736406589896, 10606.893931060898, 10669.946650266698, 10777.299475534066, 10816.837747433638, 9172.770680733034, 10878.238523303293, 10916.0, 11118.888811112109, 11273.887261127611, 7862.2923936845955, 8103.797405064921, 8116.837663246584, 8241.093479717107, 8469.0, 7954.960225198837, 9845.45849978258, 10587.523561439908, 11054.170937179582, 11125.833112503156], "value": 9308.543144381425, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.8293804743419, 1460.3647413375184, 1495.925203739813, 1753.8684598655095, 1924.0860591219173, 7752.418568607356, 8454.87317690235, 8613.397062205648, 8620.396572239946, 8622.396432249745, 8459.0, 8621.956890215555, 8630.741077767661, 8632.697855575056, 8645.0, 2408.7109546854404, 2430.8541487510715, 2432.74456182101, 2436.8050555955483, 2437.3297343230574, 2241.711016165711, 2286.977130228693, 2394.820388470863, 2414.611598330958, 2444.8533088014774, 2400.411899084727, 2434.525267572824, 2436.902523899042, 2437.390652336916, 2438.3660248335473, 2397.5924092904247, 2423.042898055267, 2423.53952748978, 2428.538577670246, 2442.3527765142253, 2321.2339927823778, 2406.073661640281, 2407.6749638798715, 2407.843490173133, 2412.8552286862846, 7540.8114797129965, 7722.150563438017, 8654.394192406535, 8686.0, 8692.261157801595, 4293.720908140961, 4766.142094423002, 4918.680285781448, 4945.579625731817, 5339.065663508909, 2995.8052726572705, 3489.755717099804, 3528.5060091587197, 3538.8230588470474, 3583.0, 1048.9527971241312, 1051.4217180550665, 1082.2965072702727, 1618.660081382911, 1709.427341840485, 8041.6381262843315, 9573.425594464285, 11433.485493152828, 11462.369569673749, 11473.254238474556, 4286.39990401344, 4548.385967894292, 4565.178267911808, 4647.34937108805, 4649.163150632851, 8894.843670322816, 8940.597673104727, 8955.865662015074, 8961.686340978034, 8970.775730606656, 5192.299039629677, 5211.661242019332, 5221.008008478377, 5225.764840582183, 5238.2404551340605, 4715.386999690087, 4884.584810291094, 4899.093667671421, 4955.058538877602, 4968.552830245223, 3798.3352913240624, 3816.5801761806733, 3819.8854034379015, 3850.8652197172964, 3927.9018024549614, 1190.678516800459, 1193.856737191525, 1199.184554502929, 1207.9335636540075, 1208.8428504294561, 854.918782715641, 863.2144748279036, 913.9954300228457, 920.9907900921039, 925.8657494663236, 5757.337906140764, 5801.7099145042575, 5885.382034886361, 5938.287405511366, 6004.309504407049, 802.5064585280128, 929.7117893453051, 967.4195482710318, 983.1593987140892, 1026.4970164619283, 3752.868649597251, 3822.9044273893373, 3852.1332700142802, 3873.7482063666334, 3934.9803250983564, 10269.229807764448, 10662.093722033715, 10718.303310284806, 10743.08683761889, 10844.295120817122, 10695.679129626124, 10697.518611662494, 10712.035916767529, 10712.94643526793, 10724.624638137628, 7749.263819937097, 8090.352771778362, 8226.177382261725, 8383.706570270011, 8387.454815436977, 4754.762261886961, 4803.927941080817, 5689.459501347365, 5704.0588302929555, 5856.707164641665, 9008.234300084567, 9302.0, 9434.81130377375, 9442.575084121094, 9459.952700236454, 9018.09819018093, 9469.10043545862, 9532.237421006164, 9545.904540954501, 9711.66009189675, 8066.23370779775, 8068.7175948841505, 8172.182781722018, 8172.387070969582, 8176.182381761776, 8106.0, 8154.551499667576, 8188.590570471333, 8188.5905704715715, 8194.795130121795, 7349.228331025379, 7392.852142957003, 7444.478886477895, 7460.104787425544, 7578.924210757822, 7259.310365515267, 7264.854702905806, 7304.598247096248, 7390.9260907390235, 7446.0, 6829.0, 6931.584104953719, 6984.126984126984, 6985.3713165815325, 6989.790306290819, 4025.7987100645437, 4243.321068629005, 4871.439784424837, 4896.2410826321975, 4975.154223782039, 4760.523947605212, 4776.952230477651, 4803.855884323476, 4852.587530059985, 5014.87462813418], "value": 4523.015882036076, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.5419903736694, 325.46948473987396, 331.805893552272, 334.2729563200039, 337.6066882082374, 5599.524040456566, 6290.685465726712, 6451.290358060615, 6495.675216239193, 6507.3167317431635, 6410.519211059178, 6420.486361091113, 6426.967865160667, 6461.353864613537, 6493.4805215582755, 4536.8185272589135, 4632.768361581924, 4633.2586786114225, 4741.952580474185, 4782.665213435061, 4661.3707149534885, 4750.121227572893, 4754.168020596399, 4757.333973243749, 4763.976180119094, 4674.90650186996, 4758.45277793053, 4773.403324584427, 4774.116788394141, 4778.378810754597, 3688.1885985083372, 4611.9077618447445, 4615.953840461586, 4616.76916154191, 4621.399218101659, 3042.1025797389825, 3965.325894597925, 4054.8175332110127, 4062.309407400749, 4069.267531844267, 5947.702614869239, 6023.096535519663, 6152.3847615238565, 6159.23009623797, 6203.596766210227, 5600.1599760035915, 5601.159826026088, 5604.607677462579, 5607.990561698893, 5667.178259152441, 5769.624974376652, 6770.72917083316, 6885.621290829003, 6885.896711549331, 6900.3444672756, 4455.391784229906, 4559.247724125516, 4625.791839367236, 4637.420322459692, 4642.0, 5966.0, 7129.3583577478275, 7261.2012678605315, 7291.708331666727, 7300.269973002657, 3774.3960966245268, 4707.5057119002695, 4711.575958163782, 4721.881952951204, 4729.266963620645, 4022.3162062449455, 4736.934189807334, 4768.427788665383, 4769.546893045155, 4792.448868380111, 4936.6297527685565, 5319.787208511616, 5589.776408943678, 5611.691356975405, 5613.101903695389, 6623.470122390294, 6892.896606550906, 6921.584704917721, 6928.930710692828, 6929.133858267716, 1578.7079390312829, 1590.109538658355, 1599.160440768606, 1606.4136590144512, 1608.035178892655, 848.4824257203101, 1008.294194064158, 1025.3850185957147, 1026.461107918349, 1032.4734385463469, 6171.814845554641, 6290.811275661737, 6343.5876668016435, 6403.551751377358, 6467.676616169173, 7233.782986510413, 7233.782986510413, 7241.782746517613, 7252.492325537161, 7254.782356529313, 5941.841340938562, 6501.609903405811, 6507.349265073455, 6509.056186852879, 6513.413792758674, 3464.601570819388, 3819.9809000955374, 3821.8662346818282, 3824.0248736572, 3826.196498735241, 7238.963805180941, 7518.661660225303, 7528.0, 7591.924080759121, 7596.430267729942, 5310.9734451327495, 6599.868002640016, 7199.568025918462, 7362.815929601696, 7404.222556631584, 7503.5497870127965, 7512.962435187898, 7522.059742532184, 7526.548407095592, 7529.623518824146, 7243.384312333511, 7261.201267860425, 7272.527285726411, 7284.307990740871, 7294.343509084208, 6864.24493305746, 7098.929010709827, 7107.0, 7156.033935418652, 7167.1757747859665, 7423.703051877865, 7539.509931854412, 7550.811229719301, 7559.924400755922, 7588.0, 4545.59083685634, 4600.5629465200755, 4608.377868987778, 4608.400907881954, 4617.907641847211, 6142.324344322032, 7002.774514460051, 7204.0994875640545, 7232.42140628759, 7233.638318083969, 6656.369189548592, 6974.9537569365, 6980.7207711693, 6990.161180658353, 6998.265182156004, 6903.1716194057035, 6904.963035904319, 6949.270326615632, 6964.164300283998, 6987.371136597731, 5632.633878797865, 6721.8572842615895, 6797.218319893275, 6810.523263371517, 6820.488463365168, 6137.3248942617165, 6951.6871740770775, 6963.651817409008, 6965.96517017432, 6976.0, 6403.967980160256, 6484.740610375532, 6539.313372095861, 6643.136392268974, 6803.353681400259], "value": 5010.36310462819, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.5731996781882, 737.1596380126655, 742.910850697916, 749.5989645539637, 787.0358810457188, 14642.633934151652, 14647.340869660864, 15067.62330941727, 15082.472113476017, 15084.94405391623, 13966.860331396709, 14063.859361406383, 14099.78850317246, 14101.294935253221, 14101.717965640706, 734.229059487536, 740.0712106306578, 743.5835931878152, 916.7799728065249, 933.2394098809483, 910.913463220993, 918.3709159225951, 922.6540047482194, 926.1664501948233, 927.2535608834885, 961.7739831139712, 962.5283611030615, 963.1620490173524, 964.5370222293303, 976.6191185437685, 764.8240904591919, 946.6781294359881, 958.7986522830214, 967.400211868639, 971.9902800972006, 712.1027505343253, 715.7137145141933, 718.2530168624625, 718.9820254493627, 721.2138768742096, 14371.353289102015, 14450.566483005527, 14455.855441445556, 14460.783088253685, 14485.855141448556, 12478.563250286286, 12524.937375313157, 12770.233785972778, 12794.296313702742, 12968.221906685629, 9785.26610504215, 9858.950705246429, 9974.301798874081, 10039.29724919256, 10107.241956853266, 3501.562304711911, 3505.964940350615, 3537.4340105582983, 3600.9279814403567, 3613.5121758562523, 70788.2921170792, 71207.64396178038, 71403.64298178475, 72083.0, 72472.63763681201, 9422.0, 9437.669681561112, 9482.194013508792, 9505.0, 9549.379290346105, 21395.25116620911, 21456.57086858254, 21465.0, 21472.570548589254, 21513.89243053806, 7404.333609975128, 7500.0, 7540.660670269851, 7550.886736698954, 7774.2614451627005, 4002.0995276063236, 4005.0788318686864, 4019.8995025124023, 4088.7546747195265, 4135.875923722233, 3158.0210134858266, 3215.694509021639, 3216.195951012247, 3218.839058047088, 3228.6771322867994, 588.4204059001879, 590.7181416326582, 659.475716805141, 687.621808005595, 689.6241548356157, 429.50821309600957, 550.9889802203934, 577.6043410263983, 593.2880543348, 613.1752792494088, 7249.970127852798, 7777.72777952769, 8196.221358970888, 8362.749117526484, 8425.536595487307, 548.4844246408363, 596.6062398816847, 602.5932495565476, 611.7614130489113, 620.7579044172776, 3718.6095459976855, 3880.631340022693, 3890.144168282973, 3929.0, 3941.0, 83850.0, 84264.57867710623, 84666.0, 84750.57624711837, 85456.57271713726, 57939.0, 62259.0, 69607.56373526902, 73918.63040684762, 76550.85173722399, 67405.66297168483, 68204.65897670579, 68819.96770048453, 69320.26699332557, 69867.0, 8141.307988821017, 8319.292860106958, 8349.12334204924, 8456.196661317164, 8524.360672949677, 46305.84235394142, 48285.5171448281, 48832.51167488279, 50198.0, 50355.496445036544, 51362.0, 64137.67931160314, 65518.67240663766, 68065.0, 68671.65664171647, 11321.830172547583, 11440.199186056896, 11512.654620361403, 11775.823362649739, 11908.52365905354, 11790.292582445081, 11805.23265987708, 12070.75858482843, 12179.634610961686, 12650.873491264969, 9765.658201962897, 11198.048165905664, 11474.196806223817, 11779.0, 12012.579559715368, 11461.770764584828, 11481.483333250191, 11500.137489688474, 11754.706132346417, 11763.647090587296, 11340.489677964346, 11395.202335836411, 11429.828552571551, 11514.021308188898, 11813.468393922121, 9455.19630831387, 9463.621455141718, 9498.287628427757, 9615.375000624937, 9845.556949937414, 9540.189083927944, 9617.51912404363, 9671.226301895691, 9713.174380177765, 9716.076972687584], "value": 6797.416304499381, "unit": "score"}], "name": "dromaeo_css", "value": 7314.644714213323, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 119.0, 113.0, 115.0, 113.0, 114.0, 113.0, 114.0, 135.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 120.0, 157.0, 153.0, 119.0, 141.0, 139.0, 125.0, 196.0, 147.0], "value": 145.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 183.0, 186.0, 185.0, 182.0, 184.0, 184.0, 186.0, 184.0, 187.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 76.0, 74.0, 148.0, 55.0, 53.0, 57.0, 63.0, 135.0, 56.0], "value": 78.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 148.0, 77.0, 80.0, 83.0, 129.0, 81.0, 103.0, 168.0], "value": 104.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 88.0, 85.0, 87.0, 83.0, 88.0, 85.0, 87.0, 85.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 94.0, 95.0, 92.0, 93.0, 94.0, 94.0, 93.0, 94.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 139.0, 141.0, 84.0, 86.0, 85.0, 87.0, 98.0, 141.0, 141.0], "value": 107.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 64.0, 65.0, 64.0, 64.0, 80.0, 58.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 73.0, 78.0, 73.0, 67.0, 69.0, 70.0, 69.0], "value": 69.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 80.0, 81.0, 80.0, 79.0, 81.0, 81.0, 82.0, 86.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [128.0, 138.0, 116.0, 133.0, 142.0, 147.0, 130.0, 128.0, 174.0, 170.0], "value": 140.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 187.0, 223.0, 187.0, 171.0, 168.0, 166.0, 167.0, 166.0, 169.0], "value": 177.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 67.0, 61.0, 88.0, 93.0, 96.0, 96.0, 66.0], "value": 76.9, "unit": "score"}], "name": "kraken", "value": 1525.8999999999999, "alertThreshold": 2.0}]} 19:11:30 INFO - Return code: 0 19:11:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:11:30 ERROR - # TBPL SUCCESS # 19:11:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:11:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:11:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:11:30 INFO - 2016-05-13 19:11:30.409 screenresolution[1953:16161] starting screenresolution argv=screenresolution get 19:11:30 INFO - 2016-05-13 19:11:30.411 screenresolution[1953:16161] Display 0: 1600x1200x32@60 19:11:30 INFO - 2016-05-13 19:11:30.417 screenresolution[1954:16165] starting screenresolution argv=screenresolution list 19:11:30 INFO - Available Modes on Display 0 19:11:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:11:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:11:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:11:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:11:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:11:30 INFO - Intel Iris: 19:11:30 INFO - Chipset Model: Intel Iris 19:11:30 INFO - Type: GPU 19:11:30 INFO - Bus: Built-In 19:11:30 INFO - VRAM (Dynamic, Max): 1536 MB 19:11:30 INFO - Vendor: Intel (0x8086) 19:11:30 INFO - Device ID: 0x0a2e 19:11:30 INFO - Revision ID: 0x0009 19:11:30 INFO - Displays: 19:11:30 INFO - Display: 19:11:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:11:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:11:30 INFO - Display Serial Number: 1600x1200 60 19:11:30 INFO - Main Display: Yes 19:11:30 INFO - Mirror: Off 19:11:30 INFO - Online: Yes 19:11:30 INFO - Rotation: Supported 19:11:30 INFO - Return code: 0 19:11:30 INFO - Running post-action listener: _resource_record_post_action 19:11:30 INFO - Running post-run listener: _resource_record_post_run 19:11:31 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 770048; Write bytes: 650841600; Read time: 8; Write time: 1825 19:11:31 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348578304; Write bytes: 346113536; Read time: 26546; Write time: 700 19:11:31 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 770048; Write bytes: 293935104; Read time: 8; Write time: 1087 19:11:31 INFO - Running post-run listener: _upload_blobber_files 19:11:31 INFO - Blob upload gear active. 19:11:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:11:31 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 19:11:31 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'] 19:11:31 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 19:11:32 INFO - (blobuploader) - INFO - Open directory for files ... 19:11:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:11:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:11:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:32 INFO - (blobuploader) - INFO - Done attempting. 19:11:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:11:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:11:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:11:33 INFO - (blobuploader) - INFO - Done attempting. 19:11:33 INFO - (blobuploader) - INFO - Iteration through files over. 19:11:33 INFO - Return code: 0 19:11:33 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:11:33 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:11:33 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/844818aed332e3974e33f9fe2f727c6ad7eac300dabdd2c7294034f20d8a8fe6020041c6eeb58776b1c650da679ffa3ef02955736bd2c8cebbf6ee85e9e0442c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25ada26ff99579dcadb9b6a4b880439db4ce5351efe986f726ee6e6ae0d92b14d5f805c6c94a4ef1300f252653ddcc17fcc60e6a694b0caade867d09ea7164f"} 19:11:33 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:11:33 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:11:33 INFO - Contents: 19:11:33 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/844818aed332e3974e33f9fe2f727c6ad7eac300dabdd2c7294034f20d8a8fe6020041c6eeb58776b1c650da679ffa3ef02955736bd2c8cebbf6ee85e9e0442c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25ada26ff99579dcadb9b6a4b880439db4ce5351efe986f726ee6e6ae0d92b14d5f805c6c94a4ef1300f252653ddcc17fcc60e6a694b0caade867d09ea7164f"} 19:11:33 INFO - Running post-run listener: copy_logs_to_upload_dir 19:11:33 INFO - Copying logs to upload dir... 19:11:33 INFO - mkdir: /builds/slave/test/build/upload/logs 19:11:33 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.000570 ========= master_lag: 0.51 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-13 19:11:34.431334) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 19:11:34.434973) ========= 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.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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/844818aed332e3974e33f9fe2f727c6ad7eac300dabdd2c7294034f20d8a8fe6020041c6eeb58776b1c650da679ffa3ef02955736bd2c8cebbf6ee85e9e0442c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25ada26ff99579dcadb9b6a4b880439db4ce5351efe986f726ee6e6ae0d92b14d5f805c6c94a4ef1300f252653ddcc17fcc60e6a694b0caade867d09ea7164f"} build_url:https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011311 build_url: 'https://queue.taskcluster.net/v1/task/OE6_XPY_QgeRMtGnUKIgVA/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/844818aed332e3974e33f9fe2f727c6ad7eac300dabdd2c7294034f20d8a8fe6020041c6eeb58776b1c650da679ffa3ef02955736bd2c8cebbf6ee85e9e0442c", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c25ada26ff99579dcadb9b6a4b880439db4ce5351efe986f726ee6e6ae0d92b14d5f805c6c94a4ef1300f252653ddcc17fcc60e6a694b0caade867d09ea7164f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-13 19:11:34.467607) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 19:11:34.467943) ========= 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.5NIZhjOk4X/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.ypSlsazOKj/Listeners TMPDIR=/var/folders/5f/d96dwhw95lj7vr7m4fjjlh4w00000w/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.005080 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-13 19:11:34.516612) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 19:11:34.519591) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-13 19:11:34.519926) ========= ========= Total master_lag: 0.64 =========