builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0249 starttime: 1465235286.35 results: success (0) buildid: 20160606053329 builduid: d7acf4330f0b47b6844d82d15649fe74 revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.349277) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.349689) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:06.349969) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.159614) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.159877) ========= 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.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-06 10:48:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.8M=0.001s 2016-06-06 10:48:07 (18.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.490484 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.672957) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.673280) ========= 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.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.026044 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:07.722816) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-06 10:48:07.723177) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --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 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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-06 10:48:07,805 truncating revision to first 12 chars 2016-06-06 10:48:07,805 Setting DEBUG logging. 2016-06-06 10:48:07,805 attempt 1/10 2016-06-06 10:48:07,805 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5c8f37e61ac7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 10:48:09,703 unpacking tar archive at: mozilla-aurora-5c8f37e61ac7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.175970 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-06-06 10:48:09.924283) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:09.924871) ========= script_repo_revision: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:09.925512) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:09.926010) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 10:48:09.944635) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-06 10:48:09.945230) ========= /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.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:10 INFO - MultiFileLogger online at 20160606 10:48:10 in /builds/slave/test 10:48: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 10:48:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:48:10 INFO - {'append_to_log': False, 10:48:10 INFO - 'base_work_dir': '/builds/slave/test', 10:48:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 10:48:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:48:10 INFO - 'branch': 'Mozilla-Aurora', 10:48:10 INFO - 'buildbot_json_path': 'buildprops.json', 10:48:10 INFO - 'config_files': ('talos/mac_config.py',), 10:48:10 INFO - 'default_actions': ('clobber', 10:48:10 INFO - 'read-buildbot-config', 10:48:10 INFO - 'download-and-extract', 10:48:10 INFO - 'populate-webroot', 10:48:10 INFO - 'create-virtualenv', 10:48:10 INFO - 'install', 10:48:10 INFO - 'run-tests'), 10:48:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:48:10 INFO - 'download_minidump_stackwalk': True, 10:48:10 INFO - 'download_symbols': 'ondemand', 10:48:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:48:10 INFO - 'tooltool.py': '/tools/tooltool.py', 10:48:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:48:10 INFO - '/tools/misc-python/virtualenv.py')}, 10:48:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:48:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:48:10 INFO - 'installer_path': 'installer.exe', 10:48:10 INFO - 'log_level': 'info', 10:48:10 INFO - 'log_name': 'talos', 10:48:10 INFO - 'log_to_console': True, 10:48:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:48:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:48:10 INFO - 'opt_config_files': (), 10:48:10 INFO - 'pip_index': False, 10:48:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:10 INFO - 'cmd': ('bash', 10:48:10 INFO - '-c', 10:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:10 INFO - 'enabled': True, 10:48:10 INFO - 'halt_on_failure': False, 10:48:10 INFO - 'name': 'check_screen_resolution'},), 10:48:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:48:10 INFO - 'cmd': ('bash', 10:48:10 INFO - '-c', 10:48:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:48:10 INFO - 'enabled': True, 10:48:10 INFO - 'halt_on_failure': False, 10:48:10 INFO - 'name': 'check_screen_resolution'},), 10:48:10 INFO - 'run_cmd_checks_enabled': True, 10:48:10 INFO - 'sps_profile': False, 10:48:10 INFO - 'sps_profile_interval': 0, 10:48:10 INFO - 'suite': 'dromaeojs', 10:48:10 INFO - 'system_bits': '32', 10:48:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:48:10 INFO - 'title': 't-yosemite-r7-0249', 10:48:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:48:10 INFO - 'use_talos_json': True, 10:48:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:48:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:48:10 INFO - 'work_dir': 'build'} 10:48:10 INFO - ##### 10:48:10 INFO - ##### Running clobber step. 10:48:10 INFO - ##### 10:48:10 INFO - Running pre-action listener: _resource_record_pre_action 10:48:10 INFO - Running main action method: clobber 10:48:10 INFO - rmtree: /builds/slave/test/build 10:48:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:48:11 INFO - Running post-action listener: _resource_record_post_action 10:48:11 INFO - ##### 10:48:11 INFO - ##### Running read-buildbot-config step. 10:48:11 INFO - ##### 10:48:11 INFO - Running pre-action listener: _resource_record_pre_action 10:48:11 INFO - Running main action method: read_buildbot_config 10:48:11 INFO - Using buildbot properties: 10:48:11 INFO - { 10:48:11 INFO - "project": "", 10:48:11 INFO - "product": "firefox", 10:48:11 INFO - "script_repo_revision": "production", 10:48:11 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 10:48:11 INFO - "repository": "", 10:48:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 10:48:11 INFO - "buildid": "20160606053329", 10:48:11 INFO - "pgo_build": "False", 10:48:11 INFO - "basedir": "/builds/slave/test", 10:48:11 INFO - "buildnumber": 33, 10:48:11 INFO - "slavename": "t-yosemite-r7-0249", 10:48:11 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 10:48:11 INFO - "platform": "yosemite_r7", 10:48:11 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 10:48:11 INFO - "branch": "mozilla-aurora", 10:48:11 INFO - "repo_path": "releases/mozilla-aurora", 10:48:11 INFO - "revision": "5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64", 10:48:11 INFO - "stage_platform": "macosx64", 10:48:11 INFO - "builduid": "d7acf4330f0b47b6844d82d15649fe74", 10:48:11 INFO - "slavebuilddir": "test" 10:48:11 INFO - } 10:48:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 10:48:11 INFO - Running post-action listener: _resource_record_post_action 10:48:11 INFO - ##### 10:48:11 INFO - ##### Running download-and-extract step. 10:48:11 INFO - ##### 10:48:11 INFO - Running pre-action listener: _resource_record_pre_action 10:48:11 INFO - Running main action method: download_and_extract 10:48:11 INFO - mkdir: /builds/slave/test/build/tests 10:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:11 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:48:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:15 INFO - Downloaded 1199 bytes. 10:48:15 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 10:48:15 INFO - Using the following test package requirements: 10:48:15 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:15 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 10:48:15 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 10:48:15 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'jsshell-mac.zip'], 10:48:15 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 10:48:15 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 10:48:15 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 10:48:15 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 10:48:15 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 10:48:15 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 10:48:15 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 10:48:15 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 10:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:15 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:18 INFO - Downloaded 18466753 bytes. 10:48:18 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'] 10:48:18 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 10:48:21 INFO - Return code: 0 10:48:21 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 10:48:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:21 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:48:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 10:48:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:31 INFO - Downloaded 18466753 bytes. 10:48:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:48:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:48:34 INFO - Return code: 0 10:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:34 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:48:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 10:48:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:35 INFO - Downloaded 10840145 bytes. 10:48:35 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'] 10:48:35 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 10:48:35 INFO - Return code: 0 10:48:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:35 INFO - https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 10:48:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 10:48:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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 10:48:38 INFO - Downloaded 114175355 bytes. 10:48:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:38 INFO - mkdir: /builds/slave/test/properties 10:48:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:48:38 INFO - Writing to file /builds/slave/test/properties/build_url 10:48:38 INFO - Contents: 10:48:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 10:48:39 INFO - Running post-action listener: _resource_record_post_action 10:48:39 INFO - Running post-action listener: set_extra_try_arguments 10:48:39 INFO - ##### 10:48:39 INFO - ##### Running populate-webroot step. 10:48:39 INFO - ##### 10:48:39 INFO - Running pre-action listener: _resource_record_pre_action 10:48:39 INFO - Running main action method: populate_webroot 10:48:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:48:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:48:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:48:39 INFO - '--tppagecycles', 10:48:39 INFO - '7'], 10:48:39 INFO - 'tests': ['tsvgm']}}, 10:48:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:48:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:48:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:48:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:48:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:48:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['damp', 'tps']}, 10:48:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'tests': ['damp', 'tps']}, 10:48:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['dromaeo_dom']}, 10:48:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:48:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'other-e10s': {'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['a11yr', 10:48:39 INFO - 'ts_paint', 10:48:39 INFO - 'tpaint', 10:48:39 INFO - 'sessionrestore', 10:48:39 INFO - 'sessionrestore_no_auto_restore', 10:48:39 INFO - 'tabpaint']}, 10:48:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['tsvgx', 10:48:39 INFO - 'tsvgr_opacity', 10:48:39 INFO - 'tart', 10:48:39 INFO - 'tscrollx', 10:48:39 INFO - 'cart']}, 10:48:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:48:39 INFO - 'tsvgr_opacity', 10:48:39 INFO - 'tart', 10:48:39 INFO - 'tscrollx', 10:48:39 INFO - 'cart']}, 10:48:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'talos_options': ['--disable-e10s'], 10:48:39 INFO - 'tests': ['tp5o']}, 10:48:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'tests': ['tp5o']}, 10:48:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'talos_options': ['--disable-e10s', 10:48:39 INFO - '--xperf_path', 10:48:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:39 INFO - 'tests': ['tp5n']}, 10:48:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:48:39 INFO - 'talos_options': ['--xperf_path', 10:48:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:48:39 INFO - 'tests': ['tp5n']}}, 10:48:39 INFO - 'talos.zip': {'path': '', 10:48:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:48:39 INFO - Running post-action listener: _resource_record_post_action 10:48:39 INFO - ##### 10:48:39 INFO - ##### Running create-virtualenv step. 10:48:39 INFO - ##### 10:48:39 INFO - Running pre-action listener: _resource_record_pre_action 10:48:39 INFO - Running main action method: create_virtualenv 10:48:39 INFO - Creating virtualenv /builds/slave/test/build/venv 10:48:39 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 10:48:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:48:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:48:40 INFO - Using real prefix '/tools/python27' 10:48:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:48:40 INFO - Installing distribute.............................................................................................................................................................................................done. 10:48:43 INFO - Installing pip.................done. 10:48:43 INFO - Return code: 0 10:48:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:43 INFO - 'HOME': '/Users/cltbld', 10:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:43 INFO - 'LOGNAME': 'cltbld', 10:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:43 INFO - 'MOZ_NO_REMOTE': '1', 10:48:43 INFO - 'NO_EM_RESTART': '1', 10:48:43 INFO - 'PAGER': '/bin/cat', 10:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:43 INFO - 'PWD': '/builds/slave/test', 10:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:43 INFO - 'SHELL': '/bin/bash', 10:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:48:43 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:43 INFO - 'USER': 'cltbld', 10:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:43 INFO - 'XPC_FLAGS': '0x0', 10:48:43 INFO - 'XPC_SERVICE_NAME': '0', 10:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:43 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 10:48:43 INFO - Cleaning up... 10:48:43 INFO - Return code: 0 10:48:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 10:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 10:48:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 10:48:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:48:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:43 INFO - 'HOME': '/Users/cltbld', 10:48:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:43 INFO - 'LOGNAME': 'cltbld', 10:48:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:43 INFO - 'MOZ_NO_REMOTE': '1', 10:48:43 INFO - 'NO_EM_RESTART': '1', 10:48:43 INFO - 'PAGER': '/bin/cat', 10:48:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:43 INFO - 'PWD': '/builds/slave/test', 10:48:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:43 INFO - 'SHELL': '/bin/bash', 10:48:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:48:43 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:48:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:43 INFO - 'USER': 'cltbld', 10:48:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:43 INFO - 'XPC_FLAGS': '0x0', 10:48:43 INFO - 'XPC_SERVICE_NAME': '0', 10:48:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:44 INFO - Downloading/unpacking psutil>=0.7.1 10:48:44 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 10:48:44 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 10:48:44 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 10:48:44 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 10:48:44 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 10:48:44 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 10:48:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:48:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:47 INFO - Installing collected packages: psutil 10:48:47 INFO - Running setup.py install for psutil 10:48:47 INFO - building 'psutil._psutil_osx' extension 10:48:47 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 10:48:47 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 10:48:47 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 10:48:47 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 10:48:47 INFO - building 'psutil._psutil_posix' extension 10:48:47 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 10:48:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:48:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:48:48 INFO - ^ 10:48:48 INFO - 1 warning generated. 10:48:48 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 10:48:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:48:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:48:48 INFO - Successfully installed psutil 10:48:48 INFO - Cleaning up... 10:48:48 INFO - Return code: 0 10:48:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 10:48:48 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')]} 10:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:48 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')]} 10:48:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:48 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:48 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 10:48:48 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 10:48:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:48:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:48 INFO - 'HOME': '/Users/cltbld', 10:48:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:48 INFO - 'LOGNAME': 'cltbld', 10:48:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:48 INFO - 'MOZ_NO_REMOTE': '1', 10:48:48 INFO - 'NO_EM_RESTART': '1', 10:48:48 INFO - 'PAGER': '/bin/cat', 10:48:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:48 INFO - 'PWD': '/builds/slave/test', 10:48:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:48 INFO - 'SHELL': '/bin/bash', 10:48:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:48:48 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:48:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:48 INFO - 'USER': 'cltbld', 10:48:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:48 INFO - 'XPC_FLAGS': '0x0', 10:48:48 INFO - 'XPC_SERVICE_NAME': '0', 10:48:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 10:48:48 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 10:48:48 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 10:48:48 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 10:48:48 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 10:48:48 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 10:48:48 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 10:48:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 10:48:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:48:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 10:48:51 INFO - Installing collected packages: mozsystemmonitor 10:48:51 INFO - Running setup.py install for mozsystemmonitor 10:48:51 INFO - Successfully installed mozsystemmonitor 10:48:51 INFO - Cleaning up... 10:48:51 INFO - Return code: 0 10:48:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:48: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')]} 10:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48: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')]} 10:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:51 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:51 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 10:48:51 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 10:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:51 INFO - 'HOME': '/Users/cltbld', 10:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:51 INFO - 'LOGNAME': 'cltbld', 10:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:51 INFO - 'MOZ_NO_REMOTE': '1', 10:48:51 INFO - 'NO_EM_RESTART': '1', 10:48:51 INFO - 'PAGER': '/bin/cat', 10:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:51 INFO - 'PWD': '/builds/slave/test', 10:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:51 INFO - 'SHELL': '/bin/bash', 10:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:48:51 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:51 INFO - 'USER': 'cltbld', 10:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:51 INFO - 'XPC_FLAGS': '0x0', 10:48:51 INFO - 'XPC_SERVICE_NAME': '0', 10:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:48:51 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 10:48:51 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 10:48:51 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 10:48:51 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 10:48:51 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 10:48:51 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 10:48:54 INFO - Downloading blobuploader-1.2.4.tar.gz 10:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:48:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:48:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:54 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 10:48:55 INFO - Downloading docopt-0.6.1.tar.gz 10:48:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:48:55 INFO - Installing collected packages: blobuploader, requests, docopt 10:48:55 INFO - Running setup.py install for blobuploader 10:48:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:48:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:48:55 INFO - Running setup.py install for requests 10:48:56 INFO - Running setup.py install for docopt 10:48:56 INFO - Successfully installed blobuploader requests docopt 10:48:56 INFO - Cleaning up... 10:48:56 INFO - Return code: 0 10:48:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:48:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:48:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:48:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:48:56 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:48:56 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 10:48:56 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 10:48:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:48:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:48:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:48:56 INFO - 'HOME': '/Users/cltbld', 10:48:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:48:56 INFO - 'LOGNAME': 'cltbld', 10:48:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:48:56 INFO - 'MOZ_NO_REMOTE': '1', 10:48:56 INFO - 'NO_EM_RESTART': '1', 10:48:56 INFO - 'PAGER': '/bin/cat', 10:48:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:48:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:48:56 INFO - 'PWD': '/builds/slave/test', 10:48:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:48:56 INFO - 'SHELL': '/bin/bash', 10:48:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:48:56 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:48:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:48:56 INFO - 'USER': 'cltbld', 10:48:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:48:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:48:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:48:56 INFO - 'XPC_FLAGS': '0x0', 10:48:56 INFO - 'XPC_SERVICE_NAME': '0', 10:48:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:48:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:48:56 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UbFMgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:48:56 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-gfXMx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:48:56 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-GwdCil-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:48:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:48:56 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-kpAQqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-vlTfiX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-S8EKgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4THUEK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Mtkk8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2wRGiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-Z5lj21-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-_6_wnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-H2sOa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:48:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:48:57 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-rYQ2po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:48:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-bi8aby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-OTDWZt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:48:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-M1IIJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:48:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:48:58 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-EfosPh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:48:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:48:58 INFO - Running setup.py install for manifestparser 10:48:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:48:58 INFO - Running setup.py install for mozcrash 10:48:58 INFO - Running setup.py install for mozdebug 10:48:58 INFO - Running setup.py install for mozdevice 10:48:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Running setup.py install for mozfile 10:48:59 INFO - Running setup.py install for mozhttpd 10:48:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Running setup.py install for mozinfo 10:48:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Running setup.py install for mozInstall 10:48:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Running setup.py install for mozleak 10:48:59 INFO - Running setup.py install for mozlog 10:48:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:48:59 INFO - Running setup.py install for moznetwork 10:49:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Running setup.py install for mozprocess 10:49:00 INFO - Running setup.py install for mozprofile 10:49:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Running setup.py install for mozrunner 10:49:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Running setup.py install for mozscreenshot 10:49:00 INFO - Running setup.py install for moztest 10:49:00 INFO - Running setup.py install for mozversion 10:49:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:49:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:49:00 INFO - Cleaning up... 10:49:01 INFO - Return code: 0 10:49:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:01 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:49:01 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 10:49:01 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 10:49:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:49:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:01 INFO - 'HOME': '/Users/cltbld', 10:49:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:01 INFO - 'LOGNAME': 'cltbld', 10:49:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:01 INFO - 'MOZ_NO_REMOTE': '1', 10:49:01 INFO - 'NO_EM_RESTART': '1', 10:49:01 INFO - 'PAGER': '/bin/cat', 10:49:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:01 INFO - 'PWD': '/builds/slave/test', 10:49:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:01 INFO - 'SHELL': '/bin/bash', 10:49:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:49:01 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:49:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:01 INFO - 'USER': 'cltbld', 10:49:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:01 INFO - 'XPC_FLAGS': '0x0', 10:49:01 INFO - 'XPC_SERVICE_NAME': '0', 10:49:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-UJ1FpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-J3jwB1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-xQel0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-OFz9qD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-A7C7DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-3jYXdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:49:01 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)) 10:49:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:49:01 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-zpDs2Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-EM_Fkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-b_CiUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-MMgaKV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-4pgDfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-BzsdAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-FequDk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-mFCQmo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-kipegb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-R4dVZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:49:02 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)) 10:49:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:49:02 INFO - Running setup.py (path:/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/pip-2ywEpj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 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)) 10:49:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:05 INFO - Downloading blessings-1.6.tar.gz 10:49:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:49:05 INFO - Installing collected packages: blessings 10:49:05 INFO - Running setup.py install for blessings 10:49:05 INFO - Successfully installed blessings 10:49:05 INFO - Cleaning up... 10:49:05 INFO - Return code: 0 10:49:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:49:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:06 INFO - Reading from file tmpfile_stdout 10:49:06 INFO - Current package versions: 10:49:06 INFO - blessings == 1.6 10:49:06 INFO - blobuploader == 1.2.4 10:49:06 INFO - docopt == 0.6.1 10:49:06 INFO - manifestparser == 1.1 10:49:06 INFO - mozInstall == 1.12 10:49:06 INFO - mozcrash == 0.17 10:49:06 INFO - mozdebug == 0.1 10:49:06 INFO - mozdevice == 0.48 10:49:06 INFO - mozfile == 1.2 10:49:06 INFO - mozhttpd == 0.7 10:49:06 INFO - mozinfo == 0.9 10:49:06 INFO - mozleak == 0.1 10:49:06 INFO - mozlog == 3.1 10:49:06 INFO - moznetwork == 0.27 10:49:06 INFO - mozprocess == 0.22 10:49:06 INFO - mozprofile == 0.28 10:49:06 INFO - mozrunner == 6.11 10:49:06 INFO - mozscreenshot == 0.1 10:49:06 INFO - mozsystemmonitor == 0.0 10:49:06 INFO - moztest == 0.7 10:49:06 INFO - mozversion == 1.4 10:49:06 INFO - psutil == 3.1.1 10:49:06 INFO - requests == 1.2.3 10:49:06 INFO - wsgiref == 0.1.2 10:49:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:49:06 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')]} 10:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:06 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')]} 10:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:06 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:49:06 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 10:49:06 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 10:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:06 INFO - 'HOME': '/Users/cltbld', 10:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:06 INFO - 'LOGNAME': 'cltbld', 10:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:06 INFO - 'MOZ_NO_REMOTE': '1', 10:49:06 INFO - 'NO_EM_RESTART': '1', 10:49:06 INFO - 'PAGER': '/bin/cat', 10:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:06 INFO - 'PWD': '/builds/slave/test', 10:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:06 INFO - 'SHELL': '/bin/bash', 10:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:49:06 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:06 INFO - 'USER': 'cltbld', 10:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:06 INFO - 'XPC_FLAGS': '0x0', 10:49:06 INFO - 'XPC_SERVICE_NAME': '0', 10:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 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)) 10:49:06 INFO - Cleaning up... 10:49:06 INFO - Return code: 0 10:49:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:49:06 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')]} 10:49:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:06 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')]} 10:49:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:06 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:49:06 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 10:49:06 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 10:49:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:49:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:06 INFO - 'HOME': '/Users/cltbld', 10:49:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:06 INFO - 'LOGNAME': 'cltbld', 10:49:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:06 INFO - 'MOZ_NO_REMOTE': '1', 10:49:06 INFO - 'NO_EM_RESTART': '1', 10:49:06 INFO - 'PAGER': '/bin/cat', 10:49:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:06 INFO - 'PWD': '/builds/slave/test', 10:49:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:06 INFO - 'SHELL': '/bin/bash', 10:49:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:49:06 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:49:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:06 INFO - 'USER': 'cltbld', 10:49:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:06 INFO - 'XPC_FLAGS': '0x0', 10:49:06 INFO - 'XPC_SERVICE_NAME': '0', 10:49:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:06 INFO - Downloading/unpacking jsonschema 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:06 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 10:49:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:49:09 INFO - Downloading/unpacking functools32 (from jsonschema) 10:49:09 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 10:49:09 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 10:49:09 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 10:49:09 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 10:49:09 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 10:49:09 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 10:49:09 INFO - Downloading functools32-3.2.3-2.tar.gz 10:49:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:49:09 INFO - warning: no files found matching '*.txt' 10:49:09 INFO - no previously-included directories found matching 'build' 10:49:09 INFO - no previously-included directories found matching 'dist' 10:49:09 INFO - no previously-included directories found matching '.git*' 10:49:09 INFO - Installing collected packages: jsonschema, functools32 10:49:09 INFO - Running setup.py install for functools32 10:49:09 INFO - warning: no files found matching '*.txt' 10:49:09 INFO - no previously-included directories found matching 'build' 10:49:09 INFO - no previously-included directories found matching 'dist' 10:49:09 INFO - no previously-included directories found matching '.git*' 10:49:09 INFO - Successfully installed jsonschema functools32 10:49:09 INFO - Cleaning up... 10:49:09 INFO - Return code: 0 10:49:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 10:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:49:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:49:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:49:09 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 0x10a8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a902420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f973b4bce70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, '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.rYO10uCzpv/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.XaaBgwDm03/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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 10:49:09 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 10:49:09 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 10:49:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:49:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:09 INFO - 'HOME': '/Users/cltbld', 10:49:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:09 INFO - 'LOGNAME': 'cltbld', 10:49:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:09 INFO - 'MOZ_NO_REMOTE': '1', 10:49:09 INFO - 'NO_EM_RESTART': '1', 10:49:09 INFO - 'PAGER': '/bin/cat', 10:49:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:09 INFO - 'PWD': '/builds/slave/test', 10:49:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:09 INFO - 'SHELL': '/bin/bash', 10:49:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:49:09 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:49:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:09 INFO - 'USER': 'cltbld', 10:49:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:09 INFO - 'XPC_FLAGS': '0x0', 10:49:09 INFO - 'XPC_SERVICE_NAME': '0', 10:49:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:49:10 INFO - Downloading/unpacking flake8 10:49: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 10:49: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 10:49: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 10:49: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 10:49: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 10:49: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 10:49:12 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 10:49:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 10:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:49:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:49:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:49:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 10:49:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 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 10:49:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 10:49:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:13 INFO - no previously-included directories found matching 'docs/_build' 10:49:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 10:49:14 INFO - Running setup.py install for pep8 10:49:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 10:49:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 10:49:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 10:49:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 10:49:14 INFO - no previously-included directories found matching 'docs/_build' 10:49:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 10:49:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 10:49:14 INFO - Cleaning up... 10:49:14 INFO - Return code: 0 10:49:14 INFO - Running post-action listener: _resource_record_post_action 10:49:14 INFO - Running post-action listener: _start_resource_monitoring 10:49:14 INFO - Starting resource monitoring. 10:49:14 INFO - ##### 10:49:14 INFO - ##### Running install step. 10:49:14 INFO - ##### 10:49:14 INFO - Running pre-action listener: _resource_record_pre_action 10:49:14 INFO - Running main action method: install 10:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:49:14 INFO - Reading from file tmpfile_stdout 10:49:14 INFO - Detecting whether we're running mozinstall >=1.0... 10:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:49:14 INFO - Reading from file tmpfile_stdout 10:49:14 INFO - Output received: 10:49:14 INFO - Usage: mozinstall [options] installer 10:49:14 INFO - Options: 10:49:14 INFO - -h, --help show this help message and exit 10:49:14 INFO - -d DEST, --destination=DEST 10:49:14 INFO - Directory to install application into. [default: 10:49:14 INFO - "/builds/slave/test"] 10:49:14 INFO - --app=APP Application being installed. [default: firefox] 10:49:14 INFO - mkdir: /builds/slave/test/build/application 10:49:14 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'] 10:49:14 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 10:49:46 INFO - Reading from file tmpfile_stdout 10:49:46 INFO - Output received: 10:49:46 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:49:46 INFO - Running post-action listener: _resource_record_post_action 10:49:46 INFO - ##### 10:49:46 INFO - ##### Running run-tests step. 10:49:46 INFO - ##### 10:49:46 INFO - Running pre-action listener: _resource_record_pre_action 10:49:46 INFO - Running main action method: run_tests 10:49:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:49:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:49:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:49:46 INFO - 2016-06-06 10:49:46.534 screenresolution[1644:7947] starting screenresolution argv=screenresolution get 10:49:46 INFO - 2016-06-06 10:49:46.537 screenresolution[1644:7947] Display 0: 1600x1200x32@60 10:49:46 INFO - 2016-06-06 10:49:46.543 screenresolution[1645:7951] starting screenresolution argv=screenresolution list 10:49:46 INFO - Available Modes on Display 0 10:49:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:49:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:49:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:49:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:49:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:49:46 INFO - Intel Iris: 10:49:46 INFO - Chipset Model: Intel Iris 10:49:46 INFO - Type: GPU 10:49:46 INFO - Bus: Built-In 10:49:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:49:46 INFO - Vendor: Intel (0x8086) 10:49:46 INFO - Device ID: 0x0a2e 10:49:46 INFO - Revision ID: 0x0009 10:49:46 INFO - Displays: 10:49:46 INFO - Display: 10:49:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:49:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:49:46 INFO - Display Serial Number: 1600x1200 60 10:49:46 INFO - Main Display: Yes 10:49:46 INFO - Mirror: Off 10:49:46 INFO - Online: Yes 10:49:46 INFO - Rotation: Supported 10:49:46 INFO - Return code: 0 10:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:49:46 INFO - Python 2.7.3 10:49:46 INFO - Return code: 0 10:49:46 INFO - grabbing minidump binary from tooltool 10:49:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:49:46 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 0x10a791e00>, '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 0x10a5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a78cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:49:46 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 10:49:46 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 10:49:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:49:46 INFO - Return code: 0 10:49:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:49:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:49:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:49:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:49:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:49:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 10:49:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 10:49:47 INFO - Return code: 0 10:49:47 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/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 10:49:47 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/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0249 --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 10:49:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.rYO10uCzpv/Render', 10:49:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:49:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:49:47 INFO - 'HOME': '/Users/cltbld', 10:49:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:49:47 INFO - 'LOGNAME': 'cltbld', 10:49:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:49:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:49:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:49:47 INFO - 'MOZ_NO_REMOTE': '1', 10:49:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:49:47 INFO - 'NO_EM_RESTART': '1', 10:49:47 INFO - 'PAGER': '/bin/cat', 10:49:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:49:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:49:47 INFO - 'PWD': '/builds/slave/test', 10:49:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:49:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:49:47 INFO - 'SHELL': '/bin/bash', 10:49:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XaaBgwDm03/Listeners', 10:49:47 INFO - 'TMPDIR': '/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/', 10:49:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:49:47 INFO - 'USER': 'cltbld', 10:49:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:49:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:49:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:49:47 INFO - 'XPC_FLAGS': '0x0', 10:49:47 INFO - 'XPC_SERVICE_NAME': '0', 10:49:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:49:47 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/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0249', '--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 10:49:48 INFO - mozversion application_buildid: 20160606053329 10:49:48 INFO - mozversion application_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:48 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:49:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:49:48 INFO - mozversion application_name: Firefox 10:49:48 INFO - mozversion application_remotingname: firefox-dev 10:49:48 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:48 INFO - mozversion application_vendor: Mozilla 10:49:48 INFO - mozversion application_version: 48.0a2 10:49:48 INFO - mozversion platform_buildid: 20160606053329 10:49:48 INFO - mozversion platform_changeset: 5c8f37e61ac7af86accd5d86f6c9d10cd0eadf64 10:49:48 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:49:48 INFO - mozversion platform_version: 48.0a2 10:49:48 INFO - using testdate: 1465235388 10:49:48 INFO - actual date: 1465235388 10:49:48 INFO - starting webserver on 'localhost:49270' 10:49:48 INFO - SUITE-START | Running 2 tests 10:49:48 INFO - TEST-START | dromaeo_css 10:49:48 INFO - operating with platform_type : mac_ 10:49:48 INFO - Initialising browser for dromaeo_css test... 10:49:48 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmp9XiWrc/profile http://localhost:49270/getInfo.html) 10:49:50 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 10:49:50 INFO - PROCESS | 1663 | colorDepth:24 10:49:50 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 10:49:50 INFO - PROCESS | 1663 | __metrics 10:49:50 INFO - PROCESS | 1663 | 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" 10:49:50 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:49:51 INFO - TEST-INFO | 1663: exit 0 10:49:51 INFO - Browser initialized. 10:49:51 INFO - Running cycle 1/1 for dromaeo_css test... 10:49:51 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmp9XiWrc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:50:03 INFO - PROCESS | 1665 | RSS: Main: 211628032 10:50:03 INFO - PROCESS | 1665 | 10:50:54 INFO - PROCESS | 1665 | 1465235454900 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] 10:53:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:53:06 INFO - PROCESS | 1665 | RSS: Main: 335048704 10:53:06 INFO - PROCESS | 1665 | 10:56:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:56:09 INFO - PROCESS | 1665 | RSS: Main: 355119104 10:56:09 INFO - PROCESS | 1665 | 10:59:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:59:12 INFO - PROCESS | 1665 | RSS: Main: 359047168 10:59:12 INFO - PROCESS | 1665 | 11:02:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 11:02:15 INFO - PROCESS | 1665 | RSS: Main: 353411072 11:02:15 INFO - PROCESS | 1665 | 11:05:18 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 11:05:19 INFO - PROCESS | 1665 | RSS: Main: 319381504 11:05:19 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 11:08:22 INFO - PROCESS | 1665 | RSS: Main: 365080576 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | __start_tp_report 11:08:22 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 11:08:22 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 11:08:22 INFO - PROCESS | 1665 | |i|pagename|runs| 11:08:22 INFO - PROCESS | 1665 | |0;dojo.html;3150.716435520803;3462.0306314232016;3477.6174620791726;3479.478078288258;3540.3627347077518;5685.772569097237;5712.771489140435;5731.111677689964;5738.454846789548;5770.3075630924295;5737.168110623957;5769.653820770756;5780.421957804228;5780.682062486572;5794.101914203295;5184.455632158626;5210.19242017486;5231.372235331746;5294.821924477488;5387.353517577876;5232.8691782705355;5269.235960785683;5272.156454967187;5278.841634750964;5280.973595132019;5249.212618107276;5275.947240527603;5293.814716484924;5310.973445132769;5326.7070311132875;5272.973635131819;5316.069687804618;5325;5326.573874090063;5354.518093371616;5145.228215767627;5149.2791009258735;5177;5262.131748261532;5263.315768950012;5616.410276920946;5626.662400255997;5634.154876768476;5639.915401268984;5642.097264437671;6221.191245138149;6255.968720156416;6259.593126446812;6263.31103578606;6273.717682704244;6189.473894718909;6211.937880621226;6306.243250809886;6307.905381419282;6309.085182648536;5382.757775900094;5461.634591352202;5868.735906884201;5887.322957859901;5900.380460051719;6304.936950630527;6305.369463053658;6314.55798094129;6315;6318.336574659595;6075;6137.754489820447;6186.10301506279;6201.720922558496;6211.844703882349;6227.501799856001;6235.657038862815;6254.186955695731;6257.405546473078;6260.217472815898;6127.816165515042;6194.442500175006;6195.845103872349;6208.286047104641;6216.316205217422;6120.846978825476;6155.569110162335;6170;6170.197874276315;6224.751009959641;4861.368022157097;4926.827561035347;4927.704337739747;4954.256861470736;4987.177115775857;3538.929221415609;4419.204543182258;4429.667774916894;4436.866893993185;4474.284114541658;5786.247787787561;5788.421157884178;5803.448672376117;5824.88350232993;5841.7371218295275;6108.2813625860135;6161.507079433634;6248.218972628421;6256.124142620037;6265.498760099181;5712.34308054579;5758.884822303614;5762.510186634099;5771.365149833515;5782.681952492653;5321.946780532146;5420.945790542044;5436.891262174734;5446.346438427414;5449.073657478239;6279.937200627935;6304.432601065926;6326.9683651581445;6351.2696039956;6367.968160159169;6252.562320637512;6256.249250090018;6285.49716022708;6290.0879372491645;6303.558750887394;6353.650549219838;6356.491480681627;6356.872862542816;6365.554411191358;6370.617762934239;5099.311592935054;6087.299960504599;6119.472088496418;6180.134781130735;6235.906461402992;6200.193974783249;6279.968600157153;6290.370962903673;6292.993121100602;6300.117983482408;6172.783952561639;6199.597026193283;6200.906986395298;6205.720742566686;6210.596311239755;6032.125341825498;6107.633541987495;6108.144859719731;6185.752569897334;6207.751689932352;5004.624653150955;6006;6043.335233124265;6058.878822423615;6080.939190608215;6143.201383820075;6153.476954458921;6160.7535698571555;6161.383861613803;6178.41305076017;5185.170372740344;5812.21535092757;5883.529317654663;5956.106584012259;6029.698515074317 11:08:22 INFO - PROCESS | 1665 | |1;ext.html;537.8601563593465;538.4507802041917;575.5050656435467;592.4460629311594;593.4955288005197;29795;31108;38495.23009539811;38527.229455410874;38626;43430.13139737203;44155.55844441555;44189;44195.11609767803;44266.77866610671;9566.617335306597;9785.951070244639;9791.461469619167;9795.314327997043;9797.216222702167;8591.613377398033;11467.025302849268;11659.533618655243;11665.766684666301;11743.7064073398;10312.123469505064;10378.221633377489;11343.752187259395;11414;11827.40862956853;8527.488350698977;8697.173768491983;11644.767104657902;11755.882441175607;11780.528778848835;11543.653690389301;11729.413529323585;11730.237534560227;11739.354335511543;11764.823527647091;38029;38354;38537.807310963544;38603.61396386028;38652.80673596642;3199.168216263765;3252.6909943555324;3320.468725004012;3416.0776590320474;3535.381308271042;26660.46679066408;27719.445611087664;27809;27970.3007424814;28143.296417589518;2750.3949131191107;2753.3529620539157;2767.239009272446;2797.1049264235453;2802.971970280312;462702;467859;468647;469668.65165674296;477711;35976.46035309473;60265.79468410607;60792.696036519534;60919.781604367665;61049;29647.703522964926;35697.64302356995;35699.28601427957;35816.283674326885;35837.56649734033;15619.297131629104;15818.288177032062;15820.683586328209;15844.366225350857;15849.841501585068;11598.94200528992;11799.88200118005;13285.269310188032;13329.333533323295;13346.46614135443;8088.757337279891;8088.797780055428;8113.107558168596;8175.223353781381;8180.918190818015;3458.4985177149674;3479.4342545854465;3499.562554680665;3507.3862074137433;3531.4702794581017;1026.993546324591;1034.5551412892528;1128.6219116596033;1131.2081542919989;1141.8915203055697;29387.706122938496;29927.102186934426;29994.700052999626;30577.69422305793;30627.08118756441;1055.5302890213934;1135.8920902514249;1159.4898244772442;1169.029705344562;1297.6626077219992;15016.774748378783;15172.468963586221;15238.085714856923;15437.614059648375;15532.68934621324;599469;601416;610724.5710171632;610760;613907;59971.400285996584;60229.698851506335;60235;60292.698536507036;60298.698506507186;1301.9283939383236;1432.8638779315947;1512.311898086368;1533.7699345097878;1546.5051183621133;28876.711232887403;29629.851850740608;29653.40693186081;30485.69514304917;30599.929002485696;49103.754481228796;49749.75125124351;49947;49968.750156248985;49973;550970;568430;568623;568835;570000;41066;42334.941626459586;44034;44116.33825492684;44134.33798493089;46176.53823461723;46421;47502.76248618873;47632.2855157166;48070.5192948066;37236.06909827168;37403.2519349606;38188.04529886775;38242.2351552973;38528.80735596304;36902.270809091315;37642.43536347012;37730.24539509249;38159;38319.23361532698;37051.44422833714;37516.62483375131;37697.81151094227;37776.433353500266;38134.42798357971;31942.201444964063;33739.662603373654;33851.984440466826;33895.491567626996;34007.82996085004;24364.87817560901;25176.74823251744;25247.18624441631;25433.74566254388;25645.096472992693 11:08:22 INFO - PROCESS | 1665 | |2;jquery.html;347.8660715624487;399.57844474079843;401.3718530499768;402.9053172504461;414.64547811621065;15090.924545377286;15289.311980960856;20525.794742052592;21203;21210.6818397724;19805;20331.79668203321;20534.69197962032;20822.479438014056;21309.68035479469;5844.152597873316;5898.8230353089475;6035;6050.42520960508;6061.212042434478;5746.48281654651;5841.299044114691;6023.096535519663;6051.75792968283;6052.364501727321;5961.433663801932;6070;6079.848003799896;6084.026555751081;6085.695715214244;5762.596618236725;5897.115432685088;5924.052151655715;5939.910901336461;5982.611130276518;5220.269162317279;5220.817271395483;5223.2165175223645;5224.085784987612;5577.358603760579;13666.658333541642;13837;13897.957653176052;13899.235542045184;13899.79150312746;1622.3186261769968;1914.8372388347007;2183.465051062484;2243.1363924888874;2409.951800963971;9851.704448866545;10072.093511583993;10749.731256718645;11397.772044559146;15140.848591514054;1755.9122043897753;1776.2645525890243;1786.767720196379;1821.6720990221754;1855.1744473709205;71391.89548087072;106013.87972240512;118099.4095029528;123429.38285308606;124573.9999999991;9683.273754468442;11459.541618335339;11808.46861891217;11834.230774999598;11924.821127683092;15053.322600483005;15087.622809429851;15103.018303810217;15109.39562417513;15122.848771512145;9026.781384513006;9050.511109444824;9077.137671921157;9173.58718857653;9198.49408282551;9142.542872856464;9160.679376221933;9167.312451566158;9231.261499080058;9239.953800231091;4615.100055489214;4669.953300466952;4687.835925742583;4697.647676424282;4705.858824235278;1947.2989723699457;1991.6614175590184;1992.0935974132026;1997.211101614854;2006.8494862885343;875.711015364928;916.3127654259306;955.044955044955;994.2692121290845;1001.2090448545713;19381.709274360896;19766.70349944752;20687.482812929797;20747.68878466824;20914.16343346249;972.8394814855469;982.921366290695;1018.6485662446444;1043.4052590023614;1046.863907691995;8926.285897128215;10924.508397122148;11035.39305338198;11049.447527623586;11094.889051109547;570956;581890;589089.0545547245;600699;601390;120760;121846;122489.16266256013;123416.38291808484;128811.35594321968;1014.0467960117466;1019.0726438940528;1050.6532844161409;1064.632701717906;1065.7122576904192;14864.702705945605;17755.556111097325;17920.462386128438;18035.008074556026;18697.878127312404;51315;52600.73699631477;52775.20837187368;52955;53128.203076953105;71451.28548714446;72022.55954880978;73318.26681733114;73672.63163684147;75058.62470687611;6845.383915447634;8252.21603947624;8271;8303.83392332138;8314.168583141638;8725;11417.771644566896;12420.565280215149;12828.358582070672;13006.869931300944;10887.618933337295;11036.834447483134;11072.778544428906;11137.944310278397;11534.596289130175;8866.33502487329;10803.513841877291;11028.779424411307;11380.715482113013;11458.713532161762;11085.27945683528;11208.215424920178;11275.605353812578;11782.52869885195;11787.646370608896;8288.792780180544;8289.171082891662;8365.163483651586;8438.578071096543;8454.66181352739;8461.153884611736;9489.620415183315;10578.153747700011;10892.727681808019;11447.141464390037 11:08:22 INFO - PROCESS | 1665 | |3;mootools.html;1460.9926950365248;1591.2282542966664;1618.6277156254057;1902.2295970132102;2011.3463124484538;7723.266289702477;8329.666813327467;8495.10801365857;8509.659613615464;8534.829303413928;8250.463719858206;8495.660173593064;8521.91478085219;8533.061363250037;8536.27441667459;2439.1219161102035;2469.814763892706;2471.023945541519;2471.752824717532;2472.196536125766;2048.682454219598;2399.940001499959;2440.719317278518;2478.5414698280874;2479.1446950801947;2443.7067551893797;2461.1509029384833;2478.677771889652;2482.8262021658493;2487.5398051360467;2434.440078781872;2458.2379462366634;2465.3959779853963;2471.8269721119527;2476.7151777545632;2122.0981083039646;2415.1667674652217;2445.8410203336725;2448.2532827487607;2451.301379106964;8282.71010514629;8525.360597955178;8577.742667719978;8581.656733730642;8588.613512391958;5294.68231906083;5433.646812957185;5438.6736795791985;5444.754986025639;5446.754896029688;2970.3910698306795;3399.388110140174;3454.4127498325347;3483.251101013269;3512.7892326460237;1044.955044955045;1067.0876400677387;1085.4355735017825;1523.1622607565905;1615.9030458172547;11217.046551043171;11289.0968722502;11295.661130166109;11315.943420282845;11340.716482087932;4490.101979604092;4499.505054444008;4587.17430862448;4587.357769912215;4623.8844028898875;9023.503707296031;9065.274778017743;9066.546672666473;9073.77315567116;9081.046490118575;5288.629795914249;5304.628675992643;5309.548688361532;5310.990911726837;5314.335708036496;4250.893727656833;4318.35224716295;4327.242732521796;4351;4938.925916111261;3207.0859804955476;3761.097336639242;3864.671502922227;3872.612738726105;3878.670313023368;1225.5710501324643;1228.502456505117;1228.8832560906699;1231.8090695942144;1237.1587320621884;858.6865793985276;914.3517365710766;940.3652534539159;940.8823897012874;990.4107056301489;5595.356533998561;5679.119736440858;5821.446962538552;5828.0966450200285;5869.765209391577;956.2159029595804;972.9124378805941;1001.9098281154731;1005.1606908231709;1013.9087482126644;3587.192881601671;3773.7924414156937;3785.905352366158;3857.787821669779;3930.292547341506;10111.241656875764;10439.277519209398;10520.526576304084;10576.153907687367;10600.681979540626;10213.591456341663;10230.335028223142;10332.948335258427;10344.482775861177;10350.896491035144;7659;7893.723719669784;7901;7913.723019694398;7995.120536740954;5342.332208473941;5588.189712491585;5619;5640.210370548045;5687.914681279867;9038.638454461749;9157.31320150978;9290.58192381331;9310.95344523273;9311.906880931376;8937.955310223668;9248.075192480697;9294.977552469358;9376.624935002796;9384.81230375375;6617.933820661924;7204.603746793977;7271.600061996641;7289.45329100309;7300.12398512182;7419.554826710415;7785.455018148676;7948.046234451903;8024.478408903403;8041.959790201012;6007.519398448027;7189.424846012202;7239.529430586995;7264.491485596169;7327.303906129123;7131.750388736369;7152.320529549685;7221.927780722336;7227.963860180876;7275;5754.6547207167705;6578.111954886221;6710.765123220664;6786.966065169642;6805.9659701701175;4691.624670026458;4699.671023028355;4797.160496913096;4800.927986080142;4818.71087734737;4784.712917224839;4797.9040419192115;4880.92678609814;4883.780229889735;5049.5960323174795 11:08:22 INFO - PROCESS | 1665 | |4;prototype.html;316.8431626344959;322.22183427023737;326.57871345963713;329.60118256909124;331.61367007436337;6475.183295266563;6552.165393689775;6553.180852393451;6568.948968165095;6580.572262802926;5675.637846916741;5982.371850955652;6489.935100648992;6502.707378167982;6527.804165875032;4583.793729282182;4699.318598803172;4712.246040633499;4806.158922188603;4865.051314993578;3907.4529565860803;4816.879578010543;4878.585320247783;4880.536349046835;4889.315495830587;3885.4754608127964;4832.405306248929;4839.903201935959;4844.636652251095;4847.830325938593;4545.590896819285;4650.558196971282;4666.999999999983;4675.906481870377;4677.836275730368;3998.420229066769;4086.979565102185;4113.424120623115;4120.072983578671;4121.567235440296;5106.821261255838;6119.969400152971;6132.926737820863;6195.81412557624;6211.534134939898;5636.267285252932;5638.126090455986;5649.378568357477;5650.378458369576;5656.095024796054;5707.343655479632;6699.727051860131;6827.897581536281;6856.485763567753;6882.69027893746;4469.195544801934;4608.400907881954;4620.884477888046;4631.652626053025;4643.582077613031;5772.9711351442975;6956.304369563054;7056.435485161175;7198.640067996632;7239.239879812597;4712.986707857827;4720.362751028636;4720.669553131316;4741.170295198326;4744.691595046311;4833.347498087714;4834.975825120922;4839.758012099381;4840.128776820206;4850.6362022848425;5737.913931291034;5740.655560666373;5743.08110702294;5745;5749.108888122264;5957.761689532457;6608.4052435281055;6649.700763465656;6847.452203823682;6968.372846443743;1346.5421756602802;1660.6429617632266;1693.5427434592837;1695.906725130105;1696.058687428489;1078.0836289154156;1081.081081081075;1095.9835602465969;1097.7200813792592;1104.7624760676492;6208.72060757267;6359.777407790705;6429.453496452854;6434.324395938359;6440.774572889926;5785.623934444247;5800.274965629296;7022.578645281299;7155.92844071563;7172.0676312079095;6497.415232629086;6540.313267106981;6542.901856472156;6544.247411547638;6559.737610495622;3778.6032466591164;3835.386338185877;3839.1170030892495;3841.558220804643;3848;7315.853682926312;7566.205548417447;7614.6954121835615;7635.580043097571;7648.235176482307;7446.7021319146625;7519.661615227328;7552.169261381244;7566.886496702554;7567.545947243183;6726.8654626908165;7249.513849660807;7663.463557550918;7697.960775295375;7719.382449404035;5941.851453713692;7307.3423391894985;7312.5246858954;7325.890111648436;7329.780106596811;6827.180738311435;7141.82145446368;7154.141503019671;7198.20819709821;7199.1361036675935;7562.054743157106;7605.353544948742;7632.771016869503;7644.235576442311;7668.309852113337;4553.06662134255;4605.677602567788;4607.14767767964;4612.7001744886475;4614.861554153381;5846.619969701956;5869.53043756507;7258.600776957318;7267.563946163247;7288.198298187089;5900.7049647518315;6710.02444987775;6868.450523958171;6990.23107458169;7015.36861682451;6915.51591388598;6958.756443524452;6979.651017449006;7013.509054366145;7052.0479735235085;6815.931840681728;6845.794626161223;6851.828704282433;6873.725050998104;6882.070920425678;6942.305769423017;6945.965270173617;6955.026296318419;6961.721531138698;6965.407940324926;6511.055896894829;6596.109525214227;6736.02327662496;6791.388775010273;6832 11:08:22 INFO - PROCESS | 1665 | |5;yui.html;704.2394214248612;742.9442791790615;743.5092838726441;756.2248695087532;779.4426984705935;15036.398544058238;15223.771643425358;15246.847531524681;15268.007579507339;16803;16126.596835079128;16203.83796162038;16211.594710132253;16227.594310142253;16247.83752162478;758.7723682895137;926.9768255793608;938.8028514012032;939.4128669581512;947.9052094790535;762.6606160258688;920.7467946314783;940.2196177172964;941.6092321686492;943.1888575824785;999.6201443451479;1001.7095042437684;1002.4787110702467;1002.9949850250739;1004.4575928998332;776.5107981971379;970.9126178643955;996.8305388084007;999.0758548342753;999.6850991937553;739.5784402890355;739.6708464733196;739.9593022383767;741.0625558668289;741.8850078237882;15233.923830380778;15338.846611533854;15349.539513814603;15362.155081470515;15365.923170384189;12151.939240303742;12223.43307905341;12464.314462704639;12547.372631368486;12582.937085314516;10112.595496180144;10114.494275286279;10143.949280253551;10466.895331046668;10509.474526273656;3430.125318043908;3442.4492081267126;3493.056874643832;3510.1224693826543;3569.607343192247;85858;86290.56854715686;87674.56162719146;88146.55926720389;88286;9184.678536251333;9187.127222913812;9274.536273186313;9304.767380815398;9392.812143757086;21468.892655536623;21528.81591512482;21547.784522154892;21593.24423645165;21612.675809862863;7412.592307423036;7491.213422590658;7519.849603008019;7723.729669461654;7735.9613201933635;4040.696947728932;4065.5527891931865;4112.712110152321;4171.728837625545;4217.704760666786;3530.7881527108457;3594.8562057517406;3595.26297109092;3601.0097223263288;3611.494390785292;651.3356376496042;660.2769966886252;665.122038908636;665.9001149827565;676.2088356622763;552.9391766905637;589.6255877517807;598.8143675460622;600.7116584039685;607.5898768331359;7047.753328633473;8091.352691784643;8225.917740822515;8262.8347433051;8336.583170841433;484.3171128708504;540.5405405405374;570.5179123640518;577.2421535801876;604.7006731667852;3109.284864481182;3722.7766334020043;3871.399933010388;3902.414637804352;4010.7794071326357;83613.74579381314;84739.57630211933;85628.57185714031;85926.14073859307;88207.11792882117;73212.63393682998;73512.26487735161;75135.24864751392;75140;75903;67830.66084669545;67877.85465744043;68476.6576167106;68768.65615671889;71070;8209.835803283782;8340.332773377993;8427.115152909102;8508.574571271289;8517.701880434155;46493;47437.52562474331;48617;48763;49051.26423103585;68323;68551.65724171547;68866;69116.96324555035;69533;10583.947080264858;10886.741006226055;11036.613718519813;11237.55049797999;11301.321920684784;11560;11808.11439142085;11864.347460889734;11886.583969561023;12232.571859984857;8836.229740129895;10686.89313106859;11304;11443.942780286045;11501.48243329069;11119.555217791198;11325.490352933974;11372.943135284271;11429.257098288585;12043.578474753342;10701.411422371844;10903.400312982862;11048.06091482233;11410.973012428922;11413.942930285295;9493.620255189715;9533.761655958657;9589.42463452195;9812.509374531388;9970.601175952881;8193;9587;9728.289401998962;9743;9802.313838031268 11:08:22 INFO - PROCESS | 1665 | __end_tp_report 11:08:22 INFO - PROCESS | 1665 | __start_cc_report 11:08:22 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1093 11:08:22 INFO - PROCESS | 1665 | __end_cc_report 11:08:22 INFO - PROCESS | 1665 | __startTimestamp1465236502391__endTimestamp 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | ------- Summary: start ------- 11:08:22 INFO - PROCESS | 1665 | Number of tests: 6 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5753.03 Median:6029.70 stddev:651.96 (10.8%) stddev-sans-first:621.18 11:08:22 INFO - PROCESS | 1665 | Values: 3150.7 3462.0 3477.6 3479.5 3540.4 5685.8 5712.8 5731.1 5738.5 5770.3 5737.2 5769.7 5780.4 5780.7 5794.1 5184.5 5210.2 5231.4 5294.8 5387.4 5232.9 5269.2 5272.2 5278.8 5281.0 5249.2 5275.9 5293.8 5311.0 5326.7 5273.0 5316.1 5325.0 5326.6 5354.5 5145.2 5149.3 5177.0 5262.1 5263.3 5616.4 5626.7 5634.2 5639.9 5642.1 6221.2 6256.0 6259.6 6263.3 6273.7 6189.5 6211.9 6306.2 6307.9 6309.1 5382.8 5461.6 5868.7 5887.3 5900.4 6304.9 6305.4 6314.6 6315.0 6318.3 6075.0 6137.8 6186.1 6201.7 6211.8 6227.5 6235.7 6254.2 6257.4 6260.2 6127.8 6194.4 6195.8 6208.3 6216.3 6120.8 6155.6 6170.0 6170.2 6224.8 4861.4 4926.8 4927.7 4954.3 4987.2 3538.9 4419.2 4429.7 4436.9 4474.3 5786.2 5788.4 5803.4 5824.9 5841.7 6108.3 6161.5 6248.2 6256.1 6265.5 5712.3 5758.9 5762.5 5771.4 5782.7 5321.9 5420.9 5436.9 5446.3 5449.1 6279.9 6304.4 6327.0 6351.3 6368.0 6252.6 6256.2 6285.5 6290.1 6303.6 6353.7 6356.5 6356.9 6365.6 6370.6 5099.3 6087.3 6119.5 6180.1 6235.9 6200.2 6280.0 6290.4 6293.0 6300.1 6172.8 6199.6 6200.9 6205.7 6210.6 6032.1 6107.6 6108.1 6185.8 6207.8 5004.6 6006.0 6043.3 6058.9 6080.9 6143.2 6153.5 6160.8 6161.4 6178.4 5185.2 5812.2 5883.5 5956.1 6029.7 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:67491.50 Median:35698.46 stddev:147037.86 (411.9%) stddev-sans-first:147364.41 11:08:22 INFO - PROCESS | 1665 | Values: 537.9 538.5 575.5 592.4 593.5 29795.0 31108.0 38495.2 38527.2 38626.0 43430.1 44155.6 44189.0 44195.1 44266.8 9566.6 9786.0 9791.5 9795.3 9797.2 8591.6 11467.0 11659.5 11665.8 11743.7 10312.1 10378.2 11343.8 11414.0 11827.4 8527.5 8697.2 11644.8 11755.9 11780.5 11543.7 11729.4 11730.2 11739.4 11764.8 38029.0 38354.0 38537.8 38603.6 38652.8 3199.2 3252.7 3320.5 3416.1 3535.4 26660.5 27719.4 27809.0 27970.3 28143.3 2750.4 2753.4 2767.2 2797.1 2803.0 462702.0 467859.0 468647.0 469668.7 477711.0 35976.5 60265.8 60792.7 60919.8 61049.0 29647.7 35697.6 35699.3 35816.3 35837.6 15619.3 15818.3 15820.7 15844.4 15849.8 11598.9 11799.9 13285.3 13329.3 13346.5 8088.8 8088.8 8113.1 8175.2 8180.9 3458.5 3479.4 3499.6 3507.4 3531.5 1027.0 1034.6 1128.6 1131.2 1141.9 29387.7 29927.1 29994.7 30577.7 30627.1 1055.5 1135.9 1159.5 1169.0 1297.7 15016.8 15172.5 15238.1 15437.6 15532.7 599469.0 601416.0 610724.6 610760.0 613907.0 59971.4 60229.7 60235.0 60292.7 60298.7 1301.9 1432.9 1512.3 1533.8 1546.5 28876.7 29629.9 29653.4 30485.7 30599.9 49103.8 49749.8 49947.0 49968.8 49973.0 550970.0 568430.0 568623.0 568835.0 570000.0 41066.0 42334.9 44034.0 44116.3 44134.3 46176.5 46421.0 47502.8 47632.3 48070.5 37236.1 37403.3 38188.0 38242.2 38528.8 36902.3 37642.4 37730.2 38159.0 38319.2 37051.4 37516.6 37697.8 37776.4 38134.4 31942.2 33739.7 33852.0 33895.5 34007.8 24364.9 25176.7 25247.2 25433.7 25645.1 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:33856.16 Median:21206.84 stddev:98102.73 (462.6%) stddev-sans-first:98345.67 11:08:22 INFO - PROCESS | 1665 | Values: 347.9 399.6 401.4 402.9 414.6 15090.9 15289.3 20525.8 21203.0 21210.7 19805.0 20331.8 20534.7 20822.5 21309.7 5844.2 5898.8 6035.0 6050.4 6061.2 5746.5 5841.3 6023.1 6051.8 6052.4 5961.4 6070.0 6079.8 6084.0 6085.7 5762.6 5897.1 5924.1 5939.9 5982.6 5220.3 5220.8 5223.2 5224.1 5577.4 13666.7 13837.0 13898.0 13899.2 13899.8 1622.3 1914.8 2183.5 2243.1 2410.0 9851.7 10072.1 10749.7 11397.8 15140.8 1755.9 1776.3 1786.8 1821.7 1855.2 71391.9 106013.9 118099.4 123429.4 124574.0 9683.3 11459.5 11808.5 11834.2 11924.8 15053.3 15087.6 15103.0 15109.4 15122.8 9026.8 9050.5 9077.1 9173.6 9198.5 9142.5 9160.7 9167.3 9231.3 9240.0 4615.1 4670.0 4687.8 4697.6 4705.9 1947.3 1991.7 1992.1 1997.2 2006.8 875.7 916.3 955.0 994.3 1001.2 19381.7 19766.7 20687.5 20747.7 20914.2 972.8 982.9 1018.6 1043.4 1046.9 8926.3 10924.5 11035.4 11049.4 11094.9 570956.0 581890.0 589089.1 600699.0 601390.0 120760.0 121846.0 122489.2 123416.4 128811.4 1014.0 1019.1 1050.7 1064.6 1065.7 14864.7 17755.6 17920.5 18035.0 18697.9 51315.0 52600.7 52775.2 52955.0 53128.2 71451.3 72022.6 73318.3 73672.6 75058.6 6845.4 8252.2 8271.0 8303.8 8314.2 8725.0 11417.8 12420.6 12828.4 13006.9 10887.6 11036.8 11072.8 11137.9 11534.6 8866.3 10803.5 11028.8 11380.7 11458.7 11085.3 11208.2 11275.6 11782.5 11787.6 8288.8 8289.2 8365.2 8438.6 8454.7 8461.2 9489.6 10578.2 10892.7 11447.1 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5507.92 Median:4911.35 stddev:2995.27 (61.0%) stddev-sans-first:2988.23 11:08:22 INFO - PROCESS | 1665 | Values: 1461.0 1591.2 1618.6 1902.2 2011.3 7723.3 8329.7 8495.1 8509.7 8534.8 8250.5 8495.7 8521.9 8533.1 8536.3 2439.1 2469.8 2471.0 2471.8 2472.2 2048.7 2399.9 2440.7 2478.5 2479.1 2443.7 2461.2 2478.7 2482.8 2487.5 2434.4 2458.2 2465.4 2471.8 2476.7 2122.1 2415.2 2445.8 2448.3 2451.3 8282.7 8525.4 8577.7 8581.7 8588.6 5294.7 5433.6 5438.7 5444.8 5446.8 2970.4 3399.4 3454.4 3483.3 3512.8 1045.0 1067.1 1085.4 1523.2 1615.9 11217.0 11289.1 11295.7 11315.9 11340.7 4490.1 4499.5 4587.2 4587.4 4623.9 9023.5 9065.3 9066.5 9073.8 9081.0 5288.6 5304.6 5309.5 5311.0 5314.3 4250.9 4318.4 4327.2 4351.0 4938.9 3207.1 3761.1 3864.7 3872.6 3878.7 1225.6 1228.5 1228.9 1231.8 1237.2 858.7 914.4 940.4 940.9 990.4 5595.4 5679.1 5821.4 5828.1 5869.8 956.2 972.9 1001.9 1005.2 1013.9 3587.2 3773.8 3785.9 3857.8 3930.3 10111.2 10439.3 10520.5 10576.2 10600.7 10213.6 10230.3 10332.9 10344.5 10350.9 7659.0 7893.7 7901.0 7913.7 7995.1 5342.3 5588.2 5619.0 5640.2 5687.9 9038.6 9157.3 9290.6 9311.0 9311.9 8938.0 9248.1 9295.0 9376.6 9384.8 6617.9 7204.6 7271.6 7289.5 7300.1 7419.6 7785.5 7948.0 8024.5 8042.0 6007.5 7189.4 7239.5 7264.5 7327.3 7131.8 7152.3 7221.9 7228.0 7275.0 5754.7 6578.1 6710.8 6787.0 6806.0 4691.6 4699.7 4797.2 4800.9 4818.7 4784.7 4797.9 4880.9 4883.8 5049.6 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5645.56 Median:6210.13 stddev:1789.43 (28.8%) stddev-sans-first:1749.18 11:08:22 INFO - PROCESS | 1665 | Values: 316.8 322.2 326.6 329.6 331.6 6475.2 6552.2 6553.2 6568.9 6580.6 5675.6 5982.4 6489.9 6502.7 6527.8 4583.8 4699.3 4712.2 4806.2 4865.1 3907.5 4816.9 4878.6 4880.5 4889.3 3885.5 4832.4 4839.9 4844.6 4847.8 4545.6 4650.6 4667.0 4675.9 4677.8 3998.4 4087.0 4113.4 4120.1 4121.6 5106.8 6120.0 6132.9 6195.8 6211.5 5636.3 5638.1 5649.4 5650.4 5656.1 5707.3 6699.7 6827.9 6856.5 6882.7 4469.2 4608.4 4620.9 4631.7 4643.6 5773.0 6956.3 7056.4 7198.6 7239.2 4713.0 4720.4 4720.7 4741.2 4744.7 4833.3 4835.0 4839.8 4840.1 4850.6 5737.9 5740.7 5743.1 5745.0 5749.1 5957.8 6608.4 6649.7 6847.5 6968.4 1346.5 1660.6 1693.5 1695.9 1696.1 1078.1 1081.1 1096.0 1097.7 1104.8 6208.7 6359.8 6429.5 6434.3 6440.8 5785.6 5800.3 7022.6 7155.9 7172.1 6497.4 6540.3 6542.9 6544.2 6559.7 3778.6 3835.4 3839.1 3841.6 3848.0 7315.9 7566.2 7614.7 7635.6 7648.2 7446.7 7519.7 7552.2 7566.9 7567.5 6726.9 7249.5 7663.5 7698.0 7719.4 5941.9 7307.3 7312.5 7325.9 7329.8 6827.2 7141.8 7154.1 7198.2 7199.1 7562.1 7605.4 7632.8 7644.2 7668.3 4553.1 4605.7 4607.1 4612.7 4614.9 5846.6 5869.5 7258.6 7267.6 7288.2 5900.7 6710.0 6868.5 6990.2 7015.4 6915.5 6958.8 6979.7 7013.5 7052.0 6815.9 6845.8 6851.8 6873.7 6882.1 6942.3 6946.0 6955.0 6961.7 6965.4 6511.1 6596.1 6736.0 6791.4 6832.0 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:18193.36 Median:602.71 stddev:25349.36 (4205.9%) stddev-sans-first:25386.46 11:08:22 INFO - PROCESS | 1665 | Values: 704.2 742.9 743.5 756.2 779.4 15036.4 15223.8 15246.8 15268.0 16803.0 16126.6 16203.8 16211.6 16227.6 16247.8 758.8 927.0 938.8 939.4 947.9 762.7 920.7 940.2 941.6 943.2 999.6 1001.7 1002.5 1003.0 1004.5 776.5 970.9 996.8 999.1 999.7 739.6 739.7 740.0 741.1 741.9 15233.9 15338.8 15349.5 15362.2 15365.9 12151.9 12223.4 12464.3 12547.4 12582.9 10112.6 10114.5 10143.9 10466.9 10509.5 3430.1 3442.4 3493.1 3510.1 3569.6 85858.0 86290.6 87674.6 88146.6 88286.0 9184.7 9187.1 9274.5 9304.8 9392.8 21468.9 21528.8 21547.8 21593.2 21612.7 7412.6 7491.2 7519.8 7723.7 7736.0 4040.7 4065.6 4112.7 4171.7 4217.7 3530.8 3594.9 3595.3 3601.0 3611.5 651.3 660.3 665.1 665.9 676.2 552.9 589.6 598.8 600.7 607.6 7047.8 8091.4 8225.9 8262.8 8336.6 484.3 540.5 570.5 577.2 604.7 3109.3 3722.8 3871.4 3902.4 4010.8 83613.7 84739.6 85628.6 85926.1 88207.1 73212.6 73512.3 75135.2 75140.0 75903.0 67830.7 67877.9 68476.7 68768.7 71070.0 8209.8 8340.3 8427.1 8508.6 8517.7 46493.0 47437.5 48617.0 48763.0 49051.3 68323.0 68551.7 68866.0 69117.0 69533.0 10583.9 10886.7 11036.6 11237.6 11301.3 11560.0 11808.1 11864.3 11886.6 12232.6 8836.2 10686.9 11304.0 11443.9 11501.5 11119.6 11325.5 11372.9 11429.3 12043.6 10701.4 10903.4 11048.1 11411.0 11413.9 9493.6 9533.8 9589.4 9812.5 9970.6 8193.0 9587.0 9728.3 9743.0 9802.3 11:08:22 INFO - PROCESS | 1665 | -------- Summary: end -------- 11:08:22 INFO - PROCESS | 1665 | 11:08:22 INFO - TEST-INFO | 1665: exit 0 11:08:22 INFO - TEST-OK | dromaeo_css | took 1114771ms 11:08:22 INFO - TEST-START | kraken 11:08:22 INFO - operating with platform_type : mac_ 11:08:22 INFO - Initialising browser for kraken test... 11:08:22 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpm0sO1A/profile http://localhost:49270/getInfo.html) 11:08:25 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 11:08:25 INFO - PROCESS | 1675 | colorDepth:24 11:08:25 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 11:08:25 INFO - PROCESS | 1675 | __metrics 11:08:25 INFO - PROCESS | 1675 | 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" 11:08:25 ERROR - PROCESS | 1675 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:08:25 INFO - TEST-INFO | 1675: exit 0 11:08:25 INFO - Browser initialized. 11:08:25 INFO - Running cycle 1/1 for kraken test... 11:08:25 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/T/tmpm0sO1A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:08:37 INFO - PROCESS | 1677 | RSS: Main: 211750912 11:08:37 INFO - PROCESS | 1677 | 11:08:40 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 11:08:41 INFO - PROCESS | 1677 | RSS: Main: 274755584 11:08:41 INFO - PROCESS | 1677 | 11:08:43 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 11:08:44 INFO - PROCESS | 1677 | RSS: Main: 289579008 11:08:44 INFO - PROCESS | 1677 | 11:08:47 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 11:08:48 INFO - PROCESS | 1677 | RSS: Main: 374366208 11:08:48 INFO - PROCESS | 1677 | 11:08:50 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 11:08:50 INFO - PROCESS | 1677 | RSS: Main: 281198592 11:08:50 INFO - PROCESS | 1677 | 11:08:52 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:08:53 INFO - PROCESS | 1677 | RSS: Main: 282157056 11:08:53 INFO - PROCESS | 1677 | 11:08:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 11:08:56 INFO - PROCESS | 1677 | RSS: Main: 320679936 11:08:56 INFO - PROCESS | 1677 | 11:08:59 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 11:09:00 INFO - PROCESS | 1677 | RSS: Main: 320544768 11:09:00 INFO - PROCESS | 1677 | 11:09:03 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 11:09:03 INFO - PROCESS | 1677 | RSS: Main: 318509056 11:09:03 INFO - PROCESS | 1677 | 11:09:05 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:09:06 INFO - PROCESS | 1677 | RSS: Main: 310910976 11:09:06 INFO - PROCESS | 1677 | 11:09:07 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:09:08 INFO - PROCESS | 1677 | RSS: Main: 317554688 11:09:08 INFO - PROCESS | 1677 | 11:09:10 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:09:11 INFO - PROCESS | 1677 | RSS: Main: 321093632 11:09:11 INFO - PROCESS | 1677 | 11:09:13 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:09:14 INFO - PROCESS | 1677 | RSS: Main: 323457024 11:09:14 INFO - PROCESS | 1677 | 11:09:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:09:18 INFO - PROCESS | 1677 | RSS: Main: 325328896 11:09:18 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 11:09:19 INFO - PROCESS | 1677 | RSS: Main: 386519040 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | __start_tp_report 11:09:19 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 11:09:19 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 11:09:19 INFO - PROCESS | 1677 | |i|pagename|runs| 11:09:19 INFO - PROCESS | 1677 | |0;ai-astar;113;112;118;112;111;111;112;113;113;132 11:09:19 INFO - PROCESS | 1677 | |1;audio-beat-detection;158;124;138;160;111;139;140;117;169;119 11:09:19 INFO - PROCESS | 1677 | |2;audio-dft;186;181;184;182;182;183;183;182;183;183 11:09:19 INFO - PROCESS | 1677 | |3;audio-fft;68;77;73;154;55;57;78;92;158;65 11:09:19 INFO - PROCESS | 1677 | |4;audio-oscillator;91;91;144;77;79;88;127;79;77;125 11:09:19 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;88;88;88;83;86;84;90;91;88;84 11:09:19 INFO - PROCESS | 1677 | |6;imaging-darkroom;92;93;92;91;92;92;92;93;92;92 11:09:19 INFO - PROCESS | 1677 | |7;imaging-desaturate;83;85;82;86;85;93;126;84;85;85 11:09:19 INFO - PROCESS | 1677 | |8;json-parse-financial;57;63;65;62;63;63;64;80;59;58 11:09:19 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;64;65;67;75;74;71;65;66;66;67 11:09:19 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;82;78;81;81;80;79;81;80;82;86 11:09:19 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;141;138;128;128;126;127;127;127;185;140 11:09:19 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;163;153;168;172;168;164;167;164;159;164 11:09:19 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;66;69;66;66;66;69;67;70;65;67 11:09:19 INFO - PROCESS | 1677 | __end_tp_report 11:09:19 INFO - PROCESS | 1677 | __start_cc_report 11:09:19 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,1964 11:09:19 INFO - PROCESS | 1677 | __end_cc_report 11:09:19 INFO - PROCESS | 1677 | __startTimestamp1465236559963__endTimestamp 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | ------- Summary: start ------- 11:09:19 INFO - PROCESS | 1677 | Number of tests: 14 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:114.70 Median:113.00 stddev:6.40 (5.7%) stddev-sans-first:6.75 11:09:19 INFO - PROCESS | 1677 | Values: 113.0 112.0 118.0 112.0 111.0 111.0 112.0 113.0 113.0 132.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:137.50 Median:139.50 stddev:19.93 (14.3%) stddev-sans-first:19.71 11:09:19 INFO - PROCESS | 1677 | Values: 158.0 124.0 138.0 160.0 111.0 139.0 140.0 117.0 169.0 119.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.37 (0.7%) stddev-sans-first:0.88 11:09:19 INFO - PROCESS | 1677 | Values: 186.0 181.0 184.0 182.0 182.0 183.0 183.0 182.0 183.0 183.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:87.70 Median:70.50 stddev:37.56 (53.3%) stddev-sans-first:39.15 11:09:19 INFO - PROCESS | 1677 | Values: 68.0 77.0 73.0 154.0 55.0 57.0 78.0 92.0 158.0 65.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:79.00 stddev:24.69 (31.3%) stddev-sans-first:26.07 11:09:19 INFO - PROCESS | 1677 | Values: 91.0 91.0 144.0 77.0 79.0 88.0 127.0 79.0 77.0 125.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:2.67 (3.0%) stddev-sans-first:2.80 11:09:19 INFO - PROCESS | 1677 | Values: 88.0 88.0 88.0 83.0 86.0 84.0 90.0 91.0 88.0 84.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 11:09:19 INFO - PROCESS | 1677 | Values: 92.0 93.0 92.0 91.0 92.0 92.0 92.0 93.0 92.0 92.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:89.40 Median:85.00 stddev:13.19 (15.5%) stddev-sans-first:13.79 11:09:19 INFO - PROCESS | 1677 | Values: 83.0 85.0 82.0 86.0 85.0 93.0 126.0 84.0 85.0 85.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:63.40 Median:63.00 stddev:6.42 (10.2%) stddev-sans-first:6.37 11:09:19 INFO - PROCESS | 1677 | Values: 57.0 63.0 65.0 62.0 63.0 63.0 64.0 80.0 59.0 58.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:68.00 Median:67.00 stddev:3.92 (5.8%) stddev-sans-first:3.88 11:09:19 INFO - PROCESS | 1677 | Values: 64.0 65.0 67.0 75.0 74.0 71.0 65.0 66.0 66.0 67.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:81.00 Median:81.00 stddev:2.16 (2.7%) stddev-sans-first:2.26 11:09:19 INFO - PROCESS | 1677 | Values: 82.0 78.0 81.0 81.0 80.0 79.0 81.0 80.0 82.0 86.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:136.70 Median:133.00 stddev:17.99 (13.5%) stddev-sans-first:19.01 11:09:19 INFO - PROCESS | 1677 | Values: 141.0 138.0 128.0 128.0 126.0 127.0 127.0 127.0 185.0 140.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.20 Median:165.50 stddev:5.29 (3.2%) stddev-sans-first:5.59 11:09:19 INFO - PROCESS | 1677 | Values: 163.0 153.0 168.0 172.0 168.0 164.0 167.0 164.0 159.0 164.0 11:09:19 INFO - PROCESS | 1677 | 11:09:19 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.66 (2.5%) stddev-sans-first:1.72 11:09:19 INFO - PROCESS | 1677 | Values: 66.0 69.0 66.0 66.0 66.0 69.0 67.0 70.0 65.0 67.0 11:09:19 INFO - PROCESS | 1677 | -------- Summary: end -------- 11:09:19 INFO - PROCESS | 1677 | 11:09:20 INFO - TEST-INFO | 1677: exit 0 11:09:20 INFO - TEST-OK | kraken | took 57810ms 11:09:20 INFO - SUITE-END | took 1172s 11:09:21 INFO - Completed test suite (00:19:32) 11:09:21 INFO - javascript benchmark 11:09:21 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3150.716435520803, 3462.0306314232016, 3477.6174620791726, 3479.478078288258, 3540.3627347077518, 5685.772569097237, 5712.771489140435, 5731.111677689964, 5738.454846789548, 5770.3075630924295, 5737.168110623957, 5769.653820770756, 5780.421957804228, 5780.682062486572, 5794.101914203295, 5184.455632158626, 5210.19242017486, 5231.372235331746, 5294.821924477488, 5387.353517577876, 5232.8691782705355, 5269.235960785683, 5272.156454967187, 5278.841634750964, 5280.973595132019, 5249.212618107276, 5275.947240527603, 5293.814716484924, 5310.973445132769, 5326.7070311132875, 5272.973635131819, 5316.069687804618, 5325.0, 5326.573874090063, 5354.518093371616, 5145.228215767627, 5149.2791009258735, 5177.0, 5262.131748261532, 5263.315768950012, 5616.410276920946, 5626.662400255997, 5634.154876768476, 5639.915401268984, 5642.097264437671, 6221.191245138149, 6255.968720156416, 6259.593126446812, 6263.31103578606, 6273.717682704244, 6189.473894718909, 6211.937880621226, 6306.243250809886, 6307.905381419282, 6309.085182648536, 5382.757775900094, 5461.634591352202, 5868.735906884201, 5887.322957859901, 5900.380460051719, 6304.936950630527, 6305.369463053658, 6314.55798094129, 6315.0, 6318.336574659595, 6075.0, 6137.754489820447, 6186.10301506279, 6201.720922558496, 6211.844703882349, 6227.501799856001, 6235.657038862815, 6254.186955695731, 6257.405546473078, 6260.217472815898, 6127.816165515042, 6194.442500175006, 6195.845103872349, 6208.286047104641, 6216.316205217422, 6120.846978825476, 6155.569110162335, 6170.0, 6170.197874276315, 6224.751009959641, 4861.368022157097, 4926.827561035347, 4927.704337739747, 4954.256861470736, 4987.177115775857, 3538.929221415609, 4419.204543182258, 4429.667774916894, 4436.866893993185, 4474.284114541658, 5786.247787787561, 5788.421157884178, 5803.448672376117, 5824.88350232993, 5841.7371218295275, 6108.2813625860135, 6161.507079433634, 6248.218972628421, 6256.124142620037, 6265.498760099181, 5712.34308054579, 5758.884822303614, 5762.510186634099, 5771.365149833515, 5782.681952492653, 5321.946780532146, 5420.945790542044, 5436.891262174734, 5446.346438427414, 5449.073657478239, 6279.937200627935, 6304.432601065926, 6326.9683651581445, 6351.2696039956, 6367.968160159169, 6252.562320637512, 6256.249250090018, 6285.49716022708, 6290.0879372491645, 6303.558750887394, 6353.650549219838, 6356.491480681627, 6356.872862542816, 6365.554411191358, 6370.617762934239, 5099.311592935054, 6087.299960504599, 6119.472088496418, 6180.134781130735, 6235.906461402992, 6200.193974783249, 6279.968600157153, 6290.370962903673, 6292.993121100602, 6300.117983482408, 6172.783952561639, 6199.597026193283, 6200.906986395298, 6205.720742566686, 6210.596311239755, 6032.125341825498, 6107.633541987495, 6108.144859719731, 6185.752569897334, 6207.751689932352, 5004.624653150955, 6006.0, 6043.335233124265, 6058.878822423615, 6080.939190608215, 6143.201383820075, 6153.476954458921, 6160.7535698571555, 6161.383861613803, 6178.41305076017, 5185.170372740344, 5812.21535092757, 5883.529317654663, 5956.106584012259, 6029.698515074317], "value": 5711.5046631022315, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [537.8601563593465, 538.4507802041917, 575.5050656435467, 592.4460629311594, 593.4955288005197, 29795.0, 31108.0, 38495.23009539811, 38527.229455410874, 38626.0, 43430.13139737203, 44155.55844441555, 44189.0, 44195.11609767803, 44266.77866610671, 9566.617335306597, 9785.951070244639, 9791.461469619167, 9795.314327997043, 9797.216222702167, 8591.613377398033, 11467.025302849268, 11659.533618655243, 11665.766684666301, 11743.7064073398, 10312.123469505064, 10378.221633377489, 11343.752187259395, 11414.0, 11827.40862956853, 8527.488350698977, 8697.173768491983, 11644.767104657902, 11755.882441175607, 11780.528778848835, 11543.653690389301, 11729.413529323585, 11730.237534560227, 11739.354335511543, 11764.823527647091, 38029.0, 38354.0, 38537.807310963544, 38603.61396386028, 38652.80673596642, 3199.168216263765, 3252.6909943555324, 3320.468725004012, 3416.0776590320474, 3535.381308271042, 26660.46679066408, 27719.445611087664, 27809.0, 27970.3007424814, 28143.296417589518, 2750.3949131191107, 2753.3529620539157, 2767.239009272446, 2797.1049264235453, 2802.971970280312, 462702.0, 467859.0, 468647.0, 469668.65165674296, 477711.0, 35976.46035309473, 60265.79468410607, 60792.696036519534, 60919.781604367665, 61049.0, 29647.703522964926, 35697.64302356995, 35699.28601427957, 35816.283674326885, 35837.56649734033, 15619.297131629104, 15818.288177032062, 15820.683586328209, 15844.366225350857, 15849.841501585068, 11598.94200528992, 11799.88200118005, 13285.269310188032, 13329.333533323295, 13346.46614135443, 8088.757337279891, 8088.797780055428, 8113.107558168596, 8175.223353781381, 8180.918190818015, 3458.4985177149674, 3479.4342545854465, 3499.562554680665, 3507.3862074137433, 3531.4702794581017, 1026.993546324591, 1034.5551412892528, 1128.6219116596033, 1131.2081542919989, 1141.8915203055697, 29387.706122938496, 29927.102186934426, 29994.700052999626, 30577.69422305793, 30627.08118756441, 1055.5302890213934, 1135.8920902514249, 1159.4898244772442, 1169.029705344562, 1297.6626077219992, 15016.774748378783, 15172.468963586221, 15238.085714856923, 15437.614059648375, 15532.68934621324, 599469.0, 601416.0, 610724.5710171632, 610760.0, 613907.0, 59971.400285996584, 60229.698851506335, 60235.0, 60292.698536507036, 60298.698506507186, 1301.9283939383236, 1432.8638779315947, 1512.311898086368, 1533.7699345097878, 1546.5051183621133, 28876.711232887403, 29629.851850740608, 29653.40693186081, 30485.69514304917, 30599.929002485696, 49103.754481228796, 49749.75125124351, 49947.0, 49968.750156248985, 49973.0, 550970.0, 568430.0, 568623.0, 568835.0, 570000.0, 41066.0, 42334.941626459586, 44034.0, 44116.33825492684, 44134.33798493089, 46176.53823461723, 46421.0, 47502.76248618873, 47632.2855157166, 48070.5192948066, 37236.06909827168, 37403.2519349606, 38188.04529886775, 38242.2351552973, 38528.80735596304, 36902.270809091315, 37642.43536347012, 37730.24539509249, 38159.0, 38319.23361532698, 37051.44422833714, 37516.62483375131, 37697.81151094227, 37776.433353500266, 38134.42798357971, 31942.201444964063, 33739.662603373654, 33851.984440466826, 33895.491567626996, 34007.82996085004, 24364.87817560901, 25176.74823251744, 25247.18624441631, 25433.74566254388, 25645.096472992693], "value": 19204.175996465667, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.8660715624487, 399.57844474079843, 401.3718530499768, 402.9053172504461, 414.64547811621065, 15090.924545377286, 15289.311980960856, 20525.794742052592, 21203.0, 21210.6818397724, 19805.0, 20331.79668203321, 20534.69197962032, 20822.479438014056, 21309.68035479469, 5844.152597873316, 5898.8230353089475, 6035.0, 6050.42520960508, 6061.212042434478, 5746.48281654651, 5841.299044114691, 6023.096535519663, 6051.75792968283, 6052.364501727321, 5961.433663801932, 6070.0, 6079.848003799896, 6084.026555751081, 6085.695715214244, 5762.596618236725, 5897.115432685088, 5924.052151655715, 5939.910901336461, 5982.611130276518, 5220.269162317279, 5220.817271395483, 5223.2165175223645, 5224.085784987612, 5577.358603760579, 13666.658333541642, 13837.0, 13897.957653176052, 13899.235542045184, 13899.79150312746, 1622.3186261769968, 1914.8372388347007, 2183.465051062484, 2243.1363924888874, 2409.951800963971, 9851.704448866545, 10072.093511583993, 10749.731256718645, 11397.772044559146, 15140.848591514054, 1755.9122043897753, 1776.2645525890243, 1786.767720196379, 1821.6720990221754, 1855.1744473709205, 71391.89548087072, 106013.87972240512, 118099.4095029528, 123429.38285308606, 124573.9999999991, 9683.273754468442, 11459.541618335339, 11808.46861891217, 11834.230774999598, 11924.821127683092, 15053.322600483005, 15087.622809429851, 15103.018303810217, 15109.39562417513, 15122.848771512145, 9026.781384513006, 9050.511109444824, 9077.137671921157, 9173.58718857653, 9198.49408282551, 9142.542872856464, 9160.679376221933, 9167.312451566158, 9231.261499080058, 9239.953800231091, 4615.100055489214, 4669.953300466952, 4687.835925742583, 4697.647676424282, 4705.858824235278, 1947.2989723699457, 1991.6614175590184, 1992.0935974132026, 1997.211101614854, 2006.8494862885343, 875.711015364928, 916.3127654259306, 955.044955044955, 994.2692121290845, 1001.2090448545713, 19381.709274360896, 19766.70349944752, 20687.482812929797, 20747.68878466824, 20914.16343346249, 972.8394814855469, 982.921366290695, 1018.6485662446444, 1043.4052590023614, 1046.863907691995, 8926.285897128215, 10924.508397122148, 11035.39305338198, 11049.447527623586, 11094.889051109547, 570956.0, 581890.0, 589089.0545547245, 600699.0, 601390.0, 120760.0, 121846.0, 122489.16266256013, 123416.38291808484, 128811.35594321968, 1014.0467960117466, 1019.0726438940528, 1050.6532844161409, 1064.632701717906, 1065.7122576904192, 14864.702705945605, 17755.556111097325, 17920.462386128438, 18035.008074556026, 18697.878127312404, 51315.0, 52600.73699631477, 52775.20837187368, 52955.0, 53128.203076953105, 71451.28548714446, 72022.55954880978, 73318.26681733114, 73672.63163684147, 75058.62470687611, 6845.383915447634, 8252.21603947624, 8271.0, 8303.83392332138, 8314.168583141638, 8725.0, 11417.771644566896, 12420.565280215149, 12828.358582070672, 13006.869931300944, 10887.618933337295, 11036.834447483134, 11072.778544428906, 11137.944310278397, 11534.596289130175, 8866.33502487329, 10803.513841877291, 11028.779424411307, 11380.715482113013, 11458.713532161762, 11085.27945683528, 11208.215424920178, 11275.605353812578, 11782.52869885195, 11787.646370608896, 8288.792780180544, 8289.171082891662, 8365.163483651586, 8438.578071096543, 8454.66181352739, 8461.153884611736, 9489.620415183315, 10578.153747700011, 10892.727681808019, 11447.141464390037], "value": 9378.814927909976, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1460.9926950365248, 1591.2282542966664, 1618.6277156254057, 1902.2295970132102, 2011.3463124484538, 7723.266289702477, 8329.666813327467, 8495.10801365857, 8509.659613615464, 8534.829303413928, 8250.463719858206, 8495.660173593064, 8521.91478085219, 8533.061363250037, 8536.27441667459, 2439.1219161102035, 2469.814763892706, 2471.023945541519, 2471.752824717532, 2472.196536125766, 2048.682454219598, 2399.940001499959, 2440.719317278518, 2478.5414698280874, 2479.1446950801947, 2443.7067551893797, 2461.1509029384833, 2478.677771889652, 2482.8262021658493, 2487.5398051360467, 2434.440078781872, 2458.2379462366634, 2465.3959779853963, 2471.8269721119527, 2476.7151777545632, 2122.0981083039646, 2415.1667674652217, 2445.8410203336725, 2448.2532827487607, 2451.301379106964, 8282.71010514629, 8525.360597955178, 8577.742667719978, 8581.656733730642, 8588.613512391958, 5294.68231906083, 5433.646812957185, 5438.6736795791985, 5444.754986025639, 5446.754896029688, 2970.3910698306795, 3399.388110140174, 3454.4127498325347, 3483.251101013269, 3512.7892326460237, 1044.955044955045, 1067.0876400677387, 1085.4355735017825, 1523.1622607565905, 1615.9030458172547, 11217.046551043171, 11289.0968722502, 11295.661130166109, 11315.943420282845, 11340.716482087932, 4490.101979604092, 4499.505054444008, 4587.17430862448, 4587.357769912215, 4623.8844028898875, 9023.503707296031, 9065.274778017743, 9066.546672666473, 9073.77315567116, 9081.046490118575, 5288.629795914249, 5304.628675992643, 5309.548688361532, 5310.990911726837, 5314.335708036496, 4250.893727656833, 4318.35224716295, 4327.242732521796, 4351.0, 4938.925916111261, 3207.0859804955476, 3761.097336639242, 3864.671502922227, 3872.612738726105, 3878.670313023368, 1225.5710501324643, 1228.502456505117, 1228.8832560906699, 1231.8090695942144, 1237.1587320621884, 858.6865793985276, 914.3517365710766, 940.3652534539159, 940.8823897012874, 990.4107056301489, 5595.356533998561, 5679.119736440858, 5821.446962538552, 5828.0966450200285, 5869.765209391577, 956.2159029595804, 972.9124378805941, 1001.9098281154731, 1005.1606908231709, 1013.9087482126644, 3587.192881601671, 3773.7924414156937, 3785.905352366158, 3857.787821669779, 3930.292547341506, 10111.241656875764, 10439.277519209398, 10520.526576304084, 10576.153907687367, 10600.681979540626, 10213.591456341663, 10230.335028223142, 10332.948335258427, 10344.482775861177, 10350.896491035144, 7659.0, 7893.723719669784, 7901.0, 7913.723019694398, 7995.120536740954, 5342.332208473941, 5588.189712491585, 5619.0, 5640.210370548045, 5687.914681279867, 9038.638454461749, 9157.31320150978, 9290.58192381331, 9310.95344523273, 9311.906880931376, 8937.955310223668, 9248.075192480697, 9294.977552469358, 9376.624935002796, 9384.81230375375, 6617.933820661924, 7204.603746793977, 7271.600061996641, 7289.45329100309, 7300.12398512182, 7419.554826710415, 7785.455018148676, 7948.046234451903, 8024.478408903403, 8041.959790201012, 6007.519398448027, 7189.424846012202, 7239.529430586995, 7264.491485596169, 7327.303906129123, 7131.750388736369, 7152.320529549685, 7221.927780722336, 7227.963860180876, 7275.0, 5754.6547207167705, 6578.111954886221, 6710.765123220664, 6786.966065169642, 6805.9659701701175, 4691.624670026458, 4699.671023028355, 4797.160496913096, 4800.927986080142, 4818.71087734737, 4784.712917224839, 4797.9040419192115, 4880.92678609814, 4883.780229889735, 5049.5960323174795], "value": 4507.847774119906, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.8431626344959, 322.22183427023737, 326.57871345963713, 329.60118256909124, 331.61367007436337, 6475.183295266563, 6552.165393689775, 6553.180852393451, 6568.948968165095, 6580.572262802926, 5675.637846916741, 5982.371850955652, 6489.935100648992, 6502.707378167982, 6527.804165875032, 4583.793729282182, 4699.318598803172, 4712.246040633499, 4806.158922188603, 4865.051314993578, 3907.4529565860803, 4816.879578010543, 4878.585320247783, 4880.536349046835, 4889.315495830587, 3885.4754608127964, 4832.405306248929, 4839.903201935959, 4844.636652251095, 4847.830325938593, 4545.590896819285, 4650.558196971282, 4666.999999999983, 4675.906481870377, 4677.836275730368, 3998.420229066769, 4086.979565102185, 4113.424120623115, 4120.072983578671, 4121.567235440296, 5106.821261255838, 6119.969400152971, 6132.926737820863, 6195.81412557624, 6211.534134939898, 5636.267285252932, 5638.126090455986, 5649.378568357477, 5650.378458369576, 5656.095024796054, 5707.343655479632, 6699.727051860131, 6827.897581536281, 6856.485763567753, 6882.69027893746, 4469.195544801934, 4608.400907881954, 4620.884477888046, 4631.652626053025, 4643.582077613031, 5772.9711351442975, 6956.304369563054, 7056.435485161175, 7198.640067996632, 7239.239879812597, 4712.986707857827, 4720.362751028636, 4720.669553131316, 4741.170295198326, 4744.691595046311, 4833.347498087714, 4834.975825120922, 4839.758012099381, 4840.128776820206, 4850.6362022848425, 5737.913931291034, 5740.655560666373, 5743.08110702294, 5745.0, 5749.108888122264, 5957.761689532457, 6608.4052435281055, 6649.700763465656, 6847.452203823682, 6968.372846443743, 1346.5421756602802, 1660.6429617632266, 1693.5427434592837, 1695.906725130105, 1696.058687428489, 1078.0836289154156, 1081.081081081075, 1095.9835602465969, 1097.7200813792592, 1104.7624760676492, 6208.72060757267, 6359.777407790705, 6429.453496452854, 6434.324395938359, 6440.774572889926, 5785.623934444247, 5800.274965629296, 7022.578645281299, 7155.92844071563, 7172.0676312079095, 6497.415232629086, 6540.313267106981, 6542.901856472156, 6544.247411547638, 6559.737610495622, 3778.6032466591164, 3835.386338185877, 3839.1170030892495, 3841.558220804643, 3848.0, 7315.853682926312, 7566.205548417447, 7614.6954121835615, 7635.580043097571, 7648.235176482307, 7446.7021319146625, 7519.661615227328, 7552.169261381244, 7566.886496702554, 7567.545947243183, 6726.8654626908165, 7249.513849660807, 7663.463557550918, 7697.960775295375, 7719.382449404035, 5941.851453713692, 7307.3423391894985, 7312.5246858954, 7325.890111648436, 7329.780106596811, 6827.180738311435, 7141.82145446368, 7154.141503019671, 7198.20819709821, 7199.1361036675935, 7562.054743157106, 7605.353544948742, 7632.771016869503, 7644.235576442311, 7668.309852113337, 4553.06662134255, 4605.677602567788, 4607.14767767964, 4612.7001744886475, 4614.861554153381, 5846.619969701956, 5869.53043756507, 7258.600776957318, 7267.563946163247, 7288.198298187089, 5900.7049647518315, 6710.02444987775, 6868.450523958171, 6990.23107458169, 7015.36861682451, 6915.51591388598, 6958.756443524452, 6979.651017449006, 7013.509054366145, 7052.0479735235085, 6815.931840681728, 6845.794626161223, 6851.828704282433, 6873.725050998104, 6882.070920425678, 6942.305769423017, 6945.965270173617, 6955.026296318419, 6961.721531138698, 6965.407940324926, 6511.055896894829, 6596.109525214227, 6736.02327662496, 6791.388775010273, 6832.0], "value": 5055.435516636781, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.2394214248612, 742.9442791790615, 743.5092838726441, 756.2248695087532, 779.4426984705935, 15036.398544058238, 15223.771643425358, 15246.847531524681, 15268.007579507339, 16803.0, 16126.596835079128, 16203.83796162038, 16211.594710132253, 16227.594310142253, 16247.83752162478, 758.7723682895137, 926.9768255793608, 938.8028514012032, 939.4128669581512, 947.9052094790535, 762.6606160258688, 920.7467946314783, 940.2196177172964, 941.6092321686492, 943.1888575824785, 999.6201443451479, 1001.7095042437684, 1002.4787110702467, 1002.9949850250739, 1004.4575928998332, 776.5107981971379, 970.9126178643955, 996.8305388084007, 999.0758548342753, 999.6850991937553, 739.5784402890355, 739.6708464733196, 739.9593022383767, 741.0625558668289, 741.8850078237882, 15233.923830380778, 15338.846611533854, 15349.539513814603, 15362.155081470515, 15365.923170384189, 12151.939240303742, 12223.43307905341, 12464.314462704639, 12547.372631368486, 12582.937085314516, 10112.595496180144, 10114.494275286279, 10143.949280253551, 10466.895331046668, 10509.474526273656, 3430.125318043908, 3442.4492081267126, 3493.056874643832, 3510.1224693826543, 3569.607343192247, 85858.0, 86290.56854715686, 87674.56162719146, 88146.55926720389, 88286.0, 9184.678536251333, 9187.127222913812, 9274.536273186313, 9304.767380815398, 9392.812143757086, 21468.892655536623, 21528.81591512482, 21547.784522154892, 21593.24423645165, 21612.675809862863, 7412.592307423036, 7491.213422590658, 7519.849603008019, 7723.729669461654, 7735.9613201933635, 4040.696947728932, 4065.5527891931865, 4112.712110152321, 4171.728837625545, 4217.704760666786, 3530.7881527108457, 3594.8562057517406, 3595.26297109092, 3601.0097223263288, 3611.494390785292, 651.3356376496042, 660.2769966886252, 665.122038908636, 665.9001149827565, 676.2088356622763, 552.9391766905637, 589.6255877517807, 598.8143675460622, 600.7116584039685, 607.5898768331359, 7047.753328633473, 8091.352691784643, 8225.917740822515, 8262.8347433051, 8336.583170841433, 484.3171128708504, 540.5405405405374, 570.5179123640518, 577.2421535801876, 604.7006731667852, 3109.284864481182, 3722.7766334020043, 3871.399933010388, 3902.414637804352, 4010.7794071326357, 83613.74579381314, 84739.57630211933, 85628.57185714031, 85926.14073859307, 88207.11792882117, 73212.63393682998, 73512.26487735161, 75135.24864751392, 75140.0, 75903.0, 67830.66084669545, 67877.85465744043, 68476.6576167106, 68768.65615671889, 71070.0, 8209.835803283782, 8340.332773377993, 8427.115152909102, 8508.574571271289, 8517.701880434155, 46493.0, 47437.52562474331, 48617.0, 48763.0, 49051.26423103585, 68323.0, 68551.65724171547, 68866.0, 69116.96324555035, 69533.0, 10583.947080264858, 10886.741006226055, 11036.613718519813, 11237.55049797999, 11301.321920684784, 11560.0, 11808.11439142085, 11864.347460889734, 11886.583969561023, 12232.571859984857, 8836.229740129895, 10686.89313106859, 11304.0, 11443.942780286045, 11501.48243329069, 11119.555217791198, 11325.490352933974, 11372.943135284271, 11429.257098288585, 12043.578474753342, 10701.411422371844, 10903.400312982862, 11048.06091482233, 11410.973012428922, 11413.942930285295, 9493.620255189715, 9533.761655958657, 9589.42463452195, 9812.509374531388, 9970.601175952881, 8193.0, 9587.0, 9728.289401998962, 9743.0, 9802.313838031268], "value": 6930.762226039898, "unit": "score"}], "name": "dromaeo_css", "value": 7387.089342572399, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 118.0, 112.0, 111.0, 111.0, 112.0, 113.0, 113.0, 132.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 124.0, 138.0, 160.0, 111.0, 139.0, 140.0, 117.0, 169.0, 119.0], "value": 137.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 181.0, 184.0, 182.0, 182.0, 183.0, 183.0, 182.0, 183.0, 183.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 73.0, 154.0, 55.0, 57.0, 78.0, 92.0, 158.0, 65.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 144.0, 77.0, 79.0, 88.0, 127.0, 79.0, 77.0, 125.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 88.0, 83.0, 86.0, 84.0, 90.0, 91.0, 88.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 92.0, 91.0, 92.0, 92.0, 92.0, 93.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 82.0, 86.0, 85.0, 93.0, 126.0, 84.0, 85.0, 85.0], "value": 89.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 63.0, 65.0, 62.0, 63.0, 63.0, 64.0, 80.0, 59.0, 58.0], "value": 63.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 65.0, 67.0, 75.0, 74.0, 71.0, 65.0, 66.0, 66.0, 67.0], "value": 68.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 81.0, 81.0, 80.0, 79.0, 81.0, 80.0, 82.0, 86.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 128.0, 128.0, 126.0, 127.0, 127.0, 127.0, 185.0, 140.0], "value": 136.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 153.0, 168.0, 172.0, 168.0, 164.0, 167.0, 164.0, 159.0, 164.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 66.0, 66.0, 66.0, 69.0, 67.0, 70.0, 65.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1469.5, "alertThreshold": 2.0}]} 11:09:21 INFO - Return code: 0 11:09:21 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:09:21 ERROR - # TBPL SUCCESS # 11:09:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:09:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:09:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:09:21 INFO - 2016-06-06 11:09:21.284 screenresolution[1681:10435] starting screenresolution argv=screenresolution get 11:09:21 INFO - 2016-06-06 11:09:21.287 screenresolution[1681:10435] Display 0: 1600x1200x32@60 11:09:21 INFO - 2016-06-06 11:09:21.293 screenresolution[1682:10438] starting screenresolution argv=screenresolution list 11:09:21 INFO - Available Modes on Display 0 11:09:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:09:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:09:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:09:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:09:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:09:21 INFO - Intel Iris: 11:09:21 INFO - Chipset Model: Intel Iris 11:09:21 INFO - Type: GPU 11:09:21 INFO - Bus: Built-In 11:09:21 INFO - VRAM (Dynamic, Max): 1536 MB 11:09:21 INFO - Vendor: Intel (0x8086) 11:09:21 INFO - Device ID: 0x0a2e 11:09:21 INFO - Revision ID: 0x0009 11:09:21 INFO - Displays: 11:09:21 INFO - Display: 11:09:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:09:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:09:21 INFO - Display Serial Number: 1600x1200 60 11:09:21 INFO - Main Display: Yes 11:09:21 INFO - Mirror: Off 11:09:21 INFO - Online: Yes 11:09:21 INFO - Rotation: Supported 11:09:21 INFO - Return code: 0 11:09:21 INFO - Running post-action listener: _resource_record_post_action 11:09:21 INFO - Running post-run listener: _resource_record_post_run 11:09:22 INFO - Total resource usage - Wall time: 1206s; CPU: 53.0%; Read bytes: 31473152; Write bytes: 656462848; Read time: 203; Write time: 1880 11:09:22 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353350144; Write bytes: 349378560; Read time: 28039; Write time: 731 11:09:22 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 29691392; Write bytes: 297266176; Read time: 189; Write time: 1111 11:09:22 INFO - Running post-run listener: _upload_blobber_files 11:09:22 INFO - Blob upload gear active. 11:09:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:09:22 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 11:09:22 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'] 11:09:22 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 11:09:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:09:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:09:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:09:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:24 INFO - (blobuploader) - INFO - Done attempting. 11:09:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:09:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:09:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:09:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:09:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:09:25 INFO - (blobuploader) - INFO - Done attempting. 11:09:25 INFO - (blobuploader) - INFO - Iteration through files over. 11:09:25 INFO - Return code: 0 11:09:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:09:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:09:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4f901ac869e868a901ca46b8a13efb1977ef2910a32b7662632d09f6151384673b1b1409382927ca470b4feaf28876fc0b4ddd80423688d70fa50c394a42b7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b988ee46d81d05fbfb382ef3dbf3ea0d416dd1033c8e22d1221e03217abd9e60af3dada0f013da2b68e536d68a66124fbec54a51c80dce75520d57ed198e05e"} 11:09:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:09:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:09:25 INFO - Contents: 11:09:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a4f901ac869e868a901ca46b8a13efb1977ef2910a32b7662632d09f6151384673b1b1409382927ca470b4feaf28876fc0b4ddd80423688d70fa50c394a42b7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b988ee46d81d05fbfb382ef3dbf3ea0d416dd1033c8e22d1221e03217abd9e60af3dada0f013da2b68e536d68a66124fbec54a51c80dce75520d57ed198e05e"} 11:09:25 INFO - Running post-run listener: copy_logs_to_upload_dir 11:09:25 INFO - Copying logs to upload dir... 11:09:25 INFO - mkdir: /builds/slave/test/build/upload/logs 11:09:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.180239 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-06-06 11:09:25.185996) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:25.196057) ========= 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.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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/a4f901ac869e868a901ca46b8a13efb1977ef2910a32b7662632d09f6151384673b1b1409382927ca470b4feaf28876fc0b4ddd80423688d70fa50c394a42b7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b988ee46d81d05fbfb382ef3dbf3ea0d416dd1033c8e22d1221e03217abd9e60af3dada0f013da2b68e536d68a66124fbec54a51c80dce75520d57ed198e05e"} build_url:https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010955 build_url: 'https://queue.taskcluster.net/v1/task/Lgw6Sol4TNSjk3AHasQeIw/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/a4f901ac869e868a901ca46b8a13efb1977ef2910a32b7662632d09f6151384673b1b1409382927ca470b4feaf28876fc0b4ddd80423688d70fa50c394a42b7a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5b988ee46d81d05fbfb382ef3dbf3ea0d416dd1033c8e22d1221e03217abd9e60af3dada0f013da2b68e536d68a66124fbec54a51c80dce75520d57ed198e05e"}' ========= master_lag: 0.55 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:25.759900) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:25.760245) ========= 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.rYO10uCzpv/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.XaaBgwDm03/Listeners TMPDIR=/var/folders/21/x9hp61sd3dbffkp44n32rd1c00000w/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.005106 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 11:09:25.813426) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:25.813750) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 11:09:25.814091) ========= ========= Total master_lag: 0.73 =========