builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0335 starttime: 1463282060.71 results: success (0) buildid: 20160514182335 builduid: cca324c9e5104676bf7e026fb9914f98 revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:20.709649) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:20.710084) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:20.710410) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:20.855243) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:20.855532) ========= 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.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-14 20:14:20-- 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% 8.30M=0.001s 2016-05-14 20:14:20 (8.30 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.188259 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:21.089325) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:21.089673) ========= 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.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.019962 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:21.146984) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 20:14:21.147325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --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 f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-14 20:14:21,180 truncating revision to first 12 chars 2016-05-14 20:14:21,180 Setting DEBUG logging. 2016-05-14 20:14:21,180 attempt 1/10 2016-05-14 20:14:21,180 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/f00f9e754ce7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-14 20:14:22,162 unpacking tar archive at: mozilla-aurora-f00f9e754ce7/testing/mozharness/ program finished with exit code 0 elapsedTime=1.228926 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-14 20:14:22.403773) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:22.404093) ========= script_repo_revision: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:22.408678) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:22.408992) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-14 20:14:22.430569) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-05-14 20:14:22.430899) ========= /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.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:14:22 INFO - MultiFileLogger online at 20160514 20:14:22 in /builds/slave/test 20:14:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 20:14:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:14:22 INFO - {'append_to_log': False, 20:14:22 INFO - 'base_work_dir': '/builds/slave/test', 20:14:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:14:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:14:22 INFO - 'branch': 'Mozilla-Aurora', 20:14:22 INFO - 'buildbot_json_path': 'buildprops.json', 20:14:22 INFO - 'config_files': ('talos/mac_config.py',), 20:14:22 INFO - 'default_actions': ('clobber', 20:14:22 INFO - 'read-buildbot-config', 20:14:22 INFO - 'download-and-extract', 20:14:22 INFO - 'populate-webroot', 20:14:22 INFO - 'create-virtualenv', 20:14:22 INFO - 'install', 20:14:22 INFO - 'run-tests'), 20:14:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:14:22 INFO - 'download_minidump_stackwalk': True, 20:14:22 INFO - 'download_symbols': 'ondemand', 20:14:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:14:22 INFO - 'tooltool.py': '/tools/tooltool.py', 20:14:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:14:22 INFO - '/tools/misc-python/virtualenv.py')}, 20:14:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:14:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:14:22 INFO - 'installer_path': 'installer.exe', 20:14:22 INFO - 'log_level': 'info', 20:14:22 INFO - 'log_name': 'talos', 20:14:22 INFO - 'log_to_console': True, 20:14:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:14:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:14:22 INFO - 'opt_config_files': (), 20:14:22 INFO - 'pip_index': False, 20:14:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:14:22 INFO - 'cmd': ('bash', 20:14:22 INFO - '-c', 20:14:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:14:22 INFO - 'enabled': True, 20:14:22 INFO - 'halt_on_failure': False, 20:14:22 INFO - 'name': 'check_screen_resolution'},), 20:14:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:14:22 INFO - 'cmd': ('bash', 20:14:22 INFO - '-c', 20:14:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:14:22 INFO - 'enabled': True, 20:14:22 INFO - 'halt_on_failure': False, 20:14:22 INFO - 'name': 'check_screen_resolution'},), 20:14:22 INFO - 'run_cmd_checks_enabled': True, 20:14:22 INFO - 'sps_profile': False, 20:14:22 INFO - 'sps_profile_interval': 0, 20:14:22 INFO - 'suite': 'dromaeojs', 20:14:22 INFO - 'system_bits': '32', 20:14:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:14:22 INFO - 'title': 't-yosemite-r7-0335', 20:14:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:14:22 INFO - 'use_talos_json': True, 20:14:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:14:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:14:22 INFO - 'work_dir': 'build'} 20:14:22 INFO - ##### 20:14:22 INFO - ##### Running clobber step. 20:14:22 INFO - ##### 20:14:22 INFO - Running pre-action listener: _resource_record_pre_action 20:14:22 INFO - Running main action method: clobber 20:14:22 INFO - rmtree: /builds/slave/test/build 20:14:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:14:24 INFO - Running post-action listener: _resource_record_post_action 20:14:24 INFO - ##### 20:14:24 INFO - ##### Running read-buildbot-config step. 20:14:24 INFO - ##### 20:14:24 INFO - Running pre-action listener: _resource_record_pre_action 20:14:24 INFO - Running main action method: read_buildbot_config 20:14:24 INFO - Using buildbot properties: 20:14:24 INFO - { 20:14:24 INFO - "project": "", 20:14:24 INFO - "product": "firefox", 20:14:24 INFO - "script_repo_revision": "production", 20:14:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:14:24 INFO - "repository": "", 20:14:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:14:24 INFO - "buildid": "20160514182335", 20:14:24 INFO - "pgo_build": "False", 20:14:24 INFO - "basedir": "/builds/slave/test", 20:14:24 INFO - "buildnumber": 6, 20:14:24 INFO - "slavename": "t-yosemite-r7-0335", 20:14:24 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:14:24 INFO - "platform": "yosemite_r7", 20:14:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:14:24 INFO - "branch": "mozilla-aurora", 20:14:24 INFO - "repo_path": "releases/mozilla-aurora", 20:14:24 INFO - "revision": "f00f9e754ce71fc5efa3e4334afea5cf6ad6c215", 20:14:24 INFO - "stage_platform": "macosx64", 20:14:24 INFO - "builduid": "cca324c9e5104676bf7e026fb9914f98", 20:14:24 INFO - "slavebuilddir": "test" 20:14:24 INFO - } 20:14:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 20:14:24 INFO - Running post-action listener: _resource_record_post_action 20:14:24 INFO - ##### 20:14:24 INFO - ##### Running download-and-extract step. 20:14:24 INFO - ##### 20:14:24 INFO - Running pre-action listener: _resource_record_pre_action 20:14:24 INFO - Running main action method: download_and_extract 20:14:24 INFO - mkdir: /builds/slave/test/build/tests 20:14:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:24 INFO - https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:14:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:14:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 20:14:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:14:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 20:14:35 INFO - Downloaded 1199 bytes. 20:14:35 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 20:14:35 INFO - Using the following test package requirements: 20:14:35 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:14:35 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 20:14:35 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 20:14:35 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'jsshell-mac.zip'], 20:14:35 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 20:14:35 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 20:14:35 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 20:14:35 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 20:14:35 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 20:14:35 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 20:14:35 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 20:14:35 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:14: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')]} 20:14:35 INFO - https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:14:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:14:50 INFO - Downloaded 18102190 bytes. 20:14:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:14:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:14:53 INFO - Return code: 0 20:14:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 20:14:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:14:53 INFO - https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:14:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 20:14:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:15:00 INFO - Downloaded 18102190 bytes. 20:15:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:15:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 20:15:04 INFO - Return code: 0 20:15:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:04 INFO - https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:15:04 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:15:04 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:15:04 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 20:15:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:15:05 INFO - Downloaded 10840145 bytes. 20:15:05 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 20:15:05 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 20:15:05 INFO - Return code: 0 20:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:05 INFO - https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 20:15:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:15:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:15:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 20:15:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 20:15:12 INFO - Downloaded 114131565 bytes. 20:15:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:15:12 INFO - mkdir: /builds/slave/test/properties 20:15:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:15:12 INFO - Writing to file /builds/slave/test/properties/build_url 20:15:12 INFO - Contents: 20:15:12 INFO - build_url:https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 20:15:13 INFO - Running post-action listener: _resource_record_post_action 20:15:13 INFO - Running post-action listener: set_extra_try_arguments 20:15:13 INFO - ##### 20:15:13 INFO - ##### Running populate-webroot step. 20:15:13 INFO - ##### 20:15:13 INFO - Running pre-action listener: _resource_record_pre_action 20:15:13 INFO - Running main action method: populate_webroot 20:15:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:15:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:15:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:15:13 INFO - '--tppagecycles', 20:15:13 INFO - '7'], 20:15:13 INFO - 'tests': ['tsvgm']}}, 20:15:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:15:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:15:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:15:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:15:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:15:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['damp', 'tps']}, 20:15:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'tests': ['damp', 'tps']}, 20:15:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['dromaeo_dom']}, 20:15:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:15:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'other-e10s': {'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['a11yr', 20:15:13 INFO - 'ts_paint', 20:15:13 INFO - 'tpaint', 20:15:13 INFO - 'sessionrestore', 20:15:13 INFO - 'sessionrestore_no_auto_restore', 20:15:13 INFO - 'tabpaint']}, 20:15:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['tsvgx', 20:15:13 INFO - 'tsvgr_opacity', 20:15:13 INFO - 'tart', 20:15:13 INFO - 'tscrollx', 20:15:13 INFO - 'cart']}, 20:15:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:15:13 INFO - 'tsvgr_opacity', 20:15:13 INFO - 'tart', 20:15:13 INFO - 'tscrollx', 20:15:13 INFO - 'cart']}, 20:15:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'talos_options': ['--disable-e10s'], 20:15:13 INFO - 'tests': ['tp5o']}, 20:15:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'tests': ['tp5o']}, 20:15:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'talos_options': ['--disable-e10s', 20:15:13 INFO - '--xperf_path', 20:15:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:13 INFO - 'tests': ['tp5n']}, 20:15:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:15:13 INFO - 'talos_options': ['--xperf_path', 20:15:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:15:13 INFO - 'tests': ['tp5n']}}, 20:15:13 INFO - 'talos.zip': {'path': '', 20:15:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:15:13 INFO - Running post-action listener: _resource_record_post_action 20:15:13 INFO - ##### 20:15:13 INFO - ##### Running create-virtualenv step. 20:15:13 INFO - ##### 20:15:13 INFO - Running pre-action listener: _resource_record_pre_action 20:15:13 INFO - Running main action method: create_virtualenv 20:15:13 INFO - Creating virtualenv /builds/slave/test/build/venv 20:15:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:15:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:15:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:15:13 INFO - Using real prefix '/tools/python27' 20:15:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:15:14 INFO - Installing distribute.............................................................................................................................................................................................done. 20:15:17 INFO - Installing pip.................done. 20:15:17 INFO - Return code: 0 20:15:17 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:17 INFO - 'HOME': '/Users/cltbld', 20:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:17 INFO - 'LOGNAME': 'cltbld', 20:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:17 INFO - 'MOZ_NO_REMOTE': '1', 20:15:17 INFO - 'NO_EM_RESTART': '1', 20:15:17 INFO - 'PAGER': '/bin/cat', 20:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:17 INFO - 'PWD': '/builds/slave/test', 20:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:17 INFO - 'SHELL': '/bin/bash', 20:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:17 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:17 INFO - 'USER': 'cltbld', 20:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:17 INFO - 'XPC_FLAGS': '0x0', 20:15:17 INFO - 'XPC_SERVICE_NAME': '0', 20:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:15:17 INFO - Cleaning up... 20:15:17 INFO - Return code: 0 20:15:17 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 20:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 20:15:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 20:15:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:17 INFO - 'HOME': '/Users/cltbld', 20:15:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:17 INFO - 'LOGNAME': 'cltbld', 20:15:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:17 INFO - 'MOZ_NO_REMOTE': '1', 20:15:17 INFO - 'NO_EM_RESTART': '1', 20:15:17 INFO - 'PAGER': '/bin/cat', 20:15:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:17 INFO - 'PWD': '/builds/slave/test', 20:15:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:17 INFO - 'SHELL': '/bin/bash', 20:15:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:17 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:17 INFO - 'USER': 'cltbld', 20:15:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:17 INFO - 'XPC_FLAGS': '0x0', 20:15:17 INFO - 'XPC_SERVICE_NAME': '0', 20:15:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:17 INFO - Downloading/unpacking psutil>=0.7.1 20:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:15:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:15:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:15:21 INFO - Installing collected packages: psutil 20:15:21 INFO - Running setup.py install for psutil 20:15:21 INFO - building 'psutil._psutil_osx' extension 20:15:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:15:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:15:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:15:21 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:15:21 INFO - building 'psutil._psutil_posix' extension 20:15:21 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:15:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:15:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:15:21 INFO - ^ 20:15:22 INFO - 1 warning generated. 20:15:22 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:15:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:15:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:15:22 INFO - Successfully installed psutil 20:15:22 INFO - Cleaning up... 20:15:22 INFO - Return code: 0 20:15:22 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 20:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 20:15:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 20:15:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:22 INFO - 'HOME': '/Users/cltbld', 20:15:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:22 INFO - 'LOGNAME': 'cltbld', 20:15:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:22 INFO - 'MOZ_NO_REMOTE': '1', 20:15:22 INFO - 'NO_EM_RESTART': '1', 20:15:22 INFO - 'PAGER': '/bin/cat', 20:15:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:22 INFO - 'PWD': '/builds/slave/test', 20:15:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:22 INFO - 'SHELL': '/bin/bash', 20:15:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:22 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:22 INFO - 'USER': 'cltbld', 20:15:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:22 INFO - 'XPC_FLAGS': '0x0', 20:15:22 INFO - 'XPC_SERVICE_NAME': '0', 20:15:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 20:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:24 INFO - Downloading mozsystemmonitor-0.0.tar.gz 20:15:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:15:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 20:15:25 INFO - Installing collected packages: mozsystemmonitor 20:15:25 INFO - Running setup.py install for mozsystemmonitor 20:15:25 INFO - Successfully installed mozsystemmonitor 20:15:25 INFO - Cleaning up... 20:15:25 INFO - Return code: 0 20:15:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:25 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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:15:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:15:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:25 INFO - 'HOME': '/Users/cltbld', 20:15:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:25 INFO - 'LOGNAME': 'cltbld', 20:15:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:25 INFO - 'MOZ_NO_REMOTE': '1', 20:15:25 INFO - 'NO_EM_RESTART': '1', 20:15:25 INFO - 'PAGER': '/bin/cat', 20:15:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:25 INFO - 'PWD': '/builds/slave/test', 20:15:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:25 INFO - 'SHELL': '/bin/bash', 20:15:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:25 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:25 INFO - 'USER': 'cltbld', 20:15:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:25 INFO - 'XPC_FLAGS': '0x0', 20:15:25 INFO - 'XPC_SERVICE_NAME': '0', 20:15:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:25 INFO - Downloading/unpacking blobuploader==1.2.4 20:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:28 INFO - Downloading blobuploader-1.2.4.tar.gz 20:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:15:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:15:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:29 INFO - Downloading docopt-0.6.1.tar.gz 20:15:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:15:29 INFO - Installing collected packages: blobuploader, requests, docopt 20:15:29 INFO - Running setup.py install for blobuploader 20:15:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:15:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:15:29 INFO - Running setup.py install for requests 20:15:30 INFO - Running setup.py install for docopt 20:15:30 INFO - Successfully installed blobuploader requests docopt 20:15:30 INFO - Cleaning up... 20:15:30 INFO - Return code: 0 20:15:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:30 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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:15:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:15:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:30 INFO - 'HOME': '/Users/cltbld', 20:15:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:30 INFO - 'LOGNAME': 'cltbld', 20:15:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:30 INFO - 'MOZ_NO_REMOTE': '1', 20:15:30 INFO - 'NO_EM_RESTART': '1', 20:15:30 INFO - 'PAGER': '/bin/cat', 20:15:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:30 INFO - 'PWD': '/builds/slave/test', 20:15:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:30 INFO - 'SHELL': '/bin/bash', 20:15:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:30 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:30 INFO - 'USER': 'cltbld', 20:15:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:30 INFO - 'XPC_FLAGS': '0x0', 20:15:30 INFO - 'XPC_SERVICE_NAME': '0', 20:15:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:15:30 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-L3nJ_R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:15:30 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-rEqeiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:15:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:15:30 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-fKqVqz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-9KGP1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-0ZlX1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-FrMw0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-LCaBOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-NZw_tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-s_wAfd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-71bxPC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-4rmRIw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:15:31 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-rIOdXV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:15:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:15:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-XWD7eT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:15:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-s3oOvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:15:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-P__hMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:15:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-9fv4ZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:15:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:15:32 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-DE0rS_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:15:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:15:32 INFO - Running setup.py install for manifestparser 20:15:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:15:32 INFO - Running setup.py install for mozcrash 20:15:32 INFO - Running setup.py install for mozdebug 20:15:32 INFO - Running setup.py install for mozdevice 20:15:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Running setup.py install for mozfile 20:15:33 INFO - Running setup.py install for mozhttpd 20:15:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Running setup.py install for mozinfo 20:15:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Running setup.py install for mozInstall 20:15:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Running setup.py install for mozleak 20:15:33 INFO - Running setup.py install for mozlog 20:15:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:15:33 INFO - Running setup.py install for moznetwork 20:15:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:15:34 INFO - Running setup.py install for mozprocess 20:15:34 INFO - Running setup.py install for mozprofile 20:15:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:15:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:15:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:15:34 INFO - Running setup.py install for mozrunner 20:15:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:15:34 INFO - Running setup.py install for mozscreenshot 20:15:34 INFO - Running setup.py install for moztest 20:15:34 INFO - Running setup.py install for mozversion 20:15:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:15:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:15:35 INFO - Cleaning up... 20:15:35 INFO - Return code: 0 20:15:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:15: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')]} 20:15:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15: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')]} 20:15:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:35 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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:15:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:15:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:35 INFO - 'HOME': '/Users/cltbld', 20:15:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:35 INFO - 'LOGNAME': 'cltbld', 20:15:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:35 INFO - 'MOZ_NO_REMOTE': '1', 20:15:35 INFO - 'NO_EM_RESTART': '1', 20:15:35 INFO - 'PAGER': '/bin/cat', 20:15:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:35 INFO - 'PWD': '/builds/slave/test', 20:15:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:35 INFO - 'SHELL': '/bin/bash', 20:15:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:35 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:35 INFO - 'USER': 'cltbld', 20:15:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:35 INFO - 'XPC_FLAGS': '0x0', 20:15:35 INFO - 'XPC_SERVICE_NAME': '0', 20:15:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-61gqd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ooB6s_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-MOLdGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-h_169P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-TTm15m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ecEveK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:15:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:15:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:15:35 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-koUbzg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Jfm5zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-TR9RZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-YuDsEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-Y5jneW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-3eKBrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-F5Xiqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-y_Jpmr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-ul5eec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:15:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:15:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:15:36 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-njgPdX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:15:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:15:37 INFO - Running setup.py (path:/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/pip-X2jPkI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:15:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:15:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:39 INFO - Downloading blessings-1.6.tar.gz 20:15:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:15:39 INFO - Installing collected packages: blessings 20:15:39 INFO - Running setup.py install for blessings 20:15:39 INFO - Successfully installed blessings 20:15:39 INFO - Cleaning up... 20:15:39 INFO - Return code: 0 20:15:39 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:15:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:15:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:15:40 INFO - Reading from file tmpfile_stdout 20:15:40 INFO - Current package versions: 20:15:40 INFO - blessings == 1.6 20:15:40 INFO - blobuploader == 1.2.4 20:15:40 INFO - docopt == 0.6.1 20:15:40 INFO - manifestparser == 1.1 20:15:40 INFO - mozInstall == 1.12 20:15:40 INFO - mozcrash == 0.17 20:15:40 INFO - mozdebug == 0.1 20:15:40 INFO - mozdevice == 0.48 20:15:40 INFO - mozfile == 1.2 20:15:40 INFO - mozhttpd == 0.7 20:15:40 INFO - mozinfo == 0.9 20:15:40 INFO - mozleak == 0.1 20:15:40 INFO - mozlog == 3.1 20:15:40 INFO - moznetwork == 0.27 20:15:40 INFO - mozprocess == 0.22 20:15:40 INFO - mozprofile == 0.28 20:15:40 INFO - mozrunner == 6.11 20:15:40 INFO - mozscreenshot == 0.1 20:15:40 INFO - mozsystemmonitor == 0.0 20:15:40 INFO - moztest == 0.7 20:15:40 INFO - mozversion == 1.4 20:15:40 INFO - psutil == 3.1.1 20:15:40 INFO - requests == 1.2.3 20:15:40 INFO - wsgiref == 0.1.2 20:15:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:40 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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:40 INFO - 'HOME': '/Users/cltbld', 20:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:40 INFO - 'LOGNAME': 'cltbld', 20:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:40 INFO - 'MOZ_NO_REMOTE': '1', 20:15:40 INFO - 'NO_EM_RESTART': '1', 20:15:40 INFO - 'PAGER': '/bin/cat', 20:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:40 INFO - 'PWD': '/builds/slave/test', 20:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:40 INFO - 'SHELL': '/bin/bash', 20:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:40 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:40 INFO - 'USER': 'cltbld', 20:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:40 INFO - 'XPC_FLAGS': '0x0', 20:15:40 INFO - 'XPC_SERVICE_NAME': '0', 20:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:15:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:15:40 INFO - Cleaning up... 20:15:40 INFO - Return code: 0 20:15:40 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:15:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15:40 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 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:15:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:15:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:40 INFO - 'HOME': '/Users/cltbld', 20:15:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:40 INFO - 'LOGNAME': 'cltbld', 20:15:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:40 INFO - 'MOZ_NO_REMOTE': '1', 20:15:40 INFO - 'NO_EM_RESTART': '1', 20:15:40 INFO - 'PAGER': '/bin/cat', 20:15:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:40 INFO - 'PWD': '/builds/slave/test', 20:15:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:40 INFO - 'SHELL': '/bin/bash', 20:15:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:40 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:40 INFO - 'USER': 'cltbld', 20:15:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:40 INFO - 'XPC_FLAGS': '0x0', 20:15:40 INFO - 'XPC_SERVICE_NAME': '0', 20:15:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:40 INFO - Downloading/unpacking jsonschema 20:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:15:43 INFO - Downloading/unpacking functools32 (from jsonschema) 20:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:43 INFO - Downloading functools32-3.2.3-2.tar.gz 20:15:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:15:43 INFO - warning: no files found matching '*.txt' 20:15:43 INFO - no previously-included directories found matching 'build' 20:15:43 INFO - no previously-included directories found matching 'dist' 20:15:43 INFO - no previously-included directories found matching '.git*' 20:15:43 INFO - Installing collected packages: jsonschema, functools32 20:15:43 INFO - Running setup.py install for functools32 20:15:43 INFO - warning: no files found matching '*.txt' 20:15:43 INFO - no previously-included directories found matching 'build' 20:15:43 INFO - no previously-included directories found matching 'dist' 20:15:43 INFO - no previously-included directories found matching '.git*' 20:15:43 INFO - Successfully installed jsonschema functools32 20:15:43 INFO - Cleaning up... 20:15:43 INFO - Return code: 0 20:15:43 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 20:15: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')]} 20:15:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:15:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15: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')]} 20:15:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:15:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:15: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b993a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9a3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff0015b9a20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, '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.qBDhOpAhql/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.20g95dKJK0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:15: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', 'flake8'] in /builds/slave/test/build 20:15: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 flake8 20:15:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:15:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:15:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:15:43 INFO - 'HOME': '/Users/cltbld', 20:15:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:15:43 INFO - 'LOGNAME': 'cltbld', 20:15:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:15:43 INFO - 'MOZ_NO_REMOTE': '1', 20:15:43 INFO - 'NO_EM_RESTART': '1', 20:15:43 INFO - 'PAGER': '/bin/cat', 20:15:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:15:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:15:43 INFO - 'PWD': '/builds/slave/test', 20:15:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:15:43 INFO - 'SHELL': '/bin/bash', 20:15:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:15:43 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:15:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:15:43 INFO - 'USER': 'cltbld', 20:15:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:15:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:15:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:15:43 INFO - 'XPC_FLAGS': '0x0', 20:15:43 INFO - 'XPC_SERVICE_NAME': '0', 20:15:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:15:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:15:44 INFO - Downloading/unpacking flake8 20:15: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 20:15: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 20:15: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 20:15: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 20:15: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 20:15: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 20:15:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 20:15:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 20:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:47 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 20:15:47 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 20:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 20:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:15:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:15:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:15:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 20:15:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:15:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:15:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:15:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:15:47 INFO - no previously-included directories found matching 'docs/_build' 20:15:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 20:15:48 INFO - Running setup.py install for pep8 20:15:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 20:15:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 20:15:48 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 20:15:48 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 20:15:48 INFO - no previously-included directories found matching 'docs/_build' 20:15:48 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 20:15:48 INFO - Successfully installed flake8 mccabe pyflakes pep8 20:15:48 INFO - Cleaning up... 20:15:48 INFO - Return code: 0 20:15:48 INFO - Running post-action listener: _resource_record_post_action 20:15:48 INFO - Running post-action listener: _start_resource_monitoring 20:15:48 INFO - Starting resource monitoring. 20:15:48 INFO - ##### 20:15:48 INFO - ##### Running install step. 20:15:48 INFO - ##### 20:15:48 INFO - Running pre-action listener: _resource_record_pre_action 20:15:48 INFO - Running main action method: install 20:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:15:48 INFO - Reading from file tmpfile_stdout 20:15:48 INFO - Detecting whether we're running mozinstall >=1.0... 20:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:15:48 INFO - Reading from file tmpfile_stdout 20:15:48 INFO - Output received: 20:15:48 INFO - Usage: mozinstall [options] installer 20:15:48 INFO - Options: 20:15:48 INFO - -h, --help show this help message and exit 20:15:48 INFO - -d DEST, --destination=DEST 20:15:48 INFO - Directory to install application into. [default: 20:15:48 INFO - "/builds/slave/test"] 20:15:48 INFO - --app=APP Application being installed. [default: firefox] 20:15:48 INFO - mkdir: /builds/slave/test/build/application 20:15:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:15:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 20:16:20 INFO - Reading from file tmpfile_stdout 20:16:20 INFO - Output received: 20:16:20 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:16:20 INFO - Running post-action listener: _resource_record_post_action 20:16:20 INFO - ##### 20:16:20 INFO - ##### Running run-tests step. 20:16:20 INFO - ##### 20:16:20 INFO - Running pre-action listener: _resource_record_pre_action 20:16:20 INFO - Running main action method: run_tests 20:16:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:16:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:16:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:16:20 INFO - 2016-05-14 20:16:20.524 screenresolution[1925:11148] starting screenresolution argv=screenresolution get 20:16:20 INFO - 2016-05-14 20:16:20.527 screenresolution[1925:11148] Display 0: 1600x1200x32@60 20:16:20 INFO - 2016-05-14 20:16:20.532 screenresolution[1926:11151] starting screenresolution argv=screenresolution list 20:16:20 INFO - Available Modes on Display 0 20:16:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:16:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:16:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:16:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:16:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:16:20 INFO - Intel Iris: 20:16:20 INFO - Chipset Model: Intel Iris 20:16:20 INFO - Type: GPU 20:16:20 INFO - Bus: Built-In 20:16:20 INFO - VRAM (Dynamic, Max): 1536 MB 20:16:20 INFO - Vendor: Intel (0x8086) 20:16:20 INFO - Device ID: 0x0a2e 20:16:20 INFO - Revision ID: 0x0009 20:16:20 INFO - Displays: 20:16:20 INFO - Display: 20:16:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:16:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:16:20 INFO - Display Serial Number: 1600x1200 60 20:16:20 INFO - Main Display: Yes 20:16:20 INFO - Mirror: Off 20:16:20 INFO - Online: Yes 20:16:20 INFO - Rotation: Supported 20:16:20 INFO - Return code: 0 20:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:16:20 INFO - Python 2.7.3 20:16:20 INFO - Return code: 0 20:16:20 INFO - grabbing minidump binary from tooltool 20:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:16:20 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 0x10b832e00>, '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 0x10b69bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b82db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:16:20 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:16:20 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:16:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:16:20 INFO - Return code: 0 20:16:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:16:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:16:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:16:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:16:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 20:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 20:16:21 INFO - Return code: 0 20:16:21 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/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:16:21 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/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0335 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:16:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qBDhOpAhql/Render', 20:16:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:16:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:16:21 INFO - 'HOME': '/Users/cltbld', 20:16:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:16:21 INFO - 'LOGNAME': 'cltbld', 20:16:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:16:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:16:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:16:21 INFO - 'MOZ_NO_REMOTE': '1', 20:16:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:16:21 INFO - 'NO_EM_RESTART': '1', 20:16:21 INFO - 'PAGER': '/bin/cat', 20:16:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:16:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:16:21 INFO - 'PWD': '/builds/slave/test', 20:16:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:16:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:16:21 INFO - 'SHELL': '/bin/bash', 20:16:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.20g95dKJK0/Listeners', 20:16:21 INFO - 'TMPDIR': '/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/', 20:16:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:16:21 INFO - 'USER': 'cltbld', 20:16:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:16:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:16:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:16:21 INFO - 'XPC_FLAGS': '0x0', 20:16:21 INFO - 'XPC_SERVICE_NAME': '0', 20:16:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:16:21 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/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0335', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:16:22 INFO - mozversion application_buildid: 20160514182335 20:16:22 INFO - mozversion application_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 20:16:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:16:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:16:22 INFO - mozversion application_name: Firefox 20:16:22 INFO - mozversion application_remotingname: firefox-dev 20:16:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:16:22 INFO - mozversion application_vendor: Mozilla 20:16:22 INFO - mozversion application_version: 48.0a2 20:16:22 INFO - mozversion platform_buildid: 20160514182335 20:16:22 INFO - mozversion platform_changeset: f00f9e754ce71fc5efa3e4334afea5cf6ad6c215 20:16:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:16:22 INFO - mozversion platform_version: 48.0a2 20:16:22 INFO - using testdate: 1463282182 20:16:22 INFO - actual date: 1463282182 20:16:22 INFO - starting webserver on 'localhost:49753' 20:16:22 INFO - SUITE-START | Running 2 tests 20:16:22 INFO - TEST-START | dromaeo_css 20:16:22 INFO - operating with platform_type : mac_ 20:16:22 INFO - Initialising browser for dromaeo_css test... 20:16:22 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpz37Ckw/profile http://localhost:49753/getInfo.html) 20:16:24 INFO - PROCESS | 1944 | __metrics Screen width/height:1600/1200 20:16:24 INFO - PROCESS | 1944 | colorDepth:24 20:16:24 INFO - PROCESS | 1944 | Browser inner width/height: 1024/689 20:16:24 INFO - PROCESS | 1944 | __metrics 20:16:24 INFO - PROCESS | 1944 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:16:24 ERROR - PROCESS | 1944 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:16:25 INFO - TEST-INFO | 1944: exit 0 20:16:25 INFO - Browser initialized. 20:16:25 INFO - Running cycle 1/1 for dromaeo_css test... 20:16:25 INFO - TEST-INFO | started process 1946 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpz37Ckw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:16:37 INFO - PROCESS | 1946 | RSS: Main: 211021824 20:16:37 INFO - PROCESS | 1946 | 20:17:28 INFO - PROCESS | 1946 | 1463282248740 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:19:39 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49753/tests/dromaeo/cssquery-ext.html) 20:19:41 INFO - PROCESS | 1946 | RSS: Main: 361869312 20:19:41 INFO - PROCESS | 1946 | 20:22:44 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-ext.html (next: http://localhost:49753/tests/dromaeo/cssquery-jquery.html) 20:22:44 INFO - PROCESS | 1946 | RSS: Main: 355229696 20:22:44 INFO - PROCESS | 1946 | 20:25:46 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49753/tests/dromaeo/cssquery-mootools.html) 20:25:47 INFO - PROCESS | 1946 | RSS: Main: 355966976 20:25:47 INFO - PROCESS | 1946 | 20:28:49 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49753/tests/dromaeo/cssquery-prototype.html) 20:28:50 INFO - PROCESS | 1946 | RSS: Main: 357474304 20:28:50 INFO - PROCESS | 1946 | 20:31:53 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49753/tests/dromaeo/cssquery-yui.html) 20:31:54 INFO - PROCESS | 1946 | RSS: Main: 323416064 20:31:54 INFO - PROCESS | 1946 | 20:34:56 INFO - PROCESS | 1946 | Cycle 1(1): loaded http://localhost:49753/tests/dromaeo/cssquery-yui.html (next: http://localhost:49753/tests/dromaeo/cssquery-dojo.html) 20:34:57 INFO - PROCESS | 1946 | RSS: Main: 365256704 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | __start_tp_report 20:34:57 INFO - PROCESS | 1946 | _x_x_mozilla_page_load 20:34:57 INFO - PROCESS | 1946 | _x_x_mozilla_page_load_details 20:34:57 INFO - PROCESS | 1946 | |i|pagename|runs| 20:34:57 INFO - PROCESS | 1946 | |0;dojo.html;2914.2714321419658;3446.6213514594165;3516;3541.5218945442357;3560.0387895268277;5841.7955371562;5844.094165404358;5844.123381492778;5849.970750146249;5863.091220860762;5815.912761308584;5854.795082172125;5861.882762344761;5867.061270196769;5896.145058966447;4249.745015299092;5171.896562068757;5239.423663397023;5306.867328316804;5378.0857254266675;5321.175217841241;5326.387465441466;5331.546818520411;5336.893262134755;5337.5196232339085;5251.947480525203;5278.947210527903;5303.363596368441;5313;5315.893682126355;4350.456192975862;5341.43914888935;5342.439043900374;5370.624056316059;5412.404635490093;5155.9484405155845;5193.454687257821;5261.0793111205385;5274.997750427407;5293.04725149473;5661.716914154275;5665.54675625953;5676.091825307973;5694.9715251423895;5764.423557644244;6321.905171422432;6330.493560515148;6330.81007569769;6337.081123237105;6356.523260755462;6240.781572644935;6291.339409362043;6296.905546416808;6310.716017779165;6319.052142178664;5906.468417842415;5959.284885813643;5966.940330596725;5971.925053490327;6009.369016253274;6354.142190804182;6356.110144579762;6366.331535188831;6379.776707815204;6385.169927909342;6140.140380346755;6198.2871969723155;6288.717007734663;6324.968375158187;6330.588511746721;6311.842203944938;6329.68351582419;6343.746250150035;6345.111684364193;6359.777407790705;6291.276503202099;6337.96831015842;6348.04779283102;6372.267189273293;6372.299047104815;6212.658303793335;6321.715522801393;6324.114623952651;6358.523110766711;6368.42684158428;4884.535969082932;4926.679765815211;4934.3338649282605;4936.234883593049;4967.850964471072;4439.267520859022;4505.7071290366675;4514.68397212192;4573.085382923428;4585.679002469862;5813.82558523245;5819.912701309484;5832.883342333214;5865.061590145556;5881.852953676193;6112.602680825732;6166.198394208814;6275;6281.309056003836;6314.968425157845;5782.797602083907;5804.738786754606;5820.738066787005;5839.2992840858515;5839.532837373;4656.8137274508645;5449.237106805051;5477.1510415885605;5478.890422191534;5480.150576660624;6292.087647291117;6368.808935731843;6378.457831084317;6385.584936979081;6398.744050237939;6256.655883926245;6274.215723034621;6279.152314437492;6288.496920246278;6305.211848518935;6239.251289845066;6407.359264073555;6430;6451.54839161273;6458.935410645833;6232.220972378453;6312.747490100528;6322.747090116344;6334.524910631629;6347.365263473615;6255.687215639109;6264.185655864709;6278.623282603058;6289.088082228142;6310;6065.575409721454;6089.238845144357;6165.599236049642;6260.561760676709;6276.780312689034;6157.507399408126;6214.844628884133;6235.875282494234;6246.000639897595;6251.374862513712;6082.178905847831;6089.634621922699;6099.2985806632805;6104.8779024420155;6111.54163437735;6088.51291896656;6150.630962142286;6229.034499652561;6244.531660125418;6249.812505624838;6042.093685947143;6116.42707509198;6125.899657344667;6132.103718785542;6138.4525421951175 20:34:57 INFO - PROCESS | 1946 | |1;ext.html;512.5617596954604;517.477347878643;527.5410392958125;553.9944600553995;657.9999999999997;27581.724182758167;31917.840410797944;31934.840325798337;31939;31942.520862187055;32657.67342326576;40497.39253911186;41274;41292.38061429081;41296.58703412965;9437.575309111073;9622;9622.566984485715;9627;9641.084097010773;8613.784655383633;9458.290628202878;9566.186874115674;9567.952160239189;9577.569009394594;8663.523506207157;11551.65345039646;11786.17496775226;11817.054635629172;11818.763624727544;11424.600138995136;11560.884391156065;11563.595274165364;11579.652610421701;11580.478878450405;8519.74440766778;11258.605948791836;11415.972562469418;11498.827517587242;11731;29690.85154574235;32344.514832277535;39881.202375952606;39969.800905972865;40012;3110.9222269443217;3159.352332771776;3178.825164615945;3252.642209356969;3281.6554261802644;22817.657735133987;27294.454110917868;27806.443871122665;28050.438991220264;28662.713372866423;2797.9720202798117;2799.0623141247706;2812.803103782736;2820.534611789052;2903.840288784116;506916.46541767055;507586;507668;508794;509246;66137.66931165314;66409.33590664128;66414.66792666005;66490.33509664939;66527;69960.65019674868;70085;70092.94860576994;70189.64905175543;70191.64904175549;16015.359385624677;17866;18114;18114.275428982957;18124.728129078074;12353.38233088342;12412.379381031093;12449.439775209956;12461.626151215478;13646.113002654994;15606.531804045897;18266.81733182651;18456.630867382577;18641.347552835585;18987;7182.3535881770895;7331.706731730777;7368.300011498899;7371.189169191384;7400.777976660709;997.546116516983;1002.1281485108003;1011.5094179323022;1058.6030238660503;1126.4592995362273;28685.56971645427;29860.55209171864;29861.701382986328;30327.545086823717;30451.847740761154;1084.4252546150553;1137.1130518195655;1151.3264740126951;1178.281248438452;1182.355616189179;15204.9356545043;15333.616659583377;15370.769438458432;15514.965556059078;15728.68542629141;599267;604603;605757;605830;606080;59949.70025149846;61137.69431152905;61232;61286.69356653278;61344;1328.5748560460754;1367.6033950154344;1485.4578079001303;1502.8572285632847;1570.4032467662216;28712.856435717687;29211.7078829209;30386.240343991576;30433;31030;50481.990360191856;50822.49177508178;50875.999999998516;50987;51005.74497127491;435025;438383;439083;439633;441073;33295.66704332926;41474;41911;42651.146977060904;43082.56917430911;43189.13621727485;43331;44380.334294984954;44788;44890;35170.59317627266;35280.47079293864;36287;36298.27403451969;37146;35578.28843423169;35681.64318356887;36194.276114477034;36520.45219321659;37594;36304.63695363118;36763.816180918926;36908.26183476369;37013.62986370102;37033;31949;33067.50398744069;33077;33131;33139;24506.50986980286;24674.629880551445;24797.87601061983;25181.370465738502;25242.621360679972 20:34:57 INFO - PROCESS | 1946 | |2;jquery.html;395.8001209389258;407.7227485309989;410.90754580219453;413.31389892778;424.38676113016703;16687.66624667506;19789.50526236843;19799.802001979977;20528.07623656935;20779.688304675405;19844.60310793787;20426.97865106746;20592.588148237028;20595.485112872186;20810.791892081073;4805.351277577517;5647.265855438797;5881.8823623527505;5902.3507414184405;6101.4203650653335;5986.281646202462;6059;6076.331603523631;6079.756809727628;6080.2703675559;5651.2370829938045;5765.336986246572;5772.163036359725;6021.09683547467;6030.42710942462;5666.603337766337;5680.8295751127325;5730.111832665943;5731.426857314277;5747.396523365028;5002.624803139743;5062.696238225718;5081.110805609003;5098.439171691111;5108.591312695013;21135.577288454144;25069;25366.746332536623;25542;25874.353141171432;2226.365485836545;2267.2291420916968;2391.7847393734473;2429.6112621980396;2645.642838216835;14761.261936903113;14908.925455372762;15098.773518397233;15139.848601513953;15412.922935385364;1809.5204770735766;1810.4387639831564;1863.30126202674;1890.0549725137432;1912.4740696308488;127273;129610.99999999905;132052;132416.33791831075;136633;21328.36014919555;21526.89236553807;21584.892075539523;21654.566908661738;21788.346349609536;34459;34469.44887480069;35422;35635.64364356375;35709.46435803465;8520.19058189471;8897.777555561162;8924.464532128093;8959.865602015974;9016.459012459274;8981;8993.685221017233;8998.910010899937;9088.182063614175;9092.227160691284;3840.231953609289;4573.085382923428;4653.347591872668;4653.348531205634;4717.315989181549;1988.2643421933976;2002.389271272276;2004.7093171490344;2006;2011.5071807407278;865.5715420866585;880.4805164952709;931.3992474853682;933.0389698610442;1014.644874294006;18727.719084213746;19618.60762784765;20191.4952126198;20303.28938487146;20318.796812031986;814.5560669435172;963.905966727769;974.6198982396871;987.0474991633108;992.4293531219578;10341.741456463498;10724.624638137628;10762.56949722018;10773.299735517166;10786.730331741612;577808;596478;605267;636333;649621;78909.60545197352;107647;113157.4342128284;138305.308473459;142393.28803355916;947.4930911962067;956.7464621875208;972.2805124208035;972.9464879431699;973.9561719722772;15037.172955487553;16223.837761622233;17538.82461175372;17755.911220443813;17815.19146515539;52726.47273527215;56052.43947560472;56329;56494.71752641375;57924.710376447845;77708;79323;80115.59942200252;83371.5831420839;83716.58141709253;7855.37157027425;8068.07217170033;8222.958885205535;8236.588170591327;8241.464304820167;9163.450192988443;12128.454219559964;12131.090168237593;12433.75132497327;12454.065945053975;9873.210143188384;10295.897041029493;10511.054005139575;10527.368357898551;10912.563497460013;9066.863997040182;10395.272330936761;10829.729256768644;10915.39965301916;11021.393823339473;10466.738331541468;10715.678529644123;11463.254888432226;11660.125490588409;11704.765904681688;8097.190280971856;8111.188881112078;8215.794605134919;8277.710280140167;8381.203785640355;8292.751217463485;10370.429626370622;10958.178136639624;11201.215914886207;11256.83114753263 20:34:57 INFO - PROCESS | 1946 | |3;mootools.html;1415.1650526189546;1501.744703400422;1562.7968364112667;1794.6321004194142;1947.1919153551269;7934.722284720029;8624.956875215623;8747.868781968276;8752.649894004247;8769.298456123512;8485.193906578881;8714.91285087149;8717.215450609443;8717.912820871805;8726.5636718164;1912.8565357598166;2162.362103179566;2355.2580937004786;2460.1881379144884;2483.552960467115;2120.5122821751006;2464.605663093905;2466.5313590417854;2469.0494159748446;2470.4318006858434;2386.367612582667;2425.842320249187;2441.218809980807;2444.449998750276;2445.8043356531434;2171.326888664519;2411.807055435561;2460.6555082288496;2462.9014839406404;2469.604863221894;2417.576924038304;2435.695538057743;2443.7556244375596;2444.352246654638;2444.4744379958393;8543.95728021362;8615.870761938577;8624.913750862474;8638.136186381375;8672.8699069514;5097.260897169889;5270.710110943896;5299.761510731988;5323.787048518055;5340.599455040849;3577.463380492921;3583.7312201584723;3597.94603080954;3628.092976755811;3662.7802331860175;1066.3228849680431;1076.5370890517074;1076.590895459728;1081.1404933078222;1081.3998230981801;11861.584844530485;11876.10929180315;11876.287422754576;11888.88111118895;11891.583794567148;4689.906201875943;4726.550977657117;4791.281307803788;4835.782389792468;4872.6832755870755;9996.450195239186;10016.79966400668;10021.398716076912;10045.648402305884;10049.397036177852;5368.570514358842;5368.570514358842;5375.731213439312;5403.675779453245;5405.567554595623;3959.366501359769;4440.533743956838;4504.25679762842;4882.340883980688;4938.580220681282;3845.423186521988;3904.9804750976064;3919.6864250860435;3951.565327813938;3973.264945985002;1341.7584834729648;1347.7371912476972;1351.094766506443;1352.1886867879193;1371.1156304183746;795.4988357334958;864.6671031652859;883.9071897450675;889.4129874282942;920.2499962530517;5749.453801888814;5820.097884827858;5906.586538942232;5966.612170208922;6055.697215139226;951.4957072751314;957.4064080270277;962.432165022626;968.675493709601;1009.8889122196553;3732.664060234592;3807.8857634271017;3873.3802591585772;3911.902202444962;3913.2760439318818;10296.691099267035;10446.164306855433;10699.679009629723;10820.458977051116;10866.728331791768;10348.275620706629;10442.582296708046;10465.68602941913;10466.372017678963;10526.894731052744;8221.383396245306;8262.876056859152;8415.11641277657;8420.873686894702;8519.233269005943;5681.17622944679;5766.330720418066;5849.970750146222;5968.731407086779;6004.5796794225735;9423.717288481357;9478.478683672463;9775.951120244354;9790;9807.803843923224;9700.951495242478;9949;9956.054174853378;10021.699349019542;10123.544440500047;6848.9726541019245;7009.719611215494;7013.929860701328;7630.961845190738;7760.495567788302;8033.236842499953;8239.340852731886;8262.132476089924;8273.33813294947;8273.751787446387;7250.238724934017;7310.96344518274;7332.890006649798;7449.851002979802;7491.175970643334;6589.670516474253;7178.916957473496;7315.670794814139;7334.743283985035;7393.852122957618;6010.939890601038;6896.413804826646;6951.721931122901;6996.020557121905;7000.7549735759;4767.76161191946;4804.615630749601;4812.51874812516;4936.481669424658;4937.432195297443;4849.3210950467665;4864.854054378375;4905.337779399735;4920.778564964657;5094.617903657315 20:34:57 INFO - PROCESS | 1946 | |4;prototype.html;326.69780453080904;330.7370640340929;336.6381140274205;339.26887557313995;342.92798512312413;5326.680399176043;6508.511861610375;6615.834604134887;6643.169603799525;6649.368310010541;6506.121673574066;6526.608403495782;6547.672616369175;6574.704138313776;6587.967060164705;4568.109218702354;4648.000679853831;4706.623470122382;4707.646926480511;4861.319415281863;4821.807127714905;4873.098476781806;4900.877478063059;4922.409310882699;4942.654014219006;4739.6208303335825;4830.275458681191;4834.468208497063;4853.684510506823;4859.343988561534;4627.8611641650805;4660.650451216138;4778.976105119487;4849.418069831625;4864.8297309594345;4124.752514849119;4125.608067233608;4144.52337981133;4170.749755014678;4182.372644103379;5612.186232996192;5720.542356611502;6295.464885484694;6315.810525684236;6334.683265836736;5078.61910356721;5378.542823859977;5753.367129615739;5769.8557536061935;5773.047447171211;7019.227884932653;7032.894506582355;7034.824129396755;7044.084269045043;7046.4010559102535;3966.6628336591425;4141.151064031867;4516.322551617251;4694.624430045588;4708.223143181371;7300.612883552161;7347.88978165328;7370.041894553728;7406.777796666109;7414.777556673254;4638;4649.930251046237;4650.116477869194;4650.534946505322;4652.023075154188;4686.133065382915;4690.132325519789;4705.882352941204;4723.527647235249;4723.83466578675;5579.609427340048;5582.302212223472;5586.888262234814;5593.8601534961135;5594.860128496739;5441.891162176814;7005.0893383859775;7059.399951004222;7067.22260551339;7073.929260707327;1606.3654856331916;1616.5796892807955;1617.9029258244307;1624.0661619568796;1627.886047976654;966.6665000574791;1021.8160731068479;1022.6420752736632;1035.6427032673716;1045.0490054050777;6259.123722678829;6318.905216421757;6468;6501.414872661483;6511.2512061113575;6436.16329877113;6840.828979275558;7305.269473052758;7311.341979221789;7320.890186647204;5309.814156504503;6418.326075762071;6473.741050357933;6482.027695845567;6496.707648155844;3805.238952209569;3853.710971677079;3856.5564960029396;3858.2476417098956;3861.768293902375;6123.540734444934;7317.451191160578;7579.696812127453;7580.469367144357;7597.392208623297;7464.925350746423;7481.551106933602;7519.548827070284;7520.699172033057;7521.360684341892;7567.091948966159;7570.697172113054;7585.62071896403;7592.126905405838;7594.696212151452;6983.032211310275;7261.963690181516;7263.782086537412;7267.36631684158;7268.0914885639295;7159.212486626363;7175.748848790267;7188.640567971476;7188.892166617609;7193.712251509881;7526.360259378014;7590.734324298623;7602.657880395506;7608.657610407434;7611.619419028915;4601.401817763669;4602.907941841077;4614.723116613014;4615.630749540096;4626.583607475343;7245.203027666847;7251.057362543047;7252.492325537161;7258.129024517092;7261.636918153966;6709;6941.271166527442;6958.060661810793;6959.095317608679;6970.546914450544;6875.243723190345;6953.930460695531;6971.825704357431;6973.3375329343635;6975.546589471668;6855.862882742217;6880.69036893331;6888.104546408935;6896.310368963063;6903.0680858083515;6037.215162028908;6834.965825170842;7054.929450705427;7081.893771593327;7082.150141982995;6614.536982411185;6616.768413105518;6789.388954994074;6838.829029274308;6985.650717464208 20:34:57 INFO - PROCESS | 1946 | |5;yui.html;698.3679769808324;749.7713197474768;756.5233902641335;758.4652819762068;780.3172224303735;15105.924470377633;15164.014339067953;15342.84657153428;15369.538913832603;15417.306221220044;14220.71558568828;14289.785653215211;14316.928415357934;14318.785218221736;14331.211783351911;745;753.4123383760675;788.6805843633338;906.1482206725657;948.8434408322619;917.7613820406675;948.6584829461389;948.7722946492827;949.5442187749884;952.5808644196566;1000.5197505197509;1004.2417974429333;1006.9748256293578;1008.1380419741125;1008.5814387029374;775.3835700618022;977.1449981266392;989.7278248481654;993.2649839119;996.6511720897701;738.4572339330588;739.0613920321216;741.0292516802951;741.262443868349;742.8737114690516;15287.770683439758;15316.081035137928;15331.846681533154;15352.616184595474;15359;13005.21968681882;13378.799318010238;13395.732085358335;13515.459381624723;13555.728885422335;10099.949500252525;10147.492625368703;10243;10538.156947444186;10908.454577271104;3535.328287625369;3555.9111022224392;3578.5526809148855;3638.490611314418;3806.638369354907;79495.20504794993;79606.60196699038;79848;81123.5943820283;90223.09776902212;9813.11681948628;9818.72885294899;9969.601215951281;9990.200783937267;10028.44843533598;22802;23477.765222347556;23487.647685284734;23527.41181470443;23553.646695299583;7705.961470192613;7812.726554570563;7824.647890844925;7954.681812727542;8077.111517733045;4097.733647312974;4103.958960410358;4109.219248342805;4236.5763423658;4397.516273210008;3139.3564319314487;3671.155634204148;3746.4754934309217;3748.081719978623;3749.2126653402547;646.1083704487777;649.9155109835691;654.9017647352936;656.2813718977711;670.2125003121313;559.560744815318;600.1477901379964;605.8757954619292;610.1336102734128;610.377415036669;7841.137474877759;8281.751547453587;8290.751277461686;8402.11777763326;8423.70517031901;597.9820605381846;606.1029676079427;610.8992016317346;611.7858749437661;612.3753771153488;3668.834902429397;3799.2211596622624;3849.3071247175217;3962.227365663726;4025;96750;97050.51474742581;98610.5069474648;99009.50495247477;99359.50320248352;81624.59187704143;82458;83001.16998829934;83972;84367.5781621088;72713.1821704451;73450.63274683699;74420;74459;75438.6228068867;7689.307962283421;7812.64843082074;8022.719204827803;8026;8047;51202.48797511977;51535.742321288155;51539.48460515347;51661.7416912913;51910.221346680584;64132.35867641451;66287.33712662812;71743.64128179326;71914;72159;10339.793204136025;10527.894721052691;10632.202584806326;10634.946825265824;10689.625863094754;10880.795396125686;10970.835437468284;11110.166737494881;11324.433778310886;11453.08375329955;10023.148032417326;10485.21360897951;10491;10629.89370106289;10696.839547406951;10320.43237621908;10323.690289291333;10425.895741042492;10618.73453163677;10665.253432259668;10239.180865530889;10311.432871192157;10312.896871031493;10456.477176141314;10502.474876256003;8916.86624700617;8929.553522323988;9100.954495227481;9213.493257870881;9260.30547708911;8975;9002.726744445872;9024.593893274687;9072.682456114004;9145.314101442285 20:34:57 INFO - PROCESS | 1946 | __end_tp_report 20:34:57 INFO - PROCESS | 1946 | __start_cc_report 20:34:57 INFO - PROCESS | 1946 | _x_x_mozilla_cycle_collect,1360 20:34:57 INFO - PROCESS | 1946 | __end_cc_report 20:34:57 INFO - PROCESS | 1946 | __startTimestamp1463283297154__endTimestamp 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | ------- Summary: start ------- 20:34:57 INFO - PROCESS | 1946 | Number of tests: 6 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#0] dojo.html Cycles:165 Average:5823.64 Median:6104.88 stddev:673.48 (11.0%) stddev-sans-first:635.70 20:34:57 INFO - PROCESS | 1946 | Values: 2914.3 3446.6 3516.0 3541.5 3560.0 5841.8 5844.1 5844.1 5850.0 5863.1 5815.9 5854.8 5861.9 5867.1 5896.1 4249.7 5171.9 5239.4 5306.9 5378.1 5321.2 5326.4 5331.5 5336.9 5337.5 5251.9 5278.9 5303.4 5313.0 5315.9 4350.5 5341.4 5342.4 5370.6 5412.4 5155.9 5193.5 5261.1 5275.0 5293.0 5661.7 5665.5 5676.1 5695.0 5764.4 6321.9 6330.5 6330.8 6337.1 6356.5 6240.8 6291.3 6296.9 6310.7 6319.1 5906.5 5959.3 5966.9 5971.9 6009.4 6354.1 6356.1 6366.3 6379.8 6385.2 6140.1 6198.3 6288.7 6325.0 6330.6 6311.8 6329.7 6343.7 6345.1 6359.8 6291.3 6338.0 6348.0 6372.3 6372.3 6212.7 6321.7 6324.1 6358.5 6368.4 4884.5 4926.7 4934.3 4936.2 4967.9 4439.3 4505.7 4514.7 4573.1 4585.7 5813.8 5819.9 5832.9 5865.1 5881.9 6112.6 6166.2 6275.0 6281.3 6315.0 5782.8 5804.7 5820.7 5839.3 5839.5 4656.8 5449.2 5477.2 5478.9 5480.2 6292.1 6368.8 6378.5 6385.6 6398.7 6256.7 6274.2 6279.2 6288.5 6305.2 6239.3 6407.4 6430.0 6451.5 6458.9 6232.2 6312.7 6322.7 6334.5 6347.4 6255.7 6264.2 6278.6 6289.1 6310.0 6065.6 6089.2 6165.6 6260.6 6276.8 6157.5 6214.8 6235.9 6246.0 6251.4 6082.2 6089.6 6099.3 6104.9 6111.5 6088.5 6150.6 6229.0 6244.5 6249.8 6042.1 6116.4 6125.9 6132.1 6138.5 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#1] ext.html Cycles:180 Average:66060.14 Median:34233.13 stddev:138987.94 (406.0%) stddev-sans-first:139290.70 20:34:57 INFO - PROCESS | 1946 | Values: 512.6 517.5 527.5 554.0 658.0 27581.7 31917.8 31934.8 31939.0 31942.5 32657.7 40497.4 41274.0 41292.4 41296.6 9437.6 9622.0 9622.6 9627.0 9641.1 8613.8 9458.3 9566.2 9568.0 9577.6 8663.5 11551.7 11786.2 11817.1 11818.8 11424.6 11560.9 11563.6 11579.7 11580.5 8519.7 11258.6 11416.0 11498.8 11731.0 29690.9 32344.5 39881.2 39969.8 40012.0 3110.9 3159.4 3178.8 3252.6 3281.7 22817.7 27294.5 27806.4 28050.4 28662.7 2798.0 2799.1 2812.8 2820.5 2903.8 506916.5 507586.0 507668.0 508794.0 509246.0 66137.7 66409.3 66414.7 66490.3 66527.0 69960.7 70085.0 70092.9 70189.6 70191.6 16015.4 17866.0 18114.0 18114.3 18124.7 12353.4 12412.4 12449.4 12461.6 13646.1 15606.5 18266.8 18456.6 18641.3 18987.0 7182.4 7331.7 7368.3 7371.2 7400.8 997.5 1002.1 1011.5 1058.6 1126.5 28685.6 29860.6 29861.7 30327.5 30451.8 1084.4 1137.1 1151.3 1178.3 1182.4 15204.9 15333.6 15370.8 15515.0 15728.7 599267.0 604603.0 605757.0 605830.0 606080.0 59949.7 61137.7 61232.0 61286.7 61344.0 1328.6 1367.6 1485.5 1502.9 1570.4 28712.9 29211.7 30386.2 30433.0 31030.0 50482.0 50822.5 50876.0 50987.0 51005.7 435025.0 438383.0 439083.0 439633.0 441073.0 33295.7 41474.0 41911.0 42651.1 43082.6 43189.1 43331.0 44380.3 44788.0 44890.0 35170.6 35280.5 36287.0 36298.3 37146.0 35578.3 35681.6 36194.3 36520.5 37594.0 36304.6 36763.8 36908.3 37013.6 37033.0 31949.0 33067.5 33077.0 33131.0 33139.0 24506.5 24674.6 24797.9 25181.4 25242.6 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#2] jquery.html Cycles:180 Average:36444.22 Median:19774.85 stddev:102344.91 (517.6%) stddev-sans-first:102596.22 20:34:57 INFO - PROCESS | 1946 | Values: 395.8 407.7 410.9 413.3 424.4 16687.7 19789.5 19799.8 20528.1 20779.7 19844.6 20427.0 20592.6 20595.5 20810.8 4805.4 5647.3 5881.9 5902.4 6101.4 5986.3 6059.0 6076.3 6079.8 6080.3 5651.2 5765.3 5772.2 6021.1 6030.4 5666.6 5680.8 5730.1 5731.4 5747.4 5002.6 5062.7 5081.1 5098.4 5108.6 21135.6 25069.0 25366.7 25542.0 25874.4 2226.4 2267.2 2391.8 2429.6 2645.6 14761.3 14908.9 15098.8 15139.8 15412.9 1809.5 1810.4 1863.3 1890.1 1912.5 127273.0 129611.0 132052.0 132416.3 136633.0 21328.4 21526.9 21584.9 21654.6 21788.3 34459.0 34469.4 35422.0 35635.6 35709.5 8520.2 8897.8 8924.5 8959.9 9016.5 8981.0 8993.7 8998.9 9088.2 9092.2 3840.2 4573.1 4653.3 4653.3 4717.3 1988.3 2002.4 2004.7 2006.0 2011.5 865.6 880.5 931.4 933.0 1014.6 18727.7 19618.6 20191.5 20303.3 20318.8 814.6 963.9 974.6 987.0 992.4 10341.7 10724.6 10762.6 10773.3 10786.7 577808.0 596478.0 605267.0 636333.0 649621.0 78909.6 107647.0 113157.4 138305.3 142393.3 947.5 956.7 972.3 972.9 974.0 15037.2 16223.8 17538.8 17755.9 17815.2 52726.5 56052.4 56329.0 56494.7 57924.7 77708.0 79323.0 80115.6 83371.6 83716.6 7855.4 8068.1 8223.0 8236.6 8241.5 9163.5 12128.5 12131.1 12433.8 12454.1 9873.2 10295.9 10511.1 10527.4 10912.6 9066.9 10395.3 10829.7 10915.4 11021.4 10466.7 10715.7 11463.3 11660.1 11704.8 8097.2 8111.2 8215.8 8277.7 8381.2 8292.8 10370.4 10958.2 11201.2 11256.8 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#3] mootools.html Cycles:180 Average:5675.51 Median:4893.84 stddev:3162.06 (64.6%) stddev-sans-first:3154.72 20:34:57 INFO - PROCESS | 1946 | Values: 1415.2 1501.7 1562.8 1794.6 1947.2 7934.7 8625.0 8747.9 8752.6 8769.3 8485.2 8714.9 8717.2 8717.9 8726.6 1912.9 2162.4 2355.3 2460.2 2483.6 2120.5 2464.6 2466.5 2469.0 2470.4 2386.4 2425.8 2441.2 2444.4 2445.8 2171.3 2411.8 2460.7 2462.9 2469.6 2417.6 2435.7 2443.8 2444.4 2444.5 8544.0 8615.9 8624.9 8638.1 8672.9 5097.3 5270.7 5299.8 5323.8 5340.6 3577.5 3583.7 3597.9 3628.1 3662.8 1066.3 1076.5 1076.6 1081.1 1081.4 11861.6 11876.1 11876.3 11888.9 11891.6 4689.9 4726.6 4791.3 4835.8 4872.7 9996.5 10016.8 10021.4 10045.6 10049.4 5368.6 5368.6 5375.7 5403.7 5405.6 3959.4 4440.5 4504.3 4882.3 4938.6 3845.4 3905.0 3919.7 3951.6 3973.3 1341.8 1347.7 1351.1 1352.2 1371.1 795.5 864.7 883.9 889.4 920.2 5749.5 5820.1 5906.6 5966.6 6055.7 951.5 957.4 962.4 968.7 1009.9 3732.7 3807.9 3873.4 3911.9 3913.3 10296.7 10446.2 10699.7 10820.5 10866.7 10348.3 10442.6 10465.7 10466.4 10526.9 8221.4 8262.9 8415.1 8420.9 8519.2 5681.2 5766.3 5850.0 5968.7 6004.6 9423.7 9478.5 9776.0 9790.0 9807.8 9701.0 9949.0 9956.1 10021.7 10123.5 6849.0 7009.7 7013.9 7631.0 7760.5 8033.2 8239.3 8262.1 8273.3 8273.8 7250.2 7311.0 7332.9 7449.9 7491.2 6589.7 7178.9 7315.7 7334.7 7393.9 6010.9 6896.4 6951.7 6996.0 7000.8 4767.8 4804.6 4812.5 4936.5 4937.4 4849.3 4864.9 4905.3 4920.8 5094.6 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#4] prototype.html Cycles:180 Average:5703.08 Median:6489.37 stddev:1825.26 (28.1%) stddev-sans-first:1785.21 20:34:57 INFO - PROCESS | 1946 | Values: 326.7 330.7 336.6 339.3 342.9 5326.7 6508.5 6615.8 6643.2 6649.4 6506.1 6526.6 6547.7 6574.7 6588.0 4568.1 4648.0 4706.6 4707.6 4861.3 4821.8 4873.1 4900.9 4922.4 4942.7 4739.6 4830.3 4834.5 4853.7 4859.3 4627.9 4660.7 4779.0 4849.4 4864.8 4124.8 4125.6 4144.5 4170.7 4182.4 5612.2 5720.5 6295.5 6315.8 6334.7 5078.6 5378.5 5753.4 5769.9 5773.0 7019.2 7032.9 7034.8 7044.1 7046.4 3966.7 4141.2 4516.3 4694.6 4708.2 7300.6 7347.9 7370.0 7406.8 7414.8 4638.0 4649.9 4650.1 4650.5 4652.0 4686.1 4690.1 4705.9 4723.5 4723.8 5579.6 5582.3 5586.9 5593.9 5594.9 5441.9 7005.1 7059.4 7067.2 7073.9 1606.4 1616.6 1617.9 1624.1 1627.9 966.7 1021.8 1022.6 1035.6 1045.0 6259.1 6318.9 6468.0 6501.4 6511.3 6436.2 6840.8 7305.3 7311.3 7320.9 5309.8 6418.3 6473.7 6482.0 6496.7 3805.2 3853.7 3856.6 3858.2 3861.8 6123.5 7317.5 7579.7 7580.5 7597.4 7464.9 7481.6 7519.5 7520.7 7521.4 7567.1 7570.7 7585.6 7592.1 7594.7 6983.0 7262.0 7263.8 7267.4 7268.1 7159.2 7175.7 7188.6 7188.9 7193.7 7526.4 7590.7 7602.7 7608.7 7611.6 4601.4 4602.9 4614.7 4615.6 4626.6 7245.2 7251.1 7252.5 7258.1 7261.6 6709.0 6941.3 6958.1 6959.1 6970.5 6875.2 6953.9 6971.8 6973.3 6975.5 6855.9 6880.7 6888.1 6896.3 6903.1 6037.2 6835.0 7054.9 7081.9 7082.2 6614.5 6616.8 6789.4 6838.8 6985.7 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - PROCESS | 1946 | [#5] yui.html Cycles:180 Average:18825.31 Median:608.12 stddev:26989.65 (4438.2%) stddev-sans-first:27031.04 20:34:57 INFO - PROCESS | 1946 | Values: 698.4 749.8 756.5 758.5 780.3 15105.9 15164.0 15342.8 15369.5 15417.3 14220.7 14289.8 14316.9 14318.8 14331.2 745.0 753.4 788.7 906.1 948.8 917.8 948.7 948.8 949.5 952.6 1000.5 1004.2 1007.0 1008.1 1008.6 775.4 977.1 989.7 993.3 996.7 738.5 739.1 741.0 741.3 742.9 15287.8 15316.1 15331.8 15352.6 15359.0 13005.2 13378.8 13395.7 13515.5 13555.7 10099.9 10147.5 10243.0 10538.2 10908.5 3535.3 3555.9 3578.6 3638.5 3806.6 79495.2 79606.6 79848.0 81123.6 90223.1 9813.1 9818.7 9969.6 9990.2 10028.4 22802.0 23477.8 23487.6 23527.4 23553.6 7706.0 7812.7 7824.6 7954.7 8077.1 4097.7 4104.0 4109.2 4236.6 4397.5 3139.4 3671.2 3746.5 3748.1 3749.2 646.1 649.9 654.9 656.3 670.2 559.6 600.1 605.9 610.1 610.4 7841.1 8281.8 8290.8 8402.1 8423.7 598.0 606.1 610.9 611.8 612.4 3668.8 3799.2 3849.3 3962.2 4025.0 96750.0 97050.5 98610.5 99009.5 99359.5 81624.6 82458.0 83001.2 83972.0 84367.6 72713.2 73450.6 74420.0 74459.0 75438.6 7689.3 7812.6 8022.7 8026.0 8047.0 51202.5 51535.7 51539.5 51661.7 51910.2 64132.4 66287.3 71743.6 71914.0 72159.0 10339.8 10527.9 10632.2 10634.9 10689.6 10880.8 10970.8 11110.2 11324.4 11453.1 10023.1 10485.2 10491.0 10629.9 10696.8 10320.4 10323.7 10425.9 10618.7 10665.3 10239.2 10311.4 10312.9 10456.5 10502.5 8916.9 8929.6 9101.0 9213.5 9260.3 8975.0 9002.7 9024.6 9072.7 9145.3 20:34:57 INFO - PROCESS | 1946 | -------- Summary: end -------- 20:34:57 INFO - PROCESS | 1946 | 20:34:57 INFO - TEST-INFO | 1946: exit 0 20:34:57 INFO - TEST-OK | dromaeo_css | took 1115563ms 20:34:57 INFO - TEST-START | kraken 20:34:57 INFO - operating with platform_type : mac_ 20:34:57 INFO - Initialising browser for kraken test... 20:34:57 INFO - TEST-INFO | started process 1952 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpTMyPL8/profile http://localhost:49753/getInfo.html) 20:34:59 INFO - PROCESS | 1952 | __metrics Screen width/height:1600/1200 20:34:59 INFO - PROCESS | 1952 | colorDepth:24 20:34:59 INFO - PROCESS | 1952 | Browser inner width/height: 1024/689 20:34:59 INFO - PROCESS | 1952 | __metrics 20:34:59 INFO - PROCESS | 1952 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:34:59 ERROR - PROCESS | 1952 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 20:35:00 INFO - TEST-INFO | 1952: exit 0 20:35:00 INFO - Browser initialized. 20:35:00 INFO - Running cycle 1/1 for kraken test... 20:35:00 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/T/tmpTMyPL8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:35:12 INFO - PROCESS | 1954 | RSS: Main: 210731008 20:35:12 INFO - PROCESS | 1954 | 20:35:15 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49753/tests/kraken/driver.html?testName=audio-beat-detection) 20:35:16 INFO - PROCESS | 1954 | RSS: Main: 272855040 20:35:16 INFO - PROCESS | 1954 | 20:35:18 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49753/tests/kraken/driver.html?testName=audio-dft) 20:35:19 INFO - PROCESS | 1954 | RSS: Main: 286875648 20:35:19 INFO - PROCESS | 1954 | 20:35:22 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49753/tests/kraken/driver.html?testName=audio-fft) 20:35:23 INFO - PROCESS | 1954 | RSS: Main: 372211712 20:35:23 INFO - PROCESS | 1954 | 20:35:25 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49753/tests/kraken/driver.html?testName=audio-oscillator) 20:35:25 INFO - PROCESS | 1954 | RSS: Main: 278650880 20:35:25 INFO - PROCESS | 1954 | 20:35:28 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49753/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:35:28 INFO - PROCESS | 1954 | RSS: Main: 279154688 20:35:28 INFO - PROCESS | 1954 | 20:35:31 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49753/tests/kraken/driver.html?testName=imaging-darkroom) 20:35:31 INFO - PROCESS | 1954 | RSS: Main: 317325312 20:35:31 INFO - PROCESS | 1954 | 20:35:34 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49753/tests/kraken/driver.html?testName=imaging-desaturate) 20:35:35 INFO - PROCESS | 1954 | RSS: Main: 315314176 20:35:35 INFO - PROCESS | 1954 | 20:35:38 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49753/tests/kraken/driver.html?testName=json-parse-financial) 20:35:39 INFO - PROCESS | 1954 | RSS: Main: 318566400 20:35:39 INFO - PROCESS | 1954 | 20:35:40 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49753/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:35:41 INFO - PROCESS | 1954 | RSS: Main: 308301824 20:35:41 INFO - PROCESS | 1954 | 20:35:43 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:35:43 INFO - PROCESS | 1954 | RSS: Main: 315613184 20:35:43 INFO - PROCESS | 1954 | 20:35:45 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:35:46 INFO - PROCESS | 1954 | RSS: Main: 318124032 20:35:46 INFO - PROCESS | 1954 | 20:35:49 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:35:49 INFO - PROCESS | 1954 | RSS: Main: 323100672 20:35:49 INFO - PROCESS | 1954 | 20:35:52 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:35:53 INFO - PROCESS | 1954 | RSS: Main: 321085440 20:35:53 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:49753/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49753/tests/kraken/driver.html?testName=ai-astar) 20:35:55 INFO - PROCESS | 1954 | RSS: Main: 385347584 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | __start_tp_report 20:35:55 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 20:35:55 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 20:35:55 INFO - PROCESS | 1954 | |i|pagename|runs| 20:35:55 INFO - PROCESS | 1954 | |0;ai-astar;113;119;164;167;113;113;113;113;114;130 20:35:55 INFO - PROCESS | 1954 | |1;audio-beat-detection;174;148;172;164;117;159;135;161;157;115 20:35:55 INFO - PROCESS | 1954 | |2;audio-dft;186;186;184;183;189;185;184;183;186;182 20:35:55 INFO - PROCESS | 1954 | |3;audio-fft;70;82;74;153;55;55;61;62;135;66 20:35:55 INFO - PROCESS | 1954 | |4;audio-oscillator;90;89;144;76;79;85;124;80;76;122 20:35:55 INFO - PROCESS | 1954 | |5;imaging-gaussian-blur;86;84;87;83;86;83;86;83;86;83 20:35:55 INFO - PROCESS | 1954 | |6;imaging-darkroom;92;93;93;93;92;92;93;92;92;92 20:35:55 INFO - PROCESS | 1954 | |7;imaging-desaturate;106;131;131;84;85;100;129;118;85;85 20:35:55 INFO - PROCESS | 1954 | |8;json-parse-financial;62;65;63;63;64;64;64;84;57;57 20:35:55 INFO - PROCESS | 1954 | |9;json-stringify-tinderbox;63;65;70;74;75;70;69;67;67;68 20:35:55 INFO - PROCESS | 1954 | |10;stanford-crypto-aes;80;78;80;80;81;80;81;76;80;86 20:35:55 INFO - PROCESS | 1954 | |11;stanford-crypto-ccm;126;128;129;126;128;129;127;130;149;132 20:35:55 INFO - PROCESS | 1954 | |12;stanford-crypto-pbkdf2;167;164;167;170;213;171;164;165;172;163 20:35:55 INFO - PROCESS | 1954 | |13;stanford-crypto-sha256-iterative;67;68;67;70;67;66;67;68;66;67 20:35:55 INFO - PROCESS | 1954 | __end_tp_report 20:35:55 INFO - PROCESS | 1954 | __start_cc_report 20:35:55 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,1967 20:35:55 INFO - PROCESS | 1954 | __end_cc_report 20:35:55 INFO - PROCESS | 1954 | __startTimestamp1463283355275__endTimestamp 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | ------- Summary: start ------- 20:35:55 INFO - PROCESS | 1954 | Number of tests: 14 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#0] ai-astar Cycles:10 Average:125.90 Median:116.50 stddev:21.55 (18.5%) stddev-sans-first:22.34 20:35:55 INFO - PROCESS | 1954 | Values: 113.0 119.0 164.0 167.0 113.0 113.0 113.0 113.0 114.0 130.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:160.00 stddev:21.21 (13.3%) stddev-sans-first:20.68 20:35:55 INFO - PROCESS | 1954 | Values: 174.0 148.0 172.0 164.0 117.0 159.0 135.0 161.0 157.0 115.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#2] audio-dft Cycles:10 Average:184.80 Median:185.50 stddev:2.04 (1.1%) stddev-sans-first:2.12 20:35:55 INFO - PROCESS | 1954 | Values: 186.0 186.0 184.0 183.0 189.0 185.0 184.0 183.0 186.0 182.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#3] audio-fft Cycles:10 Average:81.30 Median:64.00 stddev:34.33 (53.6%) stddev-sans-first:36.17 20:35:55 INFO - PROCESS | 1954 | Values: 70.0 82.0 74.0 153.0 55.0 55.0 61.0 62.0 135.0 66.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#4] audio-oscillator Cycles:10 Average:96.50 Median:79.50 stddev:24.30 (30.6%) stddev-sans-first:25.66 20:35:55 INFO - PROCESS | 1954 | Values: 90.0 89.0 144.0 76.0 79.0 85.0 124.0 80.0 76.0 122.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:86.00 stddev:1.64 (1.9%) stddev-sans-first:1.67 20:35:55 INFO - PROCESS | 1954 | Values: 86.0 84.0 87.0 83.0 86.0 83.0 86.0 83.0 86.0 83.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#6] imaging-darkroom Cycles:10 Average:92.40 Median:92.50 stddev:0.52 (0.6%) stddev-sans-first:0.53 20:35:55 INFO - PROCESS | 1954 | Values: 92.0 93.0 93.0 93.0 92.0 92.0 93.0 92.0 92.0 92.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#7] imaging-desaturate Cycles:10 Average:105.40 Median:107.50 stddev:20.45 (19.0%) stddev-sans-first:21.69 20:35:55 INFO - PROCESS | 1954 | Values: 106.0 131.0 131.0 84.0 85.0 100.0 129.0 118.0 85.0 85.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:64.00 stddev:7.48 (11.7%) stddev-sans-first:7.89 20:35:55 INFO - PROCESS | 1954 | Values: 62.0 65.0 63.0 63.0 64.0 64.0 64.0 84.0 57.0 57.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#9] json-stringify-tinderbox Cycles:10 Average:68.80 Median:69.50 stddev:3.71 (5.3%) stddev-sans-first:3.28 20:35:55 INFO - PROCESS | 1954 | Values: 63.0 65.0 70.0 74.0 75.0 70.0 69.0 67.0 67.0 68.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#10] stanford-crypto-aes Cycles:10 Average:80.20 Median:80.00 stddev:2.53 (3.2%) stddev-sans-first:2.68 20:35:55 INFO - PROCESS | 1954 | Values: 80.0 78.0 80.0 80.0 81.0 80.0 81.0 76.0 80.0 86.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#11] stanford-crypto-ccm Cycles:10 Average:130.40 Median:129.00 stddev:6.79 (5.3%) stddev-sans-first:7.01 20:35:55 INFO - PROCESS | 1954 | Values: 126.0 128.0 129.0 126.0 128.0 129.0 127.0 130.0 149.0 132.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.60 Median:168.50 stddev:14.88 (8.8%) stddev-sans-first:15.69 20:35:55 INFO - PROCESS | 1954 | Values: 167.0 164.0 167.0 170.0 213.0 171.0 164.0 165.0 172.0 163.0 20:35:55 INFO - PROCESS | 1954 | 20:35:55 INFO - PROCESS | 1954 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.16 (1.7%) stddev-sans-first:1.22 20:35:55 INFO - PROCESS | 1954 | Values: 67.0 68.0 67.0 70.0 67.0 66.0 67.0 68.0 66.0 67.0 20:35:55 INFO - PROCESS | 1954 | -------- Summary: end -------- 20:35:55 INFO - PROCESS | 1954 | 20:35:56 INFO - TEST-INFO | 1954: exit 0 20:35:56 INFO - TEST-OK | kraken | took 58341ms 20:35:56 INFO - SUITE-END | took 1173s 20:35:56 INFO - Completed test suite (00:19:34) 20:35:56 INFO - javascript benchmark 20:35:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2914.2714321419658, 3446.6213514594165, 3516.0, 3541.5218945442357, 3560.0387895268277, 5841.7955371562, 5844.094165404358, 5844.123381492778, 5849.970750146249, 5863.091220860762, 5815.912761308584, 5854.795082172125, 5861.882762344761, 5867.061270196769, 5896.145058966447, 4249.745015299092, 5171.896562068757, 5239.423663397023, 5306.867328316804, 5378.0857254266675, 5321.175217841241, 5326.387465441466, 5331.546818520411, 5336.893262134755, 5337.5196232339085, 5251.947480525203, 5278.947210527903, 5303.363596368441, 5313.0, 5315.893682126355, 4350.456192975862, 5341.43914888935, 5342.439043900374, 5370.624056316059, 5412.404635490093, 5155.9484405155845, 5193.454687257821, 5261.0793111205385, 5274.997750427407, 5293.04725149473, 5661.716914154275, 5665.54675625953, 5676.091825307973, 5694.9715251423895, 5764.423557644244, 6321.905171422432, 6330.493560515148, 6330.81007569769, 6337.081123237105, 6356.523260755462, 6240.781572644935, 6291.339409362043, 6296.905546416808, 6310.716017779165, 6319.052142178664, 5906.468417842415, 5959.284885813643, 5966.940330596725, 5971.925053490327, 6009.369016253274, 6354.142190804182, 6356.110144579762, 6366.331535188831, 6379.776707815204, 6385.169927909342, 6140.140380346755, 6198.2871969723155, 6288.717007734663, 6324.968375158187, 6330.588511746721, 6311.842203944938, 6329.68351582419, 6343.746250150035, 6345.111684364193, 6359.777407790705, 6291.276503202099, 6337.96831015842, 6348.04779283102, 6372.267189273293, 6372.299047104815, 6212.658303793335, 6321.715522801393, 6324.114623952651, 6358.523110766711, 6368.42684158428, 4884.535969082932, 4926.679765815211, 4934.3338649282605, 4936.234883593049, 4967.850964471072, 4439.267520859022, 4505.7071290366675, 4514.68397212192, 4573.085382923428, 4585.679002469862, 5813.82558523245, 5819.912701309484, 5832.883342333214, 5865.061590145556, 5881.852953676193, 6112.602680825732, 6166.198394208814, 6275.0, 6281.309056003836, 6314.968425157845, 5782.797602083907, 5804.738786754606, 5820.738066787005, 5839.2992840858515, 5839.532837373, 4656.8137274508645, 5449.237106805051, 5477.1510415885605, 5478.890422191534, 5480.150576660624, 6292.087647291117, 6368.808935731843, 6378.457831084317, 6385.584936979081, 6398.744050237939, 6256.655883926245, 6274.215723034621, 6279.152314437492, 6288.496920246278, 6305.211848518935, 6239.251289845066, 6407.359264073555, 6430.0, 6451.54839161273, 6458.935410645833, 6232.220972378453, 6312.747490100528, 6322.747090116344, 6334.524910631629, 6347.365263473615, 6255.687215639109, 6264.185655864709, 6278.623282603058, 6289.088082228142, 6310.0, 6065.575409721454, 6089.238845144357, 6165.599236049642, 6260.561760676709, 6276.780312689034, 6157.507399408126, 6214.844628884133, 6235.875282494234, 6246.000639897595, 6251.374862513712, 6082.178905847831, 6089.634621922699, 6099.2985806632805, 6104.8779024420155, 6111.54163437735, 6088.51291896656, 6150.630962142286, 6229.034499652561, 6244.531660125418, 6249.812505624838, 6042.093685947143, 6116.42707509198, 6125.899657344667, 6132.103718785542, 6138.4525421951175], "value": 5779.325313845637, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.5617596954604, 517.477347878643, 527.5410392958125, 553.9944600553995, 657.9999999999997, 27581.724182758167, 31917.840410797944, 31934.840325798337, 31939.0, 31942.520862187055, 32657.67342326576, 40497.39253911186, 41274.0, 41292.38061429081, 41296.58703412965, 9437.575309111073, 9622.0, 9622.566984485715, 9627.0, 9641.084097010773, 8613.784655383633, 9458.290628202878, 9566.186874115674, 9567.952160239189, 9577.569009394594, 8663.523506207157, 11551.65345039646, 11786.17496775226, 11817.054635629172, 11818.763624727544, 11424.600138995136, 11560.884391156065, 11563.595274165364, 11579.652610421701, 11580.478878450405, 8519.74440766778, 11258.605948791836, 11415.972562469418, 11498.827517587242, 11731.0, 29690.85154574235, 32344.514832277535, 39881.202375952606, 39969.800905972865, 40012.0, 3110.9222269443217, 3159.352332771776, 3178.825164615945, 3252.642209356969, 3281.6554261802644, 22817.657735133987, 27294.454110917868, 27806.443871122665, 28050.438991220264, 28662.713372866423, 2797.9720202798117, 2799.0623141247706, 2812.803103782736, 2820.534611789052, 2903.840288784116, 506916.46541767055, 507586.0, 507668.0, 508794.0, 509246.0, 66137.66931165314, 66409.33590664128, 66414.66792666005, 66490.33509664939, 66527.0, 69960.65019674868, 70085.0, 70092.94860576994, 70189.64905175543, 70191.64904175549, 16015.359385624677, 17866.0, 18114.0, 18114.275428982957, 18124.728129078074, 12353.38233088342, 12412.379381031093, 12449.439775209956, 12461.626151215478, 13646.113002654994, 15606.531804045897, 18266.81733182651, 18456.630867382577, 18641.347552835585, 18987.0, 7182.3535881770895, 7331.706731730777, 7368.300011498899, 7371.189169191384, 7400.777976660709, 997.546116516983, 1002.1281485108003, 1011.5094179323022, 1058.6030238660503, 1126.4592995362273, 28685.56971645427, 29860.55209171864, 29861.701382986328, 30327.545086823717, 30451.847740761154, 1084.4252546150553, 1137.1130518195655, 1151.3264740126951, 1178.281248438452, 1182.355616189179, 15204.9356545043, 15333.616659583377, 15370.769438458432, 15514.965556059078, 15728.68542629141, 599267.0, 604603.0, 605757.0, 605830.0, 606080.0, 59949.70025149846, 61137.69431152905, 61232.0, 61286.69356653278, 61344.0, 1328.5748560460754, 1367.6033950154344, 1485.4578079001303, 1502.8572285632847, 1570.4032467662216, 28712.856435717687, 29211.7078829209, 30386.240343991576, 30433.0, 31030.0, 50481.990360191856, 50822.49177508178, 50875.999999998516, 50987.0, 51005.74497127491, 435025.0, 438383.0, 439083.0, 439633.0, 441073.0, 33295.66704332926, 41474.0, 41911.0, 42651.146977060904, 43082.56917430911, 43189.13621727485, 43331.0, 44380.334294984954, 44788.0, 44890.0, 35170.59317627266, 35280.47079293864, 36287.0, 36298.27403451969, 37146.0, 35578.28843423169, 35681.64318356887, 36194.276114477034, 36520.45219321659, 37594.0, 36304.63695363118, 36763.816180918926, 36908.26183476369, 37013.62986370102, 37033.0, 31949.0, 33067.50398744069, 33077.0, 33131.0, 33139.0, 24506.50986980286, 24674.629880551445, 24797.87601061983, 25181.370465738502, 25242.621360679972], "value": 20031.181677504894, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.8001209389258, 407.7227485309989, 410.90754580219453, 413.31389892778, 424.38676113016703, 16687.66624667506, 19789.50526236843, 19799.802001979977, 20528.07623656935, 20779.688304675405, 19844.60310793787, 20426.97865106746, 20592.588148237028, 20595.485112872186, 20810.791892081073, 4805.351277577517, 5647.265855438797, 5881.8823623527505, 5902.3507414184405, 6101.4203650653335, 5986.281646202462, 6059.0, 6076.331603523631, 6079.756809727628, 6080.2703675559, 5651.2370829938045, 5765.336986246572, 5772.163036359725, 6021.09683547467, 6030.42710942462, 5666.603337766337, 5680.8295751127325, 5730.111832665943, 5731.426857314277, 5747.396523365028, 5002.624803139743, 5062.696238225718, 5081.110805609003, 5098.439171691111, 5108.591312695013, 21135.577288454144, 25069.0, 25366.746332536623, 25542.0, 25874.353141171432, 2226.365485836545, 2267.2291420916968, 2391.7847393734473, 2429.6112621980396, 2645.642838216835, 14761.261936903113, 14908.925455372762, 15098.773518397233, 15139.848601513953, 15412.922935385364, 1809.5204770735766, 1810.4387639831564, 1863.30126202674, 1890.0549725137432, 1912.4740696308488, 127273.0, 129610.99999999905, 132052.0, 132416.33791831075, 136633.0, 21328.36014919555, 21526.89236553807, 21584.892075539523, 21654.566908661738, 21788.346349609536, 34459.0, 34469.44887480069, 35422.0, 35635.64364356375, 35709.46435803465, 8520.19058189471, 8897.777555561162, 8924.464532128093, 8959.865602015974, 9016.459012459274, 8981.0, 8993.685221017233, 8998.910010899937, 9088.182063614175, 9092.227160691284, 3840.231953609289, 4573.085382923428, 4653.347591872668, 4653.348531205634, 4717.315989181549, 1988.2643421933976, 2002.389271272276, 2004.7093171490344, 2006.0, 2011.5071807407278, 865.5715420866585, 880.4805164952709, 931.3992474853682, 933.0389698610442, 1014.644874294006, 18727.719084213746, 19618.60762784765, 20191.4952126198, 20303.28938487146, 20318.796812031986, 814.5560669435172, 963.905966727769, 974.6198982396871, 987.0474991633108, 992.4293531219578, 10341.741456463498, 10724.624638137628, 10762.56949722018, 10773.299735517166, 10786.730331741612, 577808.0, 596478.0, 605267.0, 636333.0, 649621.0, 78909.60545197352, 107647.0, 113157.4342128284, 138305.308473459, 142393.28803355916, 947.4930911962067, 956.7464621875208, 972.2805124208035, 972.9464879431699, 973.9561719722772, 15037.172955487553, 16223.837761622233, 17538.82461175372, 17755.911220443813, 17815.19146515539, 52726.47273527215, 56052.43947560472, 56329.0, 56494.71752641375, 57924.710376447845, 77708.0, 79323.0, 80115.59942200252, 83371.5831420839, 83716.58141709253, 7855.37157027425, 8068.07217170033, 8222.958885205535, 8236.588170591327, 8241.464304820167, 9163.450192988443, 12128.454219559964, 12131.090168237593, 12433.75132497327, 12454.065945053975, 9873.210143188384, 10295.897041029493, 10511.054005139575, 10527.368357898551, 10912.563497460013, 9066.863997040182, 10395.272330936761, 10829.729256768644, 10915.39965301916, 11021.393823339473, 10466.738331541468, 10715.678529644123, 11463.254888432226, 11660.125490588409, 11704.765904681688, 8097.190280971856, 8111.188881112078, 8215.794605134919, 8277.710280140167, 8381.203785640355, 8292.751217463485, 10370.429626370622, 10958.178136639624, 11201.215914886207, 11256.83114753263], "value": 10023.255683563504, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1415.1650526189546, 1501.744703400422, 1562.7968364112667, 1794.6321004194142, 1947.1919153551269, 7934.722284720029, 8624.956875215623, 8747.868781968276, 8752.649894004247, 8769.298456123512, 8485.193906578881, 8714.91285087149, 8717.215450609443, 8717.912820871805, 8726.5636718164, 1912.8565357598166, 2162.362103179566, 2355.2580937004786, 2460.1881379144884, 2483.552960467115, 2120.5122821751006, 2464.605663093905, 2466.5313590417854, 2469.0494159748446, 2470.4318006858434, 2386.367612582667, 2425.842320249187, 2441.218809980807, 2444.449998750276, 2445.8043356531434, 2171.326888664519, 2411.807055435561, 2460.6555082288496, 2462.9014839406404, 2469.604863221894, 2417.576924038304, 2435.695538057743, 2443.7556244375596, 2444.352246654638, 2444.4744379958393, 8543.95728021362, 8615.870761938577, 8624.913750862474, 8638.136186381375, 8672.8699069514, 5097.260897169889, 5270.710110943896, 5299.761510731988, 5323.787048518055, 5340.599455040849, 3577.463380492921, 3583.7312201584723, 3597.94603080954, 3628.092976755811, 3662.7802331860175, 1066.3228849680431, 1076.5370890517074, 1076.590895459728, 1081.1404933078222, 1081.3998230981801, 11861.584844530485, 11876.10929180315, 11876.287422754576, 11888.88111118895, 11891.583794567148, 4689.906201875943, 4726.550977657117, 4791.281307803788, 4835.782389792468, 4872.6832755870755, 9996.450195239186, 10016.79966400668, 10021.398716076912, 10045.648402305884, 10049.397036177852, 5368.570514358842, 5368.570514358842, 5375.731213439312, 5403.675779453245, 5405.567554595623, 3959.366501359769, 4440.533743956838, 4504.25679762842, 4882.340883980688, 4938.580220681282, 3845.423186521988, 3904.9804750976064, 3919.6864250860435, 3951.565327813938, 3973.264945985002, 1341.7584834729648, 1347.7371912476972, 1351.094766506443, 1352.1886867879193, 1371.1156304183746, 795.4988357334958, 864.6671031652859, 883.9071897450675, 889.4129874282942, 920.2499962530517, 5749.453801888814, 5820.097884827858, 5906.586538942232, 5966.612170208922, 6055.697215139226, 951.4957072751314, 957.4064080270277, 962.432165022626, 968.675493709601, 1009.8889122196553, 3732.664060234592, 3807.8857634271017, 3873.3802591585772, 3911.902202444962, 3913.2760439318818, 10296.691099267035, 10446.164306855433, 10699.679009629723, 10820.458977051116, 10866.728331791768, 10348.275620706629, 10442.582296708046, 10465.68602941913, 10466.372017678963, 10526.894731052744, 8221.383396245306, 8262.876056859152, 8415.11641277657, 8420.873686894702, 8519.233269005943, 5681.17622944679, 5766.330720418066, 5849.970750146222, 5968.731407086779, 6004.5796794225735, 9423.717288481357, 9478.478683672463, 9775.951120244354, 9790.0, 9807.803843923224, 9700.951495242478, 9949.0, 9956.054174853378, 10021.699349019542, 10123.544440500047, 6848.9726541019245, 7009.719611215494, 7013.929860701328, 7630.961845190738, 7760.495567788302, 8033.236842499953, 8239.340852731886, 8262.132476089924, 8273.33813294947, 8273.751787446387, 7250.238724934017, 7310.96344518274, 7332.890006649798, 7449.851002979802, 7491.175970643334, 6589.670516474253, 7178.916957473496, 7315.670794814139, 7334.743283985035, 7393.852122957618, 6010.939890601038, 6896.413804826646, 6951.721931122901, 6996.020557121905, 7000.7549735759, 4767.76161191946, 4804.615630749601, 4812.51874812516, 4936.481669424658, 4937.432195297443, 4849.3210950467665, 4864.854054378375, 4905.337779399735, 4920.778564964657, 5094.617903657315], "value": 4582.374913652927, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.69780453080904, 330.7370640340929, 336.6381140274205, 339.26887557313995, 342.92798512312413, 5326.680399176043, 6508.511861610375, 6615.834604134887, 6643.169603799525, 6649.368310010541, 6506.121673574066, 6526.608403495782, 6547.672616369175, 6574.704138313776, 6587.967060164705, 4568.109218702354, 4648.000679853831, 4706.623470122382, 4707.646926480511, 4861.319415281863, 4821.807127714905, 4873.098476781806, 4900.877478063059, 4922.409310882699, 4942.654014219006, 4739.6208303335825, 4830.275458681191, 4834.468208497063, 4853.684510506823, 4859.343988561534, 4627.8611641650805, 4660.650451216138, 4778.976105119487, 4849.418069831625, 4864.8297309594345, 4124.752514849119, 4125.608067233608, 4144.52337981133, 4170.749755014678, 4182.372644103379, 5612.186232996192, 5720.542356611502, 6295.464885484694, 6315.810525684236, 6334.683265836736, 5078.61910356721, 5378.542823859977, 5753.367129615739, 5769.8557536061935, 5773.047447171211, 7019.227884932653, 7032.894506582355, 7034.824129396755, 7044.084269045043, 7046.4010559102535, 3966.6628336591425, 4141.151064031867, 4516.322551617251, 4694.624430045588, 4708.223143181371, 7300.612883552161, 7347.88978165328, 7370.041894553728, 7406.777796666109, 7414.777556673254, 4638.0, 4649.930251046237, 4650.116477869194, 4650.534946505322, 4652.023075154188, 4686.133065382915, 4690.132325519789, 4705.882352941204, 4723.527647235249, 4723.83466578675, 5579.609427340048, 5582.302212223472, 5586.888262234814, 5593.8601534961135, 5594.860128496739, 5441.891162176814, 7005.0893383859775, 7059.399951004222, 7067.22260551339, 7073.929260707327, 1606.3654856331916, 1616.5796892807955, 1617.9029258244307, 1624.0661619568796, 1627.886047976654, 966.6665000574791, 1021.8160731068479, 1022.6420752736632, 1035.6427032673716, 1045.0490054050777, 6259.123722678829, 6318.905216421757, 6468.0, 6501.414872661483, 6511.2512061113575, 6436.16329877113, 6840.828979275558, 7305.269473052758, 7311.341979221789, 7320.890186647204, 5309.814156504503, 6418.326075762071, 6473.741050357933, 6482.027695845567, 6496.707648155844, 3805.238952209569, 3853.710971677079, 3856.5564960029396, 3858.2476417098956, 3861.768293902375, 6123.540734444934, 7317.451191160578, 7579.696812127453, 7580.469367144357, 7597.392208623297, 7464.925350746423, 7481.551106933602, 7519.548827070284, 7520.699172033057, 7521.360684341892, 7567.091948966159, 7570.697172113054, 7585.62071896403, 7592.126905405838, 7594.696212151452, 6983.032211310275, 7261.963690181516, 7263.782086537412, 7267.36631684158, 7268.0914885639295, 7159.212486626363, 7175.748848790267, 7188.640567971476, 7188.892166617609, 7193.712251509881, 7526.360259378014, 7590.734324298623, 7602.657880395506, 7608.657610407434, 7611.619419028915, 4601.401817763669, 4602.907941841077, 4614.723116613014, 4615.630749540096, 4626.583607475343, 7245.203027666847, 7251.057362543047, 7252.492325537161, 7258.129024517092, 7261.636918153966, 6709.0, 6941.271166527442, 6958.060661810793, 6959.095317608679, 6970.546914450544, 6875.243723190345, 6953.930460695531, 6971.825704357431, 6973.3375329343635, 6975.546589471668, 6855.862882742217, 6880.69036893331, 6888.104546408935, 6896.310368963063, 6903.0680858083515, 6037.215162028908, 6834.965825170842, 7054.929450705427, 7081.893771593327, 7082.150141982995, 6614.536982411185, 6616.768413105518, 6789.388954994074, 6838.829029274308, 6985.650717464208], "value": 5094.106840351369, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [698.3679769808324, 749.7713197474768, 756.5233902641335, 758.4652819762068, 780.3172224303735, 15105.924470377633, 15164.014339067953, 15342.84657153428, 15369.538913832603, 15417.306221220044, 14220.71558568828, 14289.785653215211, 14316.928415357934, 14318.785218221736, 14331.211783351911, 745.0, 753.4123383760675, 788.6805843633338, 906.1482206725657, 948.8434408322619, 917.7613820406675, 948.6584829461389, 948.7722946492827, 949.5442187749884, 952.5808644196566, 1000.5197505197509, 1004.2417974429333, 1006.9748256293578, 1008.1380419741125, 1008.5814387029374, 775.3835700618022, 977.1449981266392, 989.7278248481654, 993.2649839119, 996.6511720897701, 738.4572339330588, 739.0613920321216, 741.0292516802951, 741.262443868349, 742.8737114690516, 15287.770683439758, 15316.081035137928, 15331.846681533154, 15352.616184595474, 15359.0, 13005.21968681882, 13378.799318010238, 13395.732085358335, 13515.459381624723, 13555.728885422335, 10099.949500252525, 10147.492625368703, 10243.0, 10538.156947444186, 10908.454577271104, 3535.328287625369, 3555.9111022224392, 3578.5526809148855, 3638.490611314418, 3806.638369354907, 79495.20504794993, 79606.60196699038, 79848.0, 81123.5943820283, 90223.09776902212, 9813.11681948628, 9818.72885294899, 9969.601215951281, 9990.200783937267, 10028.44843533598, 22802.0, 23477.765222347556, 23487.647685284734, 23527.41181470443, 23553.646695299583, 7705.961470192613, 7812.726554570563, 7824.647890844925, 7954.681812727542, 8077.111517733045, 4097.733647312974, 4103.958960410358, 4109.219248342805, 4236.5763423658, 4397.516273210008, 3139.3564319314487, 3671.155634204148, 3746.4754934309217, 3748.081719978623, 3749.2126653402547, 646.1083704487777, 649.9155109835691, 654.9017647352936, 656.2813718977711, 670.2125003121313, 559.560744815318, 600.1477901379964, 605.8757954619292, 610.1336102734128, 610.377415036669, 7841.137474877759, 8281.751547453587, 8290.751277461686, 8402.11777763326, 8423.70517031901, 597.9820605381846, 606.1029676079427, 610.8992016317346, 611.7858749437661, 612.3753771153488, 3668.834902429397, 3799.2211596622624, 3849.3071247175217, 3962.227365663726, 4025.0, 96750.0, 97050.51474742581, 98610.5069474648, 99009.50495247477, 99359.50320248352, 81624.59187704143, 82458.0, 83001.16998829934, 83972.0, 84367.5781621088, 72713.1821704451, 73450.63274683699, 74420.0, 74459.0, 75438.6228068867, 7689.307962283421, 7812.64843082074, 8022.719204827803, 8026.0, 8047.0, 51202.48797511977, 51535.742321288155, 51539.48460515347, 51661.7416912913, 51910.221346680584, 64132.35867641451, 66287.33712662812, 71743.64128179326, 71914.0, 72159.0, 10339.793204136025, 10527.894721052691, 10632.202584806326, 10634.946825265824, 10689.625863094754, 10880.795396125686, 10970.835437468284, 11110.166737494881, 11324.433778310886, 11453.08375329955, 10023.148032417326, 10485.21360897951, 10491.0, 10629.89370106289, 10696.839547406951, 10320.43237621908, 10323.690289291333, 10425.895741042492, 10618.73453163677, 10665.253432259668, 10239.180865530889, 10311.432871192157, 10312.896871031493, 10456.477176141314, 10502.474876256003, 8916.86624700617, 8929.553522323988, 9100.954495227481, 9213.493257870881, 9260.30547708911, 8975.0, 9002.726744445872, 9024.593893274687, 9072.682456114004, 9145.314101442285], "value": 6969.243903356315, "unit": "score"}], "name": "dromaeo_css", "value": 7574.068307986144, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 164.0, 167.0, 113.0, 113.0, 113.0, 113.0, 114.0, 130.0], "value": 125.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 148.0, 172.0, 164.0, 117.0, 159.0, 135.0, 161.0, 157.0, 115.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 186.0, 184.0, 183.0, 189.0, 185.0, 184.0, 183.0, 186.0, 182.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 74.0, 153.0, 55.0, 55.0, 61.0, 62.0, 135.0, 66.0], "value": 81.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 144.0, 76.0, 79.0, 85.0, 124.0, 80.0, 76.0, 122.0], "value": 96.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 83.0, 86.0, 83.0, 86.0, 83.0, 86.0, 83.0], "value": 84.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0], "value": 92.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 131.0, 131.0, 84.0, 85.0, 100.0, 129.0, 118.0, 85.0, 85.0], "value": 105.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 63.0, 63.0, 64.0, 64.0, 64.0, 84.0, 57.0, 57.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 70.0, 74.0, 75.0, 70.0, 69.0, 67.0, 67.0, 68.0], "value": 68.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 78.0, 80.0, 80.0, 81.0, 80.0, 81.0, 76.0, 80.0, 86.0], "value": 80.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 128.0, 129.0, 126.0, 128.0, 129.0, 127.0, 130.0, 149.0, 132.0], "value": 130.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 167.0, 170.0, 213.0, 171.0, 164.0, 165.0, 172.0, 163.0], "value": 171.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 70.0, 67.0, 66.0, 67.0, 68.0, 66.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1503.8, "alertThreshold": 2.0}]} 20:35:56 INFO - Return code: 0 20:35:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 20:35:56 ERROR - # TBPL SUCCESS # 20:35:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:35:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:35:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:35:56 INFO - 2016-05-14 20:35:56.610 screenresolution[1958:13081] starting screenresolution argv=screenresolution get 20:35:56 INFO - 2016-05-14 20:35:56.612 screenresolution[1958:13081] Display 0: 1600x1200x32@60 20:35:56 INFO - 2016-05-14 20:35:56.618 screenresolution[1959:13084] starting screenresolution argv=screenresolution list 20:35:56 INFO - Available Modes on Display 0 20:35:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:35:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:35:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:35:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:35:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:35:56 INFO - Intel Iris: 20:35:56 INFO - Chipset Model: Intel Iris 20:35:56 INFO - Type: GPU 20:35:56 INFO - Bus: Built-In 20:35:56 INFO - VRAM (Dynamic, Max): 1536 MB 20:35:56 INFO - Vendor: Intel (0x8086) 20:35:56 INFO - Device ID: 0x0a2e 20:35:56 INFO - Revision ID: 0x0009 20:35:56 INFO - Displays: 20:35:56 INFO - Display: 20:35:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:35:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:35:56 INFO - Display Serial Number: 1600x1200 60 20:35:56 INFO - Main Display: Yes 20:35:56 INFO - Mirror: Off 20:35:56 INFO - Online: Yes 20:35:56 INFO - Rotation: Supported 20:35:56 INFO - Return code: 0 20:35:56 INFO - Running post-action listener: _resource_record_post_action 20:35:56 INFO - Running post-run listener: _resource_record_post_run 20:35:57 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 1130496; Write bytes: 655854080; Read time: 16; Write time: 1863 20:35:57 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 352870912; Write bytes: 349034496; Read time: 27328; Write time: 731 20:35:57 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 1032192; Write bytes: 297075200; Read time: 13; Write time: 1096 20:35:57 INFO - Running post-run listener: _upload_blobber_files 20:35:57 INFO - Blob upload gear active. 20:35:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:35:57 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:35:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:35:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:35:58 INFO - (blobuploader) - INFO - Open directory for files ... 20:35:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:35:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:35:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:35:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:35:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:35:59 INFO - (blobuploader) - INFO - Done attempting. 20:35:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:35:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:35:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:36:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:36:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:36:00 INFO - (blobuploader) - INFO - Done attempting. 20:36:00 INFO - (blobuploader) - INFO - Iteration through files over. 20:36:00 INFO - Return code: 0 20:36:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:36:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:36:00 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d9c86006a3430827d25671cabe0784ee4120a0daa7e37dcf92a8e5eb2f612474d172040b7c73d9a1eefaee8ebc5a966cc2682295e046101ec07c1df2ff5dafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d977c30fe6f267835d4a4be252d5c95a76fedf3ffadad477283aa592ef1da7bedfdb5ebde15caf25b4005133153b0d3e31d8eabb236600313e0335613e31989"} 20:36:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:36:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:36:00 INFO - Contents: 20:36:00 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7d9c86006a3430827d25671cabe0784ee4120a0daa7e37dcf92a8e5eb2f612474d172040b7c73d9a1eefaee8ebc5a966cc2682295e046101ec07c1df2ff5dafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d977c30fe6f267835d4a4be252d5c95a76fedf3ffadad477283aa592ef1da7bedfdb5ebde15caf25b4005133153b0d3e31d8eabb236600313e0335613e31989"} 20:36:00 INFO - Running post-run listener: copy_logs_to_upload_dir 20:36:00 INFO - Copying logs to upload dir... 20:36:00 INFO - mkdir: /builds/slave/test/build/upload/logs 20:36:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1297.987869 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 38 secs) (at 2016-05-14 20:36:00.498799) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:36:00.503703) ========= 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.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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/7d9c86006a3430827d25671cabe0784ee4120a0daa7e37dcf92a8e5eb2f612474d172040b7c73d9a1eefaee8ebc5a966cc2682295e046101ec07c1df2ff5dafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d977c30fe6f267835d4a4be252d5c95a76fedf3ffadad477283aa592ef1da7bedfdb5ebde15caf25b4005133153b0d3e31d8eabb236600313e0335613e31989"} build_url:https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010882 build_url: 'https://queue.taskcluster.net/v1/task/Xf-pZ1xeRFOgk19Du-EH8Q/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/7d9c86006a3430827d25671cabe0784ee4120a0daa7e37dcf92a8e5eb2f612474d172040b7c73d9a1eefaee8ebc5a966cc2682295e046101ec07c1df2ff5dafd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2d977c30fe6f267835d4a4be252d5c95a76fedf3ffadad477283aa592ef1da7bedfdb5ebde15caf25b4005133153b0d3e31d8eabb236600313e0335613e31989"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-14 20:36:00.533466) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:36:00.533835) ========= 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.qBDhOpAhql/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.20g95dKJK0/Listeners TMPDIR=/var/folders/f5/y9v1vjgn6s1cnhhybj4hv4_r00000w/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.004858 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-14 20:36:00.581948) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:36:00.585350) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-14 20:36:00.586198) ========= ========= Total master_lag: 0.25 =========