builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0019 starttime: 1462482524.53 results: success (0) buildid: 20160505115745 builduid: 317faf2ddeb94033bbcea6223f4d075d revision: 0177462aac74605f426ab9c92e39bb467b7ce2d1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.527318) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.527824) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.528149) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.548350) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.548978) ========= 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.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-05 14:08:44-- 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% 16.7M=0.001s 2016-05-05 14:08:44 (16.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.112592 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.681099) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.681531) ========= 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.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.029365 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:44.730962) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 14:08:44.731412) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 0177462aac74605f426ab9c92e39bb467b7ce2d1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 0177462aac74605f426ab9c92e39bb467b7ce2d1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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-05 14:08:44,799 truncating revision to first 12 chars 2016-05-05 14:08:44,799 Setting DEBUG logging. 2016-05-05 14:08:44,799 attempt 1/10 2016-05-05 14:08:44,800 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/0177462aac74?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-05 14:08:45,725 unpacking tar archive at: mozilla-central-0177462aac74/testing/mozharness/ program finished with exit code 0 elapsedTime=1.168034 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-05 14:08:45.920383) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:45.921217) ========= script_repo_revision: 0177462aac74605f426ab9c92e39bb467b7ce2d1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:45.922162) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:45.922835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-05 14:08:45.938153) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-05 14:08:45.938864) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:08:46 INFO - MultiFileLogger online at 20160505 14:08:46 in /builds/slave/test 14:08:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:08:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:08:46 INFO - {'append_to_log': False, 14:08:46 INFO - 'base_work_dir': '/builds/slave/test', 14:08:46 INFO - 'blob_upload_branch': 'Firefox', 14:08:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:08:46 INFO - 'branch': 'Firefox', 14:08:46 INFO - 'buildbot_json_path': 'buildprops.json', 14:08:46 INFO - 'config_files': ('talos/mac_config.py',), 14:08:46 INFO - 'default_actions': ('clobber', 14:08:46 INFO - 'read-buildbot-config', 14:08:46 INFO - 'download-and-extract', 14:08:46 INFO - 'populate-webroot', 14:08:46 INFO - 'create-virtualenv', 14:08:46 INFO - 'install', 14:08:46 INFO - 'run-tests'), 14:08:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:08:46 INFO - 'download_minidump_stackwalk': True, 14:08:46 INFO - 'download_symbols': 'ondemand', 14:08:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:08:46 INFO - 'tooltool.py': '/tools/tooltool.py', 14:08:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:08:46 INFO - '/tools/misc-python/virtualenv.py')}, 14:08:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:08:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:08:46 INFO - 'installer_path': 'installer.exe', 14:08:46 INFO - 'log_level': 'info', 14:08:46 INFO - 'log_name': 'talos', 14:08:46 INFO - 'log_to_console': True, 14:08:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:08:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:08:46 INFO - 'opt_config_files': (), 14:08:46 INFO - 'pip_index': False, 14:08:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:46 INFO - 'cmd': ('bash', 14:08:46 INFO - '-c', 14:08:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:46 INFO - 'enabled': True, 14:08:46 INFO - 'halt_on_failure': False, 14:08:46 INFO - 'name': 'check_screen_resolution'},), 14:08:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:08:46 INFO - 'cmd': ('bash', 14:08:46 INFO - '-c', 14:08:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:08:46 INFO - 'enabled': True, 14:08:46 INFO - 'halt_on_failure': False, 14:08:46 INFO - 'name': 'check_screen_resolution'},), 14:08:46 INFO - 'run_cmd_checks_enabled': True, 14:08:46 INFO - 'sps_profile': False, 14:08:46 INFO - 'sps_profile_interval': 0, 14:08:46 INFO - 'suite': 'dromaeojs', 14:08:46 INFO - 'system_bits': '32', 14:08:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:08:46 INFO - 'title': 't-yosemite-r7-0019', 14:08:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:08:46 INFO - 'use_talos_json': True, 14:08:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:08:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:08:46 INFO - 'work_dir': 'build'} 14:08:46 INFO - ##### 14:08:46 INFO - ##### Running clobber step. 14:08:46 INFO - ##### 14:08:46 INFO - Running pre-action listener: _resource_record_pre_action 14:08:46 INFO - Running main action method: clobber 14:08:46 INFO - rmtree: /builds/slave/test/build 14:08:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:08:48 INFO - Running post-action listener: _resource_record_post_action 14:08:48 INFO - ##### 14:08:48 INFO - ##### Running read-buildbot-config step. 14:08:48 INFO - ##### 14:08:48 INFO - Running pre-action listener: _resource_record_pre_action 14:08:48 INFO - Running main action method: read_buildbot_config 14:08:48 INFO - Using buildbot properties: 14:08:48 INFO - { 14:08:48 INFO - "project": "", 14:08:48 INFO - "product": "firefox", 14:08:48 INFO - "script_repo_revision": "production", 14:08:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 14:08:48 INFO - "repository": "", 14:08:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 14:08:48 INFO - "buildid": "20160505115745", 14:08:48 INFO - "pgo_build": "False", 14:08:48 INFO - "basedir": "/builds/slave/test", 14:08:48 INFO - "buildnumber": 0, 14:08:48 INFO - "slavename": "t-yosemite-r7-0019", 14:08:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 14:08:48 INFO - "platform": "yosemite_r7", 14:08:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:08:48 INFO - "branch": "mozilla-central", 14:08:48 INFO - "repo_path": "mozilla-central", 14:08:48 INFO - "revision": "0177462aac74605f426ab9c92e39bb467b7ce2d1", 14:08:48 INFO - "stage_platform": "macosx64", 14:08:48 INFO - "builduid": "317faf2ddeb94033bbcea6223f4d075d", 14:08:48 INFO - "slavebuilddir": "test" 14:08:48 INFO - } 14:08:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:08:48 INFO - Running post-action listener: _resource_record_post_action 14:08:48 INFO - ##### 14:08:48 INFO - ##### Running download-and-extract step. 14:08:48 INFO - ##### 14:08:48 INFO - Running pre-action listener: _resource_record_pre_action 14:08:48 INFO - Running main action method: download_and_extract 14:08:48 INFO - mkdir: /builds/slave/test/build/tests 14:08:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:48 INFO - https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:08:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 14:08:49 INFO - Downloaded 1199 bytes. 14:08:49 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:08:49 INFO - Using the following test package requirements: 14:08:49 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:08:49 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:08:49 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:08:49 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'jsshell-mac.zip'], 14:08:49 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:08:49 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:08:49 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:08:49 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:08:49 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:08:49 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:08:49 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:08:49 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:08:49 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')]} 14:08:49 INFO - https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:08:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:08:50 INFO - Downloaded 18132820 bytes. 14:08:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:08:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:08:53 INFO - Return code: 0 14:08:53 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:08: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')]} 14:08:53 INFO - https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:08:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:08:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:08:54 INFO - Downloaded 18132820 bytes. 14:08:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:08:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:08:58 INFO - Return code: 0 14:08:58 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')]} 14:08:58 INFO - https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:08:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:08:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:08:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:08:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:08:59 INFO - Downloaded 10840152 bytes. 14:08:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:08:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:08:59 INFO - Return code: 0 14:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:08:59 INFO - https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:08:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:08:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:08:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 14:09:03 INFO - Downloaded 110355259 bytes. 14:09:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:03 INFO - mkdir: /builds/slave/test/properties 14:09:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:03 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:03 INFO - Contents: 14:09:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:09:04 INFO - Running post-action listener: _resource_record_post_action 14:09:04 INFO - Running post-action listener: set_extra_try_arguments 14:09:04 INFO - ##### 14:09:04 INFO - ##### Running populate-webroot step. 14:09:04 INFO - ##### 14:09:04 INFO - Running pre-action listener: _resource_record_pre_action 14:09:04 INFO - Running main action method: populate_webroot 14:09:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:04 INFO - '--tppagecycles', 14:09:04 INFO - '7'], 14:09:04 INFO - 'tests': ['tsvgm']}}, 14:09:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['damp', 'tps']}, 14:09:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'tests': ['damp', 'tps']}, 14:09:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['dromaeo_dom']}, 14:09:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['a11yr', 14:09:04 INFO - 'ts_paint', 14:09:04 INFO - 'tpaint', 14:09:04 INFO - 'sessionrestore', 14:09:04 INFO - 'sessionrestore_no_auto_restore', 14:09:04 INFO - 'tabpaint']}, 14:09:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['tsvgx', 14:09:04 INFO - 'tsvgr_opacity', 14:09:04 INFO - 'tart', 14:09:04 INFO - 'tscrollx', 14:09:04 INFO - 'cart']}, 14:09:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:04 INFO - 'tsvgr_opacity', 14:09:04 INFO - 'tart', 14:09:04 INFO - 'tscrollx', 14:09:04 INFO - 'cart']}, 14:09:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'talos_options': ['--disable-e10s'], 14:09:04 INFO - 'tests': ['tp5o']}, 14:09:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'tests': ['tp5o']}, 14:09:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'talos_options': ['--disable-e10s', 14:09:04 INFO - '--xperf_path', 14:09:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:04 INFO - 'tests': ['tp5n']}, 14:09:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:09:04 INFO - 'talos_options': ['--xperf_path', 14:09:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:04 INFO - 'tests': ['tp5n']}}, 14:09:04 INFO - 'talos.zip': {'path': '', 14:09:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:04 INFO - Running post-action listener: _resource_record_post_action 14:09:04 INFO - ##### 14:09:04 INFO - ##### Running create-virtualenv step. 14:09:04 INFO - ##### 14:09:04 INFO - Running pre-action listener: _resource_record_pre_action 14:09:04 INFO - Running main action method: create_virtualenv 14:09:04 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:04 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 14:09:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:04 INFO - Using real prefix '/tools/python27' 14:09:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:05 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:08 INFO - Installing pip.................done. 14:09:08 INFO - Return code: 0 14:09:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:08 INFO - 'HOME': '/Users/cltbld', 14:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:08 INFO - 'LOGNAME': 'cltbld', 14:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:08 INFO - 'MOZ_NO_REMOTE': '1', 14:09:08 INFO - 'NO_EM_RESTART': '1', 14:09:08 INFO - 'PAGER': '/bin/cat', 14:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:08 INFO - 'PWD': '/builds/slave/test', 14:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:08 INFO - 'SHELL': '/bin/bash', 14:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:08 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:08 INFO - 'USER': 'cltbld', 14:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:08 INFO - 'XPC_FLAGS': '0x0', 14:09:08 INFO - 'XPC_SERVICE_NAME': '0', 14:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:08 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 14:09:08 INFO - Cleaning up... 14:09:08 INFO - Return code: 0 14:09:08 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 14:09:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 14:09:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:08 INFO - 'HOME': '/Users/cltbld', 14:09:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:08 INFO - 'LOGNAME': 'cltbld', 14:09:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:08 INFO - 'MOZ_NO_REMOTE': '1', 14:09:08 INFO - 'NO_EM_RESTART': '1', 14:09:08 INFO - 'PAGER': '/bin/cat', 14:09:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:08 INFO - 'PWD': '/builds/slave/test', 14:09:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:08 INFO - 'SHELL': '/bin/bash', 14:09:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:08 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:08 INFO - 'USER': 'cltbld', 14:09:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:08 INFO - 'XPC_FLAGS': '0x0', 14:09:08 INFO - 'XPC_SERVICE_NAME': '0', 14:09:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:09 INFO - Downloading/unpacking psutil>=0.7.1 14:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:12 INFO - Installing collected packages: psutil 14:09:12 INFO - Running setup.py install for psutil 14:09:12 INFO - building 'psutil._psutil_osx' extension 14:09:12 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 14:09:12 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 14:09:12 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 14:09:12 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 14:09:12 INFO - building 'psutil._psutil_posix' extension 14:09:12 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 14:09:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:09:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:09:13 INFO - ^ 14:09:13 INFO - 1 warning generated. 14:09:13 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 14:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:13 INFO - Successfully installed psutil 14:09:13 INFO - Cleaning up... 14:09:13 INFO - Return code: 0 14:09:13 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:09:13 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')]} 14:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:13 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')]} 14:09:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:13 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:13 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 14:09:13 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 14:09:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:13 INFO - 'HOME': '/Users/cltbld', 14:09:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:13 INFO - 'LOGNAME': 'cltbld', 14:09:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:13 INFO - 'MOZ_NO_REMOTE': '1', 14:09:13 INFO - 'NO_EM_RESTART': '1', 14:09:13 INFO - 'PAGER': '/bin/cat', 14:09:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:13 INFO - 'PWD': '/builds/slave/test', 14:09:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:13 INFO - 'SHELL': '/bin/bash', 14:09:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:13 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:13 INFO - 'USER': 'cltbld', 14:09:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:13 INFO - 'XPC_FLAGS': '0x0', 14:09:13 INFO - 'XPC_SERVICE_NAME': '0', 14:09:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:13 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:15 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:09:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:09:16 INFO - Installing collected packages: mozsystemmonitor 14:09:16 INFO - Running setup.py install for mozsystemmonitor 14:09:16 INFO - Successfully installed mozsystemmonitor 14:09:16 INFO - Cleaning up... 14:09:16 INFO - Return code: 0 14:09:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:09:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:09:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:16 INFO - 'HOME': '/Users/cltbld', 14:09:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:16 INFO - 'LOGNAME': 'cltbld', 14:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:16 INFO - 'MOZ_NO_REMOTE': '1', 14:09:16 INFO - 'NO_EM_RESTART': '1', 14:09:16 INFO - 'PAGER': '/bin/cat', 14:09:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:16 INFO - 'PWD': '/builds/slave/test', 14:09:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:16 INFO - 'SHELL': '/bin/bash', 14:09:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:16 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:16 INFO - 'USER': 'cltbld', 14:09:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:16 INFO - 'XPC_FLAGS': '0x0', 14:09:16 INFO - 'XPC_SERVICE_NAME': '0', 14:09:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:16 INFO - Downloading/unpacking blobuploader==1.2.4 14:09:16 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 14:09:16 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 14:09:16 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 14:09:16 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 14:09:16 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 14:09:16 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 14:09:18 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:20 INFO - Downloading docopt-0.6.1.tar.gz 14:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:20 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:20 INFO - Running setup.py install for blobuploader 14:09:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:20 INFO - Running setup.py install for requests 14:09:20 INFO - Running setup.py install for docopt 14:09:21 INFO - Successfully installed blobuploader requests docopt 14:09:21 INFO - Cleaning up... 14:09:21 INFO - Return code: 0 14:09:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:21 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 14:09:21 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 14:09:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:21 INFO - 'HOME': '/Users/cltbld', 14:09:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:21 INFO - 'LOGNAME': 'cltbld', 14:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:21 INFO - 'MOZ_NO_REMOTE': '1', 14:09:21 INFO - 'NO_EM_RESTART': '1', 14:09:21 INFO - 'PAGER': '/bin/cat', 14:09:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:21 INFO - 'PWD': '/builds/slave/test', 14:09:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:21 INFO - 'SHELL': '/bin/bash', 14:09:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:21 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:21 INFO - 'USER': 'cltbld', 14:09:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:21 INFO - 'XPC_FLAGS': '0x0', 14:09:21 INFO - 'XPC_SERVICE_NAME': '0', 14:09:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-D_Wf8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-me44g_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-31it0_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-V5ZFy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:21 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-JieZcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-tO5Ve3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ObxvKc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-ModVa5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-09ytnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-iIqQJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-cmGvCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-GIq0CR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-yP03wJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-UYOHfs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:22 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-iCs8Xc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:23 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6nYx2v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:23 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-HO_BXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:23 INFO - Running setup.py install for manifestparser 14:09:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:23 INFO - Running setup.py install for mozcrash 14:09:23 INFO - Running setup.py install for mozdebug 14:09:23 INFO - Running setup.py install for mozdevice 14:09:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:09:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:23 INFO - Running setup.py install for mozfile 14:09:23 INFO - Running setup.py install for mozhttpd 14:09:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Running setup.py install for mozinfo 14:09:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Running setup.py install for mozInstall 14:09:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Running setup.py install for mozleak 14:09:24 INFO - Running setup.py install for mozlog 14:09:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Running setup.py install for moznetwork 14:09:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:24 INFO - Running setup.py install for mozprocess 14:09:25 INFO - Running setup.py install for mozprofile 14:09:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:25 INFO - Running setup.py install for mozrunner 14:09:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:25 INFO - Running setup.py install for mozscreenshot 14:09:25 INFO - Running setup.py install for moztest 14:09:25 INFO - Running setup.py install for mozversion 14:09:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:25 INFO - Cleaning up... 14:09:25 INFO - Return code: 0 14:09:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:25 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:25 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 14:09:25 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 14:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:25 INFO - 'HOME': '/Users/cltbld', 14:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:25 INFO - 'LOGNAME': 'cltbld', 14:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:25 INFO - 'MOZ_NO_REMOTE': '1', 14:09:25 INFO - 'NO_EM_RESTART': '1', 14:09:25 INFO - 'PAGER': '/bin/cat', 14:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:25 INFO - 'PWD': '/builds/slave/test', 14:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:25 INFO - 'SHELL': '/bin/bash', 14:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:25 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:25 INFO - 'USER': 'cltbld', 14:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:25 INFO - 'XPC_FLAGS': '0x0', 14:09:25 INFO - 'XPC_SERVICE_NAME': '0', 14:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-VvBRlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-pBEc1m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-jE6dh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-NF5uDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-6kDzrX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-1otCdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-OGds_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-DHGp7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:26 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)) 14:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:26 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-92NA4s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-hCkiyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-zsqzeI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-WGJgX4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-XbzdGj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-v4_5Ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-K08CdS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-rv3e3K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:27 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)) 14:09:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:27 INFO - Running setup.py (path:/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/pip-7gujli-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:27 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)) 14:09:27 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)) 14:09:27 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)) 14:09:27 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)) 14:09:27 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)) 14:09:27 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)) 14:09:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09:27 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 14:09:27 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 14:09:27 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 14:09:27 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 14:09:27 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 14:09:27 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 14:09:30 INFO - Downloading blessings-1.6.tar.gz 14:09:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:30 INFO - Installing collected packages: blessings 14:09:30 INFO - Running setup.py install for blessings 14:09:30 INFO - Successfully installed blessings 14:09:30 INFO - Cleaning up... 14:09:30 INFO - Return code: 0 14:09:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:09:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:30 INFO - Reading from file tmpfile_stdout 14:09:30 INFO - Current package versions: 14:09:30 INFO - blessings == 1.6 14:09:30 INFO - blobuploader == 1.2.4 14:09:30 INFO - docopt == 0.6.1 14:09:30 INFO - manifestparser == 1.1 14:09:30 INFO - mozInstall == 1.12 14:09:30 INFO - mozcrash == 0.17 14:09:30 INFO - mozdebug == 0.1 14:09:30 INFO - mozdevice == 0.48 14:09:30 INFO - mozfile == 1.2 14:09:30 INFO - mozhttpd == 0.7 14:09:30 INFO - mozinfo == 0.9 14:09:30 INFO - mozleak == 0.1 14:09:30 INFO - mozlog == 3.1 14:09:30 INFO - moznetwork == 0.27 14:09:30 INFO - mozprocess == 0.22 14:09:30 INFO - mozprofile == 0.28 14:09:30 INFO - mozrunner == 6.11 14:09:30 INFO - mozscreenshot == 0.1 14:09:30 INFO - mozsystemmonitor == 0.0 14:09:30 INFO - moztest == 0.7 14:09:30 INFO - mozversion == 1.4 14:09:30 INFO - psutil == 3.1.1 14:09:30 INFO - requests == 1.2.3 14:09:30 INFO - wsgiref == 0.1.2 14:09:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:30 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:30 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 14:09:30 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 14:09:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:30 INFO - 'HOME': '/Users/cltbld', 14:09:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:30 INFO - 'LOGNAME': 'cltbld', 14:09:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:30 INFO - 'MOZ_NO_REMOTE': '1', 14:09:30 INFO - 'NO_EM_RESTART': '1', 14:09:30 INFO - 'PAGER': '/bin/cat', 14:09:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:30 INFO - 'PWD': '/builds/slave/test', 14:09:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:30 INFO - 'SHELL': '/bin/bash', 14:09:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:30 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:30 INFO - 'USER': 'cltbld', 14:09:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:30 INFO - 'XPC_FLAGS': '0x0', 14:09:30 INFO - 'XPC_SERVICE_NAME': '0', 14:09:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 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)) 14:09:31 INFO - Cleaning up... 14:09:31 INFO - Return code: 0 14:09:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:09:31 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')]} 14:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:31 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')]} 14:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:31 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:31 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 14:09:31 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 14:09:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:31 INFO - 'HOME': '/Users/cltbld', 14:09:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:31 INFO - 'LOGNAME': 'cltbld', 14:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:31 INFO - 'MOZ_NO_REMOTE': '1', 14:09:31 INFO - 'NO_EM_RESTART': '1', 14:09:31 INFO - 'PAGER': '/bin/cat', 14:09:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:31 INFO - 'PWD': '/builds/slave/test', 14:09:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:31 INFO - 'SHELL': '/bin/bash', 14:09:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:31 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:31 INFO - 'USER': 'cltbld', 14:09:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:31 INFO - 'XPC_FLAGS': '0x0', 14:09:31 INFO - 'XPC_SERVICE_NAME': '0', 14:09:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:31 INFO - Downloading/unpacking jsonschema 14:09:31 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 14:09:31 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 14:09:31 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 14:09:31 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 14:09:31 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 14:09:31 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 14:09:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:33 INFO - Downloading/unpacking functools32 (from jsonschema) 14:09:33 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 14:09:33 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 14:09:33 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 14:09:33 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 14:09:33 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 14:09:33 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 14:09:34 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:34 INFO - warning: no files found matching '*.txt' 14:09:34 INFO - no previously-included directories found matching 'build' 14:09:34 INFO - no previously-included directories found matching 'dist' 14:09:34 INFO - no previously-included directories found matching '.git*' 14:09:34 INFO - Installing collected packages: jsonschema, functools32 14:09:34 INFO - Running setup.py install for functools32 14:09:34 INFO - warning: no files found matching '*.txt' 14:09:34 INFO - no previously-included directories found matching 'build' 14:09:34 INFO - no previously-included directories found matching 'dist' 14:09:34 INFO - no previously-included directories found matching '.git*' 14:09:34 INFO - Successfully installed jsonschema functools32 14:09:34 INFO - Cleaning up... 14:09:34 INFO - Return code: 0 14:09:34 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:34 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 0x10e1c3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e1d3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f81794b0570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, '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.gefpqcb5qT/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.2ioWhQdyWc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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 14:09:34 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 14:09:34 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 14:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:09:34 INFO - 'HOME': '/Users/cltbld', 14:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:09:34 INFO - 'LOGNAME': 'cltbld', 14:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:34 INFO - 'MOZ_NO_REMOTE': '1', 14:09:34 INFO - 'NO_EM_RESTART': '1', 14:09:34 INFO - 'PAGER': '/bin/cat', 14:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:34 INFO - 'PWD': '/builds/slave/test', 14:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:09:34 INFO - 'SHELL': '/bin/bash', 14:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:09:34 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:09:34 INFO - 'USER': 'cltbld', 14:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:34 INFO - 'XPC_FLAGS': '0x0', 14:09:34 INFO - 'XPC_SERVICE_NAME': '0', 14:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:34 INFO - Downloading/unpacking flake8 14:09:34 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 14:09:34 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 14:09:34 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 14:09:34 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 14:09:34 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 14:09:34 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 14:09:37 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:09:37 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:37 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:09:37 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:38 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:38 INFO - no previously-included directories found matching 'docs/_build' 14:09:38 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:09:38 INFO - Running setup.py install for pep8 14:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:09:38 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:09:38 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:09:38 INFO - no previously-included directories found matching 'docs/_build' 14:09:38 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:09:38 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:09:38 INFO - Cleaning up... 14:09:38 INFO - Return code: 0 14:09:38 INFO - Running post-action listener: _resource_record_post_action 14:09:38 INFO - Running post-action listener: _start_resource_monitoring 14:09:38 INFO - Starting resource monitoring. 14:09:38 INFO - ##### 14:09:38 INFO - ##### Running install step. 14:09:38 INFO - ##### 14:09:38 INFO - Running pre-action listener: _resource_record_pre_action 14:09:38 INFO - Running main action method: install 14:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:39 INFO - Reading from file tmpfile_stdout 14:09:39 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:09:39 INFO - Reading from file tmpfile_stdout 14:09:39 INFO - Output received: 14:09:39 INFO - Usage: mozinstall [options] installer 14:09:39 INFO - Options: 14:09:39 INFO - -h, --help show this help message and exit 14:09:39 INFO - -d DEST, --destination=DEST 14:09:39 INFO - Directory to install application into. [default: 14:09:39 INFO - "/builds/slave/test"] 14:09:39 INFO - --app=APP Application being installed. [default: firefox] 14:09:39 INFO - mkdir: /builds/slave/test/build/application 14:09:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:10:12 INFO - Reading from file tmpfile_stdout 14:10:12 INFO - Output received: 14:10:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:10:12 INFO - Running post-action listener: _resource_record_post_action 14:10:12 INFO - ##### 14:10:12 INFO - ##### Running run-tests step. 14:10:12 INFO - ##### 14:10:12 INFO - Running pre-action listener: _resource_record_pre_action 14:10:12 INFO - Running main action method: run_tests 14:10:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:10:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:10:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:10:12 INFO - 2016-05-05 14:10:12.901 screenresolution[1639:7550] starting screenresolution argv=screenresolution get 14:10:12 INFO - 2016-05-05 14:10:12.903 screenresolution[1639:7550] Display 0: 1600x1200x32@60 14:10:12 INFO - 2016-05-05 14:10:12.908 screenresolution[1640:7552] starting screenresolution argv=screenresolution list 14:10:12 INFO - Available Modes on Display 0 14:10:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:10:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:10:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:10:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:10:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:10:13 INFO - Intel Iris: 14:10:13 INFO - Chipset Model: Intel Iris 14:10:13 INFO - Type: GPU 14:10:13 INFO - Bus: Built-In 14:10:13 INFO - VRAM (Dynamic, Max): 1536 MB 14:10:13 INFO - Vendor: Intel (0x8086) 14:10:13 INFO - Device ID: 0x0a2e 14:10:13 INFO - Revision ID: 0x0009 14:10:13 INFO - Displays: 14:10:13 INFO - Display: 14:10:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:10:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:10:13 INFO - Display Serial Number: 1600x1200 60 14:10:13 INFO - Main Display: Yes 14:10:13 INFO - Mirror: Off 14:10:13 INFO - Online: Yes 14:10:13 INFO - Rotation: Supported 14:10:13 INFO - Return code: 0 14:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:10:13 INFO - Python 2.7.3 14:10:13 INFO - Return code: 0 14:10:13 INFO - grabbing minidump binary from tooltool 14:10:13 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')]} 14:10:13 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 0x10e062e00>, '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 0x10de8bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e05db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:10:13 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 14:10:13 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 14:10:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:10:13 INFO - Return code: 0 14:10:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:10:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:10:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:10:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:10:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:10:14 INFO - Return code: 0 14:10:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 14:10:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0019 --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 14:10:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gefpqcb5qT/Render', 14:10:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:10:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:10:14 INFO - 'HOME': '/Users/cltbld', 14:10:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:10:14 INFO - 'LOGNAME': 'cltbld', 14:10:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:10:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:10:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:10:14 INFO - 'MOZ_NO_REMOTE': '1', 14:10:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:10:14 INFO - 'NO_EM_RESTART': '1', 14:10:14 INFO - 'PAGER': '/bin/cat', 14:10:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:10:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:10:14 INFO - 'PWD': '/builds/slave/test', 14:10:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:10:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:10:14 INFO - 'SHELL': '/bin/bash', 14:10:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2ioWhQdyWc/Listeners', 14:10:14 INFO - 'TMPDIR': '/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/', 14:10:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:10:14 INFO - 'USER': 'cltbld', 14:10:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:10:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:10:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:10:14 INFO - 'XPC_FLAGS': '0x0', 14:10:14 INFO - 'XPC_SERVICE_NAME': '0', 14:10:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:10:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0019', '--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 14:10:14 INFO - mozversion application_buildid: 20160505115745 14:10:14 INFO - mozversion application_changeset: 0177462aac74605f426ab9c92e39bb467b7ce2d1 14:10:14 INFO - mozversion application_display_name: Nightly 14:10:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:10:14 INFO - mozversion application_name: Firefox 14:10:14 INFO - mozversion application_remotingname: firefox 14:10:14 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:10:14 INFO - mozversion application_vendor: Mozilla 14:10:14 INFO - mozversion application_version: 49.0a1 14:10:14 INFO - mozversion platform_buildid: 20160505115745 14:10:14 INFO - mozversion platform_changeset: 0177462aac74605f426ab9c92e39bb467b7ce2d1 14:10:14 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:10:14 INFO - mozversion platform_version: 49.0a1 14:10:14 INFO - using testdate: 1462482614 14:10:14 INFO - actual date: 1462482614 14:10:14 INFO - starting webserver on 'localhost:49271' 14:10:14 INFO - SUITE-START | Running 2 tests 14:10:14 INFO - TEST-START | dromaeo_css 14:10:14 INFO - operating with platform_type : mac_ 14:10:14 INFO - Initialising browser for dromaeo_css test... 14:10:14 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpscBT4t/profile http://localhost:49271/getInfo.html) 14:10:16 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 14:10:16 INFO - PROCESS | 1658 | colorDepth:24 14:10:16 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 14:10:16 INFO - PROCESS | 1658 | __metrics 14:10:17 INFO - PROCESS | 1658 | 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" 14:10:17 ERROR - PROCESS | 1658 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:10:17 INFO - TEST-INFO | 1658: exit 0 14:10:17 INFO - Browser initialized. 14:10:17 INFO - Running cycle 1/1 for dromaeo_css test... 14:10:17 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpscBT4t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:29 INFO - PROCESS | 1663 | RSS: Main: 239112192 14:10:29 INFO - PROCESS | 1663 | 14:11:20 INFO - PROCESS | 1663 | 1462482680825 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] 14:13:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 14:13:32 INFO - PROCESS | 1663 | RSS: Main: 378146816 14:13:32 INFO - PROCESS | 1663 | 14:16:35 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 14:16:36 INFO - PROCESS | 1663 | RSS: Main: 375291904 14:16:36 INFO - PROCESS | 1663 | 14:19:38 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 14:19:39 INFO - PROCESS | 1663 | RSS: Main: 373334016 14:19:39 INFO - PROCESS | 1663 | 14:22:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 14:22:42 INFO - PROCESS | 1663 | RSS: Main: 369852416 14:22:42 INFO - PROCESS | 1663 | 14:25:45 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 14:25:46 INFO - PROCESS | 1663 | RSS: Main: 340070400 14:25:46 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 14:28:49 INFO - PROCESS | 1663 | RSS: Main: 378171392 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | __start_tp_report 14:28:49 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 14:28:49 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 14:28:49 INFO - PROCESS | 1663 | |i|pagename|runs| 14:28:49 INFO - PROCESS | 1663 | |0;dojo.html;2894.7828912831537;3459.083342914128;3471.6875481206685;3492.9650703492957;3519.612842587315;5543.279373681426;5585.162225666152;5616.52259557937;5674.489295963363;5746.913796293054;5625.268715067045;5673.461021202979;5719.885602287952;5735.483806457418;5770.249867517227;5057.291979122926;5087.923681144785;5138.511841375063;5155;5249.1601343785005;5186;5244.868878278055;5256.5794736421185;5268.236105764662;5274.868128296804;5059.266406371074;5125.743712814345;5136.357955255593;5144.794208231685;5160.200168973815;5077.111505486525;5118.769655365518;5132.794688212467;5138.46046165151;5139.15203991341;5080.415752188472;5121.769520371592;5157.381114266274;5162.535371816554;5224.608154388436;5507.394186639466;5608.523275521545;5627.943720562783;5685.772569097231;5690.7439165237665;6184.6907654617535;6212.968935155295;6221.253449586034;6227.19046523949;6241.032639940817;5063.240513922904;5171.37943446785;6268.937310626881;6272.372762723737;6280.905786413207;5640.689762063126;5686.630369026;5729.312482502127;5832.3584405715765;5850.853728656775;6282.18331616896;6294.8111556653375;6296.811095667137;6305.243370795442;6307.306196318401;6141.815745527641;6170.660613666201;6174.197354343996;6175.6912154393;6183.721732521957;6050.9394906050375;6095.05526643371;6165.722542485599;6281.905771413432;6292.150559674477;6098.129681996294;6146.170267013896;6215.689215539295;6224.408681175281;6233.283172435137;6186.288576813724;6223.533235007392;6244.812655620339;6248.4688801452385;6256.812295631138;4814.20565606678;4872.926906096412;4885.487023862514;4937.456879743226;4969.130402179604;4476.104779044204;4508.301213311942;4508.504064552896;4517.2772356423475;4525.705829121097;5633.295838020247;5641.633293835887;5644.74598643062;5649.576281778801;5650.406707295757;6118.3755344619085;6184.567080304426;6202;6213;6225.844353891189;5616.747246373923;5655.095184770417;5663.32040155184;5672.716364181857;5677.403872593401;5378.542823860016;5390.622656414013;5400;5403.405625381205;5403.864903377447;6251.906221406683;6259.155014073133;6270.717817698214;6309.432151106423;6384.553081284265;6282.717277722605;6285.43431091204;6289.119523266838;6311.589746666452;6313.715882785377;6238.344973777869;6363.840903977438;6376.872462550816;6391.744330226739;6397.808065758035;6088.056351265561;6093.969530152321;6116.244470650343;6194.659293738888;6199.16311297969;5209.270702101785;6254.061890716429;6258.9374106260175;6264.248290205022;6268.968655156695;5880.970595146997;6023.5181185505935;6086.2087928569;6099.9390006099375;6119.755209791559;5989.790357337472;6111.572189946839;6121.296050954019;6124.908126378197;6141.355157708376;5718.1136923776885;5800.796972105956;5889.882202356014;5918.3489816121055;5938.524918006636;5816.563757718103;6027.849303767265;6083.300420451705;6098.157183194213;6121.816345509642;5947.6431414115295;5950.98833198366;5974.1636170935235;5991.191189189578;5992.760289588367 14:28:49 INFO - PROCESS | 1663 | |1;ext.html;502.66823896228476;506.84034529123323;510.9437961824199;525.7634064670899;527.6306585390226;29239.707602923965;42351.36472952908;43665.78167109168;43701.7814910925;45574;43861.7806910965;44219.115617687705;44263.55736442635;44284.55715442845;44415.99999999992;8608.182222688834;9673.95163024184;9844;9851.458169800659;9868.259880508955;8743.95628021859;11529.769404611903;11782.646520604396;11799.646010619652;11906.523739050428;11794.823077653842;11839.408029598486;11846.407679615984;11859.229150105259;11861.703457413547;8553.957230213871;9230.353875228737;11537.307761534335;11775.940165385113;11791.528338866436;11671.12466565011;11771.470283837249;11774.587889423829;11778.175527713063;11784.175107742461;40595;40930.38604420936;41022.9744256393;41042.79478602618;41044;3195.6644552321904;3218.1954511372155;3224.1294850390263;3266.934661306784;3439.8796042138406;29002.41995160085;29799.808007679665;29893;30187.69812301871;30488.254582058384;2716.606092116652;2751.3534319435116;2751.463464624399;2828.8302701837956;2857.39994601134;483800;484936;485423.1457685413;486246;486973;35534.822325888206;35883.64116358803;48127;60698.393016069276;60806;37171.62828371736;37227;37348.066298342215;37368.87893363149;37382.25235495275;15184;15305.923470382577;15319.387224511118;15342.846571534365;15366.924315297822;11491.77016459666;11940.641780746591;12693.746125077632;12825.294608796607;12889.291088990196;8236.50580965144;8253;8270.586470676442;8339.874901876477;8347.165283471726;3058.2660161561066;3430.730629667039;3448.827558622059;3474.818561689038;3475.800848707192;992.6525715999343;1021.6832781837654;1071.2698991129425;1084.6366467233408;1099.0273607857105;29700.257493562833;29886.953956611847;30014.24964375908;30163;30288.5456718145;918.5958178401482;1041.953112109957;1090.9781804364027;1096.276457538021;1128.7065363005677;12676.746465070646;15168.848311516964;15294.464693735667;15606;15614;641656;646107;647432;648100;648431;60363.698181508815;60878;60903.69548152231;61012.77974440486;61141.694291529144;1342.060557609677;1459.4308219794289;1486.011802151557;1492.2315007771224;1508.1403599947923;30631.693683063775;30660.080197594107;30772.076837694905;31353.90261340842;31488.370232595676;50622.2406663893;51023;51038.97922041613;51228.231576525635;51286.97426051383;495439;495703.5214824047;496004.1198764043;496263;496329;23258.53482930366;25717.742822571534;26148.8692556536;26468;27755;46295.07409851851;46500;46605.76697116628;46768.29847552221;46966.53033469622;37985.62014379821;38355.61644383632;38409.807950960065;38916.80541597274;39152.608473914894;37305;37504;37759;38286.80856595699;38599;37271.25457490889;37606.62393376031;38471.422928655535;38520;38550.42174367443;26559;31072;32363.51454728228;33045.843395481046;33916.49125263172;21198.258060967793;22089;23382.309143254515;24663.656952590194;24824.131155409475 14:28:49 INFO - PROCESS | 1663 | |2;jquery.html;392.46624590556837;400.27150585933606;404.93116170251074;407.0596921112229;427.570291856684;20134.59730805383;20767.857767822763;20858.340829437824;21365.465863353424;21447.463813404673;20305.898470507665;20309.593808123827;20499;21547.46131346717;21552.461188470297;5886.941130588692;6114.571979961404;6138.14066030756;6145.324014358417;6148.077788331763;5862.530997520188;6111.69441527924;6130.295016073142;6130.632162070267;6182.938170618281;5989.700514974234;6084.300305464862;6086.939130608682;6086.939130608682;6087.817365479043;5764.53883689306;6071.666058366788;6075.696215189223;6077.1491991121275;6091.116788065706;5278.947210527922;5293.682379057269;5296.523312901857;5296.894062118775;5302.443243459458;25231.36921576957;26076.86961565199;26397.472050559074;26403.47193056128;26431.867840660867;1909.4176276235742;2172.7175467189163;2178.7821217878245;2368.396059004943;2374.8693821839793;10233.334833235891;10391.220658450646;10406.42764647944;12106.728793476646;12131.21147125434;1682.5204816627324;1712.3749831311482;1714.2628669672035;1744.0494930263037;1802.4502526729343;124329.18872707078;128902;129336.99999999905;130050.3497482516;131547;9304.90695093054;11221.913973657309;11714.589989350332;11843.46704398304;11942.522299107939;15182.164980925934;15289.311980960883;15291.694166116838;15313.234338283264;15314.923425382802;8663.610137543825;8740.388172827968;8748.78128046804;8760.91239087601;8895.110488951183;8677.305815534743;8681.52251626154;8687.782805429915;8778.34162437807;8823.779405514784;4633.86098417048;4646.535346465326;4663.696859704109;4667.229907065296;4749.548792864673;1929.604431091623;1962.960740785205;1966.7049942508738;1969;1969.527313444763;919.4483310013939;932.8898610653301;995.3579940938055;1010.545254635417;1016.5527168046184;16763.826532142877;20007.799922000886;20054.398368048984;20116.59766804685;20416.591668166555;777.249953794591;930.3348106104168;957.305953183936;959.8896126945351;983.9508024598741;10690.037896589189;10835.83746243807;10842.945785271024;10887.401192934307;10889.782204355868;596065.0196748988;599007;599206;614374;615201;132121.357572848;137782.3110884439;138048.61951380558;141328.5867141336;141753.65970722318;1019.169376957777;1020.6019652335596;1025.436010194405;1026.1226651213256;1036.18659352408;17908.55228619295;18227;18267.995260260814;18411.53971150732;18530.536736581693;51011.48988510216;51472.713182170744;52300.95398091941;52369.738151309;52639;71859.92210116958;75333;76509;76862.61568692121;77081.61459192893;7882.763517094496;8004;8025.35797136216;8435;8439.071702112886;9150.633974641207;12088.939555302168;12505.812412813633;12942.964562835139;12945.964322854337;10744.247902646739;10962.671119866416;10975.176861735243;10985.285956412808;11189.664310070712;10559.260851740612;10749.838752418877;10772;11099.833502497631;11165.441727913734;10998.670039898816;11157.163212758913;11217;11305.321680698855;11397;8098.473599216032;8316.542590157358;8348.833023339377;8349.123342048997;8388.874166887623;10360;11038.17213708959;11313.886861131283;11348;11349.602763903224 14:28:49 INFO - PROCESS | 1663 | |3;mootools.html;1437.1736251655288;1603.1022627328693;1641.7373220284753;1874.222197787918;1994.1624517702573;7902;8699.260562852158;8962.37263391563;8962.417442866226;9083.137101975319;8166.428350015502;8873.778155546099;8878.289736821054;8881.866771998426;8886.688965886195;2167.8916054197307;2402.459446624513;2445.1808644104203;2485.689288838895;2490;2492.71333796613;2530.936726581841;2532.063136639446;2532.898684052636;2534.214393538;2437.597796363598;2500.624906264057;2501.4246723253664;2501.7998560115147;2501.874906254689;2134.5410736691606;2378.2746262389965;2431.6109422492405;2450.2281781238853;2455.4107014316437;2473.5918573435542;2477.504499100169;2504.5241404133158;2507.3731567108225;2509.2346834215555;7672.501287416356;8686.131386861263;8902.821943561157;8910.153535414125;8923.955380223122;5339.385970613352;5507.586930980193;5510.173473978895;5510.228568000483;5513.696746678931;3545.450455179451;3549.538559987211;3592.4072528032843;3663.725220608465;3669.3395188865993;1036.2021243642428;1045.9633912813092;1046.1892033673887;1048.1509976918644;1048.5386429970788;9811.558479868423;11847.940760296317;11850.229735067192;11868.05055595559;11882.58410955621;4848.345473361122;4874.902501950012;4982.103221420107;4983.700977941335;4995.225740010304;10180.338278011906;10187.694369168938;10198.592056317813;10218.948905255575;10221.233407494468;5470.452954704498;5500.532454741312;5504.80733174345;5514.558835293167;5515.227868098469;4284.228838808982;4458.5987261146;4602.355670206174;4622.422197225347;4635.0034742530515;3438.570178727659;4041.312976793893;4098.364753463218;4161.292580261363;4168.145530166309;1117.6311817100338;1315.2963164706837;1346.259557243512;1360.7346567419456;1361.9230673223215;862.508370228976;903.5888670654837;931.65063101337;941.1012483078644;965.6909788867495;5860.824175274749;6036.913355595947;6059.666718330446;6094.8171554853425;6159.692015399212;459.9862004139881;483.8669365924399;947.0434860790514;961.8893827209821;982.6315131825565;3822.961770382316;3922.8038598069984;4044.3933409988736;4061.086255592468;4129.772862492592;10109.797804043877;10966.945165274123;11147.832782508269;11152.330860148417;11159.553617855358;9190.954045229731;11087.22389432748;11098.05666518355;11112.777744445066;11115.944420277847;8177.018757749108;8353.791155221168;8439.396514662194;8497.915020849836;8550.957245213858;5873.559483038704;5952.821415357546;6015.729292181775;6033.336333003454;6062.151298818258;10073.597056117673;10248.333858299187;10319.329243599143;10327.48362581883;10330.328528645614;10128.898711013091;10152.137068349273;10403.427811470441;10457.111145552713;10509.054185123376;6659.500537459807;6847.144106986627;6857.794266172023;6928.861422771617;7069.187043489859;6544.181977252843;8052.355811535181;8076.030876294647;8575.013873404389;8706.47761134334;7443.478956473212;7576.356009739234;7604.695812167452;7734.458587899018;7787.766367008999;6217.316095229611;7461.141968673672;7528.962355188189;7677.232276772278;7683.193264707125;6059.848503787441;6985.860282794417;7083.7520686775715;7172.031775710421;7190.928090719026;4935.284383764406;5039.470855560114;5057.6459647824295;5073.59411247092;5132.820351287687;5010.298558201782;5051.646384753032;5127.974360128175;5177.792888284577;5328.17413300939 14:28:49 INFO - PROCESS | 1663 | |4;prototype.html;308.2171284936262;319.1956270199098;322.79663811798565;325.2064961493953;330.0395848082082;5341.492558206969;5356.946430535693;5385.3268341457315;5386.680263335478;5396.946030539693;4745.928811067837;5061.507559783747;5335.132703553764;5371.57027437805;5435.728213589325;3586.686587151286;3830.291396091718;3895.266606360171;4006.338954072589;4098.6516146127615;3993.7404068735586;4017.9352471594902;4059.7724808776948;4090;4125.525564560069;3371;3807.200487897531;3941.940870886939;4053.5946405359377;4068.8575899843363;3235.5470234167;3380.3915295246848;3860.841747475746;3866.76799392036;4081.7142800004012;2785.4846853332197;3213.5822343095247;3364.96635033649;3548.840302186408;3566.928661426757;4505.062822986115;5129.050960634935;5133.614978876599;5159.896802063975;5203;3781.827633433644;4085.222928026318;4371.912561748747;4594.9310760338285;4663.27719203524;4837.9032419351415;5508.807191748309;5540.778368865241;5564.582656300753;5571.721413929287;3360.5463262459743;3381.0871064812363;3752.9812350938346;3920.9607903920883;3924.2936271471126;4860.441049279308;5506.191842160527;5561.916571251434;5840.678762668052;5879.882402351929;3115.4453348254665;3479.913002174966;3891.2606604745574;3891.8637847675195;3892.863749768801;3542.9902477793703;3782.4893639358884;3871.264459752638;3951.320688707288;3953.3872249801325;4532.844124748168;4577.656675749332;4601.332806743004;4620.5148459411275;4622.791974361162;4589.770511474413;5036.985205917589;5233.476652334736;5477.67133971963;5610.915836262459;1087.5535537734793;1187.329159025148;1261.7382617382618;1265.2724683307135;1271.0086132816418;754.4907187648315;761.6708843068881;862.3058437957379;878.6002368922125;882.8852249207562;4457.058341494663;4541.704789188692;4629.675922685447;4846.933674591584;5186.4035635902355;5226.111561034557;5521.834344969658;5788.797392091256;5818.828738337616;5875.148103524965;4714.929276060861;5059.696418214919;5245.213218017251;5315.813946511854;5335.67985920846;2465.59317712579;2533.4173140177863;2711.91864244073;2888.581155732407;2989.9850500747793;4758.97681998372;5540.972295138498;5754.827355179351;5759.510441612426;5786.594938354275;4608.354830323758;5230.607704422184;5344.9732751336;5717.771289148387;5737.225474560881;5150.665206761549;5743.9138412923;5747.683877386784;5765.019946609086;5783.681897495678;4954.677945933503;5449.373322068013;5506.64206826555;5545.781687325102;5552.222688823487;5066.189409694432;5155.742212889416;5287.312649355559;5800.419958004166;5816.563757718272;5335.759890805;5984.850378740392;6065.545084118797;6077.6353418795015;6089.269287685506;3421;3818.885433437001;4049.5950404959267;4070.4911886014247;4085.8569950051606;5299.867503312448;5756.539476841926;5790.305163380422;5798.304203495608;5798.623089499169;4697.788599512962;5273.6572122811895;5592.636478628876;5600.27196464457;5640.746166422438;5067.974660126676;5272.340957380327;5556.388797232382;5606.803761868478;5623.522000629829;4252.596003379674;4588.678792484628;5069.797208111634;5477.863053423696;5519.862003449946;5025.321581586585;5200.45395233495;5403.702796346239;5526.778928842801;5541.251930989265;4670.5521288400705;5540.097014142432;5549.500544950974;5571.637843540157;5573 14:28:49 INFO - PROCESS | 1663 | |5;yui.html;546.8714852009776;622.747787146206;726.7093162734902;728.5337384074197;731.6488085718856;14685.853141468582;15129.697406051886;15318.387264509407;15353.539393818175;15473.535793926187;11562.306261624288;14172.858271417283;14198.361073751706;14209.07641003334;14319.212443315613;714.2607401339615;872.4024043530202;897.0760117079402;908.0420156734642;911.9694744938244;893.8703887936246;906.1753804038326;908.1780988205685;909.6725178935578;910.7632015675906;977.384247923807;977.4868194198067;979.8089372572351;980.2892902645597;980.3333733061515;748.2069006852727;935.3919952031166;972.3485264872481;978.1392374710281;979.0992287095818;723.6345645449068;724.6847621284758;724.9093863267092;724.971001159953;727.0475676863326;14440;14492.34784434703;14521.564353069425;14522.346494407777;14524.709505809824;10201.387916724945;12398.938005310007;12604.054695897752;12617.432215550321;12833.037522185874;8840.602172902234;10757.246992710514;10939.627555542327;10952.835707464394;11374.090072794157;4056.290149223874;4059.472268605092;4061.9796901015307;4131.958680413188;4200.768957707325;80096;80372.59813700894;83201.58399208025;83442.3311533772;84583;9500.144986951207;9541.809163816684;9718.562664679965;9851.901480985098;10060.295779295526;21765.891170544044;21868.89065554662;21869.343919682437;21881.234156804436;21896.89051554732;7416.184219735825;7421.146568144624;7489.85020299591;7594.354479869273;7659.004329437137;4005.939910901339;4052.8176232069627;4055.9594404055583;4059.167870586523;4069.1251380953236;3456.6024907135497;3554.804485753257;3558.6085530591613;3558.62634423387;3565.8751943681846;627.3193584960278;639.5011890725242;646.444058110025;649.357136434926;654.2998991079594;539.816462402785;541.1422894711926;569.7066011004337;572.0703856233621;574.3882764855416;7675.731349402744;7756.806079848049;7918.129005809356;8067.556284404297;8296.626651800683;570.2785975740692;593.0955293177852;605.9303180134253;621.7544070092379;655.3741177175785;3401.725714740176;3892.5328960524917;3920.5099362579676;3957.3470377387407;3970.4441378207075;66181;81686.24257472673;86096.56951715202;87119.56440217885;87340;47763.50719667283;67606.9858952116;74301.25698743052;75925.48149036989;76821.46357072827;66077.33922660808;66260.66869665621;66293;67195;68093.65953170203;7690.692372305269;7741.380689544935;7838.80402989907;7916.564588947663;7922.207779222263;31933;41940.580594194886;41978.79010604927;42958.14083718371;43079.7846010768;57405.71297143488;58589;58637;58740;58831.70584147052;10074.798504029732;10244.64143754965;10255.58977640886;10410.323328983592;10667.839982400425;11032.669019929415;11319.377434241196;11331.263467874562;11450.541978320774;11514.94242528782;10567;10656.25406221557;10660.200484963503;10774.946125269324;10966.561337546409;10676.786464270826;10744.623938162127;10746.946265268623;10837.443326880017;11171.888281117084;9947.502624868872;10002;10165.18678505703;10257.28199026061;10350.378977261389;8857.955710221408;8920.821583568162;9022.774430639287;9212.953935230282;9569.665061722806;8956.776080597776;9026.368154229142;9120.315976301672;9137.817243655223;9196.908030919605 14:28:49 INFO - PROCESS | 1663 | __end_tp_report 14:28:49 INFO - PROCESS | 1663 | __start_cc_report 14:28:49 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1413 14:28:49 INFO - PROCESS | 1663 | __end_cc_report 14:28:49 INFO - PROCESS | 1663 | __startTimestamp1462483729317__endTimestamp 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | ------- Summary: start ------- 14:28:49 INFO - PROCESS | 1663 | Number of tests: 6 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5707.94 Median:5889.88 stddev:642.11 (10.9%) stddev-sans-first:604.97 14:28:49 INFO - PROCESS | 1663 | Values: 2894.8 3459.1 3471.7 3493.0 3519.6 5543.3 5585.2 5616.5 5674.5 5746.9 5625.3 5673.5 5719.9 5735.5 5770.2 5057.3 5087.9 5138.5 5155.0 5249.2 5186.0 5244.9 5256.6 5268.2 5274.9 5059.3 5125.7 5136.4 5144.8 5160.2 5077.1 5118.8 5132.8 5138.5 5139.2 5080.4 5121.8 5157.4 5162.5 5224.6 5507.4 5608.5 5627.9 5685.8 5690.7 6184.7 6213.0 6221.3 6227.2 6241.0 5063.2 5171.4 6268.9 6272.4 6280.9 5640.7 5686.6 5729.3 5832.4 5850.9 6282.2 6294.8 6296.8 6305.2 6307.3 6141.8 6170.7 6174.2 6175.7 6183.7 6050.9 6095.1 6165.7 6281.9 6292.2 6098.1 6146.2 6215.7 6224.4 6233.3 6186.3 6223.5 6244.8 6248.5 6256.8 4814.2 4872.9 4885.5 4937.5 4969.1 4476.1 4508.3 4508.5 4517.3 4525.7 5633.3 5641.6 5644.7 5649.6 5650.4 6118.4 6184.6 6202.0 6213.0 6225.8 5616.7 5655.1 5663.3 5672.7 5677.4 5378.5 5390.6 5400.0 5403.4 5403.9 6251.9 6259.2 6270.7 6309.4 6384.6 6282.7 6285.4 6289.1 6311.6 6313.7 6238.3 6363.8 6376.9 6391.7 6397.8 6088.1 6094.0 6116.2 6194.7 6199.2 5209.3 6254.1 6258.9 6264.2 6269.0 5881.0 6023.5 6086.2 6099.9 6119.8 5989.8 6111.6 6121.3 6124.9 6141.4 5718.1 5800.8 5889.9 5918.3 5938.5 5816.6 6027.8 6083.3 6098.2 6121.8 5947.6 5951.0 5974.2 5991.2 5992.8 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:66749.54 Median:19505.31 stddev:146403.82 (750.6%) stddev-sans-first:146730.03 14:28:49 INFO - PROCESS | 1663 | Values: 502.7 506.8 510.9 525.8 527.6 29239.7 42351.4 43665.8 43701.8 45574.0 43861.8 44219.1 44263.6 44284.6 44416.0 8608.2 9674.0 9844.0 9851.5 9868.3 8744.0 11529.8 11782.6 11799.6 11906.5 11794.8 11839.4 11846.4 11859.2 11861.7 8554.0 9230.4 11537.3 11775.9 11791.5 11671.1 11771.5 11774.6 11778.2 11784.2 40595.0 40930.4 41023.0 41042.8 41044.0 3195.7 3218.2 3224.1 3266.9 3439.9 29002.4 29799.8 29893.0 30187.7 30488.3 2716.6 2751.4 2751.5 2828.8 2857.4 483800.0 484936.0 485423.1 486246.0 486973.0 35534.8 35883.6 48127.0 60698.4 60806.0 37171.6 37227.0 37348.1 37368.9 37382.3 15184.0 15305.9 15319.4 15342.8 15366.9 11491.8 11940.6 12693.7 12825.3 12889.3 8236.5 8253.0 8270.6 8339.9 8347.2 3058.3 3430.7 3448.8 3474.8 3475.8 992.7 1021.7 1071.3 1084.6 1099.0 29700.3 29887.0 30014.2 30163.0 30288.5 918.6 1042.0 1091.0 1096.3 1128.7 12676.7 15168.8 15294.5 15606.0 15614.0 641656.0 646107.0 647432.0 648100.0 648431.0 60363.7 60878.0 60903.7 61012.8 61141.7 1342.1 1459.4 1486.0 1492.2 1508.1 30631.7 30660.1 30772.1 31353.9 31488.4 50622.2 51023.0 51039.0 51228.2 51287.0 495439.0 495703.5 496004.1 496263.0 496329.0 23258.5 25717.7 26148.9 26468.0 27755.0 46295.1 46500.0 46605.8 46768.3 46966.5 37985.6 38355.6 38409.8 38916.8 39152.6 37305.0 37504.0 37759.0 38286.8 38599.0 37271.3 37606.6 38471.4 38520.0 38550.4 26559.0 31072.0 32363.5 33045.8 33916.5 21198.3 22089.0 23382.3 24663.7 24824.1 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:35742.37 Median:11862.59 stddev:101380.35 (854.6%) stddev-sans-first:101630.00 14:28:49 INFO - PROCESS | 1663 | Values: 392.5 400.3 404.9 407.1 427.6 20134.6 20767.9 20858.3 21365.5 21447.5 20305.9 20309.6 20499.0 21547.5 21552.5 5886.9 6114.6 6138.1 6145.3 6148.1 5862.5 6111.7 6130.3 6130.6 6182.9 5989.7 6084.3 6086.9 6086.9 6087.8 5764.5 6071.7 6075.7 6077.1 6091.1 5278.9 5293.7 5296.5 5296.9 5302.4 25231.4 26076.9 26397.5 26403.5 26431.9 1909.4 2172.7 2178.8 2368.4 2374.9 10233.3 10391.2 10406.4 12106.7 12131.2 1682.5 1712.4 1714.3 1744.0 1802.5 124329.2 128902.0 129337.0 130050.3 131547.0 9304.9 11221.9 11714.6 11843.5 11942.5 15182.2 15289.3 15291.7 15313.2 15314.9 8663.6 8740.4 8748.8 8760.9 8895.1 8677.3 8681.5 8687.8 8778.3 8823.8 4633.9 4646.5 4663.7 4667.2 4749.5 1929.6 1963.0 1966.7 1969.0 1969.5 919.4 932.9 995.4 1010.5 1016.6 16763.8 20007.8 20054.4 20116.6 20416.6 777.2 930.3 957.3 959.9 984.0 10690.0 10835.8 10842.9 10887.4 10889.8 596065.0 599007.0 599206.0 614374.0 615201.0 132121.4 137782.3 138048.6 141328.6 141753.7 1019.2 1020.6 1025.4 1026.1 1036.2 17908.6 18227.0 18268.0 18411.5 18530.5 51011.5 51472.7 52301.0 52369.7 52639.0 71859.9 75333.0 76509.0 76862.6 77081.6 7882.8 8004.0 8025.4 8435.0 8439.1 9150.6 12088.9 12505.8 12943.0 12946.0 10744.2 10962.7 10975.2 10985.3 11189.7 10559.3 10749.8 10772.0 11099.8 11165.4 10998.7 11157.2 11217.0 11305.3 11397.0 8098.5 8316.5 8348.8 8349.1 8388.9 10360.0 11038.2 11313.9 11348.0 11349.6 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5758.65 Median:4861.62 stddev:3213.79 (66.1%) stddev-sans-first:3206.39 14:28:49 INFO - PROCESS | 1663 | Values: 1437.2 1603.1 1641.7 1874.2 1994.2 7902.0 8699.3 8962.4 8962.4 9083.1 8166.4 8873.8 8878.3 8881.9 8886.7 2167.9 2402.5 2445.2 2485.7 2490.0 2492.7 2530.9 2532.1 2532.9 2534.2 2437.6 2500.6 2501.4 2501.8 2501.9 2134.5 2378.3 2431.6 2450.2 2455.4 2473.6 2477.5 2504.5 2507.4 2509.2 7672.5 8686.1 8902.8 8910.2 8924.0 5339.4 5507.6 5510.2 5510.2 5513.7 3545.5 3549.5 3592.4 3663.7 3669.3 1036.2 1046.0 1046.2 1048.2 1048.5 9811.6 11847.9 11850.2 11868.1 11882.6 4848.3 4874.9 4982.1 4983.7 4995.2 10180.3 10187.7 10198.6 10218.9 10221.2 5470.5 5500.5 5504.8 5514.6 5515.2 4284.2 4458.6 4602.4 4622.4 4635.0 3438.6 4041.3 4098.4 4161.3 4168.1 1117.6 1315.3 1346.3 1360.7 1361.9 862.5 903.6 931.7 941.1 965.7 5860.8 6036.9 6059.7 6094.8 6159.7 460.0 483.9 947.0 961.9 982.6 3823.0 3922.8 4044.4 4061.1 4129.8 10109.8 10966.9 11147.8 11152.3 11159.6 9191.0 11087.2 11098.1 11112.8 11115.9 8177.0 8353.8 8439.4 8497.9 8551.0 5873.6 5952.8 6015.7 6033.3 6062.2 10073.6 10248.3 10319.3 10327.5 10330.3 10128.9 10152.1 10403.4 10457.1 10509.1 6659.5 6847.1 6857.8 6928.9 7069.2 6544.2 8052.4 8076.0 8575.0 8706.5 7443.5 7576.4 7604.7 7734.5 7787.8 6217.3 7461.1 7529.0 7677.2 7683.2 6059.8 6985.9 7083.8 7172.0 7190.9 4935.3 5039.5 5057.6 5073.6 5132.8 5010.3 5051.6 5128.0 5177.8 5328.2 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4455.61 Median:5063.85 stddev:1397.22 (27.6%) stddev-sans-first:1366.02 14:28:49 INFO - PROCESS | 1663 | Values: 308.2 319.2 322.8 325.2 330.0 5341.5 5356.9 5385.3 5386.7 5396.9 4745.9 5061.5 5335.1 5371.6 5435.7 3586.7 3830.3 3895.3 4006.3 4098.7 3993.7 4017.9 4059.8 4090.0 4125.5 3371.0 3807.2 3941.9 4053.6 4068.9 3235.5 3380.4 3860.8 3866.8 4081.7 2785.5 3213.6 3365.0 3548.8 3566.9 4505.1 5129.1 5133.6 5159.9 5203.0 3781.8 4085.2 4371.9 4594.9 4663.3 4837.9 5508.8 5540.8 5564.6 5571.7 3360.5 3381.1 3753.0 3921.0 3924.3 4860.4 5506.2 5561.9 5840.7 5879.9 3115.4 3479.9 3891.3 3891.9 3892.9 3543.0 3782.5 3871.3 3951.3 3953.4 4532.8 4577.7 4601.3 4620.5 4622.8 4589.8 5037.0 5233.5 5477.7 5610.9 1087.6 1187.3 1261.7 1265.3 1271.0 754.5 761.7 862.3 878.6 882.9 4457.1 4541.7 4629.7 4846.9 5186.4 5226.1 5521.8 5788.8 5818.8 5875.1 4714.9 5059.7 5245.2 5315.8 5335.7 2465.6 2533.4 2711.9 2888.6 2990.0 4759.0 5541.0 5754.8 5759.5 5786.6 4608.4 5230.6 5345.0 5717.8 5737.2 5150.7 5743.9 5747.7 5765.0 5783.7 4954.7 5449.4 5506.6 5545.8 5552.2 5066.2 5155.7 5287.3 5800.4 5816.6 5335.8 5984.9 6065.5 6077.6 6089.3 3421.0 3818.9 4049.6 4070.5 4085.9 5299.9 5756.5 5790.3 5798.3 5798.6 4697.8 5273.7 5592.6 5600.3 5640.7 5068.0 5272.3 5556.4 5606.8 5623.5 4252.6 4588.7 5069.8 5477.9 5519.9 5025.3 5200.5 5403.7 5526.8 5541.3 4670.6 5540.1 5549.5 5571.6 5573.0 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17028.76 Median:58785.85 stddev:23552.43 (40.1%) stddev-sans-first:23585.99 14:28:49 INFO - PROCESS | 1663 | Values: 546.9 622.7 726.7 728.5 731.6 14685.9 15129.7 15318.4 15353.5 15473.5 11562.3 14172.9 14198.4 14209.1 14319.2 714.3 872.4 897.1 908.0 912.0 893.9 906.2 908.2 909.7 910.8 977.4 977.5 979.8 980.3 980.3 748.2 935.4 972.3 978.1 979.1 723.6 724.7 724.9 725.0 727.0 14440.0 14492.3 14521.6 14522.3 14524.7 10201.4 12398.9 12604.1 12617.4 12833.0 8840.6 10757.2 10939.6 10952.8 11374.1 4056.3 4059.5 4062.0 4132.0 4200.8 80096.0 80372.6 83201.6 83442.3 84583.0 9500.1 9541.8 9718.6 9851.9 10060.3 21765.9 21868.9 21869.3 21881.2 21896.9 7416.2 7421.1 7489.9 7594.4 7659.0 4005.9 4052.8 4056.0 4059.2 4069.1 3456.6 3554.8 3558.6 3558.6 3565.9 627.3 639.5 646.4 649.4 654.3 539.8 541.1 569.7 572.1 574.4 7675.7 7756.8 7918.1 8067.6 8296.6 570.3 593.1 605.9 621.8 655.4 3401.7 3892.5 3920.5 3957.3 3970.4 66181.0 81686.2 86096.6 87119.6 87340.0 47763.5 67607.0 74301.3 75925.5 76821.5 66077.3 66260.7 66293.0 67195.0 68093.7 7690.7 7741.4 7838.8 7916.6 7922.2 31933.0 41940.6 41978.8 42958.1 43079.8 57405.7 58589.0 58637.0 58740.0 58831.7 10074.8 10244.6 10255.6 10410.3 10667.8 11032.7 11319.4 11331.3 11450.5 11514.9 10567.0 10656.3 10660.2 10774.9 10966.6 10676.8 10744.6 10746.9 10837.4 11171.9 9947.5 10002.0 10165.2 10257.3 10350.4 8858.0 8920.8 9022.8 9213.0 9569.7 8956.8 9026.4 9120.3 9137.8 9196.9 14:28:49 INFO - PROCESS | 1663 | -------- Summary: end -------- 14:28:49 INFO - PROCESS | 1663 | 14:28:49 INFO - TEST-INFO | 1663: exit 0 14:28:49 INFO - TEST-OK | dromaeo_css | took 1115364ms 14:28:49 INFO - TEST-START | kraken 14:28:49 INFO - operating with platform_type : mac_ 14:28:49 INFO - Initialising browser for kraken test... 14:28:49 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpDgw9wc/profile http://localhost:49271/getInfo.html) 14:28:52 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 14:28:52 INFO - PROCESS | 1670 | colorDepth:24 14:28:52 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 14:28:52 INFO - PROCESS | 1670 | __metrics 14:28:52 INFO - PROCESS | 1670 | 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" 14:28:52 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:28:52 INFO - TEST-INFO | 1670: exit 0 14:28:52 INFO - Browser initialized. 14:28:52 INFO - Running cycle 1/1 for kraken test... 14:28:52 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/T/tmpDgw9wc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:29:04 INFO - PROCESS | 1672 | RSS: Main: 239173632 14:29:04 INFO - PROCESS | 1672 | 14:29:07 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 14:29:08 INFO - PROCESS | 1672 | RSS: Main: 287039488 14:29:08 INFO - PROCESS | 1672 | 14:29:10 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 14:29:11 INFO - PROCESS | 1672 | RSS: Main: 295391232 14:29:11 INFO - PROCESS | 1672 | 14:29:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 14:29:15 INFO - PROCESS | 1672 | RSS: Main: 386523136 14:29:15 INFO - PROCESS | 1672 | 14:29:17 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 14:29:18 INFO - PROCESS | 1672 | RSS: Main: 286527488 14:29:18 INFO - PROCESS | 1672 | 14:29:20 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:21 INFO - PROCESS | 1672 | RSS: Main: 290316288 14:29:21 INFO - PROCESS | 1672 | 14:29:24 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:24 INFO - PROCESS | 1672 | RSS: Main: 327942144 14:29:24 INFO - PROCESS | 1672 | 14:29:27 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:28 INFO - PROCESS | 1672 | RSS: Main: 328724480 14:29:28 INFO - PROCESS | 1672 | 14:29:30 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 14:29:31 INFO - PROCESS | 1672 | RSS: Main: 326774784 14:29:31 INFO - PROCESS | 1672 | 14:29:33 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:33 INFO - PROCESS | 1672 | RSS: Main: 315158528 14:29:33 INFO - PROCESS | 1672 | 14:29:35 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:36 INFO - PROCESS | 1672 | RSS: Main: 322715648 14:29:36 INFO - PROCESS | 1672 | 14:29:38 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:39 INFO - PROCESS | 1672 | RSS: Main: 323903488 14:29:39 INFO - PROCESS | 1672 | 14:29:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:42 INFO - PROCESS | 1672 | RSS: Main: 328511488 14:29:42 INFO - PROCESS | 1672 | 14:29:45 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:46 INFO - PROCESS | 1672 | RSS: Main: 326545408 14:29:46 INFO - PROCESS | 1672 | 14:29:47 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 14:29:48 INFO - PROCESS | 1672 | RSS: Main: 373440512 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | __start_tp_report 14:29:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 14:29:48 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 14:29:48 INFO - PROCESS | 1672 | |i|pagename|runs| 14:29:48 INFO - PROCESS | 1672 | |0;ai-astar;112;106;111;113;112;112;110;111;112;100 14:29:48 INFO - PROCESS | 1672 | |1;audio-beat-detection;155;118;142;164;119;139;138;118;169;124 14:29:48 INFO - PROCESS | 1672 | |2;audio-dft;187;186;185;241;263;185;182;184;189;182 14:29:48 INFO - PROCESS | 1672 | |3;audio-fft;79;73;58;105;58;60;56;59;112;56 14:29:48 INFO - PROCESS | 1672 | |4;audio-oscillator;91;96;101;78;79;102;137;115;78;101 14:29:48 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;89;85;89;84;91;84;77;131;136;84 14:29:48 INFO - PROCESS | 1672 | |6;imaging-darkroom;93;95;94;94;94;94;94;94;93;93 14:29:48 INFO - PROCESS | 1672 | |7;imaging-desaturate;90;128;85;85;83;83;85;85;83;84 14:29:48 INFO - PROCESS | 1672 | |8;json-parse-financial;60;61;62;60;59;61;60;77;61;59 14:29:48 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;64;69;81;73;72;70;67;69;68;68 14:29:48 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;86;86;87;84;83;86;85;83;84;87 14:29:48 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;147;149;129;144;147;132;138;137;177;139 14:29:48 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;171;170;166;218;231;167;167;167;173;165 14:29:48 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;67;67;68;68;69;68;58;58;68;84 14:29:48 INFO - PROCESS | 1672 | __end_tp_report 14:29:48 INFO - PROCESS | 1672 | __start_cc_report 14:29:48 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,2022 14:29:48 INFO - PROCESS | 1672 | __end_cc_report 14:29:48 INFO - PROCESS | 1672 | __startTimestamp1462483788144__endTimestamp 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | ------- Summary: start ------- 14:29:48 INFO - PROCESS | 1672 | Number of tests: 14 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:109.90 Median:112.00 stddev:3.98 (3.6%) stddev-sans-first:4.15 14:29:48 INFO - PROCESS | 1672 | Values: 112.0 106.0 111.0 113.0 112.0 112.0 110.0 111.0 112.0 100.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:140.50 stddev:19.14 (13.6%) stddev-sans-first:19.36 14:29:48 INFO - PROCESS | 1672 | Values: 155.0 118.0 142.0 164.0 119.0 139.0 138.0 118.0 169.0 124.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:198.40 Median:186.50 stddev:28.80 (15.4%) stddev-sans-first:30.25 14:29:48 INFO - PROCESS | 1672 | Values: 187.0 186.0 185.0 241.0 263.0 185.0 182.0 184.0 189.0 182.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:71.60 Median:58.50 stddev:20.96 (35.8%) stddev-sans-first:22.06 14:29:48 INFO - PROCESS | 1672 | Values: 79.0 73.0 58.0 105.0 58.0 60.0 56.0 59.0 112.0 56.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:78.00 stddev:18.43 (23.6%) stddev-sans-first:19.38 14:29:48 INFO - PROCESS | 1672 | Values: 91.0 96.0 101.0 78.0 79.0 102.0 137.0 115.0 78.0 101.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:95.00 Median:84.50 stddev:20.69 (24.5%) stddev-sans-first:21.83 14:29:48 INFO - PROCESS | 1672 | Values: 89.0 85.0 89.0 84.0 91.0 84.0 77.0 131.0 136.0 84.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:93.80 Median:94.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 14:29:48 INFO - PROCESS | 1672 | Values: 93.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 93.0 93.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:89.10 Median:85.00 stddev:13.82 (16.3%) stddev-sans-first:14.65 14:29:48 INFO - PROCESS | 1672 | Values: 90.0 128.0 85.0 85.0 83.0 83.0 85.0 85.0 83.0 84.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:62.00 Median:61.00 stddev:5.35 (8.8%) stddev-sans-first:5.63 14:29:48 INFO - PROCESS | 1672 | Values: 60.0 61.0 62.0 60.0 59.0 61.0 60.0 77.0 61.0 59.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:69.50 stddev:4.58 (6.6%) stddev-sans-first:4.29 14:29:48 INFO - PROCESS | 1672 | Values: 64.0 69.0 81.0 73.0 72.0 70.0 67.0 69.0 68.0 68.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:86.00 stddev:1.52 (1.8%) stddev-sans-first:1.58 14:29:48 INFO - PROCESS | 1672 | Values: 86.0 86.0 87.0 84.0 83.0 86.0 85.0 83.0 84.0 87.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:143.90 Median:145.50 stddev:13.38 (9.2%) stddev-sans-first:14.14 14:29:48 INFO - PROCESS | 1672 | Values: 147.0 149.0 129.0 144.0 147.0 132.0 138.0 137.0 177.0 139.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.50 Median:170.50 stddev:24.04 (14.1%) stddev-sans-first:25.30 14:29:48 INFO - PROCESS | 1672 | Values: 171.0 170.0 166.0 218.0 231.0 167.0 167.0 167.0 173.0 165.0 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:7.12 (10.5%) stddev-sans-first:7.55 14:29:48 INFO - PROCESS | 1672 | Values: 67.0 67.0 68.0 68.0 69.0 68.0 58.0 58.0 68.0 84.0 14:29:48 INFO - PROCESS | 1672 | -------- Summary: end -------- 14:29:48 INFO - PROCESS | 1672 | 14:29:48 INFO - TEST-INFO | 1672: exit 0 14:29:48 INFO - TEST-OK | kraken | took 59065ms 14:29:48 INFO - SUITE-END | took 1174s 14:29:49 INFO - Completed test suite (00:19:34) 14:29:49 INFO - javascript benchmark 14:29:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2894.7828912831537, 3459.083342914128, 3471.6875481206685, 3492.9650703492957, 3519.612842587315, 5543.279373681426, 5585.162225666152, 5616.52259557937, 5674.489295963363, 5746.913796293054, 5625.268715067045, 5673.461021202979, 5719.885602287952, 5735.483806457418, 5770.249867517227, 5057.291979122926, 5087.923681144785, 5138.511841375063, 5155.0, 5249.1601343785005, 5186.0, 5244.868878278055, 5256.5794736421185, 5268.236105764662, 5274.868128296804, 5059.266406371074, 5125.743712814345, 5136.357955255593, 5144.794208231685, 5160.200168973815, 5077.111505486525, 5118.769655365518, 5132.794688212467, 5138.46046165151, 5139.15203991341, 5080.415752188472, 5121.769520371592, 5157.381114266274, 5162.535371816554, 5224.608154388436, 5507.394186639466, 5608.523275521545, 5627.943720562783, 5685.772569097231, 5690.7439165237665, 6184.6907654617535, 6212.968935155295, 6221.253449586034, 6227.19046523949, 6241.032639940817, 5063.240513922904, 5171.37943446785, 6268.937310626881, 6272.372762723737, 6280.905786413207, 5640.689762063126, 5686.630369026, 5729.312482502127, 5832.3584405715765, 5850.853728656775, 6282.18331616896, 6294.8111556653375, 6296.811095667137, 6305.243370795442, 6307.306196318401, 6141.815745527641, 6170.660613666201, 6174.197354343996, 6175.6912154393, 6183.721732521957, 6050.9394906050375, 6095.05526643371, 6165.722542485599, 6281.905771413432, 6292.150559674477, 6098.129681996294, 6146.170267013896, 6215.689215539295, 6224.408681175281, 6233.283172435137, 6186.288576813724, 6223.533235007392, 6244.812655620339, 6248.4688801452385, 6256.812295631138, 4814.20565606678, 4872.926906096412, 4885.487023862514, 4937.456879743226, 4969.130402179604, 4476.104779044204, 4508.301213311942, 4508.504064552896, 4517.2772356423475, 4525.705829121097, 5633.295838020247, 5641.633293835887, 5644.74598643062, 5649.576281778801, 5650.406707295757, 6118.3755344619085, 6184.567080304426, 6202.0, 6213.0, 6225.844353891189, 5616.747246373923, 5655.095184770417, 5663.32040155184, 5672.716364181857, 5677.403872593401, 5378.542823860016, 5390.622656414013, 5400.0, 5403.405625381205, 5403.864903377447, 6251.906221406683, 6259.155014073133, 6270.717817698214, 6309.432151106423, 6384.553081284265, 6282.717277722605, 6285.43431091204, 6289.119523266838, 6311.589746666452, 6313.715882785377, 6238.344973777869, 6363.840903977438, 6376.872462550816, 6391.744330226739, 6397.808065758035, 6088.056351265561, 6093.969530152321, 6116.244470650343, 6194.659293738888, 6199.16311297969, 5209.270702101785, 6254.061890716429, 6258.9374106260175, 6264.248290205022, 6268.968655156695, 5880.970595146997, 6023.5181185505935, 6086.2087928569, 6099.9390006099375, 6119.755209791559, 5989.790357337472, 6111.572189946839, 6121.296050954019, 6124.908126378197, 6141.355157708376, 5718.1136923776885, 5800.796972105956, 5889.882202356014, 5918.3489816121055, 5938.524918006636, 5816.563757718103, 6027.849303767265, 6083.300420451705, 6098.157183194213, 6121.816345509642, 5947.6431414115295, 5950.98833198366, 5974.1636170935235, 5991.191189189578, 5992.760289588367], "value": 5667.505097241367, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.66823896228476, 506.84034529123323, 510.9437961824199, 525.7634064670899, 527.6306585390226, 29239.707602923965, 42351.36472952908, 43665.78167109168, 43701.7814910925, 45574.0, 43861.7806910965, 44219.115617687705, 44263.55736442635, 44284.55715442845, 44415.99999999992, 8608.182222688834, 9673.95163024184, 9844.0, 9851.458169800659, 9868.259880508955, 8743.95628021859, 11529.769404611903, 11782.646520604396, 11799.646010619652, 11906.523739050428, 11794.823077653842, 11839.408029598486, 11846.407679615984, 11859.229150105259, 11861.703457413547, 8553.957230213871, 9230.353875228737, 11537.307761534335, 11775.940165385113, 11791.528338866436, 11671.12466565011, 11771.470283837249, 11774.587889423829, 11778.175527713063, 11784.175107742461, 40595.0, 40930.38604420936, 41022.9744256393, 41042.79478602618, 41044.0, 3195.6644552321904, 3218.1954511372155, 3224.1294850390263, 3266.934661306784, 3439.8796042138406, 29002.41995160085, 29799.808007679665, 29893.0, 30187.69812301871, 30488.254582058384, 2716.606092116652, 2751.3534319435116, 2751.463464624399, 2828.8302701837956, 2857.39994601134, 483800.0, 484936.0, 485423.1457685413, 486246.0, 486973.0, 35534.822325888206, 35883.64116358803, 48127.0, 60698.393016069276, 60806.0, 37171.62828371736, 37227.0, 37348.066298342215, 37368.87893363149, 37382.25235495275, 15184.0, 15305.923470382577, 15319.387224511118, 15342.846571534365, 15366.924315297822, 11491.77016459666, 11940.641780746591, 12693.746125077632, 12825.294608796607, 12889.291088990196, 8236.50580965144, 8253.0, 8270.586470676442, 8339.874901876477, 8347.165283471726, 3058.2660161561066, 3430.730629667039, 3448.827558622059, 3474.818561689038, 3475.800848707192, 992.6525715999343, 1021.6832781837654, 1071.2698991129425, 1084.6366467233408, 1099.0273607857105, 29700.257493562833, 29886.953956611847, 30014.24964375908, 30163.0, 30288.5456718145, 918.5958178401482, 1041.953112109957, 1090.9781804364027, 1096.276457538021, 1128.7065363005677, 12676.746465070646, 15168.848311516964, 15294.464693735667, 15606.0, 15614.0, 641656.0, 646107.0, 647432.0, 648100.0, 648431.0, 60363.698181508815, 60878.0, 60903.69548152231, 61012.77974440486, 61141.694291529144, 1342.060557609677, 1459.4308219794289, 1486.011802151557, 1492.2315007771224, 1508.1403599947923, 30631.693683063775, 30660.080197594107, 30772.076837694905, 31353.90261340842, 31488.370232595676, 50622.2406663893, 51023.0, 51038.97922041613, 51228.231576525635, 51286.97426051383, 495439.0, 495703.5214824047, 496004.1198764043, 496263.0, 496329.0, 23258.53482930366, 25717.742822571534, 26148.8692556536, 26468.0, 27755.0, 46295.07409851851, 46500.0, 46605.76697116628, 46768.29847552221, 46966.53033469622, 37985.62014379821, 38355.61644383632, 38409.807950960065, 38916.80541597274, 39152.608473914894, 37305.0, 37504.0, 37759.0, 38286.80856595699, 38599.0, 37271.25457490889, 37606.62393376031, 38471.422928655535, 38520.0, 38550.42174367443, 26559.0, 31072.0, 32363.51454728228, 33045.843395481046, 33916.49125263172, 21198.258060967793, 22089.0, 23382.309143254515, 24663.656952590194, 24824.131155409475], "value": 18856.88982903621, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.46624590556837, 400.27150585933606, 404.93116170251074, 407.0596921112229, 427.570291856684, 20134.59730805383, 20767.857767822763, 20858.340829437824, 21365.465863353424, 21447.463813404673, 20305.898470507665, 20309.593808123827, 20499.0, 21547.46131346717, 21552.461188470297, 5886.941130588692, 6114.571979961404, 6138.14066030756, 6145.324014358417, 6148.077788331763, 5862.530997520188, 6111.69441527924, 6130.295016073142, 6130.632162070267, 6182.938170618281, 5989.700514974234, 6084.300305464862, 6086.939130608682, 6086.939130608682, 6087.817365479043, 5764.53883689306, 6071.666058366788, 6075.696215189223, 6077.1491991121275, 6091.116788065706, 5278.947210527922, 5293.682379057269, 5296.523312901857, 5296.894062118775, 5302.443243459458, 25231.36921576957, 26076.86961565199, 26397.472050559074, 26403.47193056128, 26431.867840660867, 1909.4176276235742, 2172.7175467189163, 2178.7821217878245, 2368.396059004943, 2374.8693821839793, 10233.334833235891, 10391.220658450646, 10406.42764647944, 12106.728793476646, 12131.21147125434, 1682.5204816627324, 1712.3749831311482, 1714.2628669672035, 1744.0494930263037, 1802.4502526729343, 124329.18872707078, 128902.0, 129336.99999999905, 130050.3497482516, 131547.0, 9304.90695093054, 11221.913973657309, 11714.589989350332, 11843.46704398304, 11942.522299107939, 15182.164980925934, 15289.311980960883, 15291.694166116838, 15313.234338283264, 15314.923425382802, 8663.610137543825, 8740.388172827968, 8748.78128046804, 8760.91239087601, 8895.110488951183, 8677.305815534743, 8681.52251626154, 8687.782805429915, 8778.34162437807, 8823.779405514784, 4633.86098417048, 4646.535346465326, 4663.696859704109, 4667.229907065296, 4749.548792864673, 1929.604431091623, 1962.960740785205, 1966.7049942508738, 1969.0, 1969.527313444763, 919.4483310013939, 932.8898610653301, 995.3579940938055, 1010.545254635417, 1016.5527168046184, 16763.826532142877, 20007.799922000886, 20054.398368048984, 20116.59766804685, 20416.591668166555, 777.249953794591, 930.3348106104168, 957.305953183936, 959.8896126945351, 983.9508024598741, 10690.037896589189, 10835.83746243807, 10842.945785271024, 10887.401192934307, 10889.782204355868, 596065.0196748988, 599007.0, 599206.0, 614374.0, 615201.0, 132121.357572848, 137782.3110884439, 138048.61951380558, 141328.5867141336, 141753.65970722318, 1019.169376957777, 1020.6019652335596, 1025.436010194405, 1026.1226651213256, 1036.18659352408, 17908.55228619295, 18227.0, 18267.995260260814, 18411.53971150732, 18530.536736581693, 51011.48988510216, 51472.713182170744, 52300.95398091941, 52369.738151309, 52639.0, 71859.92210116958, 75333.0, 76509.0, 76862.61568692121, 77081.61459192893, 7882.763517094496, 8004.0, 8025.35797136216, 8435.0, 8439.071702112886, 9150.633974641207, 12088.939555302168, 12505.812412813633, 12942.964562835139, 12945.964322854337, 10744.247902646739, 10962.671119866416, 10975.176861735243, 10985.285956412808, 11189.664310070712, 10559.260851740612, 10749.838752418877, 10772.0, 11099.833502497631, 11165.441727913734, 10998.670039898816, 11157.163212758913, 11217.0, 11305.321680698855, 11397.0, 8098.473599216032, 8316.542590157358, 8348.833023339377, 8349.123342048997, 8388.874166887623, 10360.0, 11038.17213708959, 11313.886861131283, 11348.0, 11349.602763903224], "value": 9672.760601780357, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1437.1736251655288, 1603.1022627328693, 1641.7373220284753, 1874.222197787918, 1994.1624517702573, 7902.0, 8699.260562852158, 8962.37263391563, 8962.417442866226, 9083.137101975319, 8166.428350015502, 8873.778155546099, 8878.289736821054, 8881.866771998426, 8886.688965886195, 2167.8916054197307, 2402.459446624513, 2445.1808644104203, 2485.689288838895, 2490.0, 2492.71333796613, 2530.936726581841, 2532.063136639446, 2532.898684052636, 2534.214393538, 2437.597796363598, 2500.624906264057, 2501.4246723253664, 2501.7998560115147, 2501.874906254689, 2134.5410736691606, 2378.2746262389965, 2431.6109422492405, 2450.2281781238853, 2455.4107014316437, 2473.5918573435542, 2477.504499100169, 2504.5241404133158, 2507.3731567108225, 2509.2346834215555, 7672.501287416356, 8686.131386861263, 8902.821943561157, 8910.153535414125, 8923.955380223122, 5339.385970613352, 5507.586930980193, 5510.173473978895, 5510.228568000483, 5513.696746678931, 3545.450455179451, 3549.538559987211, 3592.4072528032843, 3663.725220608465, 3669.3395188865993, 1036.2021243642428, 1045.9633912813092, 1046.1892033673887, 1048.1509976918644, 1048.5386429970788, 9811.558479868423, 11847.940760296317, 11850.229735067192, 11868.05055595559, 11882.58410955621, 4848.345473361122, 4874.902501950012, 4982.103221420107, 4983.700977941335, 4995.225740010304, 10180.338278011906, 10187.694369168938, 10198.592056317813, 10218.948905255575, 10221.233407494468, 5470.452954704498, 5500.532454741312, 5504.80733174345, 5514.558835293167, 5515.227868098469, 4284.228838808982, 4458.5987261146, 4602.355670206174, 4622.422197225347, 4635.0034742530515, 3438.570178727659, 4041.312976793893, 4098.364753463218, 4161.292580261363, 4168.145530166309, 1117.6311817100338, 1315.2963164706837, 1346.259557243512, 1360.7346567419456, 1361.9230673223215, 862.508370228976, 903.5888670654837, 931.65063101337, 941.1012483078644, 965.6909788867495, 5860.824175274749, 6036.913355595947, 6059.666718330446, 6094.8171554853425, 6159.692015399212, 459.9862004139881, 483.8669365924399, 947.0434860790514, 961.8893827209821, 982.6315131825565, 3822.961770382316, 3922.8038598069984, 4044.3933409988736, 4061.086255592468, 4129.772862492592, 10109.797804043877, 10966.945165274123, 11147.832782508269, 11152.330860148417, 11159.553617855358, 9190.954045229731, 11087.22389432748, 11098.05666518355, 11112.777744445066, 11115.944420277847, 8177.018757749108, 8353.791155221168, 8439.396514662194, 8497.915020849836, 8550.957245213858, 5873.559483038704, 5952.821415357546, 6015.729292181775, 6033.336333003454, 6062.151298818258, 10073.597056117673, 10248.333858299187, 10319.329243599143, 10327.48362581883, 10330.328528645614, 10128.898711013091, 10152.137068349273, 10403.427811470441, 10457.111145552713, 10509.054185123376, 6659.500537459807, 6847.144106986627, 6857.794266172023, 6928.861422771617, 7069.187043489859, 6544.181977252843, 8052.355811535181, 8076.030876294647, 8575.013873404389, 8706.47761134334, 7443.478956473212, 7576.356009739234, 7604.695812167452, 7734.458587899018, 7787.766367008999, 6217.316095229611, 7461.141968673672, 7528.962355188189, 7677.232276772278, 7683.193264707125, 6059.848503787441, 6985.860282794417, 7083.7520686775715, 7172.031775710421, 7190.928090719026, 4935.284383764406, 5039.470855560114, 5057.6459647824295, 5073.59411247092, 5132.820351287687, 5010.298558201782, 5051.646384753032, 5127.974360128175, 5177.792888284577, 5328.17413300939], "value": 4627.530991415892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.2171284936262, 319.1956270199098, 322.79663811798565, 325.2064961493953, 330.0395848082082, 5341.492558206969, 5356.946430535693, 5385.3268341457315, 5386.680263335478, 5396.946030539693, 4745.928811067837, 5061.507559783747, 5335.132703553764, 5371.57027437805, 5435.728213589325, 3586.686587151286, 3830.291396091718, 3895.266606360171, 4006.338954072589, 4098.6516146127615, 3993.7404068735586, 4017.9352471594902, 4059.7724808776948, 4090.0, 4125.525564560069, 3371.0, 3807.200487897531, 3941.940870886939, 4053.5946405359377, 4068.8575899843363, 3235.5470234167, 3380.3915295246848, 3860.841747475746, 3866.76799392036, 4081.7142800004012, 2785.4846853332197, 3213.5822343095247, 3364.96635033649, 3548.840302186408, 3566.928661426757, 4505.062822986115, 5129.050960634935, 5133.614978876599, 5159.896802063975, 5203.0, 3781.827633433644, 4085.222928026318, 4371.912561748747, 4594.9310760338285, 4663.27719203524, 4837.9032419351415, 5508.807191748309, 5540.778368865241, 5564.582656300753, 5571.721413929287, 3360.5463262459743, 3381.0871064812363, 3752.9812350938346, 3920.9607903920883, 3924.2936271471126, 4860.441049279308, 5506.191842160527, 5561.916571251434, 5840.678762668052, 5879.882402351929, 3115.4453348254665, 3479.913002174966, 3891.2606604745574, 3891.8637847675195, 3892.863749768801, 3542.9902477793703, 3782.4893639358884, 3871.264459752638, 3951.320688707288, 3953.3872249801325, 4532.844124748168, 4577.656675749332, 4601.332806743004, 4620.5148459411275, 4622.791974361162, 4589.770511474413, 5036.985205917589, 5233.476652334736, 5477.67133971963, 5610.915836262459, 1087.5535537734793, 1187.329159025148, 1261.7382617382618, 1265.2724683307135, 1271.0086132816418, 754.4907187648315, 761.6708843068881, 862.3058437957379, 878.6002368922125, 882.8852249207562, 4457.058341494663, 4541.704789188692, 4629.675922685447, 4846.933674591584, 5186.4035635902355, 5226.111561034557, 5521.834344969658, 5788.797392091256, 5818.828738337616, 5875.148103524965, 4714.929276060861, 5059.696418214919, 5245.213218017251, 5315.813946511854, 5335.67985920846, 2465.59317712579, 2533.4173140177863, 2711.91864244073, 2888.581155732407, 2989.9850500747793, 4758.97681998372, 5540.972295138498, 5754.827355179351, 5759.510441612426, 5786.594938354275, 4608.354830323758, 5230.607704422184, 5344.9732751336, 5717.771289148387, 5737.225474560881, 5150.665206761549, 5743.9138412923, 5747.683877386784, 5765.019946609086, 5783.681897495678, 4954.677945933503, 5449.373322068013, 5506.64206826555, 5545.781687325102, 5552.222688823487, 5066.189409694432, 5155.742212889416, 5287.312649355559, 5800.419958004166, 5816.563757718272, 5335.759890805, 5984.850378740392, 6065.545084118797, 6077.6353418795015, 6089.269287685506, 3421.0, 3818.885433437001, 4049.5950404959267, 4070.4911886014247, 4085.8569950051606, 5299.867503312448, 5756.539476841926, 5790.305163380422, 5798.304203495608, 5798.623089499169, 4697.788599512962, 5273.6572122811895, 5592.636478628876, 5600.27196464457, 5640.746166422438, 5067.974660126676, 5272.340957380327, 5556.388797232382, 5606.803761868478, 5623.522000629829, 4252.596003379674, 4588.678792484628, 5069.797208111634, 5477.863053423696, 5519.862003449946, 5025.321581586585, 5200.45395233495, 5403.702796346239, 5526.778928842801, 5541.251930989265, 4670.5521288400705, 5540.097014142432, 5549.500544950974, 5571.637843540157, 5573.0], "value": 4010.1276597215906, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [546.8714852009776, 622.747787146206, 726.7093162734902, 728.5337384074197, 731.6488085718856, 14685.853141468582, 15129.697406051886, 15318.387264509407, 15353.539393818175, 15473.535793926187, 11562.306261624288, 14172.858271417283, 14198.361073751706, 14209.07641003334, 14319.212443315613, 714.2607401339615, 872.4024043530202, 897.0760117079402, 908.0420156734642, 911.9694744938244, 893.8703887936246, 906.1753804038326, 908.1780988205685, 909.6725178935578, 910.7632015675906, 977.384247923807, 977.4868194198067, 979.8089372572351, 980.2892902645597, 980.3333733061515, 748.2069006852727, 935.3919952031166, 972.3485264872481, 978.1392374710281, 979.0992287095818, 723.6345645449068, 724.6847621284758, 724.9093863267092, 724.971001159953, 727.0475676863326, 14440.0, 14492.34784434703, 14521.564353069425, 14522.346494407777, 14524.709505809824, 10201.387916724945, 12398.938005310007, 12604.054695897752, 12617.432215550321, 12833.037522185874, 8840.602172902234, 10757.246992710514, 10939.627555542327, 10952.835707464394, 11374.090072794157, 4056.290149223874, 4059.472268605092, 4061.9796901015307, 4131.958680413188, 4200.768957707325, 80096.0, 80372.59813700894, 83201.58399208025, 83442.3311533772, 84583.0, 9500.144986951207, 9541.809163816684, 9718.562664679965, 9851.901480985098, 10060.295779295526, 21765.891170544044, 21868.89065554662, 21869.343919682437, 21881.234156804436, 21896.89051554732, 7416.184219735825, 7421.146568144624, 7489.85020299591, 7594.354479869273, 7659.004329437137, 4005.939910901339, 4052.8176232069627, 4055.9594404055583, 4059.167870586523, 4069.1251380953236, 3456.6024907135497, 3554.804485753257, 3558.6085530591613, 3558.62634423387, 3565.8751943681846, 627.3193584960278, 639.5011890725242, 646.444058110025, 649.357136434926, 654.2998991079594, 539.816462402785, 541.1422894711926, 569.7066011004337, 572.0703856233621, 574.3882764855416, 7675.731349402744, 7756.806079848049, 7918.129005809356, 8067.556284404297, 8296.626651800683, 570.2785975740692, 593.0955293177852, 605.9303180134253, 621.7544070092379, 655.3741177175785, 3401.725714740176, 3892.5328960524917, 3920.5099362579676, 3957.3470377387407, 3970.4441378207075, 66181.0, 81686.24257472673, 86096.56951715202, 87119.56440217885, 87340.0, 47763.50719667283, 67606.9858952116, 74301.25698743052, 75925.48149036989, 76821.46357072827, 66077.33922660808, 66260.66869665621, 66293.0, 67195.0, 68093.65953170203, 7690.692372305269, 7741.380689544935, 7838.80402989907, 7916.564588947663, 7922.207779222263, 31933.0, 41940.580594194886, 41978.79010604927, 42958.14083718371, 43079.7846010768, 57405.71297143488, 58589.0, 58637.0, 58740.0, 58831.70584147052, 10074.798504029732, 10244.64143754965, 10255.58977640886, 10410.323328983592, 10667.839982400425, 11032.669019929415, 11319.377434241196, 11331.263467874562, 11450.541978320774, 11514.94242528782, 10567.0, 10656.25406221557, 10660.200484963503, 10774.946125269324, 10966.561337546409, 10676.786464270826, 10744.623938162127, 10746.946265268623, 10837.443326880017, 11171.888281117084, 9947.502624868872, 10002.0, 10165.18678505703, 10257.28199026061, 10350.378977261389, 8857.955710221408, 8920.821583568162, 9022.774430639287, 9212.953935230282, 9569.665061722806, 8956.776080597776, 9026.368154229142, 9120.315976301672, 9137.817243655223, 9196.908030919605], "value": 6698.906154696349, "unit": "score"}], "name": "dromaeo_css", "value": 7103.866007282214, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 113.0, 112.0, 112.0, 110.0, 111.0, 112.0, 100.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 118.0, 142.0, 164.0, 119.0, 139.0, 138.0, 118.0, 169.0, 124.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 185.0, 241.0, 263.0, 185.0, 182.0, 184.0, 189.0, 182.0], "value": 198.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 105.0, 58.0, 60.0, 56.0, 59.0, 112.0, 56.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 96.0, 101.0, 78.0, 79.0, 102.0, 137.0, 115.0, 78.0, 101.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 84.0, 91.0, 84.0, 77.0, 131.0, 136.0, 84.0], "value": 95.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0, 93.0, 93.0], "value": 93.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 128.0, 85.0, 85.0, 83.0, 83.0, 85.0, 85.0, 83.0, 84.0], "value": 89.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 61.0, 62.0, 60.0, 59.0, 61.0, 60.0, 77.0, 61.0, 59.0], "value": 62.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 81.0, 73.0, 72.0, 70.0, 67.0, 69.0, 68.0, 68.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 84.0, 83.0, 86.0, 85.0, 83.0, 84.0, 87.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 149.0, 129.0, 144.0, 147.0, 132.0, 138.0, 137.0, 177.0, 139.0], "value": 143.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 170.0, 166.0, 218.0, 231.0, 167.0, 167.0, 167.0, 173.0, 165.0], "value": 179.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 68.0, 68.0, 69.0, 68.0, 58.0, 58.0, 68.0, 84.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1502.3, "alertThreshold": 2.0}]} 14:29:49 INFO - Return code: 0 14:29:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:29:49 ERROR - # TBPL SUCCESS # 14:29:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:29:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:29:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:29:49 INFO - 2016-05-05 14:29:49.452 screenresolution[1676:9770] starting screenresolution argv=screenresolution get 14:29:49 INFO - 2016-05-05 14:29:49.454 screenresolution[1676:9770] Display 0: 1600x1200x32@60 14:29:49 INFO - 2016-05-05 14:29:49.460 screenresolution[1677:9773] starting screenresolution argv=screenresolution list 14:29:49 INFO - Available Modes on Display 0 14:29:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:29:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:29:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:29:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:29:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:29:49 INFO - Intel Iris: 14:29:49 INFO - Chipset Model: Intel Iris 14:29:49 INFO - Type: GPU 14:29:49 INFO - Bus: Built-In 14:29:49 INFO - VRAM (Dynamic, Max): 1536 MB 14:29:49 INFO - Vendor: Intel (0x8086) 14:29:49 INFO - Device ID: 0x0a2e 14:29:49 INFO - Revision ID: 0x0009 14:29:49 INFO - Displays: 14:29:49 INFO - Display: 14:29:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:29:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:29:49 INFO - Display Serial Number: 1600x1200 60 14:29:49 INFO - Main Display: Yes 14:29:49 INFO - Mirror: Off 14:29:49 INFO - Online: Yes 14:29:49 INFO - Rotation: Supported 14:29:49 INFO - Return code: 0 14:29:49 INFO - Running post-action listener: _resource_record_post_action 14:29:49 INFO - Running post-run listener: _resource_record_post_run 14:29:50 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37893632; Write bytes: 698288640; Read time: 495; Write time: 2048 14:29:50 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 375872000; Write bytes: 370690048; Read time: 28252; Write time: 762 14:29:50 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 36939264; Write bytes: 298484224; Read time: 480; Write time: 1231 14:29:50 INFO - Running post-run listener: _upload_blobber_files 14:29:50 INFO - Blob upload gear active. 14:29:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:29:50 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 14:29:50 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:29:50 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:29:51 INFO - (blobuploader) - INFO - Open directory for files ... 14:29:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:29:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:29:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:52 INFO - (blobuploader) - INFO - Done attempting. 14:29:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:29:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:29:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:53 INFO - (blobuploader) - INFO - Done attempting. 14:29:53 INFO - (blobuploader) - INFO - Iteration through files over. 14:29:53 INFO - Return code: 0 14:29:53 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:29:53 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:29:53 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7542e02889db8b406678c107f569d8e103fb4dcc5815c8ae5c6cc3b95709aa734efe009214c8b311e07f479ed18ce62906582e5dad89b15a36c9f4817b2cebc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a57497763f3669151bd4559bdecfba4f5b784610337880bfc600c9d1c613f6b42e8aebcb855fcaa03dfddf300d941262f023cc62c85957f162fa843fe25f59f"} 14:29:53 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:29:53 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:29:53 INFO - Contents: 14:29:53 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7542e02889db8b406678c107f569d8e103fb4dcc5815c8ae5c6cc3b95709aa734efe009214c8b311e07f479ed18ce62906582e5dad89b15a36c9f4817b2cebc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a57497763f3669151bd4559bdecfba4f5b784610337880bfc600c9d1c613f6b42e8aebcb855fcaa03dfddf300d941262f023cc62c85957f162fa843fe25f59f"} 14:29:53 INFO - Running post-run listener: copy_logs_to_upload_dir 14:29:53 INFO - Copying logs to upload dir... 14:29:53 INFO - mkdir: /builds/slave/test/build/upload/logs 14:29:53 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.187059 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-05-05 14:29:53.160954) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 14:29:53.164619) ========= 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.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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/Firefox/sha512/7542e02889db8b406678c107f569d8e103fb4dcc5815c8ae5c6cc3b95709aa734efe009214c8b311e07f479ed18ce62906582e5dad89b15a36c9f4817b2cebc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a57497763f3669151bd4559bdecfba4f5b784610337880bfc600c9d1c613f6b42e8aebcb855fcaa03dfddf300d941262f023cc62c85957f162fa843fe25f59f"} build_url:https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011200 build_url: 'https://queue.taskcluster.net/v1/task/Ei5HkMBXRSOoKq38AUjG_Q/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7542e02889db8b406678c107f569d8e103fb4dcc5815c8ae5c6cc3b95709aa734efe009214c8b311e07f479ed18ce62906582e5dad89b15a36c9f4817b2cebc1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a57497763f3669151bd4559bdecfba4f5b784610337880bfc600c9d1c613f6b42e8aebcb855fcaa03dfddf300d941262f023cc62c85957f162fa843fe25f59f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-05 14:29:53.192307) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:29:53.192620) ========= 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.gefpqcb5qT/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.2ioWhQdyWc/Listeners TMPDIR=/var/folders/vl/13zsdy5s2c70rd9lzswz9gd000000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-05 14:29:53.241136) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 14:29:53.241456) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-05 14:29:53.241758) ========= ========= Total master_lag: 0.16 =========