builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0194 starttime: 1465096868.04 results: success (0) buildid: 20160604190644 builduid: 8c8cb62ca25f4a55bba56c381457195b revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.039685) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.040376) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.040710) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.135503) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.135892) ========= 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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-06-04 20:21:08-- 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% 23.3M=0s 2016-06-04 20:21:08 (23.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.493950 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.717322) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.717690) ========= 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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.027101 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:08.796961) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-04 20:21:08.797389) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dc972d877cd697c2c8f6248534f21bb5e1dba9ae --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 dc972d877cd697c2c8f6248534f21bb5e1dba9ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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-06-04 20:21:08,876 truncating revision to first 12 chars 2016-06-04 20:21:08,876 Setting DEBUG logging. 2016-06-04 20:21:08,876 attempt 1/10 2016-06-04 20:21:08,876 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dc972d877cd6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-04 20:21:10,440 unpacking tar archive at: mozilla-aurora-dc972d877cd6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.821748 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-04 20:21:10.653098) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:10.653783) ========= script_repo_revision: dc972d877cd697c2c8f6248534f21bb5e1dba9ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:10.654165) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:10.654445) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-04 20:21:10.787118) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2016-06-04 20:21:10.787597) ========= /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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:21:10 INFO - MultiFileLogger online at 20160604 20:21:10 in /builds/slave/test 20:21:10 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 20:21:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:21:10 INFO - {'append_to_log': False, 20:21:10 INFO - 'base_work_dir': '/builds/slave/test', 20:21:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:21:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:21:10 INFO - 'branch': 'Mozilla-Aurora', 20:21:10 INFO - 'buildbot_json_path': 'buildprops.json', 20:21:10 INFO - 'config_files': ('talos/mac_config.py',), 20:21:10 INFO - 'default_actions': ('clobber', 20:21:10 INFO - 'read-buildbot-config', 20:21:10 INFO - 'download-and-extract', 20:21:10 INFO - 'populate-webroot', 20:21:10 INFO - 'create-virtualenv', 20:21:10 INFO - 'install', 20:21:10 INFO - 'run-tests'), 20:21:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:21:10 INFO - 'download_minidump_stackwalk': True, 20:21:10 INFO - 'download_symbols': 'ondemand', 20:21:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:21:10 INFO - 'tooltool.py': '/tools/tooltool.py', 20:21:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:21:10 INFO - '/tools/misc-python/virtualenv.py')}, 20:21:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:21:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:21:10 INFO - 'installer_path': 'installer.exe', 20:21:10 INFO - 'log_level': 'info', 20:21:10 INFO - 'log_name': 'talos', 20:21:10 INFO - 'log_to_console': True, 20:21:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:21:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:21:10 INFO - 'opt_config_files': (), 20:21:10 INFO - 'pip_index': False, 20:21:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:21:10 INFO - 'cmd': ('bash', 20:21:10 INFO - '-c', 20:21:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:21:10 INFO - 'enabled': True, 20:21:10 INFO - 'halt_on_failure': False, 20:21:10 INFO - 'name': 'check_screen_resolution'},), 20:21:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:21:10 INFO - 'cmd': ('bash', 20:21:10 INFO - '-c', 20:21:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:21:10 INFO - 'enabled': True, 20:21:10 INFO - 'halt_on_failure': False, 20:21:10 INFO - 'name': 'check_screen_resolution'},), 20:21:10 INFO - 'run_cmd_checks_enabled': True, 20:21:10 INFO - 'sps_profile': False, 20:21:10 INFO - 'sps_profile_interval': 0, 20:21:10 INFO - 'suite': 'dromaeojs', 20:21:10 INFO - 'system_bits': '32', 20:21:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:21:10 INFO - 'title': 't-yosemite-r7-0194', 20:21:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:21:10 INFO - 'use_talos_json': True, 20:21:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:21:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:21:10 INFO - 'work_dir': 'build'} 20:21:10 INFO - ##### 20:21:10 INFO - ##### Running clobber step. 20:21:10 INFO - ##### 20:21:10 INFO - Running pre-action listener: _resource_record_pre_action 20:21:10 INFO - Running main action method: clobber 20:21:10 INFO - rmtree: /builds/slave/test/build 20:21:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:21:12 INFO - Running post-action listener: _resource_record_post_action 20:21:12 INFO - ##### 20:21:12 INFO - ##### Running read-buildbot-config step. 20:21:12 INFO - ##### 20:21:12 INFO - Running pre-action listener: _resource_record_pre_action 20:21:12 INFO - Running main action method: read_buildbot_config 20:21:12 INFO - Using buildbot properties: 20:21:12 INFO - { 20:21:12 INFO - "project": "", 20:21:12 INFO - "product": "firefox", 20:21:12 INFO - "script_repo_revision": "production", 20:21:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:21:12 INFO - "repository": "", 20:21:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:21:12 INFO - "buildid": "20160604190644", 20:21:12 INFO - "pgo_build": "False", 20:21:12 INFO - "basedir": "/builds/slave/test", 20:21:12 INFO - "buildnumber": 15, 20:21:12 INFO - "slavename": "t-yosemite-r7-0194", 20:21:12 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 20:21:12 INFO - "platform": "yosemite_r7", 20:21:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:21:12 INFO - "branch": "mozilla-aurora", 20:21:12 INFO - "repo_path": "releases/mozilla-aurora", 20:21:12 INFO - "revision": "dc972d877cd697c2c8f6248534f21bb5e1dba9ae", 20:21:12 INFO - "stage_platform": "macosx64", 20:21:12 INFO - "builduid": "8c8cb62ca25f4a55bba56c381457195b", 20:21:12 INFO - "slavebuilddir": "test" 20:21:12 INFO - } 20:21:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 20:21:12 INFO - Running post-action listener: _resource_record_post_action 20:21:12 INFO - ##### 20:21:12 INFO - ##### Running download-and-extract step. 20:21:12 INFO - ##### 20:21:12 INFO - Running pre-action listener: _resource_record_pre_action 20:21:12 INFO - Running main action method: download_and_extract 20:21:12 INFO - mkdir: /builds/slave/test/build/tests 20:21:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:21:12 INFO - https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:21:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:21:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:21:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:21:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:21:43 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 20:21:43 INFO - retry: attempt #1 caught exception: timed out 20:21:43 INFO - retry: Failed, sleeping 30 seconds before retrying 20:22:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 #2 20:22:40 INFO - Downloaded 1199 bytes. 20:22:40 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:22:40 INFO - Using the following test package requirements: 20:22:40 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:22:40 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 20:22:40 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 20:22:40 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'jsshell-mac.zip'], 20:22:40 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 20:22:40 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:22:40 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 20:22:40 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 20:22:40 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 20:22:40 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:22:40 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 20:22:40 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:22: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')]} 20:22:40 INFO - https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:22:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:22:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:22:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:42 INFO - Downloaded 18466760 bytes. 20:22:42 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'] 20:22:42 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 20:22:45 INFO - Return code: 0 20:22:45 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 20:22:45 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')]} 20:22:45 INFO - https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:22:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:22:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:22:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:47 INFO - Downloaded 18466760 bytes. 20:22:47 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'] 20:22:47 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 20:22:50 INFO - Return code: 0 20:22:50 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')]} 20:22:50 INFO - https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:51 INFO - Downloaded 10840145 bytes. 20:22:51 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'] 20:22:51 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 20:22:51 INFO - Return code: 0 20:22:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:22:51 INFO - https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 20:22:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:22:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:22:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 20:22:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QJwrDD06Q3GPiuWwUIj40A/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 20:22:54 INFO - Downloaded 114163727 bytes. 20:22:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:22:54 INFO - mkdir: /builds/slave/test/properties 20:22:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:22:54 INFO - Writing to file /builds/slave/test/properties/build_url 20:22:54 INFO - Contents: 20:22:54 INFO - build_url:https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:22:56 INFO - Running post-action listener: _resource_record_post_action 20:22:56 INFO - Running post-action listener: set_extra_try_arguments 20:22:56 INFO - ##### 20:22:56 INFO - ##### Running populate-webroot step. 20:22:56 INFO - ##### 20:22:56 INFO - Running pre-action listener: _resource_record_pre_action 20:22:56 INFO - Running main action method: populate_webroot 20:22:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:22:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:22:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:22:56 INFO - '--tppagecycles', 20:22:56 INFO - '7'], 20:22:56 INFO - 'tests': ['tsvgm']}}, 20:22:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:22:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:22:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:22:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:22:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:22:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['damp', 'tps']}, 20:22:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'tests': ['damp', 'tps']}, 20:22:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['dromaeo_dom']}, 20:22:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:22:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'other-e10s': {'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['a11yr', 20:22:56 INFO - 'ts_paint', 20:22:56 INFO - 'tpaint', 20:22:56 INFO - 'sessionrestore', 20:22:56 INFO - 'sessionrestore_no_auto_restore', 20:22:56 INFO - 'tabpaint']}, 20:22:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['tsvgx', 20:22:56 INFO - 'tsvgr_opacity', 20:22:56 INFO - 'tart', 20:22:56 INFO - 'tscrollx', 20:22:56 INFO - 'cart']}, 20:22:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:22:56 INFO - 'tsvgr_opacity', 20:22:56 INFO - 'tart', 20:22:56 INFO - 'tscrollx', 20:22:56 INFO - 'cart']}, 20:22:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'talos_options': ['--disable-e10s'], 20:22:56 INFO - 'tests': ['tp5o']}, 20:22:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'tests': ['tp5o']}, 20:22:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'talos_options': ['--disable-e10s', 20:22:56 INFO - '--xperf_path', 20:22:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:56 INFO - 'tests': ['tp5n']}, 20:22:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:22:56 INFO - 'talos_options': ['--xperf_path', 20:22:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:22:56 INFO - 'tests': ['tp5n']}}, 20:22:56 INFO - 'talos.zip': {'path': '', 20:22:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:22:56 INFO - Running post-action listener: _resource_record_post_action 20:22:56 INFO - ##### 20:22:56 INFO - ##### Running create-virtualenv step. 20:22:56 INFO - ##### 20:22:56 INFO - Running pre-action listener: _resource_record_pre_action 20:22:56 INFO - Running main action method: create_virtualenv 20:22:56 INFO - Creating virtualenv /builds/slave/test/build/venv 20:22:56 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 20:22:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:22:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:22:56 INFO - Using real prefix '/tools/python27' 20:22:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:22:57 INFO - Installing distribute.............................................................................................................................................................................................done. 20:22:59 INFO - Installing pip.................done. 20:22:59 INFO - Return code: 0 20:22:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:22:59 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')]} 20:22:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:22:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:59 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')]} 20:22:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:22:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:22:59 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:22:59 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 20:22:59 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 20:22:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:22:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:22:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:22:59 INFO - 'HOME': '/Users/cltbld', 20:22:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:22:59 INFO - 'LOGNAME': 'cltbld', 20:22:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:22:59 INFO - 'MOZ_NO_REMOTE': '1', 20:22:59 INFO - 'NO_EM_RESTART': '1', 20:22:59 INFO - 'PAGER': '/bin/cat', 20:22:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:22:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:22:59 INFO - 'PWD': '/builds/slave/test', 20:22:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:22:59 INFO - 'SHELL': '/bin/bash', 20:22:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:22:59 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:22:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:22:59 INFO - 'USER': 'cltbld', 20:22:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:22:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:22:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:22:59 INFO - 'XPC_FLAGS': '0x0', 20:22:59 INFO - 'XPC_SERVICE_NAME': '0', 20:22:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:00 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 20:23:00 INFO - Cleaning up... 20:23:00 INFO - Return code: 0 20:23:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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', '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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23: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', 'psutil>=0.7.1'] in /builds/slave/test/build 20:23: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 psutil>=0.7.1 20:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:00 INFO - 'HOME': '/Users/cltbld', 20:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:00 INFO - 'LOGNAME': 'cltbld', 20:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:00 INFO - 'MOZ_NO_REMOTE': '1', 20:23:00 INFO - 'NO_EM_RESTART': '1', 20:23:00 INFO - 'PAGER': '/bin/cat', 20:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:00 INFO - 'PWD': '/builds/slave/test', 20:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:00 INFO - 'SHELL': '/bin/bash', 20:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:00 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:00 INFO - 'USER': 'cltbld', 20:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:00 INFO - 'XPC_FLAGS': '0x0', 20:23:00 INFO - 'XPC_SERVICE_NAME': '0', 20:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:00 INFO - Downloading/unpacking psutil>=0.7.1 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:23:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:03 INFO - Installing collected packages: psutil 20:23:03 INFO - Running setup.py install for psutil 20:23:03 INFO - building 'psutil._psutil_osx' extension 20:23:03 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 20:23:04 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 20:23:04 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 20:23:04 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 20:23:04 INFO - building 'psutil._psutil_posix' extension 20:23:04 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 20:23:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:23:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:23:04 INFO - ^ 20:23:04 INFO - 1 warning generated. 20:23:04 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 20:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:23:04 INFO - Successfully installed psutil 20:23:04 INFO - Cleaning up... 20:23:05 INFO - Return code: 0 20:23:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:23: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')]} 20:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23: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')]} 20:23:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:23:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:23:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:05 INFO - 'HOME': '/Users/cltbld', 20:23:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:05 INFO - 'LOGNAME': 'cltbld', 20:23:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:05 INFO - 'MOZ_NO_REMOTE': '1', 20:23:05 INFO - 'NO_EM_RESTART': '1', 20:23:05 INFO - 'PAGER': '/bin/cat', 20:23:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:05 INFO - 'PWD': '/builds/slave/test', 20:23:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:05 INFO - 'SHELL': '/bin/bash', 20:23:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:05 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:05 INFO - 'USER': 'cltbld', 20:23:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:05 INFO - 'XPC_FLAGS': '0x0', 20:23:05 INFO - 'XPC_SERVICE_NAME': '0', 20:23:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:23:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:23:07 INFO - Installing collected packages: mozsystemmonitor 20:23:07 INFO - Running setup.py install for mozsystemmonitor 20:23:07 INFO - Successfully installed mozsystemmonitor 20:23:07 INFO - Cleaning up... 20:23:08 INFO - Return code: 0 20:23:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:08 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:08 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 20:23:08 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 20:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:08 INFO - 'HOME': '/Users/cltbld', 20:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:08 INFO - 'LOGNAME': 'cltbld', 20:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:08 INFO - 'MOZ_NO_REMOTE': '1', 20:23:08 INFO - 'NO_EM_RESTART': '1', 20:23:08 INFO - 'PAGER': '/bin/cat', 20:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:08 INFO - 'PWD': '/builds/slave/test', 20:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:08 INFO - 'SHELL': '/bin/bash', 20:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:08 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:08 INFO - 'USER': 'cltbld', 20:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:08 INFO - 'XPC_FLAGS': '0x0', 20:23:08 INFO - 'XPC_SERVICE_NAME': '0', 20:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:08 INFO - Downloading/unpacking blobuploader==1.2.4 20:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:10 INFO - Downloading blobuploader-1.2.4.tar.gz 20:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:23:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:23:10 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 20:23:10 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 20:23:10 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 20:23:10 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 20:23:10 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 20:23:10 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 20:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:23:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23: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 20:23:11 INFO - Downloading docopt-0.6.1.tar.gz 20:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:23:11 INFO - Installing collected packages: blobuploader, requests, docopt 20:23:11 INFO - Running setup.py install for blobuploader 20:23:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:23:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:23:12 INFO - Running setup.py install for requests 20:23:12 INFO - Running setup.py install for docopt 20:23:12 INFO - Successfully installed blobuploader requests docopt 20:23:12 INFO - Cleaning up... 20:23:12 INFO - Return code: 0 20:23:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:12 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:12 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 20:23:12 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 20:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:12 INFO - 'HOME': '/Users/cltbld', 20:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:12 INFO - 'LOGNAME': 'cltbld', 20:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:12 INFO - 'MOZ_NO_REMOTE': '1', 20:23:12 INFO - 'NO_EM_RESTART': '1', 20:23:12 INFO - 'PAGER': '/bin/cat', 20:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:12 INFO - 'PWD': '/builds/slave/test', 20:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:12 INFO - 'SHELL': '/bin/bash', 20:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:12 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:12 INFO - 'USER': 'cltbld', 20:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:12 INFO - 'XPC_FLAGS': '0x0', 20:23:12 INFO - 'XPC_SERVICE_NAME': '0', 20:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jyoNEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5p1p0H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-os7RaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-ava7dp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-9PkCKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-lpR6jW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-lB1oat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xbgNAT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Yl3OIz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:13 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-gO0M4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-5wlUtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-7Ypkkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-icrcaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JtjI3l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-9xdXWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-VeHHfI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:14 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Dwur3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:23:14 INFO - Running setup.py install for manifestparser 20:23:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:23:14 INFO - Running setup.py install for mozcrash 20:23:15 INFO - Running setup.py install for mozdebug 20:23:15 INFO - Running setup.py install for mozdevice 20:23:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Running setup.py install for mozfile 20:23:15 INFO - Running setup.py install for mozhttpd 20:23:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Running setup.py install for mozinfo 20:23:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Running setup.py install for mozInstall 20:23:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:23:15 INFO - Running setup.py install for mozleak 20:23:16 INFO - Running setup.py install for mozlog 20:23:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Running setup.py install for moznetwork 20:23:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Running setup.py install for mozprocess 20:23:16 INFO - Running setup.py install for mozprofile 20:23:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Running setup.py install for mozrunner 20:23:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:23:16 INFO - Running setup.py install for mozscreenshot 20:23:17 INFO - Running setup.py install for moztest 20:23:17 INFO - Running setup.py install for mozversion 20:23:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:23:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:23:17 INFO - Cleaning up... 20:23:17 INFO - Return code: 0 20:23:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:17 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:17 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 20:23:17 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 20:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:17 INFO - 'HOME': '/Users/cltbld', 20:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:17 INFO - 'LOGNAME': 'cltbld', 20:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:17 INFO - 'MOZ_NO_REMOTE': '1', 20:23:17 INFO - 'NO_EM_RESTART': '1', 20:23:17 INFO - 'PAGER': '/bin/cat', 20:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:17 INFO - 'PWD': '/builds/slave/test', 20:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:17 INFO - 'SHELL': '/bin/bash', 20:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:17 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:17 INFO - 'USER': 'cltbld', 20:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:17 INFO - 'XPC_FLAGS': '0x0', 20:23:17 INFO - 'XPC_SERVICE_NAME': '0', 20:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:23:17 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-xb6gVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:23:17 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)) 20:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:23:17 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-s006l8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:23:17 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)) 20:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:23:17 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-bDHVcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:23:17 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)) 20:23:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:23:17 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-iZMitH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-M3Mu8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-jXdiCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-30inOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-2Yoloi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-JGugSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-Or7upY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-USFPTH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-YISHgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:23:18 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)) 20:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:23:18 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-MHCSYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:23:19 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)) 20:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:23:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-HTYKkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:23:19 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)) 20:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-grgAJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:23:19 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)) 20:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:23:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-C7Xv2a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:23:19 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)) 20:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:23:19 INFO - Running setup.py (path:/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/pip-13p3ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:23:19 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)) 20:23:19 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)) 20:23:19 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)) 20:23:19 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)) 20:23:19 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)) 20:23:19 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)) 20:23:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:23:19 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 20:23:19 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 20:23:19 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 20:23:19 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 20:23:19 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 20:23:19 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 20:23:21 INFO - Downloading blessings-1.6.tar.gz 20:23:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:23:21 INFO - Installing collected packages: blessings 20:23:21 INFO - Running setup.py install for blessings 20:23:22 INFO - Successfully installed blessings 20:23:22 INFO - Cleaning up... 20:23:22 INFO - Return code: 0 20:23:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:23:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:22 INFO - Reading from file tmpfile_stdout 20:23:22 INFO - Current package versions: 20:23:22 INFO - blessings == 1.6 20:23:22 INFO - blobuploader == 1.2.4 20:23:22 INFO - docopt == 0.6.1 20:23:22 INFO - manifestparser == 1.1 20:23:22 INFO - mozInstall == 1.12 20:23:22 INFO - mozcrash == 0.17 20:23:22 INFO - mozdebug == 0.1 20:23:22 INFO - mozdevice == 0.48 20:23:22 INFO - mozfile == 1.2 20:23:22 INFO - mozhttpd == 0.7 20:23:22 INFO - mozinfo == 0.9 20:23:22 INFO - mozleak == 0.1 20:23:22 INFO - mozlog == 3.1 20:23:22 INFO - moznetwork == 0.27 20:23:22 INFO - mozprocess == 0.22 20:23:22 INFO - mozprofile == 0.28 20:23:22 INFO - mozrunner == 6.11 20:23:22 INFO - mozscreenshot == 0.1 20:23:22 INFO - mozsystemmonitor == 0.0 20:23:22 INFO - moztest == 0.7 20:23:22 INFO - mozversion == 1.4 20:23:22 INFO - psutil == 3.1.1 20:23:22 INFO - requests == 1.2.3 20:23:22 INFO - wsgiref == 0.1.2 20:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:22 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 20:23:22 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 20:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:22 INFO - 'HOME': '/Users/cltbld', 20:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:22 INFO - 'LOGNAME': 'cltbld', 20:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:22 INFO - 'MOZ_NO_REMOTE': '1', 20:23:22 INFO - 'NO_EM_RESTART': '1', 20:23:22 INFO - 'PAGER': '/bin/cat', 20:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:22 INFO - 'PWD': '/builds/slave/test', 20:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:22 INFO - 'SHELL': '/bin/bash', 20:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:22 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:22 INFO - 'USER': 'cltbld', 20:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:22 INFO - 'XPC_FLAGS': '0x0', 20:23:22 INFO - 'XPC_SERVICE_NAME': '0', 20:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 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)) 20:23:22 INFO - Cleaning up... 20:23:22 INFO - Return code: 0 20:23:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:22 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:22 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 20:23:22 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 20:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:22 INFO - 'HOME': '/Users/cltbld', 20:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:22 INFO - 'LOGNAME': 'cltbld', 20:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:22 INFO - 'MOZ_NO_REMOTE': '1', 20:23:22 INFO - 'NO_EM_RESTART': '1', 20:23:22 INFO - 'PAGER': '/bin/cat', 20:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:22 INFO - 'PWD': '/builds/slave/test', 20:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:22 INFO - 'SHELL': '/bin/bash', 20:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:22 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:22 INFO - 'USER': 'cltbld', 20:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:22 INFO - 'XPC_FLAGS': '0x0', 20:23:22 INFO - 'XPC_SERVICE_NAME': '0', 20:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:23 INFO - Downloading/unpacking jsonschema 20:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:23:25 INFO - Downloading/unpacking functools32 (from jsonschema) 20:23:25 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 20:23:25 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 20:23:25 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 20:23:25 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 20:23:25 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 20:23:25 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 20:23:25 INFO - Downloading functools32-3.2.3-2.tar.gz 20:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:23:26 INFO - warning: no files found matching '*.txt' 20:23:26 INFO - no previously-included directories found matching 'build' 20:23:26 INFO - no previously-included directories found matching 'dist' 20:23:26 INFO - no previously-included directories found matching '.git*' 20:23:26 INFO - Installing collected packages: jsonschema, functools32 20:23:26 INFO - Running setup.py install for functools32 20:23:26 INFO - warning: no files found matching '*.txt' 20:23:26 INFO - no previously-included directories found matching 'build' 20:23:26 INFO - no previously-included directories found matching 'dist' 20:23:26 INFO - no previously-included directories found matching '.git*' 20:23:26 INFO - Successfully installed jsonschema functools32 20:23:26 INFO - Cleaning up... 20:23:26 INFO - Return code: 0 20:23:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:23:26 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')]} 20:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:26 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')]} 20:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:23:26 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 0x104062a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104072420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9022c494e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, '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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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 20:23:26 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 20:23:26 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 20:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:23:26 INFO - 'HOME': '/Users/cltbld', 20:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:23:26 INFO - 'LOGNAME': 'cltbld', 20:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:23:26 INFO - 'MOZ_NO_REMOTE': '1', 20:23:26 INFO - 'NO_EM_RESTART': '1', 20:23:26 INFO - 'PAGER': '/bin/cat', 20:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:23:26 INFO - 'PWD': '/builds/slave/test', 20:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:23:26 INFO - 'SHELL': '/bin/bash', 20:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:23:26 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:23:26 INFO - 'USER': 'cltbld', 20:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:23:26 INFO - 'XPC_FLAGS': '0x0', 20:23:26 INFO - 'XPC_SERVICE_NAME': '0', 20:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:23:26 INFO - Downloading/unpacking flake8 20:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:23:28 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:23:28 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:23:28 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 20:23:28 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 20:23:28 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 20:23:28 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 20:23:28 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 20:23:28 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 20:23:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:23:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:29 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 20:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:23:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:23:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:23:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:23:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:23:30 INFO - no previously-included directories found matching 'docs/_build' 20:23:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:23:30 INFO - Running setup.py install for pep8 20:23:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:23:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:23:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:23:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:23:30 INFO - no previously-included directories found matching 'docs/_build' 20:23:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:23:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:23:30 INFO - Cleaning up... 20:23:30 INFO - Return code: 0 20:23:30 INFO - Running post-action listener: _resource_record_post_action 20:23:30 INFO - Running post-action listener: _start_resource_monitoring 20:23:30 INFO - Starting resource monitoring. 20:23:30 INFO - ##### 20:23:30 INFO - ##### Running install step. 20:23:30 INFO - ##### 20:23:30 INFO - Running pre-action listener: _resource_record_pre_action 20:23:30 INFO - Running main action method: install 20:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:23:30 INFO - Reading from file tmpfile_stdout 20:23:30 INFO - Detecting whether we're running mozinstall >=1.0... 20:23:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:23:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:23:30 INFO - Reading from file tmpfile_stdout 20:23:30 INFO - Output received: 20:23:30 INFO - Usage: mozinstall [options] installer 20:23:30 INFO - Options: 20:23:30 INFO - -h, --help show this help message and exit 20:23:30 INFO - -d DEST, --destination=DEST 20:23:30 INFO - Directory to install application into. [default: 20:23:30 INFO - "/builds/slave/test"] 20:23:30 INFO - --app=APP Application being installed. [default: firefox] 20:23:31 INFO - mkdir: /builds/slave/test/build/application 20:23:31 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'] 20:23:31 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 20:24:02 INFO - Reading from file tmpfile_stdout 20:24:02 INFO - Output received: 20:24:02 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:24:02 INFO - Running post-action listener: _resource_record_post_action 20:24:02 INFO - ##### 20:24:02 INFO - ##### Running run-tests step. 20:24:02 INFO - ##### 20:24:02 INFO - Running pre-action listener: _resource_record_pre_action 20:24:02 INFO - Running main action method: run_tests 20:24:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:24:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:24:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:24:02 INFO - 2016-06-04 20:24:02.354 screenresolution[1668:19100] starting screenresolution argv=screenresolution get 20:24:02 INFO - 2016-06-04 20:24:02.357 screenresolution[1668:19100] Display 0: 1600x1200x32@60 20:24:02 INFO - 2016-06-04 20:24:02.363 screenresolution[1669:19102] starting screenresolution argv=screenresolution list 20:24:02 INFO - Available Modes on Display 0 20:24:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:24:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:24:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:24:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:24:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:24:02 INFO - Intel Iris: 20:24:02 INFO - Chipset Model: Intel Iris 20:24:02 INFO - Type: GPU 20:24:02 INFO - Bus: Built-In 20:24:02 INFO - VRAM (Dynamic, Max): 1536 MB 20:24:02 INFO - Vendor: Intel (0x8086) 20:24:02 INFO - Device ID: 0x0a2e 20:24:02 INFO - Revision ID: 0x0009 20:24:02 INFO - Displays: 20:24:02 INFO - Display: 20:24:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:24:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:24:02 INFO - Display Serial Number: 1600x1200 60 20:24:02 INFO - Main Display: Yes 20:24:02 INFO - Mirror: Off 20:24:02 INFO - Online: Yes 20:24:02 INFO - Rotation: Supported 20:24:02 INFO - Return code: 0 20:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:24:02 INFO - Python 2.7.3 20:24:02 INFO - Return code: 0 20:24:02 INFO - grabbing minidump binary from tooltool 20:24:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:02 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 0x103f01e00>, '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 0x103d6adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103efcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:24:02 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 20:24:02 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 20:24:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:24:02 INFO - Return code: 0 20:24:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:24:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:24:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:24:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:24:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:24:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:24:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:24:03 INFO - Return code: 0 20:24:03 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/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 20:24:03 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/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0194 --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 20:24:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.B4DgxE7NcV/Render', 20:24:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:03 INFO - 'HOME': '/Users/cltbld', 20:24:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:03 INFO - 'LOGNAME': 'cltbld', 20:24:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:24:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:24:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:03 INFO - 'MOZ_NO_REMOTE': '1', 20:24:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:24:03 INFO - 'NO_EM_RESTART': '1', 20:24:03 INFO - 'PAGER': '/bin/cat', 20:24:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:03 INFO - 'PWD': '/builds/slave/test', 20:24:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:24:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:03 INFO - 'SHELL': '/bin/bash', 20:24:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Oku5jKUP7G/Listeners', 20:24:03 INFO - 'TMPDIR': '/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/', 20:24:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:03 INFO - 'USER': 'cltbld', 20:24:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:03 INFO - 'XPC_FLAGS': '0x0', 20:24:03 INFO - 'XPC_SERVICE_NAME': '0', 20:24:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:03 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/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0194', '--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 20:24:04 INFO - mozversion application_buildid: 20160604190644 20:24:04 INFO - mozversion application_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 20:24:04 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:24:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:24:04 INFO - mozversion application_name: Firefox 20:24:04 INFO - mozversion application_remotingname: firefox-dev 20:24:04 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:24:04 INFO - mozversion application_vendor: Mozilla 20:24:04 INFO - mozversion application_version: 48.0a2 20:24:04 INFO - mozversion platform_buildid: 20160604190644 20:24:04 INFO - mozversion platform_changeset: dc972d877cd697c2c8f6248534f21bb5e1dba9ae 20:24:04 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:24:04 INFO - mozversion platform_version: 48.0a2 20:24:04 INFO - using testdate: 1465097044 20:24:04 INFO - actual date: 1465097044 20:24:04 INFO - starting webserver on 'localhost:49280' 20:24:04 INFO - SUITE-START | Running 2 tests 20:24:04 INFO - TEST-START | dromaeo_css 20:24:04 INFO - operating with platform_type : mac_ 20:24:04 INFO - Initialising browser for dromaeo_css test... 20:24:04 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpTIo79M/profile http://localhost:49280/getInfo.html) 20:24:06 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 20:24:06 INFO - PROCESS | 1687 | colorDepth:24 20:24:06 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 20:24:06 INFO - PROCESS | 1687 | __metrics 20:24:06 INFO - PROCESS | 1687 | 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" 20:24:06 ERROR - PROCESS | 1687 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:24:06 INFO - TEST-INFO | 1687: exit 0 20:24:06 INFO - Browser initialized. 20:24:06 INFO - Running cycle 1/1 for dromaeo_css test... 20:24:06 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpTIo79M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:24:18 INFO - PROCESS | 1690 | RSS: Main: 211017728 20:24:18 INFO - PROCESS | 1690 | 20:25:10 INFO - PROCESS | 1690 | 1465097110397 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] 20:27:21 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 20:27:22 INFO - PROCESS | 1690 | RSS: Main: 374353920 20:27:22 INFO - PROCESS | 1690 | 20:30:25 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 20:30:26 INFO - PROCESS | 1690 | RSS: Main: 356610048 20:30:26 INFO - PROCESS | 1690 | 20:33:28 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 20:33:29 INFO - PROCESS | 1690 | RSS: Main: 355426304 20:33:29 INFO - PROCESS | 1690 | 20:36:31 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 20:36:32 INFO - PROCESS | 1690 | RSS: Main: 358313984 20:36:32 INFO - PROCESS | 1690 | 20:39:35 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 20:39:35 INFO - PROCESS | 1690 | RSS: Main: 321654784 20:39:35 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 20:42:38 INFO - PROCESS | 1690 | RSS: Main: 366362624 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | __start_tp_report 20:42:38 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 20:42:38 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 20:42:38 INFO - PROCESS | 1690 | |i|pagename|runs| 20:42:38 INFO - PROCESS | 1690 | |0;dojo.html;3136.9529457058143;3342.515335276384;3343.966560334396;3394.270231900141;3408.744344174186;4509.9549004509945;4681.508441613629;5302.522772950434;5394.109971854649;5455.672659640424;5476;5489.560835133189;5514.724263786805;5514.779408823642;5517.44825517449;4973.726445045521;4991.32617096691;5053.191489361703;5095.057414378333;5129.2306154077;5036.370453693288;5125.077486052509;5129.948700512984;5130.050940575987;5134.794608215667;5032.043911656791;5057.544820966113;5087.982403519292;5103.311053007852;5115.976804639087;5151.381834179885;5163.199704045879;5166.302549155872;5166.870828229286;5167.948320516784;4987.401511818605;4996.350474438336;5095.0574143783515;5104.2088476286235;5105.874741844791;5377.7311134443125;5430.294061771984;5471.7264136793;5471.72641367934;5478.671279723189;6080.756769729205;6104.542159338067;6115.602485838451;6128.111423843562;6129.356417576179;5567.916481252784;6004.3395226525045;6131.529590167202;6167.537434692371;6171.475424588915;5337.2794672719465;5646.632968857011;5671.120976248688;5671.688057156896;5701.458361455697;5216.504432078947;5707.857303567444;6223.253209614875;6246.656433896179;6260.436560709558;5720.370759216483;5864.618799778001;5989.221401217901;6029.0052141397055;6032.245969253843;6163.84590385235;6194.721237544321;6197.597156184834;6199.845003874849;6214.347493513116;6051.971164901977;6094.781043791171;6103;6104.175936248638;6132.313613756252;5929.644221346733;5976.551758618121;6042;6105.406439241515;6121.849304493819;4758;4788.23388257884;4789.592884604778;4793.688410253322;4795.136875362398;3761.5486141663177;4402.5597440255715;4404.889877753082;4418.425604671436;4450.755208463565;4931.260310953386;5119.771254898872;5568.860778480571;5581.441855814467;5598.468145526169;6003.0695242237525;6096.29892562361;6145.692715364214;6167.969160154171;6201.782937597162;5494.835154945278;5511.145772405283;5511.696856672841;5516.641418307797;5519.751611177427;5188.948110518922;5311.17676760095;5327.893442131136;5338.412774594792;5344.839654810362;6301.684915754194;6315.305316415191;6330.303666596578;6333.651649159248;6345.143405640272;5978.103284507359;6140.723667435065;6146.231721034871;6148.692565371624;6152.169457123231;6231.750729970931;6261;6335.746570137143;6357.682115894094;6366.904496432465;5999.400059993965;6040.879182416415;6056.90914636272;6064.666443345482;6083.178770865877;5990.520758339235;6100.298465676504;6160.9383906160365;6167.167432396748;6179.598326108789;5299.258103865385;5838.970805145947;5840.357560668238;5929.288485381781;5955.196048533393;4856.732879691651;5872.823815285377;5879.73541190656;5889.941100589111;5893.852653683693;5595.692236926845;5763.7406316715005;5772.307323121252;5779.913301300399;5784.797532086357;5827.737751801264;5828.0966450200285;5828.329742079715;5868.061110222344;5869.325027621708;5707.629004114885;5755.3093628763145;5773.451522105393;5775.1914731938405;5842.211301474246 20:42:38 INFO - PROCESS | 1690 | |1;ext.html;522.6759409166315;540.2139886465194;596.8328867916983;597.4473611908983;615.9076138579212;27622.58566121507;37604.247915041684;38801;38893.80553097238;39009.60990390095;42124.36813447793;42688.573114268846;42695;42702.572974270246;42717.58259339253;8526.87209691855;9428.952855235731;9652.951735241313;9667.5649595768;9676.854847177297;8587;11637.767244655102;11837.993770529516;11843;11847.170698051139;11527.596534121307;11645.126615503828;11666.766664666702;11675.24110932791;11678.824817627743;8654.87017694735;8825.779355516164;8844.425112367675;11512.021478174365;11620.186586938875;8567.057623661454;11499.367534785584;11743.588974385855;11753.764924701543;11769.293842369485;39173.216535669126;39675.40486892699;39706.20587588232;39750.40374394386;40021.79989100065;3186.474231751758;3240.578724765789;3242.238074052596;3263.479427504925;3316.0217735768006;27974.720252797415;28552.00067997631;28587.57118643222;28764.993225237227;29399.412011759858;2722.060888993294;2748.4365705030423;2782.373965857686;2783.9443211135867;2815.5495120780784;487319.56340218073;489283;490220.5488972568;491457;493433;36256;36812.079698007765;51446.22830657543;61774.69112654408;62116.37883621197;35851;35889.8205508976;35957.820210898775;36004;36145.45781813275;15363.462278820187;15497;15520.137583489828;15523.611909702122;15524.922375388276;12151.149419540725;12416.81374779379;13097.541586044443;13203.603891883258;13397.330133493286;8181.754547363588;8203.179682031869;8204.712835050745;8280.710175143962;8295.792605184919;2936.265933516621;2954.8079374840568;3397.11674964511;3438.0889064397957;3578.4453409721536;1000.8148492528906;1020.5101551255439;1022.504085518523;1095.917806164541;1112.588342313333;28228.576571351034;28616.71383286182;28834;28923;28998.710012900025;980.7911847917748;1125.386664267976;1152.193464574801;1162.7267592115845;1167.5796713183247;14770.630734231516;14773.630659233606;14778.63053423673;15148.924255378653;15196.240187990556;651485;656286;657131;658837;661938;62186;62862;62874.685626571576;62941.68529157325;63180.68409657922;1431.6707157353867;1490.9179995100158;1514.4547962733407;1526.5954522051886;1529.6481809183726;24097.75902240955;28523;29169.979050733124;29637.25906852346;29665.851670741507;51047.74476127744;51422.48577514177;51512.22731658953;51566.484335156165;51730.22404663858;509110;513264;513492;513619;516450;23677.17129900445;26635.00237482158;27014.919403224438;27327.86336068307;27374.589381159698;41668.791656041525;42843.57156428396;42854.78572607117;42911.9272018202;43351.34972975471;35057;35372.82313588416;35403.468947966314;36082.09794755152;36103.27793444169;29463.852680736458;36358.45462318014;36792;37105.81447092747;37117.515299388804;36289;36781;36942.63057369392;37509.81245093866;37702.811485942395;31270.90551830675;31767;32395.676043240208;32923.01230963075;33311.66688333086;24608;24922.750772492043;25134.24597262085;25212;25480.362990925376 20:42:38 INFO - PROCESS | 1690 | |2;jquery.html;371.8494009925981;401.444000059917;403.50973567115955;405.1875988642771;407.8246354067753;13462.93268533656;16493.587660308498;20516.28193013245;21190.78809211909;21195.258165964195;15822.683546329066;16219.189040547984;16631.002139871616;20307.49231269219;20545.691814622754;5705.2012718219485;5779.190913272135;6001.339852616209;6002.219711437507;6003.189569408118;5936.554758393117;5968;5989.3112292086535;5991.370906054845;5999.040153575429;4174.561671024544;6291.087792270118;6586.506012049092;6590.835229119262;6591.307912669148;5606.775728970836;6541.640209788459;6551.868962620745;6556.377144171296;6561.015847622894;5684.516816070639;5689.857753556153;5702.344230413472;5708.885822283572;5711.200431939532;13355.599332020054;13583.864161358457;21239.8938005309;24950.37624059395;24957.00171993118;1687.3503701075051;2181.6618424144126;2209.983407632491;2320.454693147126;2683.2218656589566;9842.507874606312;10187;10204.183665306831;10240.74398140045;10681.572737090508;1433.3478267388314;1732.2897611979029;1789.5347209725564;1802.4052062819237;1816.0375001249358;110931;113086;116518;116859;117700;9431.622735090657;9449.952750236342;11515.681619392728;11706.94146529279;11780.705482362839;14997.850021499646;15063.623409414853;15072.623184420258;15099.622509437351;15106;7551.358134558514;8383.790405239917;8708.129187081242;8843.69047083349;8862.42394244372;6811.965940170367;8706.259967902799;8728.301735861116;8772.736817895473;8888.955555222183;4504.81980720774;4582.450105987236;4590.1278757036725;4639.675222734444;4655.534446555385;1890.2816929566677;1891.7824450188405;1943.1838627776367;1950.775660798998;1962.7939066398108;747.7532414303375;921.6175287255847;963.9951800240954;1005.522376870995;1012.2863381316155;19608.803911960982;20430.284940027028;20589.485262868548;20610.969451527362;20865.956702165135;919.5540163020929;989.1443901025705;1055.672741450153;1079.1690398393193;1084.7938891610568;10579.682609521727;10684.626038088629;10759.031687148194;10837.729056773644;10843.1867609928;592692;618185;644224;650420.9831606777;655254;123080;123191.3840430792;125571;128330;136892;1056.2975621211808;1068.2895874243695;1092.3391348234204;1092.792369449802;1094.9069329106956;17589.736153957958;17747.46757597274;17786.644267114843;17892.821071789116;18181.909085454914;53414.465855340946;54574.727126364116;54949;56019;57467;83096;83136.33727325298;83653.58173209096;84450;85329.720054198;7883.408744344082;8342.152027634098;8359.414840961075;8439.113893041142;8704.434211776213;11535.653930382103;11598.304101753922;12412.937935310265;12450;12824.87175128274;10313.639022634172;10936.890631093906;11009;11205.383703896365;11210.943945280222;10766.515506802369;10848.403337816495;10903;10950.890491094988;11039;11131.833022504507;11556.959873611415;11566.479508421973;11603.88396116028;11685.357305348287;8175.67297308101;8216.753497395088;8235.13531079252;8283.710070147516;8598.097199793932;8491.660333586588;10466;10915.836262456229;11051.231802911496;11186.608468703882 20:42:38 INFO - PROCESS | 1690 | |3;mootools.html;1418.2199790115435;1556.6808804195139;1575.8896877218597;1800.3608718904788;1941.999999999999;7841.960790196048;8472.576371181447;8583.227509524142;8587.828243435135;8599.183077607633;8378.581070946444;8629.697960571366;8641.308695304377;8648.35137364697;8656.523891185981;2405.6992875890514;2436.719777225624;2437.4881274932286;2443.0838435586566;2443.7189723181796;2400.6279026750794;2431.2463136427764;2449.8285120041514;2458.950820983588;2467.1241709193246;2415.5531226723024;2447.302518782147;2458.8770561471943;2466.70399552054;2477.7770000699934;2431.2827715823873;2433.85396876188;2443.963340549893;2446.1683027770555;2476.2571228631336;2013.3054096336741;2411.9758802412102;2431.100492817669;2436.2082323245018;2443.059422122478;8292.917070829275;8589.098144694843;8623.698170564001;8633.568321583896;8654.480731156087;5202.479752024768;5396.91904621431;5409.702466364349;5414.29614150158;5415.810446634388;3354.698077173042;3378.966210337914;3380.3239352129426;3411.761176717631;3438.914027149316;1036.7667274863095;1048.3185929146039;1050.3907733514543;1059.1315121600292;1063.7925604507125;11444.027257683107;11476.024537914287;11673.824892626617;11684.065274778082;11689.123315751267;4501.864944051684;4676.672632915731;4677.929831052537;4683.7892294846815;4711.670183087151;9050.637974481078;9067.18395344409;9107.817843643223;9154.450732955911;9179.541022948826;5130.538251557377;5303.655262407931;5333.386660534011;5343.064963631349;5349.4650534946195;4336.154449882242;4439.22313595126;4724.007958328721;4817.494163112893;4915.066137433876;3981.243563722883;4000.5199376075057;4067.5525692173837;4107.97946010274;4116.608922152391;1330.9800352994712;1333.1068184316532;1343.9664008399673;1349.2376807104151;1361.251311778518;662.57595139111;692.9514933954575;920.6685593186447;921.3412410126791;969.4231932000448;5582.944170558324;5583.944160558423;5619.129034999582;5678.43481470028;5717.142428635738;497.7685376300038;510.43596825508024;510.56346823466146;516.8216965147018;565.6210339072832;3884.4173373994126;4035.5157381114454;4052.878413647595;4112.609302116294;4113.341865301538;10158.542865571068;10361.430121343248;10468.162546996222;10496.265261431627;10510.316829406063;10207.234457415576;10341.276110672332;10342.931413717137;10343.01741334572;10356.844647330296;8054.8791768122355;8085.070216925012;8138.3896207784655;8147.429679922348;8229.25936665703;5809;5829.15477255787;5888.146218798336;5939.732712028055;6123.632582044914;9298.256139508958;9325.813483730422;9476.194523465583;9497.050294970448;9538.427694338361;9188.95405522968;9482.336236463381;9738.80522389534;9819.919808821167;9912.900870991487;7265.200827909031;7397.482176247826;7468.663910124166;7495.325420712162;7509.849803004018;8031.718889838828;8036.437449378721;8117.472364296302;8118.59407029658;8126.918730812617;6182;7176.892346614909;7193.208747037717;7386.48294619393;7468.402527797654;6932.202796678245;7113.430925526049;7130.928690713234;7151.2848715128075;7167.964160179165;6706.765263215764;6781.830454238486;6793.048973143665;6794.72821087151;6801.931980680328;4737.526247375373;4760.238361862085;4807.7836497357;4900.191468407673;4950.975245123751;4829.7585120744525;4867.758721525988;4947.802087916443;5015.473375295542;5111.105556527667 20:42:38 INFO - PROCESS | 1690 | |4;prototype.html;320.0382849537141;326.1471252674256;330.55210190192287;333.7630282499427;336.1562478179768;6498.790411260171;6507.31673174317;6527.543071984963;6611.6694165291665;6618.371254730793;6539.901901471481;6543.7055332510035;6545.869082618345;6556;6572.507061970347;4613.8465383654175;4725.976370118145;4729;4730.148573256813;4873.780679869415;4808.807647694088;4871.780769865347;4880.658353915227;4881.194602890519;4902.068606964684;4724.267738500521;4845.248986407095;4846.2376445426125;4855.465898751135;4860.4653488116455;4609.539046095397;4654.674172807905;4784.210605250129;4843.709377437347;4844.491328410519;4100.425940368351;4106.096658735073;4115.567865374122;4126.133511962462;4129.091599848029;6188.938110618927;6270.372962703739;6270.529710271702;6286.591371560834;6293.181886354791;4596.701214421052;5708.885822283572;5711.572106973256;5714.342850572196;5723;6942.861142777117;6951.2353641099435;6953.930460695379;6959.721611135548;6970.268121847234;4308.913821723548;4442.977785111086;4525.9547404525865;4636;4677.812887484497;7278.890816637755;7301.561906285639;7362.190159082496;7375.963120184419;7391.593462359568;4589.58693717567;4589.770511474413;4591.989762252299;4592.793324300348;4596.7012144211185;4632.97683511587;4646.97676511622;4649.139909116824;4668.183067963161;4673.7429441381055;5547.750351234205;5547.944520554824;5557.805476808292;5558.610897237236;5562.249096371938;6766.18805743314;6999.720011199597;7012.193597736224;7014.438844892396;7035.401990830735;1591.888567800266;1605.9759103613455;1614.5398561409943;1615.9030458172313;1621.1569983608458;852.7825404521807;1003.0571263012745;1005.3716427232979;1027.1833892055834;1027.7173777211326;6283;6485.286618471964;6516.283208847023;6658.3674550917585;6671.199456065303;6383.265924418752;6902.447804175654;7314.707411703579;7345.7428989986165;7348.081489813773;6632.9336706633285;6640.900386494206;6641.269460359356;6644.468442524587;6656.001599759978;3809.8285577149095;3840.116773142137;3844.826982785781;3848.6728628066367;3850.7304488685522;7309.269073092754;7601.505902116345;7603.809904752426;7616.504927179715;7618.352440042548;7446.367058800062;7486.85026299471;7495;7513.699452021967;7537.2462753725285;7579.772606821804;7591.164971853092;7591.316781489692;7593.316601505891;7593.468457207942;6958.408535274559;7254.383377412979;7266.818329541804;7274.96362518184;7303.269673032654;7153.3919616833155;7177.677004534704;7185.676644551112;7188.784336469915;7191.280871912767;7593.05086864142;7595.5442673439775;7602.391808655184;7602.961985190039;7612.961935190288;4601.309803529497;4604.0791841631135;4607.838725644587;4611.79246933882;4613.861584152481;6995.300469952964;7241.710331586678;7244.818879528054;7247.9637601811655;7254.492185547172;5647.576431767552;6996.685149168401;7003.124609423822;7007.544509606859;7043.436525078084;6258.499320054476;6420.06908998202;6927.896081558882;7009.684564194521;7012.158540975116;5530.529904958124;6812.25065242813;6818.215905170969;6823.727050917908;6829.351211634887;6736.090627765189;7007.089078419773;7011.964940175267;7012.298770123151;7015.789526314219;6598.90101648466;6696.4977626679165;6788.4569234462115;6820.3861652451515;6920.861582768417 20:42:38 INFO - PROCESS | 1690 | |5;yui.html;693.4729605499821;693.7155766135885;734.14105496569;758.9734359297422;782.921707829217;15015.249237538106;15125.546233612995;15317.463888763868;15351.078935263891;15366.154861482628;13995.440182392693;14061.578152655411;14062.296885155727;14073.92963035186;14084;635.4757325206723;773.0182668011622;915.3592485260311;947.5262368815593;948.0093302498871;927.9164875161234;939.7697564096806;940.2900809888524;942.7407462947675;944.3153713557684;946.8816397950256;985.4776968206862;987.3631507677544;987.7975015121918;987.8370068938616;773.6518566645004;778.0274656679151;785.8703313953191;946.9715908522721;979.9510024498746;576.7375843991016;579.122629216737;728.4936968806682;732.9413646908288;733.8238822682571;14904.62738431548;14930.850691493164;14958.326875290639;14960.952733308673;14970;12542.561010364594;12663.620091397273;12737.490500379974;12755.170913890568;12904.548340808027;9433.339666223366;9496.240300775922;9581.760455988586;9673.613055477772;10192.490375481197;3340.198352395432;3378.712809411203;3460.9480857787153;3480.460528618068;3491.1970246843116;74709;74955.87566186511;75078.8738168928;75830;75872.24127758708;9219.723408297761;9248.445093294424;9254.398464099811;9333.160015598492;9515.952420237854;20285.695714564292;20311.4922126948;20315.492112697004;20321.18715251403;20743;7469.551826890404;7532.510386824839;7715.845683086419;7734.342580880688;7834.1774113718375;4067.430559721641;4106.630403263661;4122.855700050483;4149.771762553089;4368;3016.4721173794496;3530.8411121499594;3592.137886907176;3594.0835087052633;3596.8561257549404;531.7979167916244;638.2086213095821;646.1922596754057;651.9250286217051;656.3797211635055;514.1311184098861;558.8376177550684;567.7700531284754;590.5806877117209;595.8897603943284;7726.266004729654;7823.413244006722;7941.682332706742;8059.838803223903;8202.712905048415;583.5623282538096;585.841822707875;592.520058752412;604.7097393251177;609.9420555047263;3876.8255428505786;3893.6301051400073;3917.5690674025836;3935.330993731073;3944.9211015780097;93724;96770;97021.51489242508;97124.51437742767;97266;67582.3241767576;71056;71387;71554.9266760999;72581;69830.65084674646;70052.59894802075;70933.645331773;72444.91332630014;72839;7539.32146106853;7693.692252309845;7893.723719670013;7957.602119893866;7990.360771138178;48464;48747;49317.75341123271;50602.746986264836;52140.478595213564;65047.67476162778;68509.65745171242;68995.62008759897;69510.65244673744;71078.64460677664;9675.564599592895;9729.659461918798;9916.75208119774;10074.949625251827;10536;9810.264230182907;10563.524641391003;10695;10843.132549396187;11027.283226590245;9895.406275623485;10052.949735251277;10222.080012798884;10406.375617462976;10911.181661375269;9878.50607469638;10097.394156350643;10277.229207809294;10366.896331036593;10501.579936802442;9750.317477776487;10097.242706797166;10120.240981926238;10352.585896564053;10387.324823886423;8763.868541972002;8852.601632926662;8970.102989700978;9011.774705632411;9024.007359190353;8557.914420855961;8673.089325620718;8779.561021949004;8879.378443508893;8928.955355223183 20:42:38 INFO - PROCESS | 1690 | __end_tp_report 20:42:38 INFO - PROCESS | 1690 | __start_cc_report 20:42:38 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1502 20:42:38 INFO - PROCESS | 1690 | __end_cc_report 20:42:38 INFO - PROCESS | 1690 | __startTimestamp1465098158721__endTimestamp 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | ------- Summary: start ------- 20:42:38 INFO - PROCESS | 1690 | Number of tests: 6 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#0] dojo.html Cycles:165 Average:5569.84 Median:5720.37 stddev:647.68 (11.3%) stddev-sans-first:620.91 20:42:38 INFO - PROCESS | 1690 | Values: 3137.0 3342.5 3344.0 3394.3 3408.7 4510.0 4681.5 5302.5 5394.1 5455.7 5476.0 5489.6 5514.7 5514.8 5517.4 4973.7 4991.3 5053.2 5095.1 5129.2 5036.4 5125.1 5129.9 5130.1 5134.8 5032.0 5057.5 5088.0 5103.3 5116.0 5151.4 5163.2 5166.3 5166.9 5167.9 4987.4 4996.4 5095.1 5104.2 5105.9 5377.7 5430.3 5471.7 5471.7 5478.7 6080.8 6104.5 6115.6 6128.1 6129.4 5567.9 6004.3 6131.5 6167.5 6171.5 5337.3 5646.6 5671.1 5671.7 5701.5 5216.5 5707.9 6223.3 6246.7 6260.4 5720.4 5864.6 5989.2 6029.0 6032.2 6163.8 6194.7 6197.6 6199.8 6214.3 6052.0 6094.8 6103.0 6104.2 6132.3 5929.6 5976.6 6042.0 6105.4 6121.8 4758.0 4788.2 4789.6 4793.7 4795.1 3761.5 4402.6 4404.9 4418.4 4450.8 4931.3 5119.8 5568.9 5581.4 5598.5 6003.1 6096.3 6145.7 6168.0 6201.8 5494.8 5511.1 5511.7 5516.6 5519.8 5188.9 5311.2 5327.9 5338.4 5344.8 6301.7 6315.3 6330.3 6333.7 6345.1 5978.1 6140.7 6146.2 6148.7 6152.2 6231.8 6261.0 6335.7 6357.7 6366.9 5999.4 6040.9 6056.9 6064.7 6083.2 5990.5 6100.3 6160.9 6167.2 6179.6 5299.3 5839.0 5840.4 5929.3 5955.2 4856.7 5872.8 5879.7 5889.9 5893.9 5595.7 5763.7 5772.3 5779.9 5784.8 5827.7 5828.1 5828.3 5868.1 5869.3 5707.6 5755.3 5773.5 5775.2 5842.2 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#1] ext.html Cycles:180 Average:67153.68 Median:35214.91 stddev:149521.07 (424.6%) stddev-sans-first:149856.83 20:42:38 INFO - PROCESS | 1690 | Values: 522.7 540.2 596.8 597.4 615.9 27622.6 37604.2 38801.0 38893.8 39009.6 42124.4 42688.6 42695.0 42702.6 42717.6 8526.9 9429.0 9653.0 9667.6 9676.9 8587.0 11637.8 11838.0 11843.0 11847.2 11527.6 11645.1 11666.8 11675.2 11678.8 8654.9 8825.8 8844.4 11512.0 11620.2 8567.1 11499.4 11743.6 11753.8 11769.3 39173.2 39675.4 39706.2 39750.4 40021.8 3186.5 3240.6 3242.2 3263.5 3316.0 27974.7 28552.0 28587.6 28765.0 29399.4 2722.1 2748.4 2782.4 2783.9 2815.5 487319.6 489283.0 490220.5 491457.0 493433.0 36256.0 36812.1 51446.2 61774.7 62116.4 35851.0 35889.8 35957.8 36004.0 36145.5 15363.5 15497.0 15520.1 15523.6 15524.9 12151.1 12416.8 13097.5 13203.6 13397.3 8181.8 8203.2 8204.7 8280.7 8295.8 2936.3 2954.8 3397.1 3438.1 3578.4 1000.8 1020.5 1022.5 1095.9 1112.6 28228.6 28616.7 28834.0 28923.0 28998.7 980.8 1125.4 1152.2 1162.7 1167.6 14770.6 14773.6 14778.6 15148.9 15196.2 651485.0 656286.0 657131.0 658837.0 661938.0 62186.0 62862.0 62874.7 62941.7 63180.7 1431.7 1490.9 1514.5 1526.6 1529.6 24097.8 28523.0 29170.0 29637.3 29665.9 51047.7 51422.5 51512.2 51566.5 51730.2 509110.0 513264.0 513492.0 513619.0 516450.0 23677.2 26635.0 27014.9 27327.9 27374.6 41668.8 42843.6 42854.8 42911.9 43351.3 35057.0 35372.8 35403.5 36082.1 36103.3 29463.9 36358.5 36792.0 37105.8 37117.5 36289.0 36781.0 36942.6 37509.8 37702.8 31270.9 31767.0 32395.7 32923.0 33311.7 24608.0 24922.8 25134.2 25212.0 25480.4 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#2] jquery.html Cycles:180 Average:35785.60 Median:20738.46 stddev:105331.37 (507.9%) stddev-sans-first:105593.28 20:42:38 INFO - PROCESS | 1690 | Values: 371.8 401.4 403.5 405.2 407.8 13462.9 16493.6 20516.3 21190.8 21195.3 15822.7 16219.2 16631.0 20307.5 20545.7 5705.2 5779.2 6001.3 6002.2 6003.2 5936.6 5968.0 5989.3 5991.4 5999.0 4174.6 6291.1 6586.5 6590.8 6591.3 5606.8 6541.6 6551.9 6556.4 6561.0 5684.5 5689.9 5702.3 5708.9 5711.2 13355.6 13583.9 21239.9 24950.4 24957.0 1687.4 2181.7 2210.0 2320.5 2683.2 9842.5 10187.0 10204.2 10240.7 10681.6 1433.3 1732.3 1789.5 1802.4 1816.0 110931.0 113086.0 116518.0 116859.0 117700.0 9431.6 9450.0 11515.7 11706.9 11780.7 14997.9 15063.6 15072.6 15099.6 15106.0 7551.4 8383.8 8708.1 8843.7 8862.4 6812.0 8706.3 8728.3 8772.7 8889.0 4504.8 4582.5 4590.1 4639.7 4655.5 1890.3 1891.8 1943.2 1950.8 1962.8 747.8 921.6 964.0 1005.5 1012.3 19608.8 20430.3 20589.5 20611.0 20866.0 919.6 989.1 1055.7 1079.2 1084.8 10579.7 10684.6 10759.0 10837.7 10843.2 592692.0 618185.0 644224.0 650421.0 655254.0 123080.0 123191.4 125571.0 128330.0 136892.0 1056.3 1068.3 1092.3 1092.8 1094.9 17589.7 17747.5 17786.6 17892.8 18181.9 53414.5 54574.7 54949.0 56019.0 57467.0 83096.0 83136.3 83653.6 84450.0 85329.7 7883.4 8342.2 8359.4 8439.1 8704.4 11535.7 11598.3 12412.9 12450.0 12824.9 10313.6 10936.9 11009.0 11205.4 11210.9 10766.5 10848.4 10903.0 10950.9 11039.0 11131.8 11557.0 11566.5 11603.9 11685.4 8175.7 8216.8 8235.1 8283.7 8598.1 8491.7 10466.0 10915.8 11051.2 11186.6 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#3] mootools.html Cycles:180 Average:5582.19 Median:510.50 stddev:3075.01 (602.4%) stddev-sans-first:3067.71 20:42:38 INFO - PROCESS | 1690 | Values: 1418.2 1556.7 1575.9 1800.4 1942.0 7842.0 8472.6 8583.2 8587.8 8599.2 8378.6 8629.7 8641.3 8648.4 8656.5 2405.7 2436.7 2437.5 2443.1 2443.7 2400.6 2431.2 2449.8 2459.0 2467.1 2415.6 2447.3 2458.9 2466.7 2477.8 2431.3 2433.9 2444.0 2446.2 2476.3 2013.3 2412.0 2431.1 2436.2 2443.1 8292.9 8589.1 8623.7 8633.6 8654.5 5202.5 5396.9 5409.7 5414.3 5415.8 3354.7 3379.0 3380.3 3411.8 3438.9 1036.8 1048.3 1050.4 1059.1 1063.8 11444.0 11476.0 11673.8 11684.1 11689.1 4501.9 4676.7 4677.9 4683.8 4711.7 9050.6 9067.2 9107.8 9154.5 9179.5 5130.5 5303.7 5333.4 5343.1 5349.5 4336.2 4439.2 4724.0 4817.5 4915.1 3981.2 4000.5 4067.6 4108.0 4116.6 1331.0 1333.1 1344.0 1349.2 1361.3 662.6 693.0 920.7 921.3 969.4 5582.9 5583.9 5619.1 5678.4 5717.1 497.8 510.4 510.6 516.8 565.6 3884.4 4035.5 4052.9 4112.6 4113.3 10158.5 10361.4 10468.2 10496.3 10510.3 10207.2 10341.3 10342.9 10343.0 10356.8 8054.9 8085.1 8138.4 8147.4 8229.3 5809.0 5829.2 5888.1 5939.7 6123.6 9298.3 9325.8 9476.2 9497.1 9538.4 9189.0 9482.3 9738.8 9819.9 9912.9 7265.2 7397.5 7468.7 7495.3 7509.8 8031.7 8036.4 8117.5 8118.6 8126.9 6182.0 7176.9 7193.2 7386.5 7468.4 6932.2 7113.4 7130.9 7151.3 7168.0 6706.8 6781.8 6793.0 6794.7 6801.9 4737.5 4760.2 4807.8 4900.2 4951.0 4829.8 4867.8 4947.8 5015.5 5111.1 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#4] prototype.html Cycles:180 Average:5719.47 Median:6541.80 stddev:1833.89 (28.0%) stddev-sans-first:1793.70 20:42:38 INFO - PROCESS | 1690 | Values: 320.0 326.1 330.6 333.8 336.2 6498.8 6507.3 6527.5 6611.7 6618.4 6539.9 6543.7 6545.9 6556.0 6572.5 4613.8 4726.0 4729.0 4730.1 4873.8 4808.8 4871.8 4880.7 4881.2 4902.1 4724.3 4845.2 4846.2 4855.5 4860.5 4609.5 4654.7 4784.2 4843.7 4844.5 4100.4 4106.1 4115.6 4126.1 4129.1 6188.9 6270.4 6270.5 6286.6 6293.2 4596.7 5708.9 5711.6 5714.3 5723.0 6942.9 6951.2 6953.9 6959.7 6970.3 4308.9 4443.0 4526.0 4636.0 4677.8 7278.9 7301.6 7362.2 7376.0 7391.6 4589.6 4589.8 4592.0 4592.8 4596.7 4633.0 4647.0 4649.1 4668.2 4673.7 5547.8 5547.9 5557.8 5558.6 5562.2 6766.2 6999.7 7012.2 7014.4 7035.4 1591.9 1606.0 1614.5 1615.9 1621.2 852.8 1003.1 1005.4 1027.2 1027.7 6283.0 6485.3 6516.3 6658.4 6671.2 6383.3 6902.4 7314.7 7345.7 7348.1 6632.9 6640.9 6641.3 6644.5 6656.0 3809.8 3840.1 3844.8 3848.7 3850.7 7309.3 7601.5 7603.8 7616.5 7618.4 7446.4 7486.9 7495.0 7513.7 7537.2 7579.8 7591.2 7591.3 7593.3 7593.5 6958.4 7254.4 7266.8 7275.0 7303.3 7153.4 7177.7 7185.7 7188.8 7191.3 7593.1 7595.5 7602.4 7603.0 7613.0 4601.3 4604.1 4607.8 4611.8 4613.9 6995.3 7241.7 7244.8 7248.0 7254.5 5647.6 6996.7 7003.1 7007.5 7043.4 6258.5 6420.1 6927.9 7009.7 7012.2 5530.5 6812.3 6818.2 6823.7 6829.4 6736.1 7007.1 7012.0 7012.3 7015.8 6598.9 6696.5 6788.5 6820.4 6920.9 20:42:38 INFO - PROCESS | 1690 | 20:42:38 INFO - PROCESS | 1690 | [#5] yui.html Cycles:180 Average:17852.01 Median:34844.55 stddev:25412.94 (72.9%) stddev-sans-first:25451.57 20:42:38 INFO - PROCESS | 1690 | Values: 693.5 693.7 734.1 759.0 782.9 15015.2 15125.5 15317.5 15351.1 15366.2 13995.4 14061.6 14062.3 14073.9 14084.0 635.5 773.0 915.4 947.5 948.0 927.9 939.8 940.3 942.7 944.3 946.9 985.5 987.4 987.8 987.8 773.7 778.0 785.9 947.0 980.0 576.7 579.1 728.5 732.9 733.8 14904.6 14930.9 14958.3 14961.0 14970.0 12542.6 12663.6 12737.5 12755.2 12904.5 9433.3 9496.2 9581.8 9673.6 10192.5 3340.2 3378.7 3460.9 3480.5 3491.2 74709.0 74955.9 75078.9 75830.0 75872.2 9219.7 9248.4 9254.4 9333.2 9516.0 20285.7 20311.5 20315.5 20321.2 20743.0 7469.6 7532.5 7715.8 7734.3 7834.2 4067.4 4106.6 4122.9 4149.8 4368.0 3016.5 3530.8 3592.1 3594.1 3596.9 531.8 638.2 646.2 651.9 656.4 514.1 558.8 567.8 590.6 595.9 7726.3 7823.4 7941.7 8059.8 8202.7 583.6 585.8 592.5 604.7 609.9 3876.8 3893.6 3917.6 3935.3 3944.9 93724.0 96770.0 97021.5 97124.5 97266.0 67582.3 71056.0 71387.0 71554.9 72581.0 69830.7 70052.6 70933.6 72444.9 72839.0 7539.3 7693.7 7893.7 7957.6 7990.4 48464.0 48747.0 49317.8 50602.7 52140.5 65047.7 68509.7 68995.6 69510.7 71078.6 9675.6 9729.7 9916.8 10074.9 10536.0 9810.3 10563.5 10695.0 10843.1 11027.3 9895.4 10052.9 10222.1 10406.4 10911.2 9878.5 10097.4 10277.2 10366.9 10501.6 9750.3 10097.2 10120.2 10352.6 10387.3 8763.9 8852.6 8970.1 9011.8 9024.0 8557.9 8673.1 8779.6 8879.4 8929.0 20:42:38 INFO - PROCESS | 1690 | -------- Summary: end -------- 20:42:38 INFO - PROCESS | 1690 | 20:42:39 INFO - TEST-INFO | 1690: exit 0 20:42:39 INFO - TEST-OK | dromaeo_css | took 1115237ms 20:42:39 INFO - TEST-START | kraken 20:42:39 INFO - operating with platform_type : mac_ 20:42:39 INFO - Initialising browser for kraken test... 20:42:39 INFO - TEST-INFO | started process 1696 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpvxsHMR/profile http://localhost:49280/getInfo.html) 20:42:41 INFO - PROCESS | 1696 | __metrics Screen width/height:1600/1200 20:42:41 INFO - PROCESS | 1696 | colorDepth:24 20:42:41 INFO - PROCESS | 1696 | Browser inner width/height: 1024/689 20:42:41 INFO - PROCESS | 1696 | __metrics 20:42:41 INFO - PROCESS | 1696 | 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" 20:42:41 ERROR - PROCESS | 1696 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:42:42 INFO - TEST-INFO | 1696: exit 0 20:42:42 INFO - Browser initialized. 20:42:42 INFO - Running cycle 1/1 for kraken test... 20:42:42 INFO - TEST-INFO | started process 1698 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/T/tmpvxsHMR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:42:53 INFO - PROCESS | 1698 | RSS: Main: 211623936 20:42:53 INFO - PROCESS | 1698 | 20:42:56 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 20:42:57 INFO - PROCESS | 1698 | RSS: Main: 272580608 20:42:57 INFO - PROCESS | 1698 | 20:43:00 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 20:43:01 INFO - PROCESS | 1698 | RSS: Main: 286691328 20:43:01 INFO - PROCESS | 1698 | 20:43:04 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 20:43:05 INFO - PROCESS | 1698 | RSS: Main: 373411840 20:43:05 INFO - PROCESS | 1698 | 20:43:07 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 20:43:07 INFO - PROCESS | 1698 | RSS: Main: 279953408 20:43:07 INFO - PROCESS | 1698 | 20:43:09 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:43:10 INFO - PROCESS | 1698 | RSS: Main: 280707072 20:43:10 INFO - PROCESS | 1698 | 20:43:13 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 20:43:13 INFO - PROCESS | 1698 | RSS: Main: 319066112 20:43:13 INFO - PROCESS | 1698 | 20:43:16 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 20:43:17 INFO - PROCESS | 1698 | RSS: Main: 319565824 20:43:17 INFO - PROCESS | 1698 | 20:43:20 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 20:43:20 INFO - PROCESS | 1698 | RSS: Main: 317505536 20:43:20 INFO - PROCESS | 1698 | 20:43:22 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:43:23 INFO - PROCESS | 1698 | RSS: Main: 310157312 20:43:23 INFO - PROCESS | 1698 | 20:43:24 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:43:25 INFO - PROCESS | 1698 | RSS: Main: 316637184 20:43:25 INFO - PROCESS | 1698 | 20:43:27 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:43:28 INFO - PROCESS | 1698 | RSS: Main: 319291392 20:43:28 INFO - PROCESS | 1698 | 20:43:31 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:43:31 INFO - PROCESS | 1698 | RSS: Main: 324599808 20:43:31 INFO - PROCESS | 1698 | 20:43:34 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:43:35 INFO - PROCESS | 1698 | RSS: Main: 322461696 20:43:35 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 20:43:37 INFO - PROCESS | 1698 | RSS: Main: 386424832 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | __start_tp_report 20:43:37 INFO - PROCESS | 1698 | _x_x_mozilla_page_load 20:43:37 INFO - PROCESS | 1698 | _x_x_mozilla_page_load_details 20:43:37 INFO - PROCESS | 1698 | |i|pagename|runs| 20:43:37 INFO - PROCESS | 1698 | |0;ai-astar;113;114;122;111;112;111;113;115;115;133 20:43:37 INFO - PROCESS | 1698 | |1;audio-beat-detection;162;148;175;166;123;142;117;159;188;147 20:43:37 INFO - PROCESS | 1698 | |2;audio-dft;184;186;186;185;210;259;196;182;182;184 20:43:37 INFO - PROCESS | 1698 | |3;audio-fft;71;78;73;149;61;80;84;92;154;55 20:43:37 INFO - PROCESS | 1698 | |4;audio-oscillator;93;92;143;77;78;87;120;79;77;122 20:43:37 INFO - PROCESS | 1698 | |5;imaging-gaussian-blur;87;85;88;89;86;83;87;83;86;84 20:43:37 INFO - PROCESS | 1698 | |6;imaging-darkroom;92;93;97;93;93;133;150;93;90;93 20:43:37 INFO - PROCESS | 1698 | |7;imaging-desaturate;83;85;84;85;85;85;84;86;84;86 20:43:37 INFO - PROCESS | 1698 | |8;json-parse-financial;61;63;64;63;63;65;65;84;57;58 20:43:37 INFO - PROCESS | 1698 | |9;json-stringify-tinderbox;63;65;73;67;74;72;68;70;68;67 20:43:37 INFO - PROCESS | 1698 | |10;stanford-crypto-aes;81;82;78;90;80;81;80;78;81;81 20:43:37 INFO - PROCESS | 1698 | |11;stanford-crypto-ccm;129;131;146;133;126;138;142;136;150;143 20:43:37 INFO - PROCESS | 1698 | |12;stanford-crypto-pbkdf2;166;165;165;164;182;159;150;151;187;200 20:43:37 INFO - PROCESS | 1698 | |13;stanford-crypto-sha256-iterative;69;67;82;83;98;57;62;98;96;93 20:43:37 INFO - PROCESS | 1698 | __end_tp_report 20:43:37 INFO - PROCESS | 1698 | __start_cc_report 20:43:37 INFO - PROCESS | 1698 | _x_x_mozilla_cycle_collect,1983 20:43:37 INFO - PROCESS | 1698 | __end_cc_report 20:43:37 INFO - PROCESS | 1698 | __startTimestamp1465098217390__endTimestamp 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | ------- Summary: start ------- 20:43:37 INFO - PROCESS | 1698 | Number of tests: 14 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#0] ai-astar Cycles:10 Average:115.90 Median:114.50 stddev:6.79 (5.9%) stddev-sans-first:7.12 20:43:37 INFO - PROCESS | 1698 | Values: 113.0 114.0 122.0 111.0 112.0 111.0 113.0 115.0 115.0 133.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#1] audio-beat-detection Cycles:10 Average:152.70 Median:160.50 stddev:22.04 (13.7%) stddev-sans-first:23.12 20:43:37 INFO - PROCESS | 1698 | Values: 162.0 148.0 175.0 166.0 123.0 142.0 117.0 159.0 188.0 147.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#2] audio-dft Cycles:10 Average:195.40 Median:186.00 stddev:23.95 (12.9%) stddev-sans-first:25.04 20:43:37 INFO - PROCESS | 1698 | Values: 184.0 186.0 186.0 185.0 210.0 259.0 196.0 182.0 182.0 184.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#3] audio-fft Cycles:10 Average:89.70 Median:75.50 stddev:34.28 (45.4%) stddev-sans-first:35.69 20:43:37 INFO - PROCESS | 1698 | Values: 71.0 78.0 73.0 149.0 61.0 80.0 84.0 92.0 154.0 55.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#4] audio-oscillator Cycles:10 Average:96.80 Median:78.50 stddev:23.32 (29.7%) stddev-sans-first:24.70 20:43:37 INFO - PROCESS | 1698 | Values: 93.0 92.0 143.0 77.0 78.0 87.0 120.0 79.0 77.0 122.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:86.50 stddev:2.04 (2.4%) stddev-sans-first:2.12 20:43:37 INFO - PROCESS | 1698 | Values: 87.0 85.0 88.0 89.0 86.0 83.0 87.0 83.0 86.0 84.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:93.00 stddev:20.91 (22.5%) stddev-sans-first:21.81 20:43:37 INFO - PROCESS | 1698 | Values: 92.0 93.0 97.0 93.0 93.0 133.0 150.0 93.0 90.0 93.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#7] imaging-desaturate Cycles:10 Average:84.70 Median:85.00 stddev:0.95 (1.1%) stddev-sans-first:0.78 20:43:37 INFO - PROCESS | 1698 | Values: 83.0 85.0 84.0 85.0 85.0 85.0 84.0 86.0 84.0 86.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:63.50 stddev:7.44 (11.7%) stddev-sans-first:7.79 20:43:37 INFO - PROCESS | 1698 | Values: 61.0 63.0 64.0 63.0 63.0 65.0 65.0 84.0 57.0 58.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#9] json-stringify-tinderbox Cycles:10 Average:68.70 Median:69.00 stddev:3.53 (5.1%) stddev-sans-first:3.08 20:43:37 INFO - PROCESS | 1698 | Values: 63.0 65.0 73.0 67.0 74.0 72.0 68.0 70.0 68.0 67.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:3.36 (4.1%) stddev-sans-first:3.56 20:43:37 INFO - PROCESS | 1698 | Values: 81.0 82.0 78.0 90.0 80.0 81.0 80.0 78.0 81.0 81.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#11] stanford-crypto-ccm Cycles:10 Average:137.40 Median:140.00 stddev:7.81 (5.6%) stddev-sans-first:7.66 20:43:37 INFO - PROCESS | 1698 | Values: 129.0 131.0 146.0 133.0 126.0 138.0 142.0 136.0 150.0 143.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:165.50 stddev:16.00 (9.7%) stddev-sans-first:16.94 20:43:37 INFO - PROCESS | 1698 | Values: 166.0 165.0 165.0 164.0 182.0 159.0 150.0 151.0 187.0 200.0 20:43:37 INFO - PROCESS | 1698 | 20:43:37 INFO - PROCESS | 1698 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.50 Median:88.00 stddev:15.73 (17.9%) stddev-sans-first:16.12 20:43:37 INFO - PROCESS | 1698 | Values: 69.0 67.0 82.0 83.0 98.0 57.0 62.0 98.0 96.0 93.0 20:43:37 INFO - PROCESS | 1698 | -------- Summary: end -------- 20:43:37 INFO - PROCESS | 1698 | 20:43:38 INFO - TEST-INFO | 1698: exit 0 20:43:38 INFO - TEST-OK | kraken | took 59016ms 20:43:38 INFO - SUITE-END | took 1174s 20:43:38 INFO - Completed test suite (00:19:34) 20:43:38 INFO - javascript benchmark 20:43:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3136.9529457058143, 3342.515335276384, 3343.966560334396, 3394.270231900141, 3408.744344174186, 4509.9549004509945, 4681.508441613629, 5302.522772950434, 5394.109971854649, 5455.672659640424, 5476.0, 5489.560835133189, 5514.724263786805, 5514.779408823642, 5517.44825517449, 4973.726445045521, 4991.32617096691, 5053.191489361703, 5095.057414378333, 5129.2306154077, 5036.370453693288, 5125.077486052509, 5129.948700512984, 5130.050940575987, 5134.794608215667, 5032.043911656791, 5057.544820966113, 5087.982403519292, 5103.311053007852, 5115.976804639087, 5151.381834179885, 5163.199704045879, 5166.302549155872, 5166.870828229286, 5167.948320516784, 4987.401511818605, 4996.350474438336, 5095.0574143783515, 5104.2088476286235, 5105.874741844791, 5377.7311134443125, 5430.294061771984, 5471.7264136793, 5471.72641367934, 5478.671279723189, 6080.756769729205, 6104.542159338067, 6115.602485838451, 6128.111423843562, 6129.356417576179, 5567.916481252784, 6004.3395226525045, 6131.529590167202, 6167.537434692371, 6171.475424588915, 5337.2794672719465, 5646.632968857011, 5671.120976248688, 5671.688057156896, 5701.458361455697, 5216.504432078947, 5707.857303567444, 6223.253209614875, 6246.656433896179, 6260.436560709558, 5720.370759216483, 5864.618799778001, 5989.221401217901, 6029.0052141397055, 6032.245969253843, 6163.84590385235, 6194.721237544321, 6197.597156184834, 6199.845003874849, 6214.347493513116, 6051.971164901977, 6094.781043791171, 6103.0, 6104.175936248638, 6132.313613756252, 5929.644221346733, 5976.551758618121, 6042.0, 6105.406439241515, 6121.849304493819, 4758.0, 4788.23388257884, 4789.592884604778, 4793.688410253322, 4795.136875362398, 3761.5486141663177, 4402.5597440255715, 4404.889877753082, 4418.425604671436, 4450.755208463565, 4931.260310953386, 5119.771254898872, 5568.860778480571, 5581.441855814467, 5598.468145526169, 6003.0695242237525, 6096.29892562361, 6145.692715364214, 6167.969160154171, 6201.782937597162, 5494.835154945278, 5511.145772405283, 5511.696856672841, 5516.641418307797, 5519.751611177427, 5188.948110518922, 5311.17676760095, 5327.893442131136, 5338.412774594792, 5344.839654810362, 6301.684915754194, 6315.305316415191, 6330.303666596578, 6333.651649159248, 6345.143405640272, 5978.103284507359, 6140.723667435065, 6146.231721034871, 6148.692565371624, 6152.169457123231, 6231.750729970931, 6261.0, 6335.746570137143, 6357.682115894094, 6366.904496432465, 5999.400059993965, 6040.879182416415, 6056.90914636272, 6064.666443345482, 6083.178770865877, 5990.520758339235, 6100.298465676504, 6160.9383906160365, 6167.167432396748, 6179.598326108789, 5299.258103865385, 5838.970805145947, 5840.357560668238, 5929.288485381781, 5955.196048533393, 4856.732879691651, 5872.823815285377, 5879.73541190656, 5889.941100589111, 5893.852653683693, 5595.692236926845, 5763.7406316715005, 5772.307323121252, 5779.913301300399, 5784.797532086357, 5827.737751801264, 5828.0966450200285, 5828.329742079715, 5868.061110222344, 5869.325027621708, 5707.629004114885, 5755.3093628763145, 5773.451522105393, 5775.1914731938405, 5842.211301474246], "value": 5528.963928487536, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [522.6759409166315, 540.2139886465194, 596.8328867916983, 597.4473611908983, 615.9076138579212, 27622.58566121507, 37604.247915041684, 38801.0, 38893.80553097238, 39009.60990390095, 42124.36813447793, 42688.573114268846, 42695.0, 42702.572974270246, 42717.58259339253, 8526.87209691855, 9428.952855235731, 9652.951735241313, 9667.5649595768, 9676.854847177297, 8587.0, 11637.767244655102, 11837.993770529516, 11843.0, 11847.170698051139, 11527.596534121307, 11645.126615503828, 11666.766664666702, 11675.24110932791, 11678.824817627743, 8654.87017694735, 8825.779355516164, 8844.425112367675, 11512.021478174365, 11620.186586938875, 8567.057623661454, 11499.367534785584, 11743.588974385855, 11753.764924701543, 11769.293842369485, 39173.216535669126, 39675.40486892699, 39706.20587588232, 39750.40374394386, 40021.79989100065, 3186.474231751758, 3240.578724765789, 3242.238074052596, 3263.479427504925, 3316.0217735768006, 27974.720252797415, 28552.00067997631, 28587.57118643222, 28764.993225237227, 29399.412011759858, 2722.060888993294, 2748.4365705030423, 2782.373965857686, 2783.9443211135867, 2815.5495120780784, 487319.56340218073, 489283.0, 490220.5488972568, 491457.0, 493433.0, 36256.0, 36812.079698007765, 51446.22830657543, 61774.69112654408, 62116.37883621197, 35851.0, 35889.8205508976, 35957.820210898775, 36004.0, 36145.45781813275, 15363.462278820187, 15497.0, 15520.137583489828, 15523.611909702122, 15524.922375388276, 12151.149419540725, 12416.81374779379, 13097.541586044443, 13203.603891883258, 13397.330133493286, 8181.754547363588, 8203.179682031869, 8204.712835050745, 8280.710175143962, 8295.792605184919, 2936.265933516621, 2954.8079374840568, 3397.11674964511, 3438.0889064397957, 3578.4453409721536, 1000.8148492528906, 1020.5101551255439, 1022.504085518523, 1095.917806164541, 1112.588342313333, 28228.576571351034, 28616.71383286182, 28834.0, 28923.0, 28998.710012900025, 980.7911847917748, 1125.386664267976, 1152.193464574801, 1162.7267592115845, 1167.5796713183247, 14770.630734231516, 14773.630659233606, 14778.63053423673, 15148.924255378653, 15196.240187990556, 651485.0, 656286.0, 657131.0, 658837.0, 661938.0, 62186.0, 62862.0, 62874.685626571576, 62941.68529157325, 63180.68409657922, 1431.6707157353867, 1490.9179995100158, 1514.4547962733407, 1526.5954522051886, 1529.6481809183726, 24097.75902240955, 28523.0, 29169.979050733124, 29637.25906852346, 29665.851670741507, 51047.74476127744, 51422.48577514177, 51512.22731658953, 51566.484335156165, 51730.22404663858, 509110.0, 513264.0, 513492.0, 513619.0, 516450.0, 23677.17129900445, 26635.00237482158, 27014.919403224438, 27327.86336068307, 27374.589381159698, 41668.791656041525, 42843.57156428396, 42854.78572607117, 42911.9272018202, 43351.34972975471, 35057.0, 35372.82313588416, 35403.468947966314, 36082.09794755152, 36103.27793444169, 29463.852680736458, 36358.45462318014, 36792.0, 37105.81447092747, 37117.515299388804, 36289.0, 36781.0, 36942.63057369392, 37509.81245093866, 37702.811485942395, 31270.90551830675, 31767.0, 32395.676043240208, 32923.01230963075, 33311.66688333086, 24608.0, 24922.750772492043, 25134.24597262085, 25212.0, 25480.362990925376], "value": 18660.240691063987, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.8494009925981, 401.444000059917, 403.50973567115955, 405.1875988642771, 407.8246354067753, 13462.93268533656, 16493.587660308498, 20516.28193013245, 21190.78809211909, 21195.258165964195, 15822.683546329066, 16219.189040547984, 16631.002139871616, 20307.49231269219, 20545.691814622754, 5705.2012718219485, 5779.190913272135, 6001.339852616209, 6002.219711437507, 6003.189569408118, 5936.554758393117, 5968.0, 5989.3112292086535, 5991.370906054845, 5999.040153575429, 4174.561671024544, 6291.087792270118, 6586.506012049092, 6590.835229119262, 6591.307912669148, 5606.775728970836, 6541.640209788459, 6551.868962620745, 6556.377144171296, 6561.015847622894, 5684.516816070639, 5689.857753556153, 5702.344230413472, 5708.885822283572, 5711.200431939532, 13355.599332020054, 13583.864161358457, 21239.8938005309, 24950.37624059395, 24957.00171993118, 1687.3503701075051, 2181.6618424144126, 2209.983407632491, 2320.454693147126, 2683.2218656589566, 9842.507874606312, 10187.0, 10204.183665306831, 10240.74398140045, 10681.572737090508, 1433.3478267388314, 1732.2897611979029, 1789.5347209725564, 1802.4052062819237, 1816.0375001249358, 110931.0, 113086.0, 116518.0, 116859.0, 117700.0, 9431.622735090657, 9449.952750236342, 11515.681619392728, 11706.94146529279, 11780.705482362839, 14997.850021499646, 15063.623409414853, 15072.623184420258, 15099.622509437351, 15106.0, 7551.358134558514, 8383.790405239917, 8708.129187081242, 8843.69047083349, 8862.42394244372, 6811.965940170367, 8706.259967902799, 8728.301735861116, 8772.736817895473, 8888.955555222183, 4504.81980720774, 4582.450105987236, 4590.1278757036725, 4639.675222734444, 4655.534446555385, 1890.2816929566677, 1891.7824450188405, 1943.1838627776367, 1950.775660798998, 1962.7939066398108, 747.7532414303375, 921.6175287255847, 963.9951800240954, 1005.522376870995, 1012.2863381316155, 19608.803911960982, 20430.284940027028, 20589.485262868548, 20610.969451527362, 20865.956702165135, 919.5540163020929, 989.1443901025705, 1055.672741450153, 1079.1690398393193, 1084.7938891610568, 10579.682609521727, 10684.626038088629, 10759.031687148194, 10837.729056773644, 10843.1867609928, 592692.0, 618185.0, 644224.0, 650420.9831606777, 655254.0, 123080.0, 123191.3840430792, 125571.0, 128330.0, 136892.0, 1056.2975621211808, 1068.2895874243695, 1092.3391348234204, 1092.792369449802, 1094.9069329106956, 17589.736153957958, 17747.46757597274, 17786.644267114843, 17892.821071789116, 18181.909085454914, 53414.465855340946, 54574.727126364116, 54949.0, 56019.0, 57467.0, 83096.0, 83136.33727325298, 83653.58173209096, 84450.0, 85329.720054198, 7883.408744344082, 8342.152027634098, 8359.414840961075, 8439.113893041142, 8704.434211776213, 11535.653930382103, 11598.304101753922, 12412.937935310265, 12450.0, 12824.87175128274, 10313.639022634172, 10936.890631093906, 11009.0, 11205.383703896365, 11210.943945280222, 10766.515506802369, 10848.403337816495, 10903.0, 10950.890491094988, 11039.0, 11131.833022504507, 11556.959873611415, 11566.479508421973, 11603.88396116028, 11685.357305348287, 8175.67297308101, 8216.753497395088, 8235.13531079252, 8283.710070147516, 8598.097199793932, 8491.660333586588, 10466.0, 10915.836262456229, 11051.231802911496, 11186.608468703882], "value": 9515.936788455108, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1418.2199790115435, 1556.6808804195139, 1575.8896877218597, 1800.3608718904788, 1941.999999999999, 7841.960790196048, 8472.576371181447, 8583.227509524142, 8587.828243435135, 8599.183077607633, 8378.581070946444, 8629.697960571366, 8641.308695304377, 8648.35137364697, 8656.523891185981, 2405.6992875890514, 2436.719777225624, 2437.4881274932286, 2443.0838435586566, 2443.7189723181796, 2400.6279026750794, 2431.2463136427764, 2449.8285120041514, 2458.950820983588, 2467.1241709193246, 2415.5531226723024, 2447.302518782147, 2458.8770561471943, 2466.70399552054, 2477.7770000699934, 2431.2827715823873, 2433.85396876188, 2443.963340549893, 2446.1683027770555, 2476.2571228631336, 2013.3054096336741, 2411.9758802412102, 2431.100492817669, 2436.2082323245018, 2443.059422122478, 8292.917070829275, 8589.098144694843, 8623.698170564001, 8633.568321583896, 8654.480731156087, 5202.479752024768, 5396.91904621431, 5409.702466364349, 5414.29614150158, 5415.810446634388, 3354.698077173042, 3378.966210337914, 3380.3239352129426, 3411.761176717631, 3438.914027149316, 1036.7667274863095, 1048.3185929146039, 1050.3907733514543, 1059.1315121600292, 1063.7925604507125, 11444.027257683107, 11476.024537914287, 11673.824892626617, 11684.065274778082, 11689.123315751267, 4501.864944051684, 4676.672632915731, 4677.929831052537, 4683.7892294846815, 4711.670183087151, 9050.637974481078, 9067.18395344409, 9107.817843643223, 9154.450732955911, 9179.541022948826, 5130.538251557377, 5303.655262407931, 5333.386660534011, 5343.064963631349, 5349.4650534946195, 4336.154449882242, 4439.22313595126, 4724.007958328721, 4817.494163112893, 4915.066137433876, 3981.243563722883, 4000.5199376075057, 4067.5525692173837, 4107.97946010274, 4116.608922152391, 1330.9800352994712, 1333.1068184316532, 1343.9664008399673, 1349.2376807104151, 1361.251311778518, 662.57595139111, 692.9514933954575, 920.6685593186447, 921.3412410126791, 969.4231932000448, 5582.944170558324, 5583.944160558423, 5619.129034999582, 5678.43481470028, 5717.142428635738, 497.7685376300038, 510.43596825508024, 510.56346823466146, 516.8216965147018, 565.6210339072832, 3884.4173373994126, 4035.5157381114454, 4052.878413647595, 4112.609302116294, 4113.341865301538, 10158.542865571068, 10361.430121343248, 10468.162546996222, 10496.265261431627, 10510.316829406063, 10207.234457415576, 10341.276110672332, 10342.931413717137, 10343.01741334572, 10356.844647330296, 8054.8791768122355, 8085.070216925012, 8138.3896207784655, 8147.429679922348, 8229.25936665703, 5809.0, 5829.15477255787, 5888.146218798336, 5939.732712028055, 6123.632582044914, 9298.256139508958, 9325.813483730422, 9476.194523465583, 9497.050294970448, 9538.427694338361, 9188.95405522968, 9482.336236463381, 9738.80522389534, 9819.919808821167, 9912.900870991487, 7265.200827909031, 7397.482176247826, 7468.663910124166, 7495.325420712162, 7509.849803004018, 8031.718889838828, 8036.437449378721, 8117.472364296302, 8118.59407029658, 8126.918730812617, 6182.0, 7176.892346614909, 7193.208747037717, 7386.48294619393, 7468.402527797654, 6932.202796678245, 7113.430925526049, 7130.928690713234, 7151.2848715128075, 7167.964160179165, 6706.765263215764, 6781.830454238486, 6793.048973143665, 6794.72821087151, 6801.931980680328, 4737.526247375373, 4760.238361862085, 4807.7836497357, 4900.191468407673, 4950.975245123751, 4829.7585120744525, 4867.758721525988, 4947.802087916443, 5015.473375295542, 5111.105556527667], "value": 4464.348761494497, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.0382849537141, 326.1471252674256, 330.55210190192287, 333.7630282499427, 336.1562478179768, 6498.790411260171, 6507.31673174317, 6527.543071984963, 6611.6694165291665, 6618.371254730793, 6539.901901471481, 6543.7055332510035, 6545.869082618345, 6556.0, 6572.507061970347, 4613.8465383654175, 4725.976370118145, 4729.0, 4730.148573256813, 4873.780679869415, 4808.807647694088, 4871.780769865347, 4880.658353915227, 4881.194602890519, 4902.068606964684, 4724.267738500521, 4845.248986407095, 4846.2376445426125, 4855.465898751135, 4860.4653488116455, 4609.539046095397, 4654.674172807905, 4784.210605250129, 4843.709377437347, 4844.491328410519, 4100.425940368351, 4106.096658735073, 4115.567865374122, 4126.133511962462, 4129.091599848029, 6188.938110618927, 6270.372962703739, 6270.529710271702, 6286.591371560834, 6293.181886354791, 4596.701214421052, 5708.885822283572, 5711.572106973256, 5714.342850572196, 5723.0, 6942.861142777117, 6951.2353641099435, 6953.930460695379, 6959.721611135548, 6970.268121847234, 4308.913821723548, 4442.977785111086, 4525.9547404525865, 4636.0, 4677.812887484497, 7278.890816637755, 7301.561906285639, 7362.190159082496, 7375.963120184419, 7391.593462359568, 4589.58693717567, 4589.770511474413, 4591.989762252299, 4592.793324300348, 4596.7012144211185, 4632.97683511587, 4646.97676511622, 4649.139909116824, 4668.183067963161, 4673.7429441381055, 5547.750351234205, 5547.944520554824, 5557.805476808292, 5558.610897237236, 5562.249096371938, 6766.18805743314, 6999.720011199597, 7012.193597736224, 7014.438844892396, 7035.401990830735, 1591.888567800266, 1605.9759103613455, 1614.5398561409943, 1615.9030458172313, 1621.1569983608458, 852.7825404521807, 1003.0571263012745, 1005.3716427232979, 1027.1833892055834, 1027.7173777211326, 6283.0, 6485.286618471964, 6516.283208847023, 6658.3674550917585, 6671.199456065303, 6383.265924418752, 6902.447804175654, 7314.707411703579, 7345.7428989986165, 7348.081489813773, 6632.9336706633285, 6640.900386494206, 6641.269460359356, 6644.468442524587, 6656.001599759978, 3809.8285577149095, 3840.116773142137, 3844.826982785781, 3848.6728628066367, 3850.7304488685522, 7309.269073092754, 7601.505902116345, 7603.809904752426, 7616.504927179715, 7618.352440042548, 7446.367058800062, 7486.85026299471, 7495.0, 7513.699452021967, 7537.2462753725285, 7579.772606821804, 7591.164971853092, 7591.316781489692, 7593.316601505891, 7593.468457207942, 6958.408535274559, 7254.383377412979, 7266.818329541804, 7274.96362518184, 7303.269673032654, 7153.3919616833155, 7177.677004534704, 7185.676644551112, 7188.784336469915, 7191.280871912767, 7593.05086864142, 7595.5442673439775, 7602.391808655184, 7602.961985190039, 7612.961935190288, 4601.309803529497, 4604.0791841631135, 4607.838725644587, 4611.79246933882, 4613.861584152481, 6995.300469952964, 7241.710331586678, 7244.818879528054, 7247.9637601811655, 7254.492185547172, 5647.576431767552, 6996.685149168401, 7003.124609423822, 7007.544509606859, 7043.436525078084, 6258.499320054476, 6420.06908998202, 6927.896081558882, 7009.684564194521, 7012.158540975116, 5530.529904958124, 6812.25065242813, 6818.215905170969, 6823.727050917908, 6829.351211634887, 6736.090627765189, 7007.089078419773, 7011.964940175267, 7012.298770123151, 7015.789526314219, 6598.90101648466, 6696.4977626679165, 6788.4569234462115, 6820.3861652451515, 6920.861582768417], "value": 5099.150251308475, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.4729605499821, 693.7155766135885, 734.14105496569, 758.9734359297422, 782.921707829217, 15015.249237538106, 15125.546233612995, 15317.463888763868, 15351.078935263891, 15366.154861482628, 13995.440182392693, 14061.578152655411, 14062.296885155727, 14073.92963035186, 14084.0, 635.4757325206723, 773.0182668011622, 915.3592485260311, 947.5262368815593, 948.0093302498871, 927.9164875161234, 939.7697564096806, 940.2900809888524, 942.7407462947675, 944.3153713557684, 946.8816397950256, 985.4776968206862, 987.3631507677544, 987.7975015121918, 987.8370068938616, 773.6518566645004, 778.0274656679151, 785.8703313953191, 946.9715908522721, 979.9510024498746, 576.7375843991016, 579.122629216737, 728.4936968806682, 732.9413646908288, 733.8238822682571, 14904.62738431548, 14930.850691493164, 14958.326875290639, 14960.952733308673, 14970.0, 12542.561010364594, 12663.620091397273, 12737.490500379974, 12755.170913890568, 12904.548340808027, 9433.339666223366, 9496.240300775922, 9581.760455988586, 9673.613055477772, 10192.490375481197, 3340.198352395432, 3378.712809411203, 3460.9480857787153, 3480.460528618068, 3491.1970246843116, 74709.0, 74955.87566186511, 75078.8738168928, 75830.0, 75872.24127758708, 9219.723408297761, 9248.445093294424, 9254.398464099811, 9333.160015598492, 9515.952420237854, 20285.695714564292, 20311.4922126948, 20315.492112697004, 20321.18715251403, 20743.0, 7469.551826890404, 7532.510386824839, 7715.845683086419, 7734.342580880688, 7834.1774113718375, 4067.430559721641, 4106.630403263661, 4122.855700050483, 4149.771762553089, 4368.0, 3016.4721173794496, 3530.8411121499594, 3592.137886907176, 3594.0835087052633, 3596.8561257549404, 531.7979167916244, 638.2086213095821, 646.1922596754057, 651.9250286217051, 656.3797211635055, 514.1311184098861, 558.8376177550684, 567.7700531284754, 590.5806877117209, 595.8897603943284, 7726.266004729654, 7823.413244006722, 7941.682332706742, 8059.838803223903, 8202.712905048415, 583.5623282538096, 585.841822707875, 592.520058752412, 604.7097393251177, 609.9420555047263, 3876.8255428505786, 3893.6301051400073, 3917.5690674025836, 3935.330993731073, 3944.9211015780097, 93724.0, 96770.0, 97021.51489242508, 97124.51437742767, 97266.0, 67582.3241767576, 71056.0, 71387.0, 71554.9266760999, 72581.0, 69830.65084674646, 70052.59894802075, 70933.645331773, 72444.91332630014, 72839.0, 7539.32146106853, 7693.692252309845, 7893.723719670013, 7957.602119893866, 7990.360771138178, 48464.0, 48747.0, 49317.75341123271, 50602.746986264836, 52140.478595213564, 65047.67476162778, 68509.65745171242, 68995.62008759897, 69510.65244673744, 71078.64460677664, 9675.564599592895, 9729.659461918798, 9916.75208119774, 10074.949625251827, 10536.0, 9810.264230182907, 10563.524641391003, 10695.0, 10843.132549396187, 11027.283226590245, 9895.406275623485, 10052.949735251277, 10222.080012798884, 10406.375617462976, 10911.181661375269, 9878.50607469638, 10097.394156350643, 10277.229207809294, 10366.896331036593, 10501.579936802442, 9750.317477776487, 10097.242706797166, 10120.240981926238, 10352.585896564053, 10387.324823886423, 8763.868541972002, 8852.601632926662, 8970.102989700978, 9011.774705632411, 9024.007359190353, 8557.914420855961, 8673.089325620718, 8779.561021949004, 8879.378443508893, 8928.955355223183], "value": 6692.555902472721, "unit": "score"}], "name": "dromaeo_css", "value": 7285.895497660981, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 122.0, 111.0, 112.0, 111.0, 113.0, 115.0, 115.0, 133.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 148.0, 175.0, 166.0, 123.0, 142.0, 117.0, 159.0, 188.0, 147.0], "value": 152.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 185.0, 210.0, 259.0, 196.0, 182.0, 182.0, 184.0], "value": 195.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 78.0, 73.0, 149.0, 61.0, 80.0, 84.0, 92.0, 154.0, 55.0], "value": 89.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 92.0, 143.0, 77.0, 78.0, 87.0, 120.0, 79.0, 77.0, 122.0], "value": 96.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 89.0, 86.0, 83.0, 87.0, 83.0, 86.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 97.0, 93.0, 93.0, 133.0, 150.0, 93.0, 90.0, 93.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 85.0, 85.0, 85.0, 84.0, 86.0, 84.0, 86.0], "value": 84.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 64.0, 63.0, 63.0, 65.0, 65.0, 84.0, 57.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 73.0, 67.0, 74.0, 72.0, 68.0, 70.0, 68.0, 67.0], "value": 68.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 78.0, 90.0, 80.0, 81.0, 80.0, 78.0, 81.0, 81.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 131.0, 146.0, 133.0, 126.0, 138.0, 142.0, 136.0, 150.0, 143.0], "value": 137.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 164.0, 182.0, 159.0, 150.0, 151.0, 187.0, 200.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 82.0, 83.0, 98.0, 57.0, 62.0, 98.0, 96.0, 93.0], "value": 80.5, "unit": "score"}], "name": "kraken", "value": 1524.7000000000003, "alertThreshold": 2.0}]} 20:43:38 INFO - Return code: 0 20:43:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:43:38 ERROR - # TBPL SUCCESS # 20:43:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:43:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:43:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:43:38 INFO - 2016-06-04 20:43:38.680 screenresolution[1702:21228] starting screenresolution argv=screenresolution get 20:43:38 INFO - 2016-06-04 20:43:38.684 screenresolution[1702:21228] Display 0: 1600x1200x32@60 20:43:38 INFO - 2016-06-04 20:43:38.690 screenresolution[1703:21231] starting screenresolution argv=screenresolution list 20:43:38 INFO - Available Modes on Display 0 20:43:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:43:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:43:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:43:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:43:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:43:38 INFO - Intel Iris: 20:43:38 INFO - Chipset Model: Intel Iris 20:43:38 INFO - Type: GPU 20:43:38 INFO - Bus: Built-In 20:43:38 INFO - VRAM (Dynamic, Max): 1536 MB 20:43:38 INFO - Vendor: Intel (0x8086) 20:43:38 INFO - Device ID: 0x0a2e 20:43:38 INFO - Revision ID: 0x0009 20:43:38 INFO - Displays: 20:43:38 INFO - Display: 20:43:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:43:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:43:38 INFO - Display Serial Number: 1600x1200 60 20:43:38 INFO - Main Display: Yes 20:43:38 INFO - Mirror: Off 20:43:38 INFO - Online: Yes 20:43:38 INFO - Rotation: Supported 20:43:38 INFO - Return code: 0 20:43:38 INFO - Running post-action listener: _resource_record_post_action 20:43:38 INFO - Running post-run listener: _resource_record_post_run 20:43:39 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 32038400; Write bytes: 651903488; Read time: 326; Write time: 1768 20:43:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347036160; Write bytes: 346507264; Read time: 26447; Write time: 644 20:43:39 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 31116800; Write bytes: 295434752; Read time: 313; Write time: 1095 20:43:39 INFO - Running post-run listener: _upload_blobber_files 20:43:39 INFO - Blob upload gear active. 20:43:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:43:39 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 20:43:39 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'] 20:43:39 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 20:43:40 INFO - (blobuploader) - INFO - Open directory for files ... 20:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:41 INFO - (blobuploader) - INFO - Done attempting. 20:43:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:43:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:43:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:43:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:43:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:43:42 INFO - (blobuploader) - INFO - Done attempting. 20:43:42 INFO - (blobuploader) - INFO - Iteration through files over. 20:43:42 INFO - Return code: 0 20:43:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:43:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:43:42 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef48060477615f34f70e0b41b29394f85a901e91103fb55985eeabb564aeaa18e337080c6513b9278af26d736356f55aede8a7c66883b1f48b8fe8645e8444f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc111a21c83450299e96209e84a75dcd675b51a74952845b81654027540cf507a338e31c491c5df6a54b308a7708ff756405347baa6885845e96292833f9a814"} 20:43:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:43:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:43:42 INFO - Contents: 20:43:42 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ef48060477615f34f70e0b41b29394f85a901e91103fb55985eeabb564aeaa18e337080c6513b9278af26d736356f55aede8a7c66883b1f48b8fe8645e8444f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc111a21c83450299e96209e84a75dcd675b51a74952845b81654027540cf507a338e31c491c5df6a54b308a7708ff756405347baa6885845e96292833f9a814"} 20:43:42 INFO - Running post-run listener: copy_logs_to_upload_dir 20:43:42 INFO - Copying logs to upload dir... 20:43:42 INFO - mkdir: /builds/slave/test/build/upload/logs 20:43:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1352.150849 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 32 secs) (at 2016-06-04 20:43:42.983248) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 20:43:42.986806) ========= 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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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/ef48060477615f34f70e0b41b29394f85a901e91103fb55985eeabb564aeaa18e337080c6513b9278af26d736356f55aede8a7c66883b1f48b8fe8645e8444f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc111a21c83450299e96209e84a75dcd675b51a74952845b81654027540cf507a338e31c491c5df6a54b308a7708ff756405347baa6885845e96292833f9a814"} build_url:https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011170 build_url: 'https://queue.taskcluster.net/v1/task/QJwrDD06Q3GPiuWwUIj40A/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/ef48060477615f34f70e0b41b29394f85a901e91103fb55985eeabb564aeaa18e337080c6513b9278af26d736356f55aede8a7c66883b1f48b8fe8645e8444f6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fc111a21c83450299e96209e84a75dcd675b51a74952845b81654027540cf507a338e31c491c5df6a54b308a7708ff756405347baa6885845e96292833f9a814"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-04 20:43:43.018921) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:43:43.019248) ========= 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.B4DgxE7NcV/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.Oku5jKUP7G/Listeners TMPDIR=/var/folders/_b/g35s3zjn6ljbjq1c44x2r50w00000w/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.005119 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-04 20:43:43.068272) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 20:43:43.068605) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-04 20:43:43.068960) ========= ========= Total master_lag: 0.28 =========