builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0152 starttime: 1464708345.25 results: success (0) buildid: 20160531072841 builduid: af81619562df45009ddadfc41de0f686 revision: 34b32f5cb229db6eafbdaa653d079be1a6ca007e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.252812) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.253282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.253596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.273891) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.274257) ========= 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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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-31 08:25:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.27M=0.001s 2016-05-31 08:25:45 (8.27 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.080802 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.374725) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.375051) ========= 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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.020763 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:45.423174) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 08:25:45.423558) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 34b32f5cb229db6eafbdaa653d079be1a6ca007e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 34b32f5cb229db6eafbdaa653d079be1a6ca007e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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-31 08:25:45,499 truncating revision to first 12 chars 2016-05-31 08:25:45,499 Setting DEBUG logging. 2016-05-31 08:25:45,499 attempt 1/10 2016-05-31 08:25:45,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/34b32f5cb229?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 08:25:46,636 unpacking tar archive at: mozilla-aurora-34b32f5cb229/testing/mozharness/ program finished with exit code 0 elapsedTime=1.660426 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 08:25:47.104337) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:47.104687) ========= script_repo_revision: 34b32f5cb229db6eafbdaa653d079be1a6ca007e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:47.105100) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:47.105376) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 08:25:47.121785) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-31 08:25:47.122165) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:25:47 INFO - MultiFileLogger online at 20160531 08:25:47 in /builds/slave/test 08:25:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:25:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:25:47 INFO - {'append_to_log': False, 08:25:47 INFO - 'base_work_dir': '/builds/slave/test', 08:25:47 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:25:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:25:47 INFO - 'branch': 'Mozilla-Aurora', 08:25:47 INFO - 'buildbot_json_path': 'buildprops.json', 08:25:47 INFO - 'config_files': ('talos/mac_config.py',), 08:25:47 INFO - 'default_actions': ('clobber', 08:25:47 INFO - 'read-buildbot-config', 08:25:47 INFO - 'download-and-extract', 08:25:47 INFO - 'populate-webroot', 08:25:47 INFO - 'create-virtualenv', 08:25:47 INFO - 'install', 08:25:47 INFO - 'run-tests'), 08:25:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:25:47 INFO - 'download_minidump_stackwalk': True, 08:25:47 INFO - 'download_symbols': 'ondemand', 08:25:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:25:47 INFO - 'tooltool.py': '/tools/tooltool.py', 08:25:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:25:47 INFO - '/tools/misc-python/virtualenv.py')}, 08:25:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:25:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:25:47 INFO - 'installer_path': 'installer.exe', 08:25:47 INFO - 'log_level': 'info', 08:25:47 INFO - 'log_name': 'talos', 08:25:47 INFO - 'log_to_console': True, 08:25:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:25:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:25:47 INFO - 'opt_config_files': (), 08:25:47 INFO - 'pip_index': False, 08:25:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:47 INFO - 'cmd': ('bash', 08:25:47 INFO - '-c', 08:25:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:47 INFO - 'enabled': True, 08:25:47 INFO - 'halt_on_failure': False, 08:25:47 INFO - 'name': 'check_screen_resolution'},), 08:25:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:25:47 INFO - 'cmd': ('bash', 08:25:47 INFO - '-c', 08:25:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:25:47 INFO - 'enabled': True, 08:25:47 INFO - 'halt_on_failure': False, 08:25:47 INFO - 'name': 'check_screen_resolution'},), 08:25:47 INFO - 'run_cmd_checks_enabled': True, 08:25:47 INFO - 'sps_profile': False, 08:25:47 INFO - 'sps_profile_interval': 0, 08:25:47 INFO - 'suite': 'dromaeojs', 08:25:47 INFO - 'system_bits': '32', 08:25:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:25:47 INFO - 'title': 't-yosemite-r7-0152', 08:25:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:25:47 INFO - 'use_talos_json': True, 08:25:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:25:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:25:47 INFO - 'work_dir': 'build'} 08:25:47 INFO - ##### 08:25:47 INFO - ##### Running clobber step. 08:25:47 INFO - ##### 08:25:47 INFO - Running pre-action listener: _resource_record_pre_action 08:25:47 INFO - Running main action method: clobber 08:25:47 INFO - rmtree: /builds/slave/test/build 08:25:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:25:48 INFO - Running post-action listener: _resource_record_post_action 08:25:48 INFO - ##### 08:25:48 INFO - ##### Running read-buildbot-config step. 08:25:48 INFO - ##### 08:25:48 INFO - Running pre-action listener: _resource_record_pre_action 08:25:48 INFO - Running main action method: read_buildbot_config 08:25:48 INFO - Using buildbot properties: 08:25:48 INFO - { 08:25:48 INFO - "project": "", 08:25:48 INFO - "product": "firefox", 08:25:48 INFO - "script_repo_revision": "production", 08:25:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:25:48 INFO - "repository": "", 08:25:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:25:48 INFO - "buildid": "20160531072841", 08:25:48 INFO - "pgo_build": "False", 08:25:48 INFO - "basedir": "/builds/slave/test", 08:25:48 INFO - "buildnumber": 26, 08:25:48 INFO - "slavename": "t-yosemite-r7-0152", 08:25:48 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 08:25:48 INFO - "platform": "yosemite_r7", 08:25:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:25:48 INFO - "branch": "mozilla-aurora", 08:25:48 INFO - "repo_path": "releases/mozilla-aurora", 08:25:48 INFO - "revision": "34b32f5cb229db6eafbdaa653d079be1a6ca007e", 08:25:48 INFO - "stage_platform": "macosx64", 08:25:48 INFO - "builduid": "af81619562df45009ddadfc41de0f686", 08:25:48 INFO - "slavebuilddir": "test" 08:25:48 INFO - } 08:25:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 08:25:48 INFO - Running post-action listener: _resource_record_post_action 08:25:48 INFO - ##### 08:25:48 INFO - ##### Running download-and-extract step. 08:25:48 INFO - ##### 08:25:48 INFO - Running pre-action listener: _resource_record_pre_action 08:25:48 INFO - Running main action method: download_and_extract 08:25:48 INFO - mkdir: /builds/slave/test/build/tests 08:25: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')]} 08:25:48 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 08:25:48 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:25:48 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 08:25:48 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:25:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 08:25:49 INFO - Downloaded 1199 bytes. 08:25:49 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 08:25:49 INFO - Using the following test package requirements: 08:25:49 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:25:49 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 08:25:49 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 08:25:49 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'jsshell-mac.zip'], 08:25:49 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 08:25:49 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 08:25:49 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 08:25:49 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 08:25:49 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 08:25:49 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 08:25:49 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 08:25:49 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:25: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')]} 08:25:49 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:25:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:25:50 INFO - Downloaded 18463813 bytes. 08:25:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:25:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:25:53 INFO - Return code: 0 08:25:53 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:25: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')]} 08:25:53 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 08:25:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 08:25:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:25:57 INFO - Downloaded 18463813 bytes. 08:25:57 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:25:57 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 08:26:00 INFO - Return code: 0 08:26:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:00 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 08:26:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:26:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:26:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 08:26:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:26:02 INFO - Downloaded 10840145 bytes. 08:26:02 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 08:26:02 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 08:26:02 INFO - Return code: 0 08:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:02 INFO - https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 08:26:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 08:26:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 08:26:05 INFO - Downloaded 114156846 bytes. 08:26:05 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:05 INFO - mkdir: /builds/slave/test/properties 08:26:05 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:26:05 INFO - Writing to file /builds/slave/test/properties/build_url 08:26:05 INFO - Contents: 08:26:05 INFO - build_url:https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 08:26:06 INFO - Running post-action listener: _resource_record_post_action 08:26:06 INFO - Running post-action listener: set_extra_try_arguments 08:26:06 INFO - ##### 08:26:06 INFO - ##### Running populate-webroot step. 08:26:06 INFO - ##### 08:26:06 INFO - Running pre-action listener: _resource_record_pre_action 08:26:06 INFO - Running main action method: populate_webroot 08:26:06 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:26:06 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:26:06 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:26:06 INFO - '--tppagecycles', 08:26:06 INFO - '7'], 08:26:06 INFO - 'tests': ['tsvgm']}}, 08:26:06 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:26:06 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:26:06 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:26:06 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:26:06 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:06 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:26:06 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['damp', 'tps']}, 08:26:06 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'tests': ['damp', 'tps']}, 08:26:06 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['dromaeo_dom']}, 08:26:06 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:26:06 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'other-e10s': {'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['a11yr', 08:26:06 INFO - 'ts_paint', 08:26:06 INFO - 'tpaint', 08:26:06 INFO - 'sessionrestore', 08:26:06 INFO - 'sessionrestore_no_auto_restore', 08:26:06 INFO - 'tabpaint']}, 08:26:06 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['tsvgx', 08:26:06 INFO - 'tsvgr_opacity', 08:26:06 INFO - 'tart', 08:26:06 INFO - 'tscrollx', 08:26:06 INFO - 'cart']}, 08:26:06 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:26:06 INFO - 'tsvgr_opacity', 08:26:06 INFO - 'tart', 08:26:06 INFO - 'tscrollx', 08:26:06 INFO - 'cart']}, 08:26:06 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'talos_options': ['--disable-e10s'], 08:26:06 INFO - 'tests': ['tp5o']}, 08:26:06 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'tests': ['tp5o']}, 08:26:06 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'talos_options': ['--disable-e10s', 08:26:06 INFO - '--xperf_path', 08:26:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:06 INFO - 'tests': ['tp5n']}, 08:26:06 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:26:06 INFO - 'talos_options': ['--xperf_path', 08:26:06 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:26:06 INFO - 'tests': ['tp5n']}}, 08:26:06 INFO - 'talos.zip': {'path': '', 08:26:06 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:26:06 INFO - Running post-action listener: _resource_record_post_action 08:26:06 INFO - ##### 08:26:06 INFO - ##### Running create-virtualenv step. 08:26:06 INFO - ##### 08:26:06 INFO - Running pre-action listener: _resource_record_pre_action 08:26:06 INFO - Running main action method: create_virtualenv 08:26:06 INFO - Creating virtualenv /builds/slave/test/build/venv 08:26:06 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 08:26:06 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:26:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:26:07 INFO - Using real prefix '/tools/python27' 08:26:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:26:07 INFO - Installing distribute.............................................................................................................................................................................................done. 08:26:10 INFO - Installing pip.................done. 08:26:10 INFO - Return code: 0 08:26:10 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:26:10 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')]} 08:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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')]} 08:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:10 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 08:26:10 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 08:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:10 INFO - 'HOME': '/Users/cltbld', 08:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:10 INFO - 'LOGNAME': 'cltbld', 08:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:10 INFO - 'MOZ_NO_REMOTE': '1', 08:26:10 INFO - 'NO_EM_RESTART': '1', 08:26:10 INFO - 'PAGER': '/bin/cat', 08:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:10 INFO - 'PWD': '/builds/slave/test', 08:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:10 INFO - 'SHELL': '/bin/bash', 08:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:10 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:10 INFO - 'USER': 'cltbld', 08:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:10 INFO - 'XPC_FLAGS': '0x0', 08:26:10 INFO - 'XPC_SERVICE_NAME': '0', 08:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:10 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 08:26:10 INFO - Cleaning up... 08:26:10 INFO - Return code: 0 08:26:10 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 08:26:10 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')]} 08:26:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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')]} 08:26:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:10 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:10 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 08:26:10 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 08:26:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:10 INFO - 'HOME': '/Users/cltbld', 08:26:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:10 INFO - 'LOGNAME': 'cltbld', 08:26:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:10 INFO - 'MOZ_NO_REMOTE': '1', 08:26:10 INFO - 'NO_EM_RESTART': '1', 08:26:10 INFO - 'PAGER': '/bin/cat', 08:26:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:10 INFO - 'PWD': '/builds/slave/test', 08:26:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:10 INFO - 'SHELL': '/bin/bash', 08:26:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:10 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:10 INFO - 'USER': 'cltbld', 08:26:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:10 INFO - 'XPC_FLAGS': '0x0', 08:26:10 INFO - 'XPC_SERVICE_NAME': '0', 08:26:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:11 INFO - Downloading/unpacking psutil>=0.7.1 08:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:26:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:14 INFO - Installing collected packages: psutil 08:26:14 INFO - Running setup.py install for psutil 08:26:14 INFO - building 'psutil._psutil_osx' extension 08:26:14 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 08:26:14 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 08:26:14 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 08:26:14 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 08:26:14 INFO - building 'psutil._psutil_posix' extension 08:26:14 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 08:26:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:26:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:26:14 INFO - ^ 08:26:14 INFO - 1 warning generated. 08:26:14 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 08:26:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:26:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:26:15 INFO - Successfully installed psutil 08:26:15 INFO - Cleaning up... 08:26:15 INFO - Return code: 0 08:26:15 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 08:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 08:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 08:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:15 INFO - 'HOME': '/Users/cltbld', 08:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:15 INFO - 'LOGNAME': 'cltbld', 08:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:15 INFO - 'MOZ_NO_REMOTE': '1', 08:26:15 INFO - 'NO_EM_RESTART': '1', 08:26:15 INFO - 'PAGER': '/bin/cat', 08:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:15 INFO - 'PWD': '/builds/slave/test', 08:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:15 INFO - 'SHELL': '/bin/bash', 08:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:15 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:15 INFO - 'USER': 'cltbld', 08:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:15 INFO - 'XPC_FLAGS': '0x0', 08:26:15 INFO - 'XPC_SERVICE_NAME': '0', 08:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:15 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 08:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:17 INFO - Downloading mozsystemmonitor-0.0.tar.gz 08:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:26:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 08:26:17 INFO - Installing collected packages: mozsystemmonitor 08:26:17 INFO - Running setup.py install for mozsystemmonitor 08:26:18 INFO - Successfully installed mozsystemmonitor 08:26:18 INFO - Cleaning up... 08:26:18 INFO - Return code: 0 08:26:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:18 INFO - 'HOME': '/Users/cltbld', 08:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:18 INFO - 'LOGNAME': 'cltbld', 08:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:18 INFO - 'MOZ_NO_REMOTE': '1', 08:26:18 INFO - 'NO_EM_RESTART': '1', 08:26:18 INFO - 'PAGER': '/bin/cat', 08:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:18 INFO - 'PWD': '/builds/slave/test', 08:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:18 INFO - 'SHELL': '/bin/bash', 08:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:18 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:18 INFO - 'USER': 'cltbld', 08:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:18 INFO - 'XPC_FLAGS': '0x0', 08:26:18 INFO - 'XPC_SERVICE_NAME': '0', 08:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:18 INFO - Downloading/unpacking blobuploader==1.2.4 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26:20 INFO - Downloading blobuploader-1.2.4.tar.gz 08:26:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:26:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:26:20 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 08:26:20 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 08:26:20 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 08:26:20 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 08:26:20 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 08:26:20 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 08:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:26:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:21 INFO - Downloading docopt-0.6.1.tar.gz 08:26:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:26:22 INFO - Installing collected packages: blobuploader, requests, docopt 08:26:22 INFO - Running setup.py install for blobuploader 08:26:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:26:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:26:22 INFO - Running setup.py install for requests 08:26:22 INFO - Running setup.py install for docopt 08:26:22 INFO - Successfully installed blobuploader requests docopt 08:26:22 INFO - Cleaning up... 08:26:22 INFO - Return code: 0 08:26:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 08:26:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:22 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:22 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 08:26:22 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 08:26:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:22 INFO - 'HOME': '/Users/cltbld', 08:26:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:22 INFO - 'LOGNAME': 'cltbld', 08:26:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:22 INFO - 'MOZ_NO_REMOTE': '1', 08:26:22 INFO - 'NO_EM_RESTART': '1', 08:26:22 INFO - 'PAGER': '/bin/cat', 08:26:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:22 INFO - 'PWD': '/builds/slave/test', 08:26:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:22 INFO - 'SHELL': '/bin/bash', 08:26:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:22 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:22 INFO - 'USER': 'cltbld', 08:26:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:22 INFO - 'XPC_FLAGS': '0x0', 08:26:22 INFO - 'XPC_SERVICE_NAME': '0', 08:26:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-PcWzby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IK1wei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-v35OVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-_4EuAZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-T5QSvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-jarGDL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-HMWIp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IHJZ8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:23 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-vEdmQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-6b21QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-xUyyOF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-7TeR8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-DEJpVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-lVfeTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-LY6qMX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-43UnOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:24 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-s3Uf_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:24 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:26:24 INFO - Running setup.py install for manifestparser 08:26:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Running setup.py install for mozcrash 08:26:25 INFO - Running setup.py install for mozdebug 08:26:25 INFO - Running setup.py install for mozdevice 08:26:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Running setup.py install for mozfile 08:26:25 INFO - Running setup.py install for mozhttpd 08:26:25 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Running setup.py install for mozinfo 08:26:25 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:26:25 INFO - Running setup.py install for mozInstall 08:26:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozleak 08:26:26 INFO - Running setup.py install for mozlog 08:26:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for moznetwork 08:26:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozprocess 08:26:26 INFO - Running setup.py install for mozprofile 08:26:26 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:26:26 INFO - Running setup.py install for mozrunner 08:26:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Running setup.py install for mozscreenshot 08:26:27 INFO - Running setup.py install for moztest 08:26:27 INFO - Running setup.py install for mozversion 08:26:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:26:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:26:27 INFO - Cleaning up... 08:26:27 INFO - Return code: 0 08:26:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:27 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')]} 08:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:27 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')]} 08:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:27 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:27 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 08:26:27 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 08:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:27 INFO - 'HOME': '/Users/cltbld', 08:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:27 INFO - 'LOGNAME': 'cltbld', 08:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:27 INFO - 'MOZ_NO_REMOTE': '1', 08:26:27 INFO - 'NO_EM_RESTART': '1', 08:26:27 INFO - 'PAGER': '/bin/cat', 08:26:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:27 INFO - 'PWD': '/builds/slave/test', 08:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:27 INFO - 'SHELL': '/bin/bash', 08:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:27 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:27 INFO - 'USER': 'cltbld', 08:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:27 INFO - 'XPC_FLAGS': '0x0', 08:26:27 INFO - 'XPC_SERVICE_NAME': '0', 08:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:26:27 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-okWnx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:26:27 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)) 08:26:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:26:27 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-5RO6BV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-4HqTgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-jt7TdZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-P45Im3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Abpq9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-Ldf99s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-rYeHgV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-xGa14q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-IezmnC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-AOVekJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:26:28 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)) 08:26:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:26:28 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-WTPZ54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:26:29 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-8vb7ih-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:26:29 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-ZqCaA4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:29 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-M89Bnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:26:29 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-M5H9yw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:26:29 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)) 08:26:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:26:29 INFO - Running setup.py (path:/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/pip-6B01bs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:26:29 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)) 08:26:29 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)) 08:26:29 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)) 08:26:29 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)) 08:26:29 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)) 08:26:29 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)) 08:26:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:26:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:26:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:26:31 INFO - Downloading blessings-1.6.tar.gz 08:26:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:26:32 INFO - Installing collected packages: blessings 08:26:32 INFO - Running setup.py install for blessings 08:26:32 INFO - Successfully installed blessings 08:26:32 INFO - Cleaning up... 08:26:32 INFO - Return code: 0 08:26:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:26:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:32 INFO - Reading from file tmpfile_stdout 08:26:32 INFO - Current package versions: 08:26:32 INFO - blessings == 1.6 08:26:32 INFO - blobuploader == 1.2.4 08:26:32 INFO - docopt == 0.6.1 08:26:32 INFO - manifestparser == 1.1 08:26:32 INFO - mozInstall == 1.12 08:26:32 INFO - mozcrash == 0.17 08:26:32 INFO - mozdebug == 0.1 08:26:32 INFO - mozdevice == 0.48 08:26:32 INFO - mozfile == 1.2 08:26:32 INFO - mozhttpd == 0.7 08:26:32 INFO - mozinfo == 0.9 08:26:32 INFO - mozleak == 0.1 08:26:32 INFO - mozlog == 3.1 08:26:32 INFO - moznetwork == 0.27 08:26:32 INFO - mozprocess == 0.22 08:26:32 INFO - mozprofile == 0.28 08:26:32 INFO - mozrunner == 6.11 08:26:32 INFO - mozscreenshot == 0.1 08:26:32 INFO - mozsystemmonitor == 0.0 08:26:32 INFO - moztest == 0.7 08:26:32 INFO - mozversion == 1.4 08:26:32 INFO - psutil == 3.1.1 08:26:32 INFO - requests == 1.2.3 08:26:32 INFO - wsgiref == 0.1.2 08:26:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:26:32 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')]} 08:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:32 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')]} 08:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:32 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:32 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 08:26:32 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 08:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:32 INFO - 'HOME': '/Users/cltbld', 08:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:32 INFO - 'LOGNAME': 'cltbld', 08:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:32 INFO - 'MOZ_NO_REMOTE': '1', 08:26:32 INFO - 'NO_EM_RESTART': '1', 08:26:32 INFO - 'PAGER': '/bin/cat', 08:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:32 INFO - 'PWD': '/builds/slave/test', 08:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:32 INFO - 'SHELL': '/bin/bash', 08:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:32 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:32 INFO - 'USER': 'cltbld', 08:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:32 INFO - 'XPC_FLAGS': '0x0', 08:26:32 INFO - 'XPC_SERVICE_NAME': '0', 08:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 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)) 08:26:32 INFO - Cleaning up... 08:26:32 INFO - Return code: 0 08:26:32 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:26:32 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')]} 08:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:32 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')]} 08:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:32 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:32 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 08:26:32 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 08:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:32 INFO - 'HOME': '/Users/cltbld', 08:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:32 INFO - 'LOGNAME': 'cltbld', 08:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:32 INFO - 'MOZ_NO_REMOTE': '1', 08:26:32 INFO - 'NO_EM_RESTART': '1', 08:26:32 INFO - 'PAGER': '/bin/cat', 08:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:32 INFO - 'PWD': '/builds/slave/test', 08:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:32 INFO - 'SHELL': '/bin/bash', 08:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:32 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:32 INFO - 'USER': 'cltbld', 08:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:32 INFO - 'XPC_FLAGS': '0x0', 08:26:32 INFO - 'XPC_SERVICE_NAME': '0', 08:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:33 INFO - Downloading/unpacking jsonschema 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26: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 08:26:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:26:35 INFO - Downloading/unpacking functools32 (from jsonschema) 08:26:35 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 08:26:35 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 08:26:35 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 08:26:35 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 08:26:35 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 08:26:35 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 08:26:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:26:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:26:36 INFO - warning: no files found matching '*.txt' 08:26:36 INFO - no previously-included directories found matching 'build' 08:26:36 INFO - no previously-included directories found matching 'dist' 08:26:36 INFO - no previously-included directories found matching '.git*' 08:26:36 INFO - Installing collected packages: jsonschema, functools32 08:26:36 INFO - Running setup.py install for functools32 08:26:36 INFO - warning: no files found matching '*.txt' 08:26:36 INFO - no previously-included directories found matching 'build' 08:26:36 INFO - no previously-included directories found matching 'dist' 08:26:36 INFO - no previously-included directories found matching '.git*' 08:26:36 INFO - Successfully installed jsonschema functools32 08:26:36 INFO - Cleaning up... 08:26:36 INFO - Return code: 0 08:26:36 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 08:26:36 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')]} 08:26:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:26:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:36 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')]} 08:26:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:26:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:26:36 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 0x10f66aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f67a420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f91d1cbc780>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, '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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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 08:26:36 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 08:26:36 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 08:26:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:26:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:26:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:26:36 INFO - 'HOME': '/Users/cltbld', 08:26:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:26:36 INFO - 'LOGNAME': 'cltbld', 08:26:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:26:36 INFO - 'MOZ_NO_REMOTE': '1', 08:26:36 INFO - 'NO_EM_RESTART': '1', 08:26:36 INFO - 'PAGER': '/bin/cat', 08:26:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:26:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:26:36 INFO - 'PWD': '/builds/slave/test', 08:26:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:26:36 INFO - 'SHELL': '/bin/bash', 08:26:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:26:36 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:26:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:26:36 INFO - 'USER': 'cltbld', 08:26:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:26:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:26:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:26:36 INFO - 'XPC_FLAGS': '0x0', 08:26:36 INFO - 'XPC_SERVICE_NAME': '0', 08:26:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:26:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:26:36 INFO - Downloading/unpacking flake8 08:26:36 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 08:26:36 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 08:26:36 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 08:26:36 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 08:26:36 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 08:26:36 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 08:26:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 08:26:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 08:26:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:39 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 08:26:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 08:26:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:26:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:26:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:26:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:26:40 INFO - no previously-included directories found matching 'docs/_build' 08:26:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 08:26:40 INFO - Running setup.py install for pep8 08:26:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 08:26:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 08:26:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 08:26:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 08:26:40 INFO - no previously-included directories found matching 'docs/_build' 08:26:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 08:26:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 08:26:40 INFO - Cleaning up... 08:26:40 INFO - Return code: 0 08:26:40 INFO - Running post-action listener: _resource_record_post_action 08:26:40 INFO - Running post-action listener: _start_resource_monitoring 08:26:40 INFO - Starting resource monitoring. 08:26:40 INFO - ##### 08:26:40 INFO - ##### Running install step. 08:26:40 INFO - ##### 08:26:40 INFO - Running pre-action listener: _resource_record_pre_action 08:26:40 INFO - Running main action method: install 08:26:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:26:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:26:41 INFO - Reading from file tmpfile_stdout 08:26:41 INFO - Detecting whether we're running mozinstall >=1.0... 08:26:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:26:41 INFO - Reading from file tmpfile_stdout 08:26:41 INFO - Output received: 08:26:41 INFO - Usage: mozinstall [options] installer 08:26:41 INFO - Options: 08:26:41 INFO - -h, --help show this help message and exit 08:26:41 INFO - -d DEST, --destination=DEST 08:26:41 INFO - Directory to install application into. [default: 08:26:41 INFO - "/builds/slave/test"] 08:26:41 INFO - --app=APP Application being installed. [default: firefox] 08:26:41 INFO - mkdir: /builds/slave/test/build/application 08:26:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:26:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:27:12 INFO - Reading from file tmpfile_stdout 08:27:12 INFO - Output received: 08:27:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:27:12 INFO - Running post-action listener: _resource_record_post_action 08:27:12 INFO - ##### 08:27:12 INFO - ##### Running run-tests step. 08:27:12 INFO - ##### 08:27:12 INFO - Running pre-action listener: _resource_record_pre_action 08:27:12 INFO - Running main action method: run_tests 08:27:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:27:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:27:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:27:12 INFO - 2016-05-31 08:27:12.997 screenresolution[2283:20609] starting screenresolution argv=screenresolution get 08:27:13 INFO - 2016-05-31 08:27:12.999 screenresolution[2283:20609] Display 0: 1600x1200x32@60 08:27:13 INFO - 2016-05-31 08:27:13.005 screenresolution[2284:20611] starting screenresolution argv=screenresolution list 08:27:13 INFO - Available Modes on Display 0 08:27:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:27:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:27:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:27:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:27:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:27:13 INFO - Intel Iris: 08:27:13 INFO - Chipset Model: Intel Iris 08:27:13 INFO - Type: GPU 08:27:13 INFO - Bus: Built-In 08:27:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:27:13 INFO - Vendor: Intel (0x8086) 08:27:13 INFO - Device ID: 0x0a2e 08:27:13 INFO - Revision ID: 0x0009 08:27:13 INFO - Displays: 08:27:13 INFO - Display: 08:27:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:27:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:27:13 INFO - Display Serial Number: 1600x1200 60 08:27:13 INFO - Main Display: Yes 08:27:13 INFO - Mirror: Off 08:27:13 INFO - Online: Yes 08:27:13 INFO - Rotation: Supported 08:27:13 INFO - Return code: 0 08:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:27:13 INFO - Python 2.7.3 08:27:13 INFO - Return code: 0 08:27:13 INFO - grabbing minidump binary from tooltool 08:27: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')]} 08:27: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 0x10f509e00>, '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 0x10f372db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f504b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:27: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 08:27: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 08:27:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:27:13 INFO - Return code: 0 08:27:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:27:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:27:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:27:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:27:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:27:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 08:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 08:27:14 INFO - Return code: 0 08:27:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 08:27:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0152 --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 08:27:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DfTyxItGVx/Render', 08:27:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:27:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:27:14 INFO - 'HOME': '/Users/cltbld', 08:27:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:27:14 INFO - 'LOGNAME': 'cltbld', 08:27:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:27:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:27:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:27:14 INFO - 'MOZ_NO_REMOTE': '1', 08:27:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:27:14 INFO - 'NO_EM_RESTART': '1', 08:27:14 INFO - 'PAGER': '/bin/cat', 08:27:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:27:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:27:14 INFO - 'PWD': '/builds/slave/test', 08:27:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:27:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:27:14 INFO - 'SHELL': '/bin/bash', 08:27:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Pm9QHR2Wld/Listeners', 08:27:14 INFO - 'TMPDIR': '/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/', 08:27:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:27:14 INFO - 'USER': 'cltbld', 08:27:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:27:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:27:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:27:14 INFO - 'XPC_FLAGS': '0x0', 08:27:14 INFO - 'XPC_SERVICE_NAME': '0', 08:27:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:27:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0152', '--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 08:27:14 INFO - mozversion application_buildid: 20160531072841 08:27:14 INFO - mozversion application_changeset: 34b32f5cb229db6eafbdaa653d079be1a6ca007e 08:27:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:27:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:27:14 INFO - mozversion application_name: Firefox 08:27:14 INFO - mozversion application_remotingname: firefox-dev 08:27:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:14 INFO - mozversion application_vendor: Mozilla 08:27:14 INFO - mozversion application_version: 48.0a2 08:27:14 INFO - mozversion platform_buildid: 20160531072841 08:27:14 INFO - mozversion platform_changeset: 34b32f5cb229db6eafbdaa653d079be1a6ca007e 08:27:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:27:14 INFO - mozversion platform_version: 48.0a2 08:27:14 INFO - using testdate: 1464708434 08:27:14 INFO - actual date: 1464708434 08:27:14 INFO - starting webserver on 'localhost:49967' 08:27:14 INFO - SUITE-START | Running 2 tests 08:27:14 INFO - TEST-START | dromaeo_css 08:27:14 INFO - operating with platform_type : mac_ 08:27:14 INFO - Initialising browser for dromaeo_css test... 08:27:14 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpDUxplK/profile http://localhost:49967/getInfo.html) 08:27:16 INFO - PROCESS | 2302 | __metrics Screen width/height:1600/1200 08:27:16 INFO - PROCESS | 2302 | colorDepth:24 08:27:16 INFO - PROCESS | 2302 | Browser inner width/height: 1024/689 08:27:16 INFO - PROCESS | 2302 | __metrics 08:27:16 INFO - PROCESS | 2302 | 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" 08:27:16 ERROR - PROCESS | 2302 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:27:17 INFO - TEST-INFO | 2302: exit 0 08:27:17 INFO - Browser initialized. 08:27:17 INFO - Running cycle 1/1 for dromaeo_css test... 08:27:17 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpDUxplK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:27:29 INFO - PROCESS | 2304 | RSS: Main: 211484672 08:27:29 INFO - PROCESS | 2304 | 08:28:20 INFO - PROCESS | 2304 | 1464708500741 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] 08:30:31 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49967/tests/dromaeo/cssquery-ext.html) 08:30:33 INFO - PROCESS | 2304 | RSS: Main: 317521920 08:30:33 INFO - PROCESS | 2304 | 08:33:35 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-ext.html (next: http://localhost:49967/tests/dromaeo/cssquery-jquery.html) 08:33:36 INFO - PROCESS | 2304 | RSS: Main: 359129088 08:33:36 INFO - PROCESS | 2304 | 08:36:38 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49967/tests/dromaeo/cssquery-mootools.html) 08:36:38 INFO - PROCESS | 2304 | RSS: Main: 360955904 08:36:38 INFO - PROCESS | 2304 | 08:39:41 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49967/tests/dromaeo/cssquery-prototype.html) 08:39:41 INFO - PROCESS | 2304 | RSS: Main: 362708992 08:39:41 INFO - PROCESS | 2304 | 08:42:45 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49967/tests/dromaeo/cssquery-yui.html) 08:42:45 INFO - PROCESS | 2304 | RSS: Main: 326754304 08:42:45 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | Cycle 1(1): loaded http://localhost:49967/tests/dromaeo/cssquery-yui.html (next: http://localhost:49967/tests/dromaeo/cssquery-dojo.html) 08:45:48 INFO - PROCESS | 2304 | RSS: Main: 368537600 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | __start_tp_report 08:45:48 INFO - PROCESS | 2304 | _x_x_mozilla_page_load 08:45:48 INFO - PROCESS | 2304 | _x_x_mozilla_page_load_details 08:45:48 INFO - PROCESS | 2304 | |i|pagename|runs| 08:45:48 INFO - PROCESS | 2304 | |0;dojo.html;3329.1011426914733;3371.7639765216445;3377.189474526113;3405.8126803025825;3436.123788433949;5622.0161471742485;5636.520895723868;5650.576206784487;5658.151277308406;5665.29183852018;5612.102063669814;5629.802956896509;5633.746481408336;5634.690092044936;5666.291713535808;5095.439501654815;5157.871053223662;5172.689638621695;5207.427183009866;5243.606729495284;5261.39493958194;5277.261183434322;5278.894422111555;5293.04725149473;5307.2569840222395;5175.430702622709;5218.660787048849;5233.555147812422;5265.7893684252585;5290.285811415448;5289.968456151071;5299.735013249322;5330.786768529254;5336.813211537577;5376.166694162411;5095.668781529189;5119.974400128012;5133.897322053575;5241.109011468059;5245.449227831099;5534.778608855641;5549.833504994856;5567;5567.526760225385;5599;6251.437370636619;6285.182926219608;6287.49700023997;6291.433770960635;6298.87402251957;6091;6186.7525298987985;6202.534809889231;6328.905066424008;6333.081703153063;5850.590458667937;5866.413358664185;5870.236869207019;5885.499732522783;5908.704564771787;6316.589421687576;6321.810345689637;6341.524385671093;6342.429181373698;6353.364663533702;5981.940180598225;6084.60450070744;6103.633781973096;6210.813675589739;6256.405641464054;6142.754289828356;6152.723127459275;6219.191505104308;6265.843353916189;6280.780172693934;6238.594491358047;6268.592541484789;6277.309495955441;6286.685665716695;6289.96855015722;6232.781852635136;6254.437100660963;6296.5592408531875;6305.117283580303;6308.779192728232;4760.619150467955;4883.755812209446;4884.462709101996;4890.559849613481;4891.290762839369;4429.047754732748;4454.7549884756645;4463.4420697412825;4469.776511174428;4481.7983190756495;5617.43825617435;5623.775048998076;5643.943560564424;5734.741936612862;5754.309482862;6205.410486003822;6263.217097862767;6298;6304.747810087545;6306.716197771111;5740.425957404226;5742.741576628978;5742.856428589235;5743.282089738783;5768.971155144198;5301.575873930076;5332.013577488177;5382.973085134628;5424.484673955968;5446.91829622556;6327.810165695037;6341.619502829845;6373.6494492801485;6378.712957916905;6402.295747467775;5160.974195129151;6277.5605707600025;6323.715432805443;6331.96834015827;6345.016522439029;6220.377962203744;6282.780102696383;6328.683565821599;6344;6368.012957991518;6228.159198508143;6237.594556353823;6250.3749625037135;6252.624842509464;6255.749770009149;6086.3000754913755;6290.496760259077;6320.968395157995;6328.367163283635;6338.746450142127;5959.344472108151;6009.90985135232;6106.551676171979;6126.938730612816;6144.877102457836;6115.027386288435;6127.387261273837;6129.894918927609;6157;6183.628982261079;4922.680025798455;5793.188953546422;5909.143174239625;5945.464908158287;5981.641101533922;5376.408595054462;5993.550483713651;6006.8498287541415;6120.843333897588;6133.854967165127;5962.582619216613;5979.103134529855;6014.699265036818;6016.067509536029;6022.307434644897 08:45:48 INFO - PROCESS | 2304 | |1;ext.html;495.64066052112224;499.1489510384794;516.3751860249099;527.6596595196098;527.6913005891553;27615;31816;31837.52243716346;31839.36321273576;31840.68159318406;39429.211415771664;40396.79801600988;40494;40514;40524.39213411794;9460.38507497014;9652.951735241313;9660.130588247057;9664.951675241615;9672.177864881494;9527.42835429873;9653.613855445808;9668.274879384038;9671.468069256189;9691.176250018756;9681.51592420376;9730.513474326292;9752.366096203725;9756.756081097994;9768.169705575036;9708.17480514157;9758.75603109928;9760.121589057018;9768.90231097687;9777.12005919467;9497.287703422271;9590.232781377474;9591.136797688241;9597.424154550748;9600.279979001532;39408.60591394078;39811.8009409954;39849.80075099635;39867.80066099651;39891.8005409974;3252.8210948397827;3279.622843373019;3404.7957122572725;3433;3682.705383569308;28497.71502284992;28572.714272857214;28582.428351432856;28746.281342966595;28948.009041905672;2696.298962269804;2715.972840271592;2757.4347258811895;2772.986135069332;2780.9304767380777;480360;480499;482271.9999999965;482647;482839;61941.69029154826;62456.063159051744;62457.375426246064;62614;62829.68585157136;64503.03245451322;64836;69082.65458672775;70270;70358;17409.65180696379;17410.216540255464;17569;17577.824221757874;17583.560410989572;13302;13389.531366402129;13409.731805364034;13424.731505369838;13431.3955871986;19266.428834057144;19986.501012424127;20002.799972000386;20020.899895500428;20063.899680501796;6529.706163222571;7948.125706172316;7975.049231784161;8051.315638170704;8061.435699501096;974.2108891797667;1009.4145395670494;1072.5413437724687;1114.554178328675;1124.7806677697934;29339.55990660142;29779.957701480344;29984;30503;30527;1111.9888801112047;1125.6172901213627;1154.1689983212075;1170.584442522909;1214.1440284599337;15270.23648817577;15302.311395987208;15370.538883833502;15635.687286254211;15662.765058524132;621176.9411529405;627392;633515;635989;636243.6375636185;60100;60728.69635651882;60828.391716082275;60890.08664870031;61031;1277.3421687830776;1321.0554453565746;1486.7472529669983;1496.5061529695174;1559.2281820498927;28751.99368022109;29004.564931526467;29047.12858614245;29245.976390826218;29832.10503684893;40132.3980140304;47871.76064119657;48189.518104818504;48225;48407.27389089237;358152;360589;360985;361126;362378;24010.759892400853;26993.325166870985;27039.324016898947;27328.453430930873;27689.584656230574;41444.96387590334;42686.146277074906;43680.12639747251;44029.77985110054;44215.778921105186;28858.567121492775;34267.14332141716;34887.953361399195;36130;36545.817270913474;36155.27689446249;36476;37023.81488092542;37562.81218593889;37784.24431511409;35792.82103589569;36375;37106.8144659275;37475.43786843145;37741;26987.325316867235;31149.532757009118;31156.688433115378;32758.577592024772;32788.68845246163;24275;24479.877600611882;24548.75451245536;24986.500269994864;25073.623895641944 08:45:48 INFO - PROCESS | 2304 | |2;jquery.html;396.39945482593873;396.9662578680812;398.1618692651969;399.11994053112886;411.54318706236074;14494.565163045125;18044;20089.799102008976;20754.89622551889;20758.6886196707;16117.952333098341;16469.25888335025;16987.490375288762;20464.07911643976;20502.589948201028;4780.259059845729;5672.5178359839465;5865.912011319833;5870.442307980757;5873.236479257691;5719.571032172583;5745.540356771448;5753.424657534255;5776.566757493184;5785.82642520725;3884.184321292532;5880.8235752927485;6161.907571386433;6166.537509686791;6180.412860778241;6150.446459818615;6157;6160;6169.198004259462;6386.16979792624;5566.359868615121;5682.943170568283;5710.628809127394;5712.600117991742;5745.741441635137;13253.801192982113;13518.729625407437;13755;13765.793513097311;13790.517331893336;2194.133317339658;2262.4117729390314;2415.758424157588;2442.2795275393623;2656.150031989764;9495.287853411022;9500.144986951207;10081;10189.89810101897;10255.384676919408;1683.2173039536551;1717.210083361655;1768.3633891798943;1802.9549261268442;1833.963810447093;74955.62522187355;112658.43670781594;113063;115264;117538.4123079379;11488.540458381738;11690.47392867323;11808.586699465477;11833.875781800714;11874.296649199749;15084.396624135132;15110.320035598425;15121.621959451102;15124.39502419891;15146.924265378602;7858.821176823408;7922.445428819928;8679.91320086791;8770.91229087701;8914.821703565893;8879.733607991771;8904.24313933323;8932.821343573092;8969.865452018224;9031;4217.894552636148;4247.362895565628;4268.103698223345;4603.95396046042;4604.953950460519;1771.792713718889;1867.8038805925453;1957.3932081054918;1960.6568850451115;1961.3723608445162;830.2610676497922;870.3994243971639;904.3443503459939;909.0318810466964;913.7116665501611;18761.15574799137;19359.806401936083;19535.902320488305;19675.508112297306;19824.008799559964;940.431039221275;948.0661548374845;961.1253759079344;991.6330957545754;995.183949161695;10478.423686697304;10611.522481488351;10639.308444951053;10640.148788097089;10648.680539583826;596254;600517;618147;619753;620305;98947.01052989523;120491.39754301173;122783;123132.38433807774;129244.70755292515;997.6957028106498;998.761535695732;1008.1682611845257;1008.6016023670755;1010.6917390196061;17421.738673920154;17529.035903025455;17750.733738993666;17920.731189031914;18736;41938.37092443555;55327.893442130124;55686.886262273714;56479.43520564906;59555;75127.62436187784;76673.61663191872;78512;78776.60611696905;78902;7099.503034787515;8298.834023319621;8499.617517211864;8544.145585441405;8569.628859382468;7802.960985195038;12537.87462125367;12760.08598710201;12791.80812287835;13138.737225255632;11057.83413248818;11386.975172234539;11389.155278510256;11401.486933088197;11485.598004070152;10881.945590271998;11017.11863050938;11092;11263.493142808757;11378.658640240807;10548.103411210133;10761.192910531585;11394.658160255205;11493.54025838957;11602;8221.465604735913;8413.200745929127;8427.283680886949;8441.11368306319;8659.740207793777;10263.794724105626;11255.606053788078;11525.538978440769;11589.6523104307;11616.825747613962 08:45:48 INFO - PROCESS | 2304 | |3;mootools.html;1404.7260784147084;1542.2828384801069;1556.6419723463607;1734.7744793176894;1878.3801345555976;6963.860722785541;7799.493032952854;8642.092580279075;8651.783705407368;8655.134486551342;8325.875111873327;8617.310615150789;8620.65517379304;8628.95685521573;8653.221210091091;2146.8604540704882;2204.096320508592;2385.677933478984;2396.9041238350487;2421.2615152378517;2454.4109413740753;2460.9138680146198;2461.1509029384833;2469.3826543364157;2472.802175825939;2391.3227439482707;2411.144043864429;2449.5835707929605;2450.473148273121;2450.546648869965;2128.4998025464;2194.034624765114;2194.133317339658;2196.428928478591;2435.3546310227716;2379.5716770981217;2446;2447.229122826304;2450.1179575352858;2451.4851881104987;8528.232459078712;8606.483610983361;8608.741737747878;8622.43954142985;8624.396292259544;5188.299579556749;5289.206619007141;5298.735063246861;5302.204669299597;5329.22726204702;3340.8830690925943;3407.0460271124125;3455.7926524408617;3486.0587641336697;3497.0557949353533;1026.9332493387906;1045.426632917462;1046.8168070587617;1065.7815147894662;1080.1197024425069;11915.94042029793;11927.701807454796;11929.98595119416;11930.164888457724;11992.820107698391;3978.5424676162033;4624.791884365211;4702;4847.515248475124;4849.151398505247;9992.900070999342;9999.700008999742;10009.199264058858;10011.349262297927;10051.69844904654;5357.249985002103;5362.490563396471;5363.651362661492;5411.621186516906;5417.133258678593;4239.788010599457;4395.824167033346;4781.378420805273;4822.469528351879;4871.29366241893;3959.5842436544326;3992.540857801332;4067.6949228807957;4084.162746636992;4101.26177288091;1309.8755618216255;1325.4631874091008;1325.6288239293012;1332.9800052999212;1346.5421756602802;760.9657565409569;777.8837368376398;839.5718183726343;840.2521755637488;917.5137177296044;4793.705699461196;5442.918356224581;5553.805616803393;5858.970705146532;6023.156758053876;441.7791104447776;447.46304434677864;449.19145538031415;462.7293033575329;466.86927660255185;3815.9809200954373;3913.471681323042;3927;3970.4242884782125;3997.400389941532;10347.586096556053;10757.892421075845;10806;10846.240763146661;10867.83698244527;9977.351472154287;10491.318064325795;10614.681559553226;10645.627403040857;10653.73365665849;8225.79435514105;8365.330773538102;8376.664933402717;8449.28181104613;8455.027671817697;5653.519450846724;5868.882622347614;5985.978254411585;6003.879922401615;6070.696465176812;9567.61729530811;9596.568154433207;9597.23222142213;9723.416595004323;9735.70792876215;9508.239340852577;9941.602335906482;10040.899591004289;10055.29612927088;10059.497025148567;6740.022696708852;6934.791956241321;7272.381847542806;7534.736284230025;7663.386929045551;8152.144024877302;8167.673293068448;8220.219079187467;8231.917680823355;8239.670413183645;7153.642317884189;7267.345938865527;7284.271572842886;7366.852662946818;7430.071241094864;7152.177499587614;7191.568505889663;7219.494635375473;7232.42140628759;7237.493375463878;6203.658798766111;6649.534532582867;6871.690773915086;7024.121984751906;7038.331358520933;4766.380370551806;4797.688150270221;4808.495108013608;4914.7542622867995;4985;4854.5873600744335;4889;4922.975385123052;4945.480724524017;5162.948370516397 08:45:48 INFO - PROCESS | 2304 | |4;prototype.html;320.4328338840253;321.98873039443623;328.55808936979753;328.7682184060238;333.4082004442117;6631.635260060695;6642.236142843574;6693.9665301673485;6731.562448440848;6738.764143254987;6618.271990081087;6618.404343609074;6625.668716564177;6633.402993730563;6693.899591506131;4624.028953919681;4757.85726428206;4784.425868895737;4876.268559716035;4932.580730637882;4879.316895634614;4904.656674032819;4967.627427942901;4969.130402179622;4972.975135124338;4924.162892308316;4925.729084900329;4930.137225985456;4931.728754918478;4944.53026962438;4632.189366860786;4749.4564266613315;4832.879178020543;4835.564799168056;4841.7579121044155;4077.8776636700945;4176.853810116661;4190.371444283382;4204.747715137071;4243.384709217177;6338.746450141989;6347.714352854133;6366.808995730135;6369.93630063698;6399.744010239585;5791.420857914217;5797.942020579782;5798.88402231953;5801.83963207356;5804.303483581955;6809.829754256183;7057.470689698293;7072.964635176842;7080.150381954147;7081.4688898332315;3884.0191502333732;4734.502877197913;4738.218193997986;4761.999980004203;4773.236282194832;7422.294881986203;7490.438217133682;7493.2506749325175;7508.587027713474;7514.47398682087;4803.687760295569;4827.986122914157;4828.66199366041;4829.782659780319;4830.347903033045;4858.05267972749;4896.681715688469;4908.239222920453;4915.26271059344;4918.827841025619;5592.294350223221;5804.071348584206;5804.709764511757;5805.825825225165;5818.010938140526;5958.285005799332;6952.756653517204;7114.679839407136;7128.215896251409;7176.067111275501;1659.120666046997;1709.837565431282;1713.3917459301767;1713.905735184552;1714.4513755598339;1065.2756125834508;1083.5936523803573;1096.8519249901162;1103.1450625765096;1108.933463992163;6411.903821442682;6644.933550664528;6683.43190828785;6689.799306020827;6706.899396509057;7374.410047196211;7396.778096657109;7426.554406735613;7457.216992215847;7471.887921681179;6754.527183097136;6771.187457505032;6775.254721980578;6775.288594697486;6779.898301525481;3859.7491163074874;3909.8631547896257;3913.8630147945255;3917.7864506553706;3919.6080391961145;7489.176190619028;7660.923390766021;7774.222577742181;7784.221577842283;7788.649510772028;7612.8477430452185;7643.1974642662835;7677.078750549969;7681;7701.807454813563;6236.937630623635;7747.109082455477;7758.456908016385;7761.301482866456;7766.378689704697;7398.926010739824;7411.295926886937;7411.851762964819;7415.258474152542;7418.925810742039;6991.440684745106;7230.385417239593;7258.237885022208;7298.890516642361;7301.415886729155;6967.7212911482975;7631.847363052597;7724.9227507726455;7738.922610774045;7749.7675069748;4809.639277054165;4867;4879.146149423766;4881.438634556929;4885.462599114024;7297.817554561178;7308.232635573188;7315.268473152642;7324.560526368435;7325.890111648436;6834.1115654966525;7061.752838650623;7095.467839911924;7101.2898710128575;7110.7511237106455;5971.402859713994;5985.970070149621;7008.123984501937;7095;7123.145222573117;6349.269833969152;6364.013577895434;6973.302669733189;7005.859882802417;7021.508494405342;7155.034070400429;7162.677679504539;7180.928190718026;7187.892181617175;7194.676239569337;6674.966625167037;6732.562383445061;6839.487038472234;7024.262452442625;7030.718771249298 08:45:48 INFO - PROCESS | 2304 | |5;yui.html;696.7944456385367;712.9964350178249;745.8508298340333;755.6033082631621;801.4389927051064;15068.02057866238;15395.846041539582;15405.075695458267;15447.073175609457;16994.235259413326;16056.919715401436;16121.838781612181;16129.43546975856;16130.354785808553;16139.43511977078;724.9565026098425;731.2833423245223;894.9105089491047;928.5310917986408;929.739672891588;916.0701887584074;933.5798890499268;934.2432629570036;935.6771913689767;936.1060187520935;856.3662889461784;937.9577918993662;961.0197598449578;975.6829030565095;975.790205105902;768.6848392159188;951.3863558005118;955.1546881010287;967.032967032967;973.5570315506427;719.1873183302887;725.6625669063858;728.8469421421507;730.1384366447589;731.2029887422734;15119.697606047817;15184.164870932094;15189.164595947219;15190;15193.164375959317;12477.251364918135;12496.375181240992;12762.936185319015;12814.487420503168;12814.615561533168;10083.949580252052;10173.135283500911;10200;10304.94847525765;10327.741806454824;3524.3479956208184;3536.504889315498;3592.497050412944;3602.963970360289;3607.4047782115917;83383.16616833815;83725.16274837234;84079.99999999939;84486.57756711238;85180;7937.3650107991225;9346.158845703783;9428.95285523568;9449.570948886954;9564.856527152098;21523.13907443716;21704.02331895037;21721.891390542947;21752.34742957682;21780;7477.214892436325;7577.583232922242;7643.694252229849;7761;7866.016747906512;4091.243120022805;4110.617686695912;4118.8352465901025;4143.747158480578;4405.933910991338;3538.663826936437;3582.265635544707;3597.424412094105;3602.2795440911923;3606.296772129462;640.3372509452694;648.5654611410366;648.5687018132888;652.2987788127782;658.6937074260492;561.7865211219711;588.6879953624585;596.3053043204618;609.7103875659116;619.3558698953128;7812.570308632966;7988.680452781824;8136.779483077586;8861.911380886237;8900.198982091642;510.8518529626441;597.698162427971;625.7747211004034;626.0952923026274;631.3370960491558;3738.9626103738615;3852.9614703853163;3863.265979463893;3927.096767743435;4023.5976402360116;83436.16563834406;85012.14987850166;85448;85544.57227713821;85931.28137437216;75390.73827785175;76015.23984760192;76626;76743.23256767473;79181;68216.63566728638;69295.3070469299;69538;70016.29983700199;71117.2888271121;8369;8396.076431592397;8496.70261540843;8502.957485212535;8722.215000649972;44338.77830610827;46356.30465542952;48821.53535393944;49691.50925472242;50913.98172036613;61217;65140;67615;69599.65200174169;69857.60284794377;11077.94461027722;11192.552297907992;11205.103591712481;11441.42792860337;11676.182667213214;11833.349165795964;11921.701957451132;11972;12270.815937760763;12692.36538173106;11289.266197697123;11526.077913767047;11556.422178890854;11631.127665425296;11816;11423.714407139889;11468.31190128595;11548.711282217675;11684.240524365889;11766.117541184274;11347.48936297852;11374.919382658634;11532.654020379403;11551.306921584732;11567.768644626893;9699.127078562964;9811.264155188246;9813.01869813013;9870.358426702242;10141.442220677933;7711.575863327571;9506.904930950603;9663.710088697351;9678.467684277432;9891.55488003056 08:45:48 INFO - PROCESS | 2304 | __end_tp_report 08:45:48 INFO - PROCESS | 2304 | __start_cc_report 08:45:48 INFO - PROCESS | 2304 | _x_x_mozilla_cycle_collect,1326 08:45:48 INFO - PROCESS | 2304 | __end_cc_report 08:45:48 INFO - PROCESS | 2304 | __startTimestamp1464709548655__endTimestamp 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | ------- Summary: start ------- 08:45:48 INFO - PROCESS | 2304 | Number of tests: 6 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#0] dojo.html Cycles:165 Average:5744.18 Median:5962.58 stddev:649.36 (10.9%) stddev-sans-first:623.11 08:45:48 INFO - PROCESS | 2304 | Values: 3329.1 3371.8 3377.2 3405.8 3436.1 5622.0 5636.5 5650.6 5658.2 5665.3 5612.1 5629.8 5633.7 5634.7 5666.3 5095.4 5157.9 5172.7 5207.4 5243.6 5261.4 5277.3 5278.9 5293.0 5307.3 5175.4 5218.7 5233.6 5265.8 5290.3 5290.0 5299.7 5330.8 5336.8 5376.2 5095.7 5120.0 5133.9 5241.1 5245.4 5534.8 5549.8 5567.0 5567.5 5599.0 6251.4 6285.2 6287.5 6291.4 6298.9 6091.0 6186.8 6202.5 6328.9 6333.1 5850.6 5866.4 5870.2 5885.5 5908.7 6316.6 6321.8 6341.5 6342.4 6353.4 5981.9 6084.6 6103.6 6210.8 6256.4 6142.8 6152.7 6219.2 6265.8 6280.8 6238.6 6268.6 6277.3 6286.7 6290.0 6232.8 6254.4 6296.6 6305.1 6308.8 4760.6 4883.8 4884.5 4890.6 4891.3 4429.0 4454.8 4463.4 4469.8 4481.8 5617.4 5623.8 5643.9 5734.7 5754.3 6205.4 6263.2 6298.0 6304.7 6306.7 5740.4 5742.7 5742.9 5743.3 5769.0 5301.6 5332.0 5383.0 5424.5 5446.9 6327.8 6341.6 6373.6 6378.7 6402.3 5161.0 6277.6 6323.7 6332.0 6345.0 6220.4 6282.8 6328.7 6344.0 6368.0 6228.2 6237.6 6250.4 6252.6 6255.7 6086.3 6290.5 6321.0 6328.4 6338.7 5959.3 6009.9 6106.6 6126.9 6144.9 6115.0 6127.4 6129.9 6157.0 6183.6 4922.7 5793.2 5909.1 5945.5 5981.6 5376.4 5993.6 6006.8 6120.8 6133.9 5962.6 5979.1 6014.7 6016.1 6022.3 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#1] ext.html Cycles:180 Average:63215.52 Median:36510.91 stddev:134447.31 (368.2%) stddev-sans-first:134742.00 08:45:48 INFO - PROCESS | 2304 | Values: 495.6 499.1 516.4 527.7 527.7 27615.0 31816.0 31837.5 31839.4 31840.7 39429.2 40396.8 40494.0 40514.0 40524.4 9460.4 9653.0 9660.1 9665.0 9672.2 9527.4 9653.6 9668.3 9671.5 9691.2 9681.5 9730.5 9752.4 9756.8 9768.2 9708.2 9758.8 9760.1 9768.9 9777.1 9497.3 9590.2 9591.1 9597.4 9600.3 39408.6 39811.8 39849.8 39867.8 39891.8 3252.8 3279.6 3404.8 3433.0 3682.7 28497.7 28572.7 28582.4 28746.3 28948.0 2696.3 2716.0 2757.4 2773.0 2780.9 480360.0 480499.0 482272.0 482647.0 482839.0 61941.7 62456.1 62457.4 62614.0 62829.7 64503.0 64836.0 69082.7 70270.0 70358.0 17409.7 17410.2 17569.0 17577.8 17583.6 13302.0 13389.5 13409.7 13424.7 13431.4 19266.4 19986.5 20002.8 20020.9 20063.9 6529.7 7948.1 7975.0 8051.3 8061.4 974.2 1009.4 1072.5 1114.6 1124.8 29339.6 29780.0 29984.0 30503.0 30527.0 1112.0 1125.6 1154.2 1170.6 1214.1 15270.2 15302.3 15370.5 15635.7 15662.8 621176.9 627392.0 633515.0 635989.0 636243.6 60100.0 60728.7 60828.4 60890.1 61031.0 1277.3 1321.1 1486.7 1496.5 1559.2 28752.0 29004.6 29047.1 29246.0 29832.1 40132.4 47871.8 48189.5 48225.0 48407.3 358152.0 360589.0 360985.0 361126.0 362378.0 24010.8 26993.3 27039.3 27328.5 27689.6 41445.0 42686.1 43680.1 44029.8 44215.8 28858.6 34267.1 34888.0 36130.0 36545.8 36155.3 36476.0 37023.8 37562.8 37784.2 35792.8 36375.0 37106.8 37475.4 37741.0 26987.3 31149.5 31156.7 32758.6 32788.7 24275.0 24479.9 24548.8 24986.5 25073.6 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#2] jquery.html Cycles:180 Average:34348.34 Median:2429.02 stddev:101536.64 (4180.1%) stddev-sans-first:101789.47 08:45:48 INFO - PROCESS | 2304 | Values: 396.4 397.0 398.2 399.1 411.5 14494.6 18044.0 20089.8 20754.9 20758.7 16118.0 16469.3 16987.5 20464.1 20502.6 4780.3 5672.5 5865.9 5870.4 5873.2 5719.6 5745.5 5753.4 5776.6 5785.8 3884.2 5880.8 6161.9 6166.5 6180.4 6150.4 6157.0 6160.0 6169.2 6386.2 5566.4 5682.9 5710.6 5712.6 5745.7 13253.8 13518.7 13755.0 13765.8 13790.5 2194.1 2262.4 2415.8 2442.3 2656.2 9495.3 9500.1 10081.0 10189.9 10255.4 1683.2 1717.2 1768.4 1803.0 1834.0 74955.6 112658.4 113063.0 115264.0 117538.4 11488.5 11690.5 11808.6 11833.9 11874.3 15084.4 15110.3 15121.6 15124.4 15146.9 7858.8 7922.4 8679.9 8770.9 8914.8 8879.7 8904.2 8932.8 8969.9 9031.0 4217.9 4247.4 4268.1 4604.0 4605.0 1771.8 1867.8 1957.4 1960.7 1961.4 830.3 870.4 904.3 909.0 913.7 18761.2 19359.8 19535.9 19675.5 19824.0 940.4 948.1 961.1 991.6 995.2 10478.4 10611.5 10639.3 10640.1 10648.7 596254.0 600517.0 618147.0 619753.0 620305.0 98947.0 120491.4 122783.0 123132.4 129244.7 997.7 998.8 1008.2 1008.6 1010.7 17421.7 17529.0 17750.7 17920.7 18736.0 41938.4 55327.9 55686.9 56479.4 59555.0 75127.6 76673.6 78512.0 78776.6 78902.0 7099.5 8298.8 8499.6 8544.1 8569.6 7803.0 12537.9 12760.1 12791.8 13138.7 11057.8 11387.0 11389.2 11401.5 11485.6 10881.9 11017.1 11092.0 11263.5 11378.7 10548.1 10761.2 11394.7 11493.5 11602.0 8221.5 8413.2 8427.3 8441.1 8659.7 10263.8 11255.6 11525.5 11589.7 11616.8 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#3] mootools.html Cycles:180 Average:5633.47 Median:4834.99 stddev:3186.01 (65.9%) stddev-sans-first:3179.09 08:45:48 INFO - PROCESS | 2304 | Values: 1404.7 1542.3 1556.6 1734.8 1878.4 6963.9 7799.5 8642.1 8651.8 8655.1 8325.9 8617.3 8620.7 8629.0 8653.2 2146.9 2204.1 2385.7 2396.9 2421.3 2454.4 2460.9 2461.2 2469.4 2472.8 2391.3 2411.1 2449.6 2450.5 2450.5 2128.5 2194.0 2194.1 2196.4 2435.4 2379.6 2446.0 2447.2 2450.1 2451.5 8528.2 8606.5 8608.7 8622.4 8624.4 5188.3 5289.2 5298.7 5302.2 5329.2 3340.9 3407.0 3455.8 3486.1 3497.1 1026.9 1045.4 1046.8 1065.8 1080.1 11915.9 11927.7 11930.0 11930.2 11992.8 3978.5 4624.8 4702.0 4847.5 4849.2 9992.9 9999.7 10009.2 10011.3 10051.7 5357.2 5362.5 5363.7 5411.6 5417.1 4239.8 4395.8 4781.4 4822.5 4871.3 3959.6 3992.5 4067.7 4084.2 4101.3 1309.9 1325.5 1325.6 1333.0 1346.5 761.0 777.9 839.6 840.3 917.5 4793.7 5442.9 5553.8 5859.0 6023.2 441.8 447.5 449.2 462.7 466.9 3816.0 3913.5 3927.0 3970.4 3997.4 10347.6 10757.9 10806.0 10846.2 10867.8 9977.4 10491.3 10614.7 10645.6 10653.7 8225.8 8365.3 8376.7 8449.3 8455.0 5653.5 5868.9 5986.0 6003.9 6070.7 9567.6 9596.6 9597.2 9723.4 9735.7 9508.2 9941.6 10040.9 10055.3 10059.5 6740.0 6934.8 7272.4 7534.7 7663.4 8152.1 8167.7 8220.2 8231.9 8239.7 7153.6 7267.3 7284.3 7366.9 7430.1 7152.2 7191.6 7219.5 7232.4 7237.5 6203.7 6649.5 6871.7 7024.1 7038.3 4766.4 4797.7 4808.5 4914.8 4985.0 4854.6 4889.0 4923.0 4945.5 5162.9 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#4] prototype.html Cycles:180 Average:5834.47 Median:6632.52 stddev:1842.97 (27.8%) stddev-sans-first:1801.07 08:45:48 INFO - PROCESS | 2304 | Values: 320.4 322.0 328.6 328.8 333.4 6631.6 6642.2 6694.0 6731.6 6738.8 6618.3 6618.4 6625.7 6633.4 6693.9 4624.0 4757.9 4784.4 4876.3 4932.6 4879.3 4904.7 4967.6 4969.1 4973.0 4924.2 4925.7 4930.1 4931.7 4944.5 4632.2 4749.5 4832.9 4835.6 4841.8 4077.9 4176.9 4190.4 4204.7 4243.4 6338.7 6347.7 6366.8 6369.9 6399.7 5791.4 5797.9 5798.9 5801.8 5804.3 6809.8 7057.5 7073.0 7080.2 7081.5 3884.0 4734.5 4738.2 4762.0 4773.2 7422.3 7490.4 7493.3 7508.6 7514.5 4803.7 4828.0 4828.7 4829.8 4830.3 4858.1 4896.7 4908.2 4915.3 4918.8 5592.3 5804.1 5804.7 5805.8 5818.0 5958.3 6952.8 7114.7 7128.2 7176.1 1659.1 1709.8 1713.4 1713.9 1714.5 1065.3 1083.6 1096.9 1103.1 1108.9 6411.9 6644.9 6683.4 6689.8 6706.9 7374.4 7396.8 7426.6 7457.2 7471.9 6754.5 6771.2 6775.3 6775.3 6779.9 3859.7 3909.9 3913.9 3917.8 3919.6 7489.2 7660.9 7774.2 7784.2 7788.6 7612.8 7643.2 7677.1 7681.0 7701.8 6236.9 7747.1 7758.5 7761.3 7766.4 7398.9 7411.3 7411.9 7415.3 7418.9 6991.4 7230.4 7258.2 7298.9 7301.4 6967.7 7631.8 7724.9 7738.9 7749.8 4809.6 4867.0 4879.1 4881.4 4885.5 7297.8 7308.2 7315.3 7324.6 7325.9 6834.1 7061.8 7095.5 7101.3 7110.8 5971.4 5986.0 7008.1 7095.0 7123.1 6349.3 6364.0 6973.3 7005.9 7021.5 7155.0 7162.7 7180.9 7187.9 7194.7 6675.0 6732.6 6839.5 7024.3 7030.7 08:45:48 INFO - PROCESS | 2304 | 08:45:48 INFO - PROCESS | 2304 | [#5] yui.html Cycles:180 Average:18193.51 Median:628.72 stddev:25128.09 (3996.7%) stddev-sans-first:25164.23 08:45:48 INFO - PROCESS | 2304 | Values: 696.8 713.0 745.9 755.6 801.4 15068.0 15395.8 15405.1 15447.1 16994.2 16056.9 16121.8 16129.4 16130.4 16139.4 725.0 731.3 894.9 928.5 929.7 916.1 933.6 934.2 935.7 936.1 856.4 938.0 961.0 975.7 975.8 768.7 951.4 955.2 967.0 973.6 719.2 725.7 728.8 730.1 731.2 15119.7 15184.2 15189.2 15190.0 15193.2 12477.3 12496.4 12762.9 12814.5 12814.6 10083.9 10173.1 10200.0 10304.9 10327.7 3524.3 3536.5 3592.5 3603.0 3607.4 83383.2 83725.2 84080.0 84486.6 85180.0 7937.4 9346.2 9429.0 9449.6 9564.9 21523.1 21704.0 21721.9 21752.3 21780.0 7477.2 7577.6 7643.7 7761.0 7866.0 4091.2 4110.6 4118.8 4143.7 4405.9 3538.7 3582.3 3597.4 3602.3 3606.3 640.3 648.6 648.6 652.3 658.7 561.8 588.7 596.3 609.7 619.4 7812.6 7988.7 8136.8 8861.9 8900.2 510.9 597.7 625.8 626.1 631.3 3739.0 3853.0 3863.3 3927.1 4023.6 83436.2 85012.1 85448.0 85544.6 85931.3 75390.7 76015.2 76626.0 76743.2 79181.0 68216.6 69295.3 69538.0 70016.3 71117.3 8369.0 8396.1 8496.7 8503.0 8722.2 44338.8 46356.3 48821.5 49691.5 50914.0 61217.0 65140.0 67615.0 69599.7 69857.6 11077.9 11192.6 11205.1 11441.4 11676.2 11833.3 11921.7 11972.0 12270.8 12692.4 11289.3 11526.1 11556.4 11631.1 11816.0 11423.7 11468.3 11548.7 11684.2 11766.1 11347.5 11374.9 11532.7 11551.3 11567.8 9699.1 9811.3 9813.0 9870.4 10141.4 7711.6 9506.9 9663.7 9678.5 9891.6 08:45:48 INFO - PROCESS | 2304 | -------- Summary: end -------- 08:45:48 INFO - PROCESS | 2304 | 08:45:49 INFO - TEST-INFO | 2304: exit 0 08:45:49 INFO - TEST-OK | dromaeo_css | took 1114598ms 08:45:49 INFO - TEST-START | kraken 08:45:49 INFO - operating with platform_type : mac_ 08:45:49 INFO - Initialising browser for kraken test... 08:45:49 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpe2EtyS/profile http://localhost:49967/getInfo.html) 08:45:51 INFO - PROCESS | 2308 | __metrics Screen width/height:1600/1200 08:45:51 INFO - PROCESS | 2308 | colorDepth:24 08:45:51 INFO - PROCESS | 2308 | Browser inner width/height: 1024/689 08:45:51 INFO - PROCESS | 2308 | __metrics 08:45:51 INFO - PROCESS | 2308 | 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" 08:45:51 ERROR - PROCESS | 2308 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 08:45:52 INFO - TEST-INFO | 2308: exit 0 08:45:52 INFO - Browser initialized. 08:45:52 INFO - Running cycle 1/1 for kraken test... 08:45:52 INFO - TEST-INFO | started process 2310 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/tmpe2EtyS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:46:04 INFO - PROCESS | 2310 | RSS: Main: 211025920 08:46:04 INFO - PROCESS | 2310 | 08:46:07 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49967/tests/kraken/driver.html?testName=audio-beat-detection) 08:46:07 INFO - PROCESS | 2310 | RSS: Main: 271863808 08:46:07 INFO - PROCESS | 2310 | 08:46:10 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49967/tests/kraken/driver.html?testName=audio-dft) 08:46:11 INFO - PROCESS | 2310 | RSS: Main: 285683712 08:46:11 INFO - PROCESS | 2310 | 08:46:14 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49967/tests/kraken/driver.html?testName=audio-fft) 08:46:14 INFO - PROCESS | 2310 | RSS: Main: 371982336 08:46:14 INFO - PROCESS | 2310 | 08:46:16 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49967/tests/kraken/driver.html?testName=audio-oscillator) 08:46:17 INFO - PROCESS | 2310 | RSS: Main: 279113728 08:46:17 INFO - PROCESS | 2310 | 08:46:19 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49967/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:46:20 INFO - PROCESS | 2310 | RSS: Main: 278720512 08:46:20 INFO - PROCESS | 2310 | 08:46:23 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49967/tests/kraken/driver.html?testName=imaging-darkroom) 08:46:23 INFO - PROCESS | 2310 | RSS: Main: 319680512 08:46:23 INFO - PROCESS | 2310 | 08:46:26 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49967/tests/kraken/driver.html?testName=imaging-desaturate) 08:46:27 INFO - PROCESS | 2310 | RSS: Main: 318726144 08:46:27 INFO - PROCESS | 2310 | 08:46:30 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49967/tests/kraken/driver.html?testName=json-parse-financial) 08:46:30 INFO - PROCESS | 2310 | RSS: Main: 317825024 08:46:30 INFO - PROCESS | 2310 | 08:46:32 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49967/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:46:33 INFO - PROCESS | 2310 | RSS: Main: 308944896 08:46:33 INFO - PROCESS | 2310 | 08:46:34 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:46:35 INFO - PROCESS | 2310 | RSS: Main: 315895808 08:46:35 INFO - PROCESS | 2310 | 08:46:37 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:46:38 INFO - PROCESS | 2310 | RSS: Main: 318902272 08:46:38 INFO - PROCESS | 2310 | 08:46:41 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:46:41 INFO - PROCESS | 2310 | RSS: Main: 323624960 08:46:41 INFO - PROCESS | 2310 | 08:46:44 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:46:45 INFO - PROCESS | 2310 | RSS: Main: 321499136 08:46:45 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:49967/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49967/tests/kraken/driver.html?testName=ai-astar) 08:46:47 INFO - PROCESS | 2310 | RSS: Main: 385589248 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | __start_tp_report 08:46:47 INFO - PROCESS | 2310 | _x_x_mozilla_page_load 08:46:47 INFO - PROCESS | 2310 | _x_x_mozilla_page_load_details 08:46:47 INFO - PROCESS | 2310 | |i|pagename|runs| 08:46:47 INFO - PROCESS | 2310 | |0;ai-astar;115;115;119;118;161;163;114;115;112;134 08:46:47 INFO - PROCESS | 2310 | |1;audio-beat-detection;160;122;159;158;119;142;118;152;153;114 08:46:47 INFO - PROCESS | 2310 | |2;audio-dft;178;186;186;184;185;185;186;186;186;185 08:46:47 INFO - PROCESS | 2310 | |3;audio-fft;70;78;74;155;56;54;58;61;129;55 08:46:47 INFO - PROCESS | 2310 | |4;audio-oscillator;92;90;143;77;78;87;126;80;101;166 08:46:47 INFO - PROCESS | 2310 | |5;imaging-gaussian-blur;89;85;89;86;88;85;88;86;88;85 08:46:47 INFO - PROCESS | 2310 | |6;imaging-darkroom;93;108;150;95;93;93;95;128;151;93 08:46:47 INFO - PROCESS | 2310 | |7;imaging-desaturate;85;84;84;87;82;83;83;86;69;146 08:46:47 INFO - PROCESS | 2310 | |8;json-parse-financial;60;63;67;65;64;64;63;84;58;58 08:46:47 INFO - PROCESS | 2310 | |9;json-stringify-tinderbox;59;63;68;76;77;71;70;69;69;71 08:46:47 INFO - PROCESS | 2310 | |10;stanford-crypto-aes;82;81;82;80;80;83;77;86;95;110 08:46:47 INFO - PROCESS | 2310 | |11;stanford-crypto-ccm;145;152;144;126;129;124;126;129;148;145 08:46:47 INFO - PROCESS | 2310 | |12;stanford-crypto-pbkdf2;169;166;164;166;165;166;162;162;166;165 08:46:47 INFO - PROCESS | 2310 | |13;stanford-crypto-sha256-iterative;67;68;69;68;71;85;97;97;98;69 08:46:47 INFO - PROCESS | 2310 | __end_tp_report 08:46:47 INFO - PROCESS | 2310 | __start_cc_report 08:46:47 INFO - PROCESS | 2310 | _x_x_mozilla_cycle_collect,2008 08:46:47 INFO - PROCESS | 2310 | __end_cc_report 08:46:47 INFO - PROCESS | 2310 | __startTimestamp1464709607352__endTimestamp 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | ------- Summary: start ------- 08:46:47 INFO - PROCESS | 2310 | Number of tests: 14 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#0] ai-astar Cycles:10 Average:126.60 Median:118.50 stddev:19.64 (16.6%) stddev-sans-first:20.37 08:46:47 INFO - PROCESS | 2310 | Values: 115.0 115.0 119.0 118.0 161.0 163.0 114.0 115.0 112.0 134.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#1] audio-beat-detection Cycles:10 Average:139.70 Median:152.50 stddev:19.22 (12.6%) stddev-sans-first:18.93 08:46:47 INFO - PROCESS | 2310 | Values: 160.0 122.0 159.0 158.0 119.0 142.0 118.0 152.0 153.0 114.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#2] audio-dft Cycles:10 Average:184.70 Median:186.00 stddev:2.45 (1.3%) stddev-sans-first:0.73 08:46:47 INFO - PROCESS | 2310 | Values: 178.0 186.0 186.0 184.0 185.0 185.0 186.0 186.0 186.0 185.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#3] audio-fft Cycles:10 Average:79.00 Median:59.50 stddev:34.77 (58.4%) stddev-sans-first:36.72 08:46:47 INFO - PROCESS | 2310 | Values: 70.0 78.0 74.0 155.0 56.0 54.0 58.0 61.0 129.0 55.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#4] audio-oscillator Cycles:10 Average:104.00 Median:79.00 stddev:30.67 (38.8%) stddev-sans-first:32.23 08:46:47 INFO - PROCESS | 2310 | Values: 92.0 90.0 143.0 77.0 78.0 87.0 126.0 80.0 101.0 166.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.66 (1.9%) stddev-sans-first:1.58 08:46:47 INFO - PROCESS | 2310 | Values: 89.0 85.0 89.0 86.0 88.0 85.0 88.0 86.0 88.0 85.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#6] imaging-darkroom Cycles:10 Average:109.90 Median:93.00 stddev:24.07 (25.9%) stddev-sans-first:24.74 08:46:47 INFO - PROCESS | 2310 | Values: 93.0 108.0 150.0 95.0 93.0 93.0 95.0 128.0 151.0 93.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#7] imaging-desaturate Cycles:10 Average:88.90 Median:84.00 stddev:20.68 (24.6%) stddev-sans-first:21.89 08:46:47 INFO - PROCESS | 2310 | Values: 85.0 84.0 84.0 87.0 82.0 83.0 83.0 86.0 69.0 146.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#8] json-parse-financial Cycles:10 Average:64.60 Median:64.00 stddev:7.43 (11.6%) stddev-sans-first:7.69 08:46:47 INFO - PROCESS | 2310 | Values: 60.0 63.0 67.0 65.0 64.0 64.0 63.0 84.0 58.0 58.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#9] json-stringify-tinderbox Cycles:10 Average:69.30 Median:70.50 stddev:5.36 (7.6%) stddev-sans-first:4.19 08:46:47 INFO - PROCESS | 2310 | Values: 59.0 63.0 68.0 76.0 77.0 71.0 70.0 69.0 69.0 71.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:82.00 stddev:9.86 (12.0%) stddev-sans-first:10.37 08:46:47 INFO - PROCESS | 2310 | Values: 82.0 81.0 82.0 80.0 80.0 83.0 77.0 86.0 95.0 110.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#11] stanford-crypto-ccm Cycles:10 Average:136.80 Median:144.50 stddev:10.86 (7.5%) stddev-sans-first:11.11 08:46:47 INFO - PROCESS | 2310 | Values: 145.0 152.0 144.0 126.0 129.0 124.0 126.0 129.0 148.0 145.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.10 Median:166.00 stddev:2.08 (1.3%) stddev-sans-first:1.66 08:46:47 INFO - PROCESS | 2310 | Values: 169.0 166.0 164.0 166.0 165.0 166.0 162.0 162.0 166.0 165.0 08:46:47 INFO - PROCESS | 2310 | 08:46:47 INFO - PROCESS | 2310 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.90 Median:78.00 stddev:13.72 (17.6%) stddev-sans-first:13.86 08:46:47 INFO - PROCESS | 2310 | Values: 67.0 68.0 69.0 68.0 71.0 85.0 97.0 97.0 98.0 69.0 08:46:47 INFO - PROCESS | 2310 | -------- Summary: end -------- 08:46:47 INFO - PROCESS | 2310 | 08:46:48 INFO - TEST-INFO | 2310: exit 0 08:46:48 INFO - TEST-OK | kraken | took 58915ms 08:46:48 INFO - SUITE-END | took 1173s 08:46:48 INFO - Completed test suite (00:19:33) 08:46:48 INFO - javascript benchmark 08:46:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3329.1011426914733, 3371.7639765216445, 3377.189474526113, 3405.8126803025825, 3436.123788433949, 5622.0161471742485, 5636.520895723868, 5650.576206784487, 5658.151277308406, 5665.29183852018, 5612.102063669814, 5629.802956896509, 5633.746481408336, 5634.690092044936, 5666.291713535808, 5095.439501654815, 5157.871053223662, 5172.689638621695, 5207.427183009866, 5243.606729495284, 5261.39493958194, 5277.261183434322, 5278.894422111555, 5293.04725149473, 5307.2569840222395, 5175.430702622709, 5218.660787048849, 5233.555147812422, 5265.7893684252585, 5290.285811415448, 5289.968456151071, 5299.735013249322, 5330.786768529254, 5336.813211537577, 5376.166694162411, 5095.668781529189, 5119.974400128012, 5133.897322053575, 5241.109011468059, 5245.449227831099, 5534.778608855641, 5549.833504994856, 5567.0, 5567.526760225385, 5599.0, 6251.437370636619, 6285.182926219608, 6287.49700023997, 6291.433770960635, 6298.87402251957, 6091.0, 6186.7525298987985, 6202.534809889231, 6328.905066424008, 6333.081703153063, 5850.590458667937, 5866.413358664185, 5870.236869207019, 5885.499732522783, 5908.704564771787, 6316.589421687576, 6321.810345689637, 6341.524385671093, 6342.429181373698, 6353.364663533702, 5981.940180598225, 6084.60450070744, 6103.633781973096, 6210.813675589739, 6256.405641464054, 6142.754289828356, 6152.723127459275, 6219.191505104308, 6265.843353916189, 6280.780172693934, 6238.594491358047, 6268.592541484789, 6277.309495955441, 6286.685665716695, 6289.96855015722, 6232.781852635136, 6254.437100660963, 6296.5592408531875, 6305.117283580303, 6308.779192728232, 4760.619150467955, 4883.755812209446, 4884.462709101996, 4890.559849613481, 4891.290762839369, 4429.047754732748, 4454.7549884756645, 4463.4420697412825, 4469.776511174428, 4481.7983190756495, 5617.43825617435, 5623.775048998076, 5643.943560564424, 5734.741936612862, 5754.309482862, 6205.410486003822, 6263.217097862767, 6298.0, 6304.747810087545, 6306.716197771111, 5740.425957404226, 5742.741576628978, 5742.856428589235, 5743.282089738783, 5768.971155144198, 5301.575873930076, 5332.013577488177, 5382.973085134628, 5424.484673955968, 5446.91829622556, 6327.810165695037, 6341.619502829845, 6373.6494492801485, 6378.712957916905, 6402.295747467775, 5160.974195129151, 6277.5605707600025, 6323.715432805443, 6331.96834015827, 6345.016522439029, 6220.377962203744, 6282.780102696383, 6328.683565821599, 6344.0, 6368.012957991518, 6228.159198508143, 6237.594556353823, 6250.3749625037135, 6252.624842509464, 6255.749770009149, 6086.3000754913755, 6290.496760259077, 6320.968395157995, 6328.367163283635, 6338.746450142127, 5959.344472108151, 6009.90985135232, 6106.551676171979, 6126.938730612816, 6144.877102457836, 6115.027386288435, 6127.387261273837, 6129.894918927609, 6157.0, 6183.628982261079, 4922.680025798455, 5793.188953546422, 5909.143174239625, 5945.464908158287, 5981.641101533922, 5376.408595054462, 5993.550483713651, 6006.8498287541415, 6120.843333897588, 6133.854967165127, 5962.582619216613, 5979.103134529855, 6014.699265036818, 6016.067509536029, 6022.307434644897], "value": 5702.634548109077, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [495.64066052112224, 499.1489510384794, 516.3751860249099, 527.6596595196098, 527.6913005891553, 27615.0, 31816.0, 31837.52243716346, 31839.36321273576, 31840.68159318406, 39429.211415771664, 40396.79801600988, 40494.0, 40514.0, 40524.39213411794, 9460.38507497014, 9652.951735241313, 9660.130588247057, 9664.951675241615, 9672.177864881494, 9527.42835429873, 9653.613855445808, 9668.274879384038, 9671.468069256189, 9691.176250018756, 9681.51592420376, 9730.513474326292, 9752.366096203725, 9756.756081097994, 9768.169705575036, 9708.17480514157, 9758.75603109928, 9760.121589057018, 9768.90231097687, 9777.12005919467, 9497.287703422271, 9590.232781377474, 9591.136797688241, 9597.424154550748, 9600.279979001532, 39408.60591394078, 39811.8009409954, 39849.80075099635, 39867.80066099651, 39891.8005409974, 3252.8210948397827, 3279.622843373019, 3404.7957122572725, 3433.0, 3682.705383569308, 28497.71502284992, 28572.714272857214, 28582.428351432856, 28746.281342966595, 28948.009041905672, 2696.298962269804, 2715.972840271592, 2757.4347258811895, 2772.986135069332, 2780.9304767380777, 480360.0, 480499.0, 482271.9999999965, 482647.0, 482839.0, 61941.69029154826, 62456.063159051744, 62457.375426246064, 62614.0, 62829.68585157136, 64503.03245451322, 64836.0, 69082.65458672775, 70270.0, 70358.0, 17409.65180696379, 17410.216540255464, 17569.0, 17577.824221757874, 17583.560410989572, 13302.0, 13389.531366402129, 13409.731805364034, 13424.731505369838, 13431.3955871986, 19266.428834057144, 19986.501012424127, 20002.799972000386, 20020.899895500428, 20063.899680501796, 6529.706163222571, 7948.125706172316, 7975.049231784161, 8051.315638170704, 8061.435699501096, 974.2108891797667, 1009.4145395670494, 1072.5413437724687, 1114.554178328675, 1124.7806677697934, 29339.55990660142, 29779.957701480344, 29984.0, 30503.0, 30527.0, 1111.9888801112047, 1125.6172901213627, 1154.1689983212075, 1170.584442522909, 1214.1440284599337, 15270.23648817577, 15302.311395987208, 15370.538883833502, 15635.687286254211, 15662.765058524132, 621176.9411529405, 627392.0, 633515.0, 635989.0, 636243.6375636185, 60100.0, 60728.69635651882, 60828.391716082275, 60890.08664870031, 61031.0, 1277.3421687830776, 1321.0554453565746, 1486.7472529669983, 1496.5061529695174, 1559.2281820498927, 28751.99368022109, 29004.564931526467, 29047.12858614245, 29245.976390826218, 29832.10503684893, 40132.3980140304, 47871.76064119657, 48189.518104818504, 48225.0, 48407.27389089237, 358152.0, 360589.0, 360985.0, 361126.0, 362378.0, 24010.759892400853, 26993.325166870985, 27039.324016898947, 27328.453430930873, 27689.584656230574, 41444.96387590334, 42686.146277074906, 43680.12639747251, 44029.77985110054, 44215.778921105186, 28858.567121492775, 34267.14332141716, 34887.953361399195, 36130.0, 36545.817270913474, 36155.27689446249, 36476.0, 37023.81488092542, 37562.81218593889, 37784.24431511409, 35792.82103589569, 36375.0, 37106.8144659275, 37475.43786843145, 37741.0, 26987.325316867235, 31149.532757009118, 31156.688433115378, 32758.577592024772, 32788.68845246163, 24275.0, 24479.877600611882, 24548.75451245536, 24986.500269994864, 25073.623895641944], "value": 19477.02128382921, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.39945482593873, 396.9662578680812, 398.1618692651969, 399.11994053112886, 411.54318706236074, 14494.565163045125, 18044.0, 20089.799102008976, 20754.89622551889, 20758.6886196707, 16117.952333098341, 16469.25888335025, 16987.490375288762, 20464.07911643976, 20502.589948201028, 4780.259059845729, 5672.5178359839465, 5865.912011319833, 5870.442307980757, 5873.236479257691, 5719.571032172583, 5745.540356771448, 5753.424657534255, 5776.566757493184, 5785.82642520725, 3884.184321292532, 5880.8235752927485, 6161.907571386433, 6166.537509686791, 6180.412860778241, 6150.446459818615, 6157.0, 6160.0, 6169.198004259462, 6386.16979792624, 5566.359868615121, 5682.943170568283, 5710.628809127394, 5712.600117991742, 5745.741441635137, 13253.801192982113, 13518.729625407437, 13755.0, 13765.793513097311, 13790.517331893336, 2194.133317339658, 2262.4117729390314, 2415.758424157588, 2442.2795275393623, 2656.150031989764, 9495.287853411022, 9500.144986951207, 10081.0, 10189.89810101897, 10255.384676919408, 1683.2173039536551, 1717.210083361655, 1768.3633891798943, 1802.9549261268442, 1833.963810447093, 74955.62522187355, 112658.43670781594, 113063.0, 115264.0, 117538.4123079379, 11488.540458381738, 11690.47392867323, 11808.586699465477, 11833.875781800714, 11874.296649199749, 15084.396624135132, 15110.320035598425, 15121.621959451102, 15124.39502419891, 15146.924265378602, 7858.821176823408, 7922.445428819928, 8679.91320086791, 8770.91229087701, 8914.821703565893, 8879.733607991771, 8904.24313933323, 8932.821343573092, 8969.865452018224, 9031.0, 4217.894552636148, 4247.362895565628, 4268.103698223345, 4603.95396046042, 4604.953950460519, 1771.792713718889, 1867.8038805925453, 1957.3932081054918, 1960.6568850451115, 1961.3723608445162, 830.2610676497922, 870.3994243971639, 904.3443503459939, 909.0318810466964, 913.7116665501611, 18761.15574799137, 19359.806401936083, 19535.902320488305, 19675.508112297306, 19824.008799559964, 940.431039221275, 948.0661548374845, 961.1253759079344, 991.6330957545754, 995.183949161695, 10478.423686697304, 10611.522481488351, 10639.308444951053, 10640.148788097089, 10648.680539583826, 596254.0, 600517.0, 618147.0, 619753.0, 620305.0, 98947.01052989523, 120491.39754301173, 122783.0, 123132.38433807774, 129244.70755292515, 997.6957028106498, 998.761535695732, 1008.1682611845257, 1008.6016023670755, 1010.6917390196061, 17421.738673920154, 17529.035903025455, 17750.733738993666, 17920.731189031914, 18736.0, 41938.37092443555, 55327.893442130124, 55686.886262273714, 56479.43520564906, 59555.0, 75127.62436187784, 76673.61663191872, 78512.0, 78776.60611696905, 78902.0, 7099.503034787515, 8298.834023319621, 8499.617517211864, 8544.145585441405, 8569.628859382468, 7802.960985195038, 12537.87462125367, 12760.08598710201, 12791.80812287835, 13138.737225255632, 11057.83413248818, 11386.975172234539, 11389.155278510256, 11401.486933088197, 11485.598004070152, 10881.945590271998, 11017.11863050938, 11092.0, 11263.493142808757, 11378.658640240807, 10548.103411210133, 10761.192910531585, 11394.658160255205, 11493.54025838957, 11602.0, 8221.465604735913, 8413.200745929127, 8427.283680886949, 8441.11368306319, 8659.740207793777, 10263.794724105626, 11255.606053788078, 11525.538978440769, 11589.6523104307, 11616.825747613962], "value": 9304.564471639787, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1404.7260784147084, 1542.2828384801069, 1556.6419723463607, 1734.7744793176894, 1878.3801345555976, 6963.860722785541, 7799.493032952854, 8642.092580279075, 8651.783705407368, 8655.134486551342, 8325.875111873327, 8617.310615150789, 8620.65517379304, 8628.95685521573, 8653.221210091091, 2146.8604540704882, 2204.096320508592, 2385.677933478984, 2396.9041238350487, 2421.2615152378517, 2454.4109413740753, 2460.9138680146198, 2461.1509029384833, 2469.3826543364157, 2472.802175825939, 2391.3227439482707, 2411.144043864429, 2449.5835707929605, 2450.473148273121, 2450.546648869965, 2128.4998025464, 2194.034624765114, 2194.133317339658, 2196.428928478591, 2435.3546310227716, 2379.5716770981217, 2446.0, 2447.229122826304, 2450.1179575352858, 2451.4851881104987, 8528.232459078712, 8606.483610983361, 8608.741737747878, 8622.43954142985, 8624.396292259544, 5188.299579556749, 5289.206619007141, 5298.735063246861, 5302.204669299597, 5329.22726204702, 3340.8830690925943, 3407.0460271124125, 3455.7926524408617, 3486.0587641336697, 3497.0557949353533, 1026.9332493387906, 1045.426632917462, 1046.8168070587617, 1065.7815147894662, 1080.1197024425069, 11915.94042029793, 11927.701807454796, 11929.98595119416, 11930.164888457724, 11992.820107698391, 3978.5424676162033, 4624.791884365211, 4702.0, 4847.515248475124, 4849.151398505247, 9992.900070999342, 9999.700008999742, 10009.199264058858, 10011.349262297927, 10051.69844904654, 5357.249985002103, 5362.490563396471, 5363.651362661492, 5411.621186516906, 5417.133258678593, 4239.788010599457, 4395.824167033346, 4781.378420805273, 4822.469528351879, 4871.29366241893, 3959.5842436544326, 3992.540857801332, 4067.6949228807957, 4084.162746636992, 4101.26177288091, 1309.8755618216255, 1325.4631874091008, 1325.6288239293012, 1332.9800052999212, 1346.5421756602802, 760.9657565409569, 777.8837368376398, 839.5718183726343, 840.2521755637488, 917.5137177296044, 4793.705699461196, 5442.918356224581, 5553.805616803393, 5858.970705146532, 6023.156758053876, 441.7791104447776, 447.46304434677864, 449.19145538031415, 462.7293033575329, 466.86927660255185, 3815.9809200954373, 3913.471681323042, 3927.0, 3970.4242884782125, 3997.400389941532, 10347.586096556053, 10757.892421075845, 10806.0, 10846.240763146661, 10867.83698244527, 9977.351472154287, 10491.318064325795, 10614.681559553226, 10645.627403040857, 10653.73365665849, 8225.79435514105, 8365.330773538102, 8376.664933402717, 8449.28181104613, 8455.027671817697, 5653.519450846724, 5868.882622347614, 5985.978254411585, 6003.879922401615, 6070.696465176812, 9567.61729530811, 9596.568154433207, 9597.23222142213, 9723.416595004323, 9735.70792876215, 9508.239340852577, 9941.602335906482, 10040.899591004289, 10055.29612927088, 10059.497025148567, 6740.022696708852, 6934.791956241321, 7272.381847542806, 7534.736284230025, 7663.386929045551, 8152.144024877302, 8167.673293068448, 8220.219079187467, 8231.917680823355, 8239.670413183645, 7153.642317884189, 7267.345938865527, 7284.271572842886, 7366.852662946818, 7430.071241094864, 7152.177499587614, 7191.568505889663, 7219.494635375473, 7232.42140628759, 7237.493375463878, 6203.658798766111, 6649.534532582867, 6871.690773915086, 7024.121984751906, 7038.331358520933, 4766.380370551806, 4797.688150270221, 4808.495108013608, 4914.7542622867995, 4985.0, 4854.5873600744335, 4889.0, 4922.975385123052, 4945.480724524017, 5162.948370516397], "value": 4454.300969451495, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.4328338840253, 321.98873039443623, 328.55808936979753, 328.7682184060238, 333.4082004442117, 6631.635260060695, 6642.236142843574, 6693.9665301673485, 6731.562448440848, 6738.764143254987, 6618.271990081087, 6618.404343609074, 6625.668716564177, 6633.402993730563, 6693.899591506131, 4624.028953919681, 4757.85726428206, 4784.425868895737, 4876.268559716035, 4932.580730637882, 4879.316895634614, 4904.656674032819, 4967.627427942901, 4969.130402179622, 4972.975135124338, 4924.162892308316, 4925.729084900329, 4930.137225985456, 4931.728754918478, 4944.53026962438, 4632.189366860786, 4749.4564266613315, 4832.879178020543, 4835.564799168056, 4841.7579121044155, 4077.8776636700945, 4176.853810116661, 4190.371444283382, 4204.747715137071, 4243.384709217177, 6338.746450141989, 6347.714352854133, 6366.808995730135, 6369.93630063698, 6399.744010239585, 5791.420857914217, 5797.942020579782, 5798.88402231953, 5801.83963207356, 5804.303483581955, 6809.829754256183, 7057.470689698293, 7072.964635176842, 7080.150381954147, 7081.4688898332315, 3884.0191502333732, 4734.502877197913, 4738.218193997986, 4761.999980004203, 4773.236282194832, 7422.294881986203, 7490.438217133682, 7493.2506749325175, 7508.587027713474, 7514.47398682087, 4803.687760295569, 4827.986122914157, 4828.66199366041, 4829.782659780319, 4830.347903033045, 4858.05267972749, 4896.681715688469, 4908.239222920453, 4915.26271059344, 4918.827841025619, 5592.294350223221, 5804.071348584206, 5804.709764511757, 5805.825825225165, 5818.010938140526, 5958.285005799332, 6952.756653517204, 7114.679839407136, 7128.215896251409, 7176.067111275501, 1659.120666046997, 1709.837565431282, 1713.3917459301767, 1713.905735184552, 1714.4513755598339, 1065.2756125834508, 1083.5936523803573, 1096.8519249901162, 1103.1450625765096, 1108.933463992163, 6411.903821442682, 6644.933550664528, 6683.43190828785, 6689.799306020827, 6706.899396509057, 7374.410047196211, 7396.778096657109, 7426.554406735613, 7457.216992215847, 7471.887921681179, 6754.527183097136, 6771.187457505032, 6775.254721980578, 6775.288594697486, 6779.898301525481, 3859.7491163074874, 3909.8631547896257, 3913.8630147945255, 3917.7864506553706, 3919.6080391961145, 7489.176190619028, 7660.923390766021, 7774.222577742181, 7784.221577842283, 7788.649510772028, 7612.8477430452185, 7643.1974642662835, 7677.078750549969, 7681.0, 7701.807454813563, 6236.937630623635, 7747.109082455477, 7758.456908016385, 7761.301482866456, 7766.378689704697, 7398.926010739824, 7411.295926886937, 7411.851762964819, 7415.258474152542, 7418.925810742039, 6991.440684745106, 7230.385417239593, 7258.237885022208, 7298.890516642361, 7301.415886729155, 6967.7212911482975, 7631.847363052597, 7724.9227507726455, 7738.922610774045, 7749.7675069748, 4809.639277054165, 4867.0, 4879.146149423766, 4881.438634556929, 4885.462599114024, 7297.817554561178, 7308.232635573188, 7315.268473152642, 7324.560526368435, 7325.890111648436, 6834.1115654966525, 7061.752838650623, 7095.467839911924, 7101.2898710128575, 7110.7511237106455, 5971.402859713994, 5985.970070149621, 7008.123984501937, 7095.0, 7123.145222573117, 6349.269833969152, 6364.013577895434, 6973.302669733189, 7005.859882802417, 7021.508494405342, 7155.034070400429, 7162.677679504539, 7180.928190718026, 7187.892181617175, 7194.676239569337, 6674.966625167037, 6732.562383445061, 6839.487038472234, 7024.262452442625, 7030.718771249298], "value": 5216.346556779821, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [696.7944456385367, 712.9964350178249, 745.8508298340333, 755.6033082631621, 801.4389927051064, 15068.02057866238, 15395.846041539582, 15405.075695458267, 15447.073175609457, 16994.235259413326, 16056.919715401436, 16121.838781612181, 16129.43546975856, 16130.354785808553, 16139.43511977078, 724.9565026098425, 731.2833423245223, 894.9105089491047, 928.5310917986408, 929.739672891588, 916.0701887584074, 933.5798890499268, 934.2432629570036, 935.6771913689767, 936.1060187520935, 856.3662889461784, 937.9577918993662, 961.0197598449578, 975.6829030565095, 975.790205105902, 768.6848392159188, 951.3863558005118, 955.1546881010287, 967.032967032967, 973.5570315506427, 719.1873183302887, 725.6625669063858, 728.8469421421507, 730.1384366447589, 731.2029887422734, 15119.697606047817, 15184.164870932094, 15189.164595947219, 15190.0, 15193.164375959317, 12477.251364918135, 12496.375181240992, 12762.936185319015, 12814.487420503168, 12814.615561533168, 10083.949580252052, 10173.135283500911, 10200.0, 10304.94847525765, 10327.741806454824, 3524.3479956208184, 3536.504889315498, 3592.497050412944, 3602.963970360289, 3607.4047782115917, 83383.16616833815, 83725.16274837234, 84079.99999999939, 84486.57756711238, 85180.0, 7937.3650107991225, 9346.158845703783, 9428.95285523568, 9449.570948886954, 9564.856527152098, 21523.13907443716, 21704.02331895037, 21721.891390542947, 21752.34742957682, 21780.0, 7477.214892436325, 7577.583232922242, 7643.694252229849, 7761.0, 7866.016747906512, 4091.243120022805, 4110.617686695912, 4118.8352465901025, 4143.747158480578, 4405.933910991338, 3538.663826936437, 3582.265635544707, 3597.424412094105, 3602.2795440911923, 3606.296772129462, 640.3372509452694, 648.5654611410366, 648.5687018132888, 652.2987788127782, 658.6937074260492, 561.7865211219711, 588.6879953624585, 596.3053043204618, 609.7103875659116, 619.3558698953128, 7812.570308632966, 7988.680452781824, 8136.779483077586, 8861.911380886237, 8900.198982091642, 510.8518529626441, 597.698162427971, 625.7747211004034, 626.0952923026274, 631.3370960491558, 3738.9626103738615, 3852.9614703853163, 3863.265979463893, 3927.096767743435, 4023.5976402360116, 83436.16563834406, 85012.14987850166, 85448.0, 85544.57227713821, 85931.28137437216, 75390.73827785175, 76015.23984760192, 76626.0, 76743.23256767473, 79181.0, 68216.63566728638, 69295.3070469299, 69538.0, 70016.29983700199, 71117.2888271121, 8369.0, 8396.076431592397, 8496.70261540843, 8502.957485212535, 8722.215000649972, 44338.77830610827, 46356.30465542952, 48821.53535393944, 49691.50925472242, 50913.98172036613, 61217.0, 65140.0, 67615.0, 69599.65200174169, 69857.60284794377, 11077.94461027722, 11192.552297907992, 11205.103591712481, 11441.42792860337, 11676.182667213214, 11833.349165795964, 11921.701957451132, 11972.0, 12270.815937760763, 12692.36538173106, 11289.266197697123, 11526.077913767047, 11556.422178890854, 11631.127665425296, 11816.0, 11423.714407139889, 11468.31190128595, 11548.711282217675, 11684.240524365889, 11766.117541184274, 11347.48936297852, 11374.919382658634, 11532.654020379403, 11551.306921584732, 11567.768644626893, 9699.127078562964, 9811.264155188246, 9813.01869813013, 9870.358426702242, 10141.442220677933, 7711.575863327571, 9506.904930950603, 9663.710088697351, 9678.467684277432, 9891.55488003056], "value": 6969.098290182024, "unit": "score"}], "name": "dromaeo_css", "value": 7423.504601181069, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 119.0, 118.0, 161.0, 163.0, 114.0, 115.0, 112.0, 134.0], "value": 126.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 122.0, 159.0, 158.0, 119.0, 142.0, 118.0, 152.0, 153.0, 114.0], "value": 139.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 186.0, 186.0, 184.0, 185.0, 185.0, 186.0, 186.0, 186.0, 185.0], "value": 184.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 74.0, 155.0, 56.0, 54.0, 58.0, 61.0, 129.0, 55.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 143.0, 77.0, 78.0, 87.0, 126.0, 80.0, 101.0, 166.0], "value": 104.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 88.0, 85.0, 88.0, 86.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 108.0, 150.0, 95.0, 93.0, 93.0, 95.0, 128.0, 151.0, 93.0], "value": 109.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 87.0, 82.0, 83.0, 83.0, 86.0, 69.0, 146.0], "value": 88.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 67.0, 65.0, 64.0, 64.0, 63.0, 84.0, 58.0, 58.0], "value": 64.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 63.0, 68.0, 76.0, 77.0, 71.0, 70.0, 69.0, 69.0, 71.0], "value": 69.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 80.0, 80.0, 83.0, 77.0, 86.0, 95.0, 110.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 152.0, 144.0, 126.0, 129.0, 124.0, 126.0, 129.0, 148.0, 145.0], "value": 136.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 164.0, 166.0, 165.0, 166.0, 162.0, 162.0, 166.0, 165.0], "value": 165.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 68.0, 71.0, 85.0, 97.0, 97.0, 98.0, 69.0], "value": 78.9, "unit": "score"}], "name": "kraken", "value": 1519.9999999999998, "alertThreshold": 2.0}]} 08:46:48 INFO - Return code: 0 08:46:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:46:48 ERROR - # TBPL SUCCESS # 08:46:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:46:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:46:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:46:48 INFO - 2016-05-31 08:46:48.664 screenresolution[2314:22619] starting screenresolution argv=screenresolution get 08:46:48 INFO - 2016-05-31 08:46:48.667 screenresolution[2314:22619] Display 0: 1600x1200x32@60 08:46:48 INFO - 2016-05-31 08:46:48.672 screenresolution[2315:22622] starting screenresolution argv=screenresolution list 08:46:48 INFO - Available Modes on Display 0 08:46:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:46:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:46:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:46:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:46:48 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:46:48 INFO - Intel Iris: 08:46:48 INFO - Chipset Model: Intel Iris 08:46:48 INFO - Type: GPU 08:46:48 INFO - Bus: Built-In 08:46:48 INFO - VRAM (Dynamic, Max): 1536 MB 08:46:48 INFO - Vendor: Intel (0x8086) 08:46:48 INFO - Device ID: 0x0a2e 08:46:48 INFO - Revision ID: 0x0009 08:46:48 INFO - Displays: 08:46:48 INFO - Display: 08:46:48 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:46:48 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:46:48 INFO - Display Serial Number: 1600x1200 60 08:46:48 INFO - Main Display: Yes 08:46:48 INFO - Mirror: Off 08:46:48 INFO - Online: Yes 08:46:48 INFO - Rotation: Supported 08:46:48 INFO - Return code: 0 08:46:48 INFO - Running post-action listener: _resource_record_post_action 08:46:48 INFO - Running post-run listener: _resource_record_post_run 08:46:49 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 122880; Write bytes: 651969024; Read time: 2; Write time: 2058 08:46:49 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 354509312; Write bytes: 352450048; Read time: 27810; Write time: 683 08:46:49 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 122880; Write bytes: 295017472; Read time: 2; Write time: 1353 08:46:49 INFO - Running post-run listener: _upload_blobber_files 08:46:49 INFO - Blob upload gear active. 08:46:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:46:49 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 08:46:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:46:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:46:50 INFO - (blobuploader) - INFO - Open directory for files ... 08:46:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:46:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:46:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:51 INFO - (blobuploader) - INFO - Done attempting. 08:46:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:46:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:46:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:46:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:46:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:46:52 INFO - (blobuploader) - INFO - Done attempting. 08:46:52 INFO - (blobuploader) - INFO - Iteration through files over. 08:46:52 INFO - Return code: 0 08:46:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:46:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:46:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afca77ace5ac7d2237f31ea90cf0ccf311c3f04eaf901d46904610e0d38408b50760cf6bb395b0522b7d669b2c1ca8fcd3dcc41d3098eb3cd7aa2fd28735a7e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34ae90cf8c28c59d1421ed1dcaf7f286c56876ca33ac295abd337834c58ac0a9ebe12c28020130fadc02e5d49b754764dbd8070c83ffdfb73cbc9bd161bcbb03"} 08:46:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:46:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:46:52 INFO - Contents: 08:46:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afca77ace5ac7d2237f31ea90cf0ccf311c3f04eaf901d46904610e0d38408b50760cf6bb395b0522b7d669b2c1ca8fcd3dcc41d3098eb3cd7aa2fd28735a7e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34ae90cf8c28c59d1421ed1dcaf7f286c56876ca33ac295abd337834c58ac0a9ebe12c28020130fadc02e5d49b754764dbd8070c83ffdfb73cbc9bd161bcbb03"} 08:46:52 INFO - Running post-run listener: copy_logs_to_upload_dir 08:46:52 INFO - Copying logs to upload dir... 08:46:52 INFO - mkdir: /builds/slave/test/build/upload/logs 08:46:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.663359 ========= master_lag: 0.63 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-31 08:46:53.418389) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-31 08:46:53.426927) ========= 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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afca77ace5ac7d2237f31ea90cf0ccf311c3f04eaf901d46904610e0d38408b50760cf6bb395b0522b7d669b2c1ca8fcd3dcc41d3098eb3cd7aa2fd28735a7e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34ae90cf8c28c59d1421ed1dcaf7f286c56876ca33ac295abd337834c58ac0a9ebe12c28020130fadc02e5d49b754764dbd8070c83ffdfb73cbc9bd161bcbb03"} build_url:https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011365 build_url: 'https://queue.taskcluster.net/v1/task/QKXYt7xnSo26EmHwFD5Ctg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/afca77ace5ac7d2237f31ea90cf0ccf311c3f04eaf901d46904610e0d38408b50760cf6bb395b0522b7d669b2c1ca8fcd3dcc41d3098eb3cd7aa2fd28735a7e7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/34ae90cf8c28c59d1421ed1dcaf7f286c56876ca33ac295abd337834c58ac0a9ebe12c28020130fadc02e5d49b754764dbd8070c83ffdfb73cbc9bd161bcbb03"}' ========= master_lag: 1.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-05-31 08:46:54.809374) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:46:54.809778) ========= 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.DfTyxItGVx/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.Pm9QHR2Wld/Listeners TMPDIR=/var/folders/x7/246bjm1d2c1fylzrbln074gc00000w/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.005461 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 08:46:54.858640) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 08:46:54.858967) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 08:46:54.859308) ========= ========= Total master_lag: 2.11 =========