builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0066 starttime: 1464027099.56 results: success (0) buildid: 20160522193137 builduid: c858fb39af334cefa248ec2a3b22030a revision: 2e8129bd6590404ff56ea35ac30d719cf7f566ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.558605) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.558973) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.559244) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.671356) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:39.671622) ========= 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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-23 11:11:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 28.2M=0s 2016-05-23 11:11:40 (28.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.404526 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:40.099529) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:40.099809) ========= 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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.027118 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:40.148179) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 11:11:40.148540) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2e8129bd6590404ff56ea35ac30d719cf7f566ae --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-beta --rev 2e8129bd6590404ff56ea35ac30d719cf7f566ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-23 11:11:40,222 truncating revision to first 12 chars 2016-05-23 11:11:40,222 Setting DEBUG logging. 2016-05-23 11:11:40,222 attempt 1/10 2016-05-23 11:11:40,222 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2e8129bd6590?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 11:11:41,434 unpacking tar archive at: mozilla-beta-2e8129bd6590/testing/mozharness/ program finished with exit code 0 elapsedTime=1.459443 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 11:11:41.625649) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.625934) ========= script_repo_revision: 2e8129bd6590404ff56ea35ac30d719cf7f566ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.626303) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.626581) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 11:11:41.641044) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 28 secs) (at 2016-05-23 11:11:41.641330) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:11:41 INFO - MultiFileLogger online at 20160523 11:11:41 in /builds/slave/test 11:11:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 11:11:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:11:41 INFO - {'append_to_log': False, 11:11:41 INFO - 'base_work_dir': '/builds/slave/test', 11:11:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 11:11:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:11:41 INFO - 'branch': 'Mozilla-Beta', 11:11:41 INFO - 'buildbot_json_path': 'buildprops.json', 11:11:41 INFO - 'config_files': ('talos/mac_config.py',), 11:11:41 INFO - 'default_actions': ('clobber', 11:11:41 INFO - 'read-buildbot-config', 11:11:41 INFO - 'download-and-extract', 11:11:41 INFO - 'populate-webroot', 11:11:41 INFO - 'create-virtualenv', 11:11:41 INFO - 'install', 11:11:41 INFO - 'run-tests'), 11:11:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:11:41 INFO - 'download_minidump_stackwalk': True, 11:11:41 INFO - 'download_symbols': 'ondemand', 11:11:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:11:41 INFO - 'tooltool.py': '/tools/tooltool.py', 11:11:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:11:41 INFO - '/tools/misc-python/virtualenv.py')}, 11:11:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:11:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:11:41 INFO - 'installer_path': 'installer.exe', 11:11:41 INFO - 'log_level': 'info', 11:11:41 INFO - 'log_name': 'talos', 11:11:41 INFO - 'log_to_console': True, 11:11:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:11:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:11:41 INFO - 'opt_config_files': (), 11:11:41 INFO - 'pip_index': False, 11:11:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:41 INFO - 'cmd': ('bash', 11:11:41 INFO - '-c', 11:11:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:41 INFO - 'enabled': True, 11:11:41 INFO - 'halt_on_failure': False, 11:11:41 INFO - 'name': 'check_screen_resolution'},), 11:11:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:11:41 INFO - 'cmd': ('bash', 11:11:41 INFO - '-c', 11:11:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:11:41 INFO - 'enabled': True, 11:11:41 INFO - 'halt_on_failure': False, 11:11:41 INFO - 'name': 'check_screen_resolution'},), 11:11:41 INFO - 'run_cmd_checks_enabled': True, 11:11:41 INFO - 'sps_profile': False, 11:11:41 INFO - 'sps_profile_interval': 0, 11:11:41 INFO - 'suite': 'dromaeojs-e10s', 11:11:41 INFO - 'system_bits': '32', 11:11:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:11:41 INFO - 'title': 't-yosemite-r7-0066', 11:11:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:11:41 INFO - 'use_talos_json': True, 11:11:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:11:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:11:41 INFO - 'work_dir': 'build'} 11:11:41 INFO - ##### 11:11:41 INFO - ##### Running clobber step. 11:11:41 INFO - ##### 11:11:41 INFO - Running pre-action listener: _resource_record_pre_action 11:11:41 INFO - Running main action method: clobber 11:11:41 INFO - rmtree: /builds/slave/test/build 11:11:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:11:43 INFO - Running post-action listener: _resource_record_post_action 11:11:43 INFO - ##### 11:11:43 INFO - ##### Running read-buildbot-config step. 11:11:43 INFO - ##### 11:11:43 INFO - Running pre-action listener: _resource_record_pre_action 11:11:43 INFO - Running main action method: read_buildbot_config 11:11:43 INFO - Using buildbot properties: 11:11:43 INFO - { 11:11:43 INFO - "project": "", 11:11:43 INFO - "product": "firefox", 11:11:43 INFO - "script_repo_revision": "production", 11:11:43 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 11:11:43 INFO - "repository": "", 11:11:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 11:11:43 INFO - "buildid": "20160522193137", 11:11:43 INFO - "pgo_build": "False", 11:11:43 INFO - "basedir": "/builds/slave/test", 11:11:43 INFO - "buildnumber": 86, 11:11:43 INFO - "slavename": "t-yosemite-r7-0066", 11:11:43 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:11:43 INFO - "platform": "yosemite_r7", 11:11:43 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 11:11:43 INFO - "branch": "mozilla-beta", 11:11:43 INFO - "repo_path": "releases/mozilla-beta", 11:11:43 INFO - "revision": "2e8129bd6590404ff56ea35ac30d719cf7f566ae", 11:11:43 INFO - "stage_platform": "macosx64", 11:11:43 INFO - "builduid": "c858fb39af334cefa248ec2a3b22030a", 11:11:43 INFO - "slavebuilddir": "test" 11:11:43 INFO - } 11:11:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 11:11:43 INFO - Running post-action listener: _resource_record_post_action 11:11:43 INFO - ##### 11:11:43 INFO - ##### Running download-and-extract step. 11:11:43 INFO - ##### 11:11:43 INFO - Running pre-action listener: _resource_record_pre_action 11:11:43 INFO - Running main action method: download_and_extract 11:11:43 INFO - mkdir: /builds/slave/test/build/tests 11:11:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:11:43 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:11:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 11:11:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 11:11:55 INFO - Downloaded 1241 bytes. 11:11:55 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 11:11:55 INFO - Using the following test package requirements: 11:11:55 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:11:55 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 11:11:55 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 11:11:55 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 11:11:55 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 11:11:55 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:11:55 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 11:11:55 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 11:11:55 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 11:11:55 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 11:11:55 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 11:11:55 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 11:11:55 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 11:11:55 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')]} 11:11:55 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:11:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:11:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:11:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 11:11:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 11:12:25 INFO - retry: attempt #1 caught exception: '' 11:12:25 INFO - retry: Failed, sleeping 30 seconds before retrying 11:12:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #2 11:13:25 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip: timed out 11:13:25 INFO - retry: attempt #2 caught exception: timed out 11:13:25 INFO - retry: Failed, sleeping 60 seconds before retrying 11:14:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #3 11:14:26 INFO - Downloaded 17747767 bytes. 11:14:26 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:26 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:14:29 INFO - Return code: 0 11:14:29 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 11:14:29 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')]} 11:14:29 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:14:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 11:14:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 11:14:31 INFO - Downloaded 17747767 bytes. 11:14:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:14:34 INFO - Return code: 0 11:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:34 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:14:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 11:14:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 11:14:35 INFO - Downloaded 10845194 bytes. 11:14:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:14:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:14:35 INFO - Return code: 0 11:14:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:35 INFO - https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 11:14:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 11:14:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 11:14:38 INFO - Downloaded 88423479 bytes. 11:14:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:38 INFO - mkdir: /builds/slave/test/properties 11:14:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:14:38 INFO - Writing to file /builds/slave/test/properties/build_url 11:14:38 INFO - Contents: 11:14:38 INFO - build_url:https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 11:14:39 INFO - Running post-action listener: _resource_record_post_action 11:14:39 INFO - Running post-action listener: set_extra_try_arguments 11:14:39 INFO - ##### 11:14:39 INFO - ##### Running populate-webroot step. 11:14:39 INFO - ##### 11:14:39 INFO - Running pre-action listener: _resource_record_pre_action 11:14:39 INFO - Running main action method: populate_webroot 11:14:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:14:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:14:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:14:39 INFO - '--tppagecycles', 11:14:39 INFO - '7'], 11:14:39 INFO - 'tests': ['tsvgm']}}, 11:14:39 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 11:14:39 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:14:39 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 11:14:39 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:14:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:14:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'tests': ['damp', 'tps']}, 11:14:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['damp', 'tps']}, 11:14:39 INFO - 'g3': {'tests': ['dromaeo_dom']}, 11:14:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:14:39 INFO - 'other': {'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'other-e10s': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'other_l64': {'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'other_nol64': {'tests': ['a11yr', 11:14:39 INFO - 'ts_paint', 11:14:39 INFO - 'tpaint', 11:14:39 INFO - 'sessionrestore', 11:14:39 INFO - 'sessionrestore_no_auto_restore', 11:14:39 INFO - 'tabpaint']}, 11:14:39 INFO - 'svgr': {'tests': ['tsvgx', 11:14:39 INFO - 'tsvgr_opacity', 11:14:39 INFO - 'tart', 11:14:39 INFO - 'tscrollx', 11:14:39 INFO - 'cart']}, 11:14:39 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['tsvgx', 11:14:39 INFO - 'tsvgr_opacity', 11:14:39 INFO - 'tart', 11:14:39 INFO - 'tscrollx', 11:14:39 INFO - 'cart']}, 11:14:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'tests': ['tp5o']}, 11:14:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'talos_options': ['--e10s'], 11:14:39 INFO - 'tests': ['tp5o']}, 11:14:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'talos_options': ['--xperf_path', 11:14:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:39 INFO - 'tests': ['tp5n']}, 11:14:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:14:39 INFO - 'talos_options': ['--e10s', 11:14:39 INFO - '--xperf_path', 11:14:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:14:39 INFO - 'tests': ['tp5n']}}, 11:14:39 INFO - 'talos.zip': {'path': '', 11:14:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:14:39 INFO - Running post-action listener: _resource_record_post_action 11:14:39 INFO - ##### 11:14:39 INFO - ##### Running create-virtualenv step. 11:14:39 INFO - ##### 11:14:39 INFO - Running pre-action listener: _resource_record_pre_action 11:14:39 INFO - Running main action method: create_virtualenv 11:14:39 INFO - Creating virtualenv /builds/slave/test/build/venv 11:14:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:14:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:14:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:14:39 INFO - Using real prefix '/tools/python27' 11:14:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:14:40 INFO - Installing distribute.............................................................................................................................................................................................done. 11:14:43 INFO - Installing pip.................done. 11:14:43 INFO - Return code: 0 11:14:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:43 INFO - 'HOME': '/Users/cltbld', 11:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:43 INFO - 'LOGNAME': 'cltbld', 11:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:43 INFO - 'MOZ_NO_REMOTE': '1', 11:14:43 INFO - 'NO_EM_RESTART': '1', 11:14:43 INFO - 'PAGER': '/bin/cat', 11:14:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:43 INFO - 'PWD': '/builds/slave/test', 11:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:43 INFO - 'SHELL': '/bin/bash', 11:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:14:43 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:43 INFO - 'USER': 'cltbld', 11:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:43 INFO - 'XPC_FLAGS': '0x0', 11:14:43 INFO - 'XPC_SERVICE_NAME': '0', 11:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:43 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:14:43 INFO - Cleaning up... 11:14:43 INFO - Return code: 0 11:14:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 11:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:14:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 11:14:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 11:14:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:14:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:43 INFO - 'HOME': '/Users/cltbld', 11:14:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:43 INFO - 'LOGNAME': 'cltbld', 11:14:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:43 INFO - 'MOZ_NO_REMOTE': '1', 11:14:43 INFO - 'NO_EM_RESTART': '1', 11:14:43 INFO - 'PAGER': '/bin/cat', 11:14:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:43 INFO - 'PWD': '/builds/slave/test', 11:14:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:43 INFO - 'SHELL': '/bin/bash', 11:14:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:14:43 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:14:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:43 INFO - 'USER': 'cltbld', 11:14:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:43 INFO - 'XPC_FLAGS': '0x0', 11:14:43 INFO - 'XPC_SERVICE_NAME': '0', 11:14:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:43 INFO - Downloading/unpacking psutil>=0.7.1 11:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:14:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:14:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:14:46 INFO - Installing collected packages: psutil 11:14:46 INFO - Running setup.py install for psutil 11:14:46 INFO - building 'psutil._psutil_osx' extension 11:14:46 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 11:14:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:14:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:14:47 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:14:47 INFO - building 'psutil._psutil_posix' extension 11:14:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:14:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:14:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:14:47 INFO - ^ 11:14:47 INFO - 1 warning generated. 11:14:47 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 11:14:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:14:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:14:48 INFO - Successfully installed psutil 11:14:48 INFO - Cleaning up... 11:14:48 INFO - Return code: 0 11:14:48 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 11:14: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')]} 11:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14: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')]} 11:14:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:14:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 11:14:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 11:14:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:14:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:48 INFO - 'HOME': '/Users/cltbld', 11:14:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:48 INFO - 'LOGNAME': 'cltbld', 11:14:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:48 INFO - 'MOZ_NO_REMOTE': '1', 11:14:48 INFO - 'NO_EM_RESTART': '1', 11:14:48 INFO - 'PAGER': '/bin/cat', 11:14:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:48 INFO - 'PWD': '/builds/slave/test', 11:14:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:48 INFO - 'SHELL': '/bin/bash', 11:14:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:14:48 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:14:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:48 INFO - 'USER': 'cltbld', 11:14:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:48 INFO - 'XPC_FLAGS': '0x0', 11:14:48 INFO - 'XPC_SERVICE_NAME': '0', 11:14:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 11:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 11:14:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:14:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 11:14:50 INFO - Installing collected packages: mozsystemmonitor 11:14:50 INFO - Running setup.py install for mozsystemmonitor 11:14:50 INFO - Successfully installed mozsystemmonitor 11:14:50 INFO - Cleaning up... 11:14:51 INFO - Return code: 0 11:14:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:14:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:14:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:14:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:14:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:14:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:51 INFO - 'HOME': '/Users/cltbld', 11:14:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:51 INFO - 'LOGNAME': 'cltbld', 11:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:51 INFO - 'MOZ_NO_REMOTE': '1', 11:14:51 INFO - 'NO_EM_RESTART': '1', 11:14:51 INFO - 'PAGER': '/bin/cat', 11:14:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:51 INFO - 'PWD': '/builds/slave/test', 11:14:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:51 INFO - 'SHELL': '/bin/bash', 11:14:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:14:51 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:14:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:51 INFO - 'USER': 'cltbld', 11:14:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:51 INFO - 'XPC_FLAGS': '0x0', 11:14:51 INFO - 'XPC_SERVICE_NAME': '0', 11:14:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:51 INFO - Downloading/unpacking blobuploader==1.2.4 11:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:53 INFO - Downloading blobuploader-1.2.4.tar.gz 11:14:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:14:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:14:53 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 11:14:53 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 11:14:53 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 11:14:53 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 11:14:53 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 11:14:53 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 11:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:14:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:14:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:14:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:14:54 INFO - Downloading docopt-0.6.1.tar.gz 11:14:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:14:54 INFO - Installing collected packages: blobuploader, requests, docopt 11:14:54 INFO - Running setup.py install for blobuploader 11:14:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:14:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:14:55 INFO - Running setup.py install for requests 11:14:55 INFO - Running setup.py install for docopt 11:14:55 INFO - Successfully installed blobuploader requests docopt 11:14:55 INFO - Cleaning up... 11:14:55 INFO - Return code: 0 11:14:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:14:55 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')]} 11:14:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:14:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:55 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')]} 11:14:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:14:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:14:55 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 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:14:55 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 11:14:55 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 11:14:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:14:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:14:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:14:55 INFO - 'HOME': '/Users/cltbld', 11:14:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:14:55 INFO - 'LOGNAME': 'cltbld', 11:14:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:14:55 INFO - 'MOZ_NO_REMOTE': '1', 11:14:55 INFO - 'NO_EM_RESTART': '1', 11:14:55 INFO - 'PAGER': '/bin/cat', 11:14:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:14:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:14:55 INFO - 'PWD': '/builds/slave/test', 11:14:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:14:55 INFO - 'SHELL': '/bin/bash', 11:14:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:14:55 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:14:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:14:55 INFO - 'USER': 'cltbld', 11:14:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:14:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:14:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:14:55 INFO - 'XPC_FLAGS': '0x0', 11:14:55 INFO - 'XPC_SERVICE_NAME': '0', 11:14:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:14:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-cV8Etw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-2ZWNUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Arv0lb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-zMGeCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Pw9Wox-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-jZRnZT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-KnKt_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-GpnK1H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-gv802q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:14:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:14:56 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-q6eO15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-XnxsFE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-2pKnuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-wPax2p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-aqGIIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-ekTAf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-fS6xMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:14:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:14:57 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-5x0ESd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:14:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:14:57 INFO - Running setup.py install for manifestparser 11:14:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:14:57 INFO - Running setup.py install for mozcrash 11:14:58 INFO - Running setup.py install for mozdebug 11:14:58 INFO - Running setup.py install for mozdevice 11:14:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Running setup.py install for mozfile 11:14:58 INFO - Running setup.py install for mozhttpd 11:14:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Running setup.py install for mozinfo 11:14:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Running setup.py install for mozInstall 11:14:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:14:58 INFO - Running setup.py install for mozleak 11:14:59 INFO - Running setup.py install for mozlog 11:14:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Running setup.py install for moznetwork 11:14:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Running setup.py install for mozprocess 11:14:59 INFO - Running setup.py install for mozprofile 11:14:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Running setup.py install for mozrunner 11:14:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:14:59 INFO - Running setup.py install for mozscreenshot 11:15:00 INFO - Running setup.py install for moztest 11:15:00 INFO - Running setup.py install for mozversion 11:15:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:15:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:15:00 INFO - Cleaning up... 11:15:00 INFO - Return code: 0 11:15:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15: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')]} 11:15:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15: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')]} 11:15:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:00 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 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:15:00 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 11:15:00 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 11:15:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:15:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:00 INFO - 'HOME': '/Users/cltbld', 11:15:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:00 INFO - 'LOGNAME': 'cltbld', 11:15:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:00 INFO - 'MOZ_NO_REMOTE': '1', 11:15:00 INFO - 'NO_EM_RESTART': '1', 11:15:00 INFO - 'PAGER': '/bin/cat', 11:15:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:00 INFO - 'PWD': '/builds/slave/test', 11:15:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:00 INFO - 'SHELL': '/bin/bash', 11:15:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:15:00 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:15:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:00 INFO - 'USER': 'cltbld', 11:15:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:00 INFO - 'XPC_FLAGS': '0x0', 11:15:00 INFO - 'XPC_SERVICE_NAME': '0', 11:15:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:15:00 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-GPG_9v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:15:00 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)) 11:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:15:00 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Wm44y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:15:00 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)) 11:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:15:00 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-4FMUdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:15:00 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)) 11:15:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:15:00 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-rigV5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-r6JRpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-sY2F_v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:15:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-DexDKo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-lhg3Ok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-Pncudn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-nai2UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-bd68Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-7q3aCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:15:01 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)) 11:15:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:15:01 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-0JcX12-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:15:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-rJI5mb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-z6bq2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:15:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-bBdBCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:15:02 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:15:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:15:02 INFO - Running setup.py (path:/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/pip-QIIKfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:15:02 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)) 11:15:02 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)) 11:15:02 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)) 11:15:02 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)) 11:15:02 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)) 11:15:02 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)) 11:15:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:15:02 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 11:15:02 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 11:15:02 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 11:15:02 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 11:15:02 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 11:15:02 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 11:15:04 INFO - Downloading blessings-1.6.tar.gz 11:15:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:15:04 INFO - Installing collected packages: blessings 11:15:04 INFO - Running setup.py install for blessings 11:15:05 INFO - Successfully installed blessings 11:15:05 INFO - Cleaning up... 11:15:05 INFO - Return code: 0 11:15:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:15:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:05 INFO - Reading from file tmpfile_stdout 11:15:05 INFO - Current package versions: 11:15:05 INFO - blessings == 1.6 11:15:05 INFO - blobuploader == 1.2.4 11:15:05 INFO - docopt == 0.6.1 11:15:05 INFO - manifestparser == 1.1 11:15:05 INFO - mozInstall == 1.12 11:15:05 INFO - mozcrash == 0.17 11:15:05 INFO - mozdebug == 0.1 11:15:05 INFO - mozdevice == 0.48 11:15:05 INFO - mozfile == 1.2 11:15:05 INFO - mozhttpd == 0.7 11:15:05 INFO - mozinfo == 0.9 11:15:05 INFO - mozleak == 0.1 11:15:05 INFO - mozlog == 3.1 11:15:05 INFO - moznetwork == 0.27 11:15:05 INFO - mozprocess == 0.22 11:15:05 INFO - mozprofile == 0.28 11:15:05 INFO - mozrunner == 6.11 11:15:05 INFO - mozscreenshot == 0.1 11:15:05 INFO - mozsystemmonitor == 0.0 11:15:05 INFO - moztest == 0.7 11:15:05 INFO - mozversion == 1.4 11:15:05 INFO - psutil == 3.1.1 11:15:05 INFO - requests == 1.2.3 11:15:05 INFO - wsgiref == 0.1.2 11:15:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:05 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 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:15:05 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 11:15:05 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 11:15:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:15:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:05 INFO - 'HOME': '/Users/cltbld', 11:15:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:05 INFO - 'LOGNAME': 'cltbld', 11:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:05 INFO - 'MOZ_NO_REMOTE': '1', 11:15:05 INFO - 'NO_EM_RESTART': '1', 11:15:05 INFO - 'PAGER': '/bin/cat', 11:15:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:05 INFO - 'PWD': '/builds/slave/test', 11:15:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:05 INFO - 'SHELL': '/bin/bash', 11:15:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:15:05 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:15:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:05 INFO - 'USER': 'cltbld', 11:15:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:05 INFO - 'XPC_FLAGS': '0x0', 11:15:05 INFO - 'XPC_SERVICE_NAME': '0', 11:15:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 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)) 11:15:05 INFO - Cleaning up... 11:15:05 INFO - Return code: 0 11:15:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:15:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:15:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:15:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:15:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:05 INFO - 'HOME': '/Users/cltbld', 11:15:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:05 INFO - 'LOGNAME': 'cltbld', 11:15:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:05 INFO - 'MOZ_NO_REMOTE': '1', 11:15:05 INFO - 'NO_EM_RESTART': '1', 11:15:05 INFO - 'PAGER': '/bin/cat', 11:15:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:05 INFO - 'PWD': '/builds/slave/test', 11:15:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:05 INFO - 'SHELL': '/bin/bash', 11:15:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:15:05 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:15:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:05 INFO - 'USER': 'cltbld', 11:15:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:05 INFO - 'XPC_FLAGS': '0x0', 11:15:05 INFO - 'XPC_SERVICE_NAME': '0', 11:15:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:06 INFO - Downloading/unpacking jsonschema 11:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:15:08 INFO - Downloading/unpacking functools32 (from jsonschema) 11:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:08 INFO - Downloading functools32-3.2.3-2.tar.gz 11:15:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:15:09 INFO - warning: no files found matching '*.txt' 11:15:09 INFO - no previously-included directories found matching 'build' 11:15:09 INFO - no previously-included directories found matching 'dist' 11:15:09 INFO - no previously-included directories found matching '.git*' 11:15:09 INFO - Installing collected packages: jsonschema, functools32 11:15:09 INFO - Running setup.py install for functools32 11:15:09 INFO - warning: no files found matching '*.txt' 11:15:09 INFO - no previously-included directories found matching 'build' 11:15:09 INFO - no previously-included directories found matching 'dist' 11:15:09 INFO - no previously-included directories found matching '.git*' 11:15:09 INFO - Successfully installed jsonschema functools32 11:15:09 INFO - Cleaning up... 11:15:09 INFO - Return code: 0 11:15:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:15:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:15:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:15:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:15:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109aada30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109ab44c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fae714dd400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, '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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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 11:15:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 11:15:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 11:15:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:15:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:09 INFO - 'HOME': '/Users/cltbld', 11:15:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:09 INFO - 'LOGNAME': 'cltbld', 11:15:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:09 INFO - 'MOZ_NO_REMOTE': '1', 11:15:09 INFO - 'NO_EM_RESTART': '1', 11:15:09 INFO - 'PAGER': '/bin/cat', 11:15:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:09 INFO - 'PWD': '/builds/slave/test', 11:15:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:09 INFO - 'SHELL': '/bin/bash', 11:15:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:15:09 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:15:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:09 INFO - 'USER': 'cltbld', 11:15:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:09 INFO - 'XPC_FLAGS': '0x0', 11:15:09 INFO - 'XPC_SERVICE_NAME': '0', 11:15:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:15:09 INFO - Downloading/unpacking flake8 11:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:15:12 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:15:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:15:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:15:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:15:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:15:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:15:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:15:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:15:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:15:13 INFO - no previously-included directories found matching 'docs/_build' 11:15:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:15:13 INFO - Running setup.py install for pep8 11:15:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:15:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:15:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:15:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:15:13 INFO - no previously-included directories found matching 'docs/_build' 11:15:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:15:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:15:13 INFO - Cleaning up... 11:15:13 INFO - Return code: 0 11:15:13 INFO - Running post-action listener: _resource_record_post_action 11:15:13 INFO - Running post-action listener: _start_resource_monitoring 11:15:13 INFO - Starting resource monitoring. 11:15:13 INFO - ##### 11:15:13 INFO - ##### Running install step. 11:15:13 INFO - ##### 11:15:13 INFO - Running pre-action listener: _resource_record_pre_action 11:15:13 INFO - Running main action method: install 11:15:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:15:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:15:14 INFO - Reading from file tmpfile_stdout 11:15:14 INFO - Detecting whether we're running mozinstall >=1.0... 11:15:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:15:14 INFO - Reading from file tmpfile_stdout 11:15:14 INFO - Output received: 11:15:14 INFO - Usage: mozinstall [options] installer 11:15:14 INFO - Options: 11:15:14 INFO - -h, --help show this help message and exit 11:15:14 INFO - -d DEST, --destination=DEST 11:15:14 INFO - Directory to install application into. [default: 11:15:14 INFO - "/builds/slave/test"] 11:15:14 INFO - --app=APP Application being installed. [default: firefox] 11:15:14 INFO - mkdir: /builds/slave/test/build/application 11:15:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:15:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 11:15:36 INFO - Reading from file tmpfile_stdout 11:15:36 INFO - Output received: 11:15:36 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 11:15:36 INFO - Running post-action listener: _resource_record_post_action 11:15:36 INFO - ##### 11:15:36 INFO - ##### Running run-tests step. 11:15:36 INFO - ##### 11:15:36 INFO - Running pre-action listener: _resource_record_pre_action 11:15:36 INFO - Running main action method: run_tests 11:15:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:15:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:15:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:15:36 INFO - 2016-05-23 11:15:36.770 screenresolution[1651:9765] starting screenresolution argv=screenresolution get 11:15:36 INFO - 2016-05-23 11:15:36.772 screenresolution[1651:9765] Display 0: 1600x1200x32@60 11:15:36 INFO - 2016-05-23 11:15:36.778 screenresolution[1652:9767] starting screenresolution argv=screenresolution list 11:15:36 INFO - Available Modes on Display 0 11:15:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:15:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:15:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:15:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:15:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:15:37 INFO - Intel Iris: 11:15:37 INFO - Chipset Model: Intel Iris 11:15:37 INFO - Type: GPU 11:15:37 INFO - Bus: Built-In 11:15:37 INFO - VRAM (Dynamic, Max): 1536 MB 11:15:37 INFO - Vendor: Intel (0x8086) 11:15:37 INFO - Device ID: 0x0a2e 11:15:37 INFO - Revision ID: 0x0009 11:15:37 INFO - Displays: 11:15:37 INFO - Display: 11:15:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:15:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:15:37 INFO - Display Serial Number: 1600x1200 60 11:15:37 INFO - Main Display: Yes 11:15:37 INFO - Mirror: Off 11:15:37 INFO - Online: Yes 11:15:37 INFO - Rotation: Supported 11:15:37 INFO - Return code: 0 11:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:15:37 INFO - Python 2.7.3 11:15:37 INFO - Return code: 0 11:15:37 INFO - grabbing minidump binary from tooltool 11:15:37 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')]} 11:15:37 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 0x109951d30>, '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 0x1097b5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109948b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:15:37 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 11:15:37 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 11:15:37 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:15:37 INFO - Return code: 0 11:15:37 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:15:37 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:15:37 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:15:37 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:15:37 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:15:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:15:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:15:38 INFO - Return code: 0 11:15:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:15:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0066 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:15:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GqqekmVBxa/Render', 11:15:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:15:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:15:38 INFO - 'HOME': '/Users/cltbld', 11:15:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:15:38 INFO - 'LOGNAME': 'cltbld', 11:15:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:15:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:15:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:15:38 INFO - 'MOZ_NO_REMOTE': '1', 11:15:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:15:38 INFO - 'NO_EM_RESTART': '1', 11:15:38 INFO - 'PAGER': '/bin/cat', 11:15:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:15:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:15:38 INFO - 'PWD': '/builds/slave/test', 11:15:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:15:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:15:38 INFO - 'SHELL': '/bin/bash', 11:15:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lm2QXa4c5e/Listeners', 11:15:38 INFO - 'TMPDIR': '/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/', 11:15:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:15:38 INFO - 'USER': 'cltbld', 11:15:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:15:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:15:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:15:38 INFO - 'XPC_FLAGS': '0x0', 11:15:38 INFO - 'XPC_SERVICE_NAME': '0', 11:15:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:15:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0066', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:15:38 INFO - mozversion application_buildid: 20160522193137 11:15:38 INFO - mozversion application_changeset: 2e8129bd6590404ff56ea35ac30d719cf7f566ae 11:15:38 INFO - mozversion application_display_name: Firefox 11:15:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:15:38 INFO - mozversion application_name: Firefox 11:15:38 INFO - mozversion application_remotingname: firefox 11:15:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 11:15:38 INFO - mozversion application_vendor: Mozilla 11:15:38 INFO - mozversion application_version: 47.0 11:15:38 INFO - mozversion platform_buildid: 20160522193137 11:15:38 INFO - mozversion platform_changeset: 2e8129bd6590404ff56ea35ac30d719cf7f566ae 11:15:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 11:15:38 INFO - mozversion platform_version: 47.0 11:15:38 INFO - using testdate: 1464027338 11:15:38 INFO - actual date: 1464027338 11:15:38 INFO - starting webserver on 'localhost:49281' 11:15:38 INFO - SUITE-START | Running 2 tests 11:15:38 INFO - TEST-START | dromaeo_css 11:15:38 INFO - operating with platform_type : mac_ 11:15:38 INFO - Initialising browser for dromaeo_css test... 11:15:38 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpMScf_B/profile http://localhost:49281/getInfo.html) 11:15:41 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 11:15:41 INFO - PROCESS | 1670 | colorDepth:24 11:15:41 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 11:15:41 INFO - PROCESS | 1670 | __metrics 11:15:41 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 11:15:42 INFO - TEST-INFO | 1670: exit 0 11:15:42 INFO - Browser initialized. 11:15:42 INFO - Running cycle 1/1 for dromaeo_css test... 11:15:42 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpMScf_B/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:15:53 INFO - PROCESS | 1675 | RSS: Main: 167862272 11:15:53 INFO - PROCESS | 1675 | 11:16:43 INFO - PROCESS | 1675 | 1464027403561 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] 11:18:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 11:18:54 INFO - PROCESS | 1675 | RSS: Main: 178925568 11:18:54 INFO - PROCESS | 1675 | 11:21:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 11:21:57 INFO - PROCESS | 1675 | RSS: Main: 189632512 11:21:57 INFO - PROCESS | 1675 | 11:24:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 11:24:59 INFO - PROCESS | 1675 | RSS: Main: 192229376 11:24:59 INFO - PROCESS | 1675 | 11:28:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 11:28:01 INFO - PROCESS | 1675 | RSS: Main: 194977792 11:28:01 INFO - PROCESS | 1675 | 11:31:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 11:31:05 INFO - PROCESS | 1675 | RSS: Main: 194080768 11:31:05 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 11:34:07 INFO - PROCESS | 1675 | RSS: Main: 199442432 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | __start_tp_report 11:34:07 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 11:34:07 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 11:34:07 INFO - PROCESS | 1675 | |i|pagename|runs| 11:34:07 INFO - PROCESS | 1675 | |0;dojo.html;3603.207294395232;3648.689861361784;3661.9450708239365;3692.8153592320396;3697.2051009033053;5936.465718085371;5952.107183922414;5970.402959704022;5990;5999.430054144855;4858.979614280996;5978.402159784019;6008.279006519213;6018.8796224075495;6064.240770449446;5273.604479664031;5359.490848369399;5412.783488660449;5441;5548.58385621078;5321.946780532184;5462.3445186577665;5463.890722185554;5467.9453205468035;5479.068558345071;5263.631545791796;5276.287701160351;5293.947060529403;5297.17893726473;5404.83785486436;5452.863678408032;5477.99999999998;5483.7806487740845;5486.890262194774;5514.393416724157;5325.467453254682;5363.034653762321;5389.407165211824;5397.865053373658;5421.756020979065;5680.573956953203;5732.656040637575;5772.538196944276;5850.736716847752;6038.486728628071;6243.094751261087;6252.406021427957;6259.687015649245;6276;6284.27730810958;6363.522735794788;6370.948793449075;6376.107344971708;6388.55280130391;6416.358364163546;5949;6071.907056729787;6126.27339965311;6127.325994140641;6137.7447333336595;6419.614823110628;6450.580712253689;6451.387118223761;6478.481721462272;6503.2521260054755;5927.585069045125;6214.844628884223;6225.688715564203;6246.063090536456;6247.562670613013;5113.360829896263;6187.566870319124;6207.782727604512;6245.687715614292;6266.968665156736;6221.751129954841;6237.500999919996;6277.24673039229;6282.623042617366;6313.9368606314265;6148.508119350441;6217.067439883963;6281.466075383552;6288.717007734663;6289.622622642656;4929.827456039093;5035.823746168867;5044.167712327425;5045.621578381636;5091.930694554111;4511.142882852248;4550.817967281338;4551.112533056023;4551.613112885376;4569.086182763461;6035.064564992434;6091.269047714302;6130.724117414727;6140.048292514667;6186.505079593621;6248.875022499525;6339.7781077662985;6369.20384951881;6420.486361091195;6431.710573024225;6135.754569817157;6158;6165;6179.3820617938745;6198.752049917953;5465.863353416117;5562.582806289544;5568.99758043548;5588.161775733673;5614.63504872182;5412;5587.972060139755;6297.874042519215;6657.434118099919;6664.666766661744;6405.391487808651;6415.1981002374705;6441.452476539546;6454.257760357619;6454.386833250834;5532;6382.648954307558;6538.934610653832;6608.306127856506;6677.799666010027;6266.467350275096;6294.24469063697;6294.779682711266;6295.559310848196;6300.2754683212015;6376.298607153302;6384.712687929147;6387.904181437375;6397;6399.9680001601555;6131.407250743203;6142.038385241405;6202.317745047951;6203.16257305258;6228.003519436868;6167.784127555514;6184.845378865564;6215.254169499689;6220.937790622036;6223.315435302204;5995.490383317467;6167.969160154171;6219.315875253809;6225.315215326401;6230.875382492415;6065.969670151621;6148.846278843064;6169.938300616936;6204.472619827185;6226.937730622636;6188.876222475616;6218.657973811484;6229.875402492015;6230.906536402048;6244.906326405198 11:34:07 INFO - PROCESS | 1675 | |1;ext.html;430.6124487960833;541.1044720986768;557.6263903184865;603.3001718007113;603.939606039396;39921;40494;40599.59400405999;40663;40689.593104068954;43255.56744432555;43767.12465750683;44139.89650258745;44247.557524424745;44265.33601995964;8378.706745263917;9812.215022798158;9908.653197138101;10016.899831001707;10024;8593.398462107654;9794.853077203812;9808.31341806074;9835.508224588742;9928.702138935843;9849.50752462374;9874.851877221847;9933.701988940344;9946.751331216741;9975.301728878982;9859.950700246489;9882.851757223647;9905.504724763769;9932.304738668296;9968.551415186297;9790.412575245437;9796.167325777245;9816.65641702537;9893.109620134222;9924.15644670204;40601.98495037618;40880.182396352204;41274.79362603198;41399.1720165598;41477.58522414797;3452.188735647121;3491.8428670709627;3536.098294934801;3602.67575918166;3631.400818864884;27289.181324560097;29434.264143396587;29474.263143421373;29583.7936654991;29860.552091718426;2812.1141840320233;2819.788515861298;2830.1651012951024;2868.153894601098;2876.8849246030136;452533;504961;535725;544642.999999996;545220;49161.26258106131;50045;75348.62325688337;76402;76457;41967.37048944207;46363.30455043177;46837.53162468332;48097;48173.7956551082;17116.572085697957;17674.293028278982;17791.46625601234;17851.018193999455;17880.642387152184;13950.23273719955;14073.788893166611;14242;14267.357968891425;14347;15548.455804046804;15663.843361566467;15988.76016859748;15993.920030399773;15994.520164395086;6779.3220677932795;6828.829279267959;6832.658367081626;6849.691763870538;6889.207741109736;944.3389627260945;995.1640621877658;1030.850526673628;1134.6936327191613;1222.8899399054128;30497.542536861965;30901.69098309033;30916.845415772776;31452.842735786173;31513.52729709056;1147.4320211495362;1168.8889555492215;1254.4919307680404;1272.7899896517156;1312.6587087357357;15716.842831571767;15802.60493487672;15895;15938.349502456496;16147.919260403858;525663;526997;531344;532008;533120;62224.06663900045;62335.37664623386;62441;62453.37546624566;62955.6852215736;1557.9844201558067;1570.7486802111607;1594.3781925049009;1601.135386891089;1601.639631082997;30325.545116823705;30426.616045559174;30585.388292233587;30858;31144.377112457172;55159.172612410046;55301;55335.72332138474;55494.44505554893;55674;499401;501804;502650;503129;503327;34706;35482.1129471755;35515.112122197155;35808.64191358053;35837.64162358343;47471.525284748095;48531;49026.019479609495;49278.014439711726;49635.503644964534;38686;39622;39800;40110.39834402428;40485.98785030398;38293.04267393338;38522;38980;39020.60979390283;39232.41151382789;38271.6172838268;38477.845664630106;38863.2227355457;39107.6089239104;39426;33061.17347066246;34749.82625086959;35084.47373289454;35102;35148.82425587856;26118.477630447665;26143.477130457664;26205;26232.8688356557;26657.067002654814 11:34:07 INFO - PROCESS | 1675 | |2;jquery.html;378.7538100234847;384.2200333323354;387.7440889013251;391.3288709862585;393.19787633228214;15562.766558501604;19670.90164549177;20418.89790551049;21447.463813404673;21548.245811396584;20835.687464688042;20880.791192088076;20917.267895623656;21072.789272107275;21400.785992140074;5369.570434365241;5891.793787217438;6102.786402475915;6152.446279834814;6170.290416602081;6028.668423236721;6113.480354169878;6117.051856962155;6148.815535533919;6154.26148862135;5756.453136951983;5943.108533719934;6097.634141951475;6141.355157708421;6176.382361763833;5052.520010599005;5994.730237139339;6028.608140470856;6107.847303817396;6147.170132032208;5546.833594992157;5626.465485778845;5641.548676105902;5735.627184233012;5784.4504772046585;13506.392212350467;15178.848211517965;22464.32607021792;22987.77012229873;24116.79416029209;2110.915563377463;2188.2997440818945;2274.56783211191;2279.0428020231543;2318.107528601484;9863.30956833022;10108.595656173744;10173.89826101737;13526.526571570046;13986.440542378292;1818.7817461904656;1833.8807977481554;1844.6757086071511;1849.6763066463316;1851.5000949743494;93405.59891601632;94254;98622.52066219013;98663;99109.99999999927;10967.287126336763;11612.361320127306;11644.708882278012;11708.765824683458;11880.524779008916;12931.741365172644;13284.73430531384;13658.77071063609;13894.652633684238;14220.431182752574;6599.822058720611;6755.088063111515;8356.080831108573;8854.114588541095;8889.73330800077;8945.23965462943;8988.910110898938;9047.77380565478;9061.864072038925;9105.635774568944;4046.736962097454;4486.214912390318;4558.521355257716;4561.407017087824;4609.308603709471;1934.6324198402258;1969;1982.5341044854447;2016.959660806805;2020.1515363547346;854.9526829633649;927.2813569483569;991.2268430624125;1026.43033116621;1110.7892397286996;19276.90361548183;19516.512087197934;19667.311644092675;20030.599388012448;20297.594048118954;925.5233554719325;1002.0380434782691;1022.4529876516177;1028.8919663435381;1054.7310435838813;8691.435056721293;8874.822503549893;9569.325368060701;9744.512774361254;10855.51150198243;654689;672970;675713;681756;684777;94748;128872;128991.35504322419;130299;134449.65550344568;890.8307421589877;958.6309270930602;1069.5026812532055;1076.4294923690456;1077.590515604081;14816.333265002886;18171.54571135732;18255.726164107815;18351.724724128882;18557.536061598028;37493.250134996604;47225.527744722116;49149.754251228514;49377.50622493729;54964.72517637386;65539.6723016401;66179;67135;67565;68292.31707683059;8041.316488098577;8111.837763244584;8138.837223255383;8145.959270203611;8437.662493500191;11855.525778968744;12610.117291789853;12711.682207944505;12761.872381276069;12775.297358645365;11245.325280483197;11479.25384849982;11500.827487587525;11525.711857203636;11549.88450115488;10933.56265749361;11111.888881111085;11222.438878055902;11464.25482343645;11750.118741094622;11332.093432525213;11464.25482343645;11520.193586448868;11903.464344104363;11929.164958452826;8149.918500814916;8161.510309381456;8224.136465671018;8264.628091736007;8456.365772567206;10039.996000399902;11021.228514003942;11155.44222788841;11250.606228781953;11515.596954106566 11:34:07 INFO - PROCESS | 1675 | |3;mootools.html;1483.829359623643;1665.159094657198;1962.5486138188217;2064.6696528555435;2080.074366906726;7579.6589153488085;8253.257206851382;8425.567653498905;9177.036411176821;9186.081391860818;8008.439409241356;8912.732618021453;8989.190972812445;9003.5048072356;9016.053314401976;2153.6231159547096;2533.5439620868283;2574.66529351185;2580.354911272182;2590.650262214596;2490.3151633300804;2501.1496091328945;2519.0805356045003;2524.343670645627;2528.4311030018284;2548.2864797856537;2555.514452254075;2578.0847799031353;2579.6001619748968;2584.095566551711;2451.3258853815255;2456.4227406559444;2460.0651752333997;2474.7525247475287;2483.627455881623;2579.161772423951;2603.570410882202;2608.282722251377;2624.8950041998296;2635.169921474729;8862.379633425662;8998.14517620825;8999;9026.097390260988;9068.455892646462;5617;5651.350094739117;5736.541076713895;5771.076627739583;5810.302763668386;3522.84147213376;3688.2623475305045;3767.020574650583;3769.9057523561855;3815.294170578424;1077.7036315013356;1080.475969154959;1105.309181761399;1106.0874778307927;1112.6940091474828;11899.464524096436;12041.217320874115;12114.21257618252;12231.082668799876;12256.019518438592;4989.525995030466;5041.571466425395;5195.558377537876;5202.933398653267;5212.374515058217;10374.688759337232;10453.581856725647;10500.632477863239;10556.36661800279;10593.84109238362;5735.512481439125;5763.7118144093465;5764.913526297109;5798.59409841307;5798.681072540966;4587.2201725706045;4945.035718034948;4991.550760431579;5046.1169295373165;5201;3763.9623603764157;3925.156091440354;3989.960100399017;4168.8332466701595;4220.282551966173;1365.9931700341435;1386.736520061185;1389.3400634698437;1390.7009992851586;1394.372532360442;1238.2013601227159;1249.956251531192;1271.173737070893;1424.5156646740159;1441.1209162410921;7346.375558077623;8316;8410.158984101663;8651.653933842701;8654.221120099095;1288.452407726731;1290.5481333499813;1299.3503248375812;1302.1080559816555;1327.157255142991;6434;6470.611763294218;6530.444912182517;6541.509386795914;6560.851850926068;10565.049145576935;10969.177311701495;10979.83530247047;11000.22998390121;11025;10614.840777388345;10740.731481712863;10758.031777140095;10827.67516974492;11068.667939961813;8574.871376929352;8594.52730099838;8602.87095693565;8630.136986301446;8740.562971851383;7999.520028798291;8130.186981301823;8161.673533058611;8213.95893020555;8245.340372770284;9152.4966126861;9293.814123717622;9579.616815327308;9743.9025609743;10011.199104071511;9471.384360016576;9831.950840245752;9836.311458197857;9838;9871.802563948537;7529;7621.466497345355;7623.161452240138;7679.884801728091;7783.649735761791;8270.627821747916;8274.669013239403;8297.377696672895;8428.157184281523;8437.62030708632;7555.206703296075;7696.8460630788195;7778.261065198797;7800;7817.687292508236;6686.829804784241;7555.206703296295;7572.507786993828;7679.19368466303;7777.844443111219;7893.565853878092;8000.4399692021;8046.637901294573;8081.353491720531;8087;7209.603471808891;7312.963435183003;7321.377682897013;7337.192908780136;7362.447816413897;7385.187629360874;7402.222766609429;7426.368758655575;7431;7440.665170067468 11:34:07 INFO - PROCESS | 1675 | |4;prototype.html;302.2142429682825;337.5932001937665;345.7389670798545;346.43703981030825;348.10710527496985;6016.789412370568;7005.089338386009;7048.295170482949;7052.0479735235795;7058.470614703899;6350.523710721703;6936.479764017707;6943.02797608334;6966.790996270107;6975.162980442341;4235.767032813194;4760.857174284768;5018.498150184971;5089.46560611136;5205.713685747282;5173.146430838907;5186.818461353853;5193.610479214055;5200.0379929712935;5202.531772140506;5091.439941606421;5126.897462050775;5129.846104616849;5130.845559749064;5138.100832354342;5126.307948426952;5128.230765385185;5144.09978253806;5144.382674079097;5154.819581314655;4452.910941781146;4462.44219472566;4463.598276155161;4477.037436951071;4485.237509623372;6513.088167656532;6684.431823295025;6707.463402927754;6763.695633696496;6777.220619628707;5962.761489540413;6024.126501657235;6035.792841431731;6038.939610603926;6040.697965101727;7482.700691972314;7526.059242594676;7532.0208372911175;7539.982102416213;7546.924530754677;4931.975340123312;4955.35580374549;4956.479569645171;4957.628177886673;4962.082014827269;6781.593104413701;7607.353374963135;7629.504082234637;7944.165862584403;7981.800454988614;5027.120253955543;5068.239764035424;5075.568576671024;5082.110630639697;5093.8726531836255;5162.6902385856965;5166.070107380632;5174.844754657366;5181.948180518147;5197.22041693749;6030.517558595302;6034.155218269446;6046.879062418727;6046.909296360558;6056.636601803906;6477.838054048687;7573.129090154593;7589.58257295854;7595.088589369311;7606.087269527692;1331.3010669398643;1694.144457049207;1731.5238309464746;1770.6193168468842;1776.005436955309;993.3096497934011;1169.2867350915935;1172.6807341740541;1182.1134149387958;1185.9347735874437;6993.195782484978;7038.683259253244;7120.715171393086;7143.392811611071;7154.391876690435;6927.718372101177;7807.336376407955;7811.414143939227;7829.921700782919;7836.177201393885;5473.808416705396;6391.552591318656;7213.8557228855125;7258.056452661226;7307.3058059484265;4143.875683729493;4182;4185.414042034117;4195.748255104703;4202.936955945664;8301.667933282723;8318.21158450936;8321.417500774887;8340.749777506684;8349.54077525742;6593.329701470912;7943.761687149395;8167.918320816834;8182.059063207688;8240.587970601446;8175.95912020448;8275.875861861956;8309.00291964968;8325.66697332112;8329.750107496784;7934.2859142677435;7950.171460563987;7977.242161994834;7983.640736166771;8012.759617211493;6283.7800676976085;7746.147923728498;7756.914032035406;7765.611719413893;7788.766337009899;6670.9332906670315;8026.8394632105865;8321.12628174033;8324.125966773401;8351.457155284887;4896.4124305083615;4919.704817710949;4941.530554597308;4941.728204948762;4951.455339912534;7833.09919359281;7845.254700803414;7854.450188486751;7857;7860.449768516149;7371.299726525972;7599.506032107895;7603.0876294844975;7608.353289970193;7640.885386719315;7560.924390756022;7574.924250757643;7602.1257555379625;7603.2776886195725;7625.389968802593;6021.277446706424;7362.77911662651;7400.703971841282;7433.145188303414;7443.144038435649;7533.246675332643;7578.280063393969;7584.658690359058;7585.544867307979;7618.847623047619;7172.318629730167;7412.369948554433;7431.033965584439;7465.328120469184;7480.962595186989 11:34:07 INFO - PROCESS | 1675 | |5;yui.html;790.960451977401;795.2326005404783;797.6490344248531;800.4036992440629;821.2075347289866;14794.038387504805;15701.450449234264;15903.761443578327;15921.52235432936;15946.601334966632;13351;14900.701985960275;14915;14946.40214391423;14977.700445991073;806.2904643913348;973.3186769261528;981.4601968917103;990.5542505872351;996.0587245053428;958.6596758150861;967.0281367225963;981.1316984468725;981.6269817469351;991.9107280344767;996.8255555277833;1023.3501726403713;1030.258214085857;1044.0446991003223;1056.2817284246748;981.1071924548697;998.2014388489216;1026.2251999740222;1026.558579810681;1038.9636362727306;665.7569986954727;678.5013015433652;755.561774170985;788.0071110400936;791.3866753266207;15393.23033848303;15586.532404027897;15618.68762624741;15654.765178522332;15682.529524114294;13593.456261749518;13594.32028398576;13709.862901370958;13715.794263086062;13723.519676811264;10103.949480252626;10169.898301016969;10251.589936402535;10293.279470437072;10492.16062714981;3615.4576813477925;3647.1611529348133;3657.487951686766;3663.9740872555726;3742.270257299828;86748;88993.11006889913;90910;91055.08944910599;91361;9811.166050885755;9903.851442228228;9934.552945117486;9943.055409736064;10125.493725313705;22714.886425567765;22912;23051.423714406938;23970.88014559916;23980.7601923982;7550.7734767957045;7856.371490280764;7884.211578842184;8029.197080291924;8092.757217283491;4161.646260067928;4251.829926802955;4270.466191726035;4284.700070995001;4482.148391805546;2957.639485836496;3265.2000259936467;3319.054069590155;3359.6976272135626;3374.0721301642243;664.2460806984108;670.3999920071606;673.4343151752552;676.7597502886503;694.9478789090839;562.9802956896489;589.0898561722149;610.991893180727;614.7264467312093;615.2340336281359;8612.18184272493;8673.653053877773;8762;8805.251553617885;8901.243394311427;615.4153554123601;620.3951147631113;623.1711823275033;626.0421555020826;632.7785275153751;3934.1738234970976;3977.423273625308;4037.354023356249;4058.3100872851687;4185;94639;95960.52019739857;97701;98205.50897245469;98929.01070989342;76644.61677691576;78385.21614783893;79212;80060.59969700115;80743.59628201938;64814;65122.674386628714;65682.01476977849;69339.65330173317;69896;8545.401821872658;8614.956925215334;8948.86576701363;8979.32655050887;9027.961784394616;49791.75520611869;51196;52689.47310526846;52828.20757688561;53399;68957;69614;70666.29333706597;70926;72225;11168.050715688933;11168.944155279172;11211.71970700739;11527.250728702607;11682.824757628472;12185.573504927284;12209.69475763113;12238.326892021025;12565;12646.114771965875;11786.410679466164;11836.289822610326;11935;11976.940115299367;12015.399230038638;11720.765584688428;11769.941150294193;11866.347350895783;11903.46434410471;11935.403229838646;11356.772864542829;11450.255733377304;11554.133439992202;11785.528578856749;11798.941005294919;8129.8374032520205;9781.315307928377;9971.700848974542;10119.544620491948;10125.949370253102;8502.403953498786;9738.951305243429;10070;10101;10239.590416383262 11:34:07 INFO - PROCESS | 1675 | __end_tp_report 11:34:07 INFO - PROCESS | 1675 | __start_cc_report 11:34:07 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,358 11:34:07 INFO - PROCESS | 1675 | __end_cc_report 11:34:07 INFO - PROCESS | 1675 | __startTimestamp1464028447426__endTimestamp 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | ------- Summary: start ------- 11:34:07 INFO - PROCESS | 1675 | Number of tests: 6 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5891.82 Median:6165.00 stddev:621.68 (10.1%) stddev-sans-first:597.10 11:34:07 INFO - PROCESS | 1675 | Values: 3603.2 3648.7 3661.9 3692.8 3697.2 5936.5 5952.1 5970.4 5990.0 5999.4 4859.0 5978.4 6008.3 6018.9 6064.2 5273.6 5359.5 5412.8 5441.0 5548.6 5321.9 5462.3 5463.9 5467.9 5479.1 5263.6 5276.3 5293.9 5297.2 5404.8 5452.9 5478.0 5483.8 5486.9 5514.4 5325.5 5363.0 5389.4 5397.9 5421.8 5680.6 5732.7 5772.5 5850.7 6038.5 6243.1 6252.4 6259.7 6276.0 6284.3 6363.5 6370.9 6376.1 6388.6 6416.4 5949.0 6071.9 6126.3 6127.3 6137.7 6419.6 6450.6 6451.4 6478.5 6503.3 5927.6 6214.8 6225.7 6246.1 6247.6 5113.4 6187.6 6207.8 6245.7 6267.0 6221.8 6237.5 6277.2 6282.6 6313.9 6148.5 6217.1 6281.5 6288.7 6289.6 4929.8 5035.8 5044.2 5045.6 5091.9 4511.1 4550.8 4551.1 4551.6 4569.1 6035.1 6091.3 6130.7 6140.0 6186.5 6248.9 6339.8 6369.2 6420.5 6431.7 6135.8 6158.0 6165.0 6179.4 6198.8 5465.9 5562.6 5569.0 5588.2 5614.6 5412.0 5588.0 6297.9 6657.4 6664.7 6405.4 6415.2 6441.5 6454.3 6454.4 5532.0 6382.6 6538.9 6608.3 6677.8 6266.5 6294.2 6294.8 6295.6 6300.3 6376.3 6384.7 6387.9 6397.0 6400.0 6131.4 6142.0 6202.3 6203.2 6228.0 6167.8 6184.8 6215.3 6220.9 6223.3 5995.5 6168.0 6219.3 6225.3 6230.9 6066.0 6148.8 6169.9 6204.5 6226.9 6188.9 6218.7 6229.9 6230.9 6244.9 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:66310.94 Median:40489.99 stddev:137405.49 (339.4%) stddev-sans-first:137701.92 11:34:07 INFO - PROCESS | 1675 | Values: 430.6 541.1 557.6 603.3 603.9 39921.0 40494.0 40599.6 40663.0 40689.6 43255.6 43767.1 44139.9 44247.6 44265.3 8378.7 9812.2 9908.7 10016.9 10024.0 8593.4 9794.9 9808.3 9835.5 9928.7 9849.5 9874.9 9933.7 9946.8 9975.3 9860.0 9882.9 9905.5 9932.3 9968.6 9790.4 9796.2 9816.7 9893.1 9924.2 40602.0 40880.2 41274.8 41399.2 41477.6 3452.2 3491.8 3536.1 3602.7 3631.4 27289.2 29434.3 29474.3 29583.8 29860.6 2812.1 2819.8 2830.2 2868.2 2876.9 452533.0 504961.0 535725.0 544643.0 545220.0 49161.3 50045.0 75348.6 76402.0 76457.0 41967.4 46363.3 46837.5 48097.0 48173.8 17116.6 17674.3 17791.5 17851.0 17880.6 13950.2 14073.8 14242.0 14267.4 14347.0 15548.5 15663.8 15988.8 15993.9 15994.5 6779.3 6828.8 6832.7 6849.7 6889.2 944.3 995.2 1030.9 1134.7 1222.9 30497.5 30901.7 30916.8 31452.8 31513.5 1147.4 1168.9 1254.5 1272.8 1312.7 15716.8 15802.6 15895.0 15938.3 16147.9 525663.0 526997.0 531344.0 532008.0 533120.0 62224.1 62335.4 62441.0 62453.4 62955.7 1558.0 1570.7 1594.4 1601.1 1601.6 30325.5 30426.6 30585.4 30858.0 31144.4 55159.2 55301.0 55335.7 55494.4 55674.0 499401.0 501804.0 502650.0 503129.0 503327.0 34706.0 35482.1 35515.1 35808.6 35837.6 47471.5 48531.0 49026.0 49278.0 49635.5 38686.0 39622.0 39800.0 40110.4 40486.0 38293.0 38522.0 38980.0 39020.6 39232.4 38271.6 38477.8 38863.2 39107.6 39426.0 33061.2 34749.8 35084.5 35102.0 35148.8 26118.5 26143.5 26205.0 26232.9 26657.1 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:35768.31 Median:12652.94 stddev:111312.36 (879.7%) stddev-sans-first:111592.90 11:34:07 INFO - PROCESS | 1675 | Values: 378.8 384.2 387.7 391.3 393.2 15562.8 19670.9 20418.9 21447.5 21548.2 20835.7 20880.8 20917.3 21072.8 21400.8 5369.6 5891.8 6102.8 6152.4 6170.3 6028.7 6113.5 6117.1 6148.8 6154.3 5756.5 5943.1 6097.6 6141.4 6176.4 5052.5 5994.7 6028.6 6107.8 6147.2 5546.8 5626.5 5641.5 5735.6 5784.5 13506.4 15178.8 22464.3 22987.8 24116.8 2110.9 2188.3 2274.6 2279.0 2318.1 9863.3 10108.6 10173.9 13526.5 13986.4 1818.8 1833.9 1844.7 1849.7 1851.5 93405.6 94254.0 98622.5 98663.0 99110.0 10967.3 11612.4 11644.7 11708.8 11880.5 12931.7 13284.7 13658.8 13894.7 14220.4 6599.8 6755.1 8356.1 8854.1 8889.7 8945.2 8988.9 9047.8 9061.9 9105.6 4046.7 4486.2 4558.5 4561.4 4609.3 1934.6 1969.0 1982.5 2017.0 2020.2 855.0 927.3 991.2 1026.4 1110.8 19276.9 19516.5 19667.3 20030.6 20297.6 925.5 1002.0 1022.5 1028.9 1054.7 8691.4 8874.8 9569.3 9744.5 10855.5 654689.0 672970.0 675713.0 681756.0 684777.0 94748.0 128872.0 128991.4 130299.0 134449.7 890.8 958.6 1069.5 1076.4 1077.6 14816.3 18171.5 18255.7 18351.7 18557.5 37493.3 47225.5 49149.8 49377.5 54964.7 65539.7 66179.0 67135.0 67565.0 68292.3 8041.3 8111.8 8138.8 8146.0 8437.7 11855.5 12610.1 12711.7 12761.9 12775.3 11245.3 11479.3 11500.8 11525.7 11549.9 10933.6 11111.9 11222.4 11464.3 11750.1 11332.1 11464.3 11520.2 11903.5 11929.2 8149.9 8161.5 8224.1 8264.6 8456.4 10040.0 11021.2 11155.4 11250.6 11515.6 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:6231.27 Median:5414.69 stddev:3214.87 (59.4%) stddev-sans-first:3204.08 11:34:07 INFO - PROCESS | 1675 | Values: 1483.8 1665.2 1962.5 2064.7 2080.1 7579.7 8253.3 8425.6 9177.0 9186.1 8008.4 8912.7 8989.2 9003.5 9016.1 2153.6 2533.5 2574.7 2580.4 2590.7 2490.3 2501.1 2519.1 2524.3 2528.4 2548.3 2555.5 2578.1 2579.6 2584.1 2451.3 2456.4 2460.1 2474.8 2483.6 2579.2 2603.6 2608.3 2624.9 2635.2 8862.4 8998.1 8999.0 9026.1 9068.5 5617.0 5651.4 5736.5 5771.1 5810.3 3522.8 3688.3 3767.0 3769.9 3815.3 1077.7 1080.5 1105.3 1106.1 1112.7 11899.5 12041.2 12114.2 12231.1 12256.0 4989.5 5041.6 5195.6 5202.9 5212.4 10374.7 10453.6 10500.6 10556.4 10593.8 5735.5 5763.7 5764.9 5798.6 5798.7 4587.2 4945.0 4991.6 5046.1 5201.0 3764.0 3925.2 3990.0 4168.8 4220.3 1366.0 1386.7 1389.3 1390.7 1394.4 1238.2 1250.0 1271.2 1424.5 1441.1 7346.4 8316.0 8410.2 8651.7 8654.2 1288.5 1290.5 1299.4 1302.1 1327.2 6434.0 6470.6 6530.4 6541.5 6560.9 10565.0 10969.2 10979.8 11000.2 11025.0 10614.8 10740.7 10758.0 10827.7 11068.7 8574.9 8594.5 8602.9 8630.1 8740.6 7999.5 8130.2 8161.7 8214.0 8245.3 9152.5 9293.8 9579.6 9743.9 10011.2 9471.4 9832.0 9836.3 9838.0 9871.8 7529.0 7621.5 7623.2 7679.9 7783.6 8270.6 8274.7 8297.4 8428.2 8437.6 7555.2 7696.8 7778.3 7800.0 7817.7 6686.8 7555.2 7572.5 7679.2 7777.8 7893.6 8000.4 8046.6 8081.4 8087.0 7209.6 7313.0 7321.4 7337.2 7362.4 7385.2 7402.2 7426.4 7431.0 7440.7 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:6153.52 Median:6939.75 stddev:1980.22 (28.5%) stddev-sans-first:1936.46 11:34:07 INFO - PROCESS | 1675 | Values: 302.2 337.6 345.7 346.4 348.1 6016.8 7005.1 7048.3 7052.0 7058.5 6350.5 6936.5 6943.0 6966.8 6975.2 4235.8 4760.9 5018.5 5089.5 5205.7 5173.1 5186.8 5193.6 5200.0 5202.5 5091.4 5126.9 5129.8 5130.8 5138.1 5126.3 5128.2 5144.1 5144.4 5154.8 4452.9 4462.4 4463.6 4477.0 4485.2 6513.1 6684.4 6707.5 6763.7 6777.2 5962.8 6024.1 6035.8 6038.9 6040.7 7482.7 7526.1 7532.0 7540.0 7546.9 4932.0 4955.4 4956.5 4957.6 4962.1 6781.6 7607.4 7629.5 7944.2 7981.8 5027.1 5068.2 5075.6 5082.1 5093.9 5162.7 5166.1 5174.8 5181.9 5197.2 6030.5 6034.2 6046.9 6046.9 6056.6 6477.8 7573.1 7589.6 7595.1 7606.1 1331.3 1694.1 1731.5 1770.6 1776.0 993.3 1169.3 1172.7 1182.1 1185.9 6993.2 7038.7 7120.7 7143.4 7154.4 6927.7 7807.3 7811.4 7829.9 7836.2 5473.8 6391.6 7213.9 7258.1 7307.3 4143.9 4182.0 4185.4 4195.7 4202.9 8301.7 8318.2 8321.4 8340.7 8349.5 6593.3 7943.8 8167.9 8182.1 8240.6 8176.0 8275.9 8309.0 8325.7 8329.8 7934.3 7950.2 7977.2 7983.6 8012.8 6283.8 7746.1 7756.9 7765.6 7788.8 6670.9 8026.8 8321.1 8324.1 8351.5 4896.4 4919.7 4941.5 4941.7 4951.5 7833.1 7845.3 7854.5 7857.0 7860.4 7371.3 7599.5 7603.1 7608.4 7640.9 7560.9 7574.9 7602.1 7603.3 7625.4 6021.3 7362.8 7400.7 7433.1 7443.1 7533.2 7578.3 7584.7 7585.5 7618.8 7172.3 7412.4 7431.0 7465.3 7481.0 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:19025.64 Median:52758.84 stddev:26782.44 (50.8%) stddev-sans-first:26822.57 11:34:07 INFO - PROCESS | 1675 | Values: 791.0 795.2 797.6 800.4 821.2 14794.0 15701.5 15903.8 15921.5 15946.6 13351.0 14900.7 14915.0 14946.4 14977.7 806.3 973.3 981.5 990.6 996.1 958.7 967.0 981.1 981.6 991.9 996.8 1023.4 1030.3 1044.0 1056.3 981.1 998.2 1026.2 1026.6 1039.0 665.8 678.5 755.6 788.0 791.4 15393.2 15586.5 15618.7 15654.8 15682.5 13593.5 13594.3 13709.9 13715.8 13723.5 10103.9 10169.9 10251.6 10293.3 10492.2 3615.5 3647.2 3657.5 3664.0 3742.3 86748.0 88993.1 90910.0 91055.1 91361.0 9811.2 9903.9 9934.6 9943.1 10125.5 22714.9 22912.0 23051.4 23970.9 23980.8 7550.8 7856.4 7884.2 8029.2 8092.8 4161.6 4251.8 4270.5 4284.7 4482.1 2957.6 3265.2 3319.1 3359.7 3374.1 664.2 670.4 673.4 676.8 694.9 563.0 589.1 611.0 614.7 615.2 8612.2 8673.7 8762.0 8805.3 8901.2 615.4 620.4 623.2 626.0 632.8 3934.2 3977.4 4037.4 4058.3 4185.0 94639.0 95960.5 97701.0 98205.5 98929.0 76644.6 78385.2 79212.0 80060.6 80743.6 64814.0 65122.7 65682.0 69339.7 69896.0 8545.4 8615.0 8948.9 8979.3 9028.0 49791.8 51196.0 52689.5 52828.2 53399.0 68957.0 69614.0 70666.3 70926.0 72225.0 11168.1 11168.9 11211.7 11527.3 11682.8 12185.6 12209.7 12238.3 12565.0 12646.1 11786.4 11836.3 11935.0 11976.9 12015.4 11720.8 11769.9 11866.3 11903.5 11935.4 11356.8 11450.3 11554.1 11785.5 11798.9 8129.8 9781.3 9971.7 10119.5 10125.9 8502.4 9739.0 10070.0 10101.0 10239.6 11:34:07 INFO - PROCESS | 1675 | -------- Summary: end -------- 11:34:07 INFO - PROCESS | 1675 | 11:34:07 INFO - TEST-INFO | 1675: exit 0 11:34:07 INFO - TEST-OK | dromaeo_css | took 1109430ms 11:34:07 INFO - TEST-START | kraken 11:34:07 INFO - operating with platform_type : mac_ 11:34:07 INFO - Initialising browser for kraken test... 11:34:07 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpG10Qp4/profile http://localhost:49281/getInfo.html) 11:34:10 INFO - PROCESS | 1682 | __metrics Screen width/height:1600/1200 11:34:10 INFO - PROCESS | 1682 | colorDepth:24 11:34:10 INFO - PROCESS | 1682 | Browser inner width/height: 1024/689 11:34:10 INFO - PROCESS | 1682 | __metrics 11:34:10 ERROR - PROCESS | 1682 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 11:34:11 INFO - TEST-INFO | 1682: exit 0 11:34:11 INFO - Browser initialized. 11:34:11 INFO - Running cycle 1/1 for kraken test... 11:34:11 INFO - TEST-INFO | started process 1686 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/T/tmpG10Qp4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:34:23 INFO - PROCESS | 1686 | RSS: Main: 167772160 11:34:23 INFO - PROCESS | 1686 | 11:34:25 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 11:34:26 INFO - PROCESS | 1686 | RSS: Main: 179728384 11:34:26 INFO - PROCESS | 1686 | 11:34:29 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 11:34:29 INFO - PROCESS | 1686 | RSS: Main: 188747776 11:34:29 INFO - PROCESS | 1686 | 11:34:33 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 11:34:33 INFO - PROCESS | 1686 | RSS: Main: 192299008 11:34:33 INFO - PROCESS | 1686 | 11:34:35 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 11:34:36 INFO - PROCESS | 1686 | RSS: Main: 194289664 11:34:36 INFO - PROCESS | 1686 | 11:34:38 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:34:38 INFO - PROCESS | 1686 | RSS: Main: 194088960 11:34:38 INFO - PROCESS | 1686 | 11:34:41 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 11:34:42 INFO - PROCESS | 1686 | RSS: Main: 195084288 11:34:42 INFO - PROCESS | 1686 | 11:34:44 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 11:34:45 INFO - PROCESS | 1686 | RSS: Main: 194985984 11:34:45 INFO - PROCESS | 1686 | 11:34:48 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 11:34:48 INFO - PROCESS | 1686 | RSS: Main: 195084288 11:34:48 INFO - PROCESS | 1686 | 11:34:50 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:34:51 INFO - PROCESS | 1686 | RSS: Main: 194064384 11:34:51 INFO - PROCESS | 1686 | 11:34:52 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:34:53 INFO - PROCESS | 1686 | RSS: Main: 194203648 11:34:53 INFO - PROCESS | 1686 | 11:34:55 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:34:56 INFO - PROCESS | 1686 | RSS: Main: 194433024 11:34:56 INFO - PROCESS | 1686 | 11:34:58 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:34:59 INFO - PROCESS | 1686 | RSS: Main: 194510848 11:34:59 INFO - PROCESS | 1686 | 11:35:01 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:35:02 INFO - PROCESS | 1686 | RSS: Main: 194592768 11:35:02 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 11:35:04 INFO - PROCESS | 1686 | RSS: Main: 196116480 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | __start_tp_report 11:35:04 INFO - PROCESS | 1686 | _x_x_mozilla_page_load 11:35:04 INFO - PROCESS | 1686 | _x_x_mozilla_page_load_details 11:35:04 INFO - PROCESS | 1686 | |i|pagename|runs| 11:35:04 INFO - PROCESS | 1686 | |0;ai-astar;115;111;115;113;115;128;124;162;116;148 11:35:04 INFO - PROCESS | 1686 | |1;audio-beat-detection;180;114;180;182;150;144;116;151;144;120 11:35:04 INFO - PROCESS | 1686 | |2;audio-dft;276;254;188;220;195;197;196;194;198;198 11:35:04 INFO - PROCESS | 1686 | |3;audio-fft;70;73;67;107;56;57;58;59;121;82 11:35:04 INFO - PROCESS | 1686 | |4;audio-oscillator;80;82;115;79;79;82;147;115;115;117 11:35:04 INFO - PROCESS | 1686 | |5;imaging-gaussian-blur;90;88;90;88;92;88;91;96;120;87 11:35:04 INFO - PROCESS | 1686 | |6;imaging-darkroom;96;95;95;94;95;95;95;96;94;95 11:35:04 INFO - PROCESS | 1686 | |7;imaging-desaturate;90;84;137;146;92;90;94;91;92;90 11:35:04 INFO - PROCESS | 1686 | |8;json-parse-financial;57;58;57;60;57;87;57;59;56;58 11:35:04 INFO - PROCESS | 1686 | |9;json-stringify-tinderbox;67;66;70;77;70;74;66;64;64;67 11:35:04 INFO - PROCESS | 1686 | |10;stanford-crypto-aes;84;82;84;83;80;83;80;82;86;87 11:35:04 INFO - PROCESS | 1686 | |11;stanford-crypto-ccm;130;134;125;102;108;108;158;132;124;128 11:35:04 INFO - PROCESS | 1686 | |12;stanford-crypto-pbkdf2;130;167;136;136;138;140;137;134;136;123 11:35:04 INFO - PROCESS | 1686 | |13;stanford-crypto-sha256-iterative;68;63;76;69;93;94;93;96;66;65 11:35:04 INFO - PROCESS | 1686 | __end_tp_report 11:35:04 INFO - PROCESS | 1686 | __start_cc_report 11:35:04 INFO - PROCESS | 1686 | _x_x_mozilla_cycle_collect,905 11:35:04 INFO - PROCESS | 1686 | __end_cc_report 11:35:04 INFO - PROCESS | 1686 | __startTimestamp1464028504426__endTimestamp 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | ------- Summary: start ------- 11:35:04 INFO - PROCESS | 1686 | Number of tests: 14 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#0] ai-astar Cycles:10 Average:124.70 Median:120.00 stddev:17.09 (14.2%) stddev-sans-first:17.76 11:35:04 INFO - PROCESS | 1686 | Values: 115.0 111.0 115.0 113.0 115.0 128.0 124.0 162.0 116.0 148.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#1] audio-beat-detection Cycles:10 Average:148.10 Median:150.50 stddev:26.27 (17.5%) stddev-sans-first:25.20 11:35:04 INFO - PROCESS | 1686 | Values: 180.0 114.0 180.0 182.0 150.0 144.0 116.0 151.0 144.0 120.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#2] audio-dft Cycles:10 Average:211.60 Median:198.00 stddev:29.79 (15.0%) stddev-sans-first:20.54 11:35:04 INFO - PROCESS | 1686 | Values: 276.0 254.0 188.0 220.0 195.0 197.0 196.0 194.0 198.0 198.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#3] audio-fft Cycles:10 Average:75.00 Median:63.00 stddev:22.39 (35.5%) stddev-sans-first:23.68 11:35:04 INFO - PROCESS | 1686 | Values: 70.0 73.0 67.0 107.0 56.0 57.0 58.0 59.0 121.0 82.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#4] audio-oscillator Cycles:10 Average:101.10 Median:79.00 stddev:23.78 (30.1%) stddev-sans-first:23.97 11:35:04 INFO - PROCESS | 1686 | Values: 80.0 82.0 115.0 79.0 79.0 82.0 147.0 115.0 115.0 117.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#5] imaging-gaussian-blur Cycles:10 Average:93.00 Median:90.00 stddev:9.84 (10.9%) stddev-sans-first:10.38 11:35:04 INFO - PROCESS | 1686 | Values: 90.0 88.0 90.0 88.0 92.0 88.0 91.0 96.0 120.0 87.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 11:35:04 INFO - PROCESS | 1686 | Values: 96.0 95.0 95.0 94.0 95.0 95.0 95.0 96.0 94.0 95.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#7] imaging-desaturate Cycles:10 Average:100.60 Median:90.50 stddev:21.81 (24.1%) stddev-sans-first:22.80 11:35:04 INFO - PROCESS | 1686 | Values: 90.0 84.0 137.0 146.0 92.0 90.0 94.0 91.0 92.0 90.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#8] json-parse-financial Cycles:10 Average:60.60 Median:58.00 stddev:9.35 (16.1%) stddev-sans-first:9.82 11:35:04 INFO - PROCESS | 1686 | Values: 57.0 58.0 57.0 60.0 57.0 87.0 57.0 59.0 56.0 58.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:68.50 stddev:4.28 (6.2%) stddev-sans-first:4.50 11:35:04 INFO - PROCESS | 1686 | Values: 67.0 66.0 70.0 77.0 70.0 74.0 66.0 64.0 64.0 67.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:83.50 stddev:2.28 (2.7%) stddev-sans-first:2.40 11:35:04 INFO - PROCESS | 1686 | Values: 84.0 82.0 84.0 83.0 80.0 83.0 80.0 82.0 86.0 87.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#11] stanford-crypto-ccm Cycles:10 Average:124.90 Median:129.00 stddev:16.20 (12.6%) stddev-sans-first:17.07 11:35:04 INFO - PROCESS | 1686 | Values: 130.0 134.0 125.0 102.0 108.0 108.0 158.0 132.0 124.0 128.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:137.70 Median:136.50 stddev:11.36 (8.3%) stddev-sans-first:11.71 11:35:04 INFO - PROCESS | 1686 | Values: 130.0 167.0 136.0 136.0 138.0 140.0 137.0 134.0 136.0 123.0 11:35:04 INFO - PROCESS | 1686 | 11:35:04 INFO - PROCESS | 1686 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.30 Median:84.50 stddev:13.95 (16.5%) stddev-sans-first:14.29 11:35:04 INFO - PROCESS | 1686 | Values: 68.0 63.0 76.0 69.0 93.0 94.0 93.0 96.0 66.0 65.0 11:35:04 INFO - PROCESS | 1686 | -------- Summary: end -------- 11:35:04 INFO - PROCESS | 1686 | 11:35:05 INFO - TEST-INFO | 1686: exit 0 11:35:05 INFO - TEST-OK | kraken | took 57201ms 11:35:05 INFO - SUITE-END | took 1166s 11:35:05 INFO - Completed test suite (00:19:27) 11:35:05 INFO - javascript benchmark 11:35:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3603.207294395232, 3648.689861361784, 3661.9450708239365, 3692.8153592320396, 3697.2051009033053, 5936.465718085371, 5952.107183922414, 5970.402959704022, 5990.0, 5999.430054144855, 4858.979614280996, 5978.402159784019, 6008.279006519213, 6018.8796224075495, 6064.240770449446, 5273.604479664031, 5359.490848369399, 5412.783488660449, 5441.0, 5548.58385621078, 5321.946780532184, 5462.3445186577665, 5463.890722185554, 5467.9453205468035, 5479.068558345071, 5263.631545791796, 5276.287701160351, 5293.947060529403, 5297.17893726473, 5404.83785486436, 5452.863678408032, 5477.99999999998, 5483.7806487740845, 5486.890262194774, 5514.393416724157, 5325.467453254682, 5363.034653762321, 5389.407165211824, 5397.865053373658, 5421.756020979065, 5680.573956953203, 5732.656040637575, 5772.538196944276, 5850.736716847752, 6038.486728628071, 6243.094751261087, 6252.406021427957, 6259.687015649245, 6276.0, 6284.27730810958, 6363.522735794788, 6370.948793449075, 6376.107344971708, 6388.55280130391, 6416.358364163546, 5949.0, 6071.907056729787, 6126.27339965311, 6127.325994140641, 6137.7447333336595, 6419.614823110628, 6450.580712253689, 6451.387118223761, 6478.481721462272, 6503.2521260054755, 5927.585069045125, 6214.844628884223, 6225.688715564203, 6246.063090536456, 6247.562670613013, 5113.360829896263, 6187.566870319124, 6207.782727604512, 6245.687715614292, 6266.968665156736, 6221.751129954841, 6237.500999919996, 6277.24673039229, 6282.623042617366, 6313.9368606314265, 6148.508119350441, 6217.067439883963, 6281.466075383552, 6288.717007734663, 6289.622622642656, 4929.827456039093, 5035.823746168867, 5044.167712327425, 5045.621578381636, 5091.930694554111, 4511.142882852248, 4550.817967281338, 4551.112533056023, 4551.613112885376, 4569.086182763461, 6035.064564992434, 6091.269047714302, 6130.724117414727, 6140.048292514667, 6186.505079593621, 6248.875022499525, 6339.7781077662985, 6369.20384951881, 6420.486361091195, 6431.710573024225, 6135.754569817157, 6158.0, 6165.0, 6179.3820617938745, 6198.752049917953, 5465.863353416117, 5562.582806289544, 5568.99758043548, 5588.161775733673, 5614.63504872182, 5412.0, 5587.972060139755, 6297.874042519215, 6657.434118099919, 6664.666766661744, 6405.391487808651, 6415.1981002374705, 6441.452476539546, 6454.257760357619, 6454.386833250834, 5532.0, 6382.648954307558, 6538.934610653832, 6608.306127856506, 6677.799666010027, 6266.467350275096, 6294.24469063697, 6294.779682711266, 6295.559310848196, 6300.2754683212015, 6376.298607153302, 6384.712687929147, 6387.904181437375, 6397.0, 6399.9680001601555, 6131.407250743203, 6142.038385241405, 6202.317745047951, 6203.16257305258, 6228.003519436868, 6167.784127555514, 6184.845378865564, 6215.254169499689, 6220.937790622036, 6223.315435302204, 5995.490383317467, 6167.969160154171, 6219.315875253809, 6225.315215326401, 6230.875382492415, 6065.969670151621, 6148.846278843064, 6169.938300616936, 6204.472619827185, 6226.937730622636, 6188.876222475616, 6218.657973811484, 6229.875402492015, 6230.906536402048, 6244.906326405198], "unit": "score", "name": "dojo.html", "value": 5856.305288292235}, {"lowerIsBetter": false, "replicates": [430.6124487960833, 541.1044720986768, 557.6263903184865, 603.3001718007113, 603.939606039396, 39921.0, 40494.0, 40599.59400405999, 40663.0, 40689.593104068954, 43255.56744432555, 43767.12465750683, 44139.89650258745, 44247.557524424745, 44265.33601995964, 8378.706745263917, 9812.215022798158, 9908.653197138101, 10016.899831001707, 10024.0, 8593.398462107654, 9794.853077203812, 9808.31341806074, 9835.508224588742, 9928.702138935843, 9849.50752462374, 9874.851877221847, 9933.701988940344, 9946.751331216741, 9975.301728878982, 9859.950700246489, 9882.851757223647, 9905.504724763769, 9932.304738668296, 9968.551415186297, 9790.412575245437, 9796.167325777245, 9816.65641702537, 9893.109620134222, 9924.15644670204, 40601.98495037618, 40880.182396352204, 41274.79362603198, 41399.1720165598, 41477.58522414797, 3452.188735647121, 3491.8428670709627, 3536.098294934801, 3602.67575918166, 3631.400818864884, 27289.181324560097, 29434.264143396587, 29474.263143421373, 29583.7936654991, 29860.552091718426, 2812.1141840320233, 2819.788515861298, 2830.1651012951024, 2868.153894601098, 2876.8849246030136, 452533.0, 504961.0, 535725.0, 544642.999999996, 545220.0, 49161.26258106131, 50045.0, 75348.62325688337, 76402.0, 76457.0, 41967.37048944207, 46363.30455043177, 46837.53162468332, 48097.0, 48173.7956551082, 17116.572085697957, 17674.293028278982, 17791.46625601234, 17851.018193999455, 17880.642387152184, 13950.23273719955, 14073.788893166611, 14242.0, 14267.357968891425, 14347.0, 15548.455804046804, 15663.843361566467, 15988.76016859748, 15993.920030399773, 15994.520164395086, 6779.3220677932795, 6828.829279267959, 6832.658367081626, 6849.691763870538, 6889.207741109736, 944.3389627260945, 995.1640621877658, 1030.850526673628, 1134.6936327191613, 1222.8899399054128, 30497.542536861965, 30901.69098309033, 30916.845415772776, 31452.842735786173, 31513.52729709056, 1147.4320211495362, 1168.8889555492215, 1254.4919307680404, 1272.7899896517156, 1312.6587087357357, 15716.842831571767, 15802.60493487672, 15895.0, 15938.349502456496, 16147.919260403858, 525663.0, 526997.0, 531344.0, 532008.0, 533120.0, 62224.06663900045, 62335.37664623386, 62441.0, 62453.37546624566, 62955.6852215736, 1557.9844201558067, 1570.7486802111607, 1594.3781925049009, 1601.135386891089, 1601.639631082997, 30325.545116823705, 30426.616045559174, 30585.388292233587, 30858.0, 31144.377112457172, 55159.172612410046, 55301.0, 55335.72332138474, 55494.44505554893, 55674.0, 499401.0, 501804.0, 502650.0, 503129.0, 503327.0, 34706.0, 35482.1129471755, 35515.112122197155, 35808.64191358053, 35837.64162358343, 47471.525284748095, 48531.0, 49026.019479609495, 49278.014439711726, 49635.503644964534, 38686.0, 39622.0, 39800.0, 40110.39834402428, 40485.98785030398, 38293.04267393338, 38522.0, 38980.0, 39020.60979390283, 39232.41151382789, 38271.6172838268, 38477.845664630106, 38863.2227355457, 39107.6089239104, 39426.0, 33061.17347066246, 34749.82625086959, 35084.47373289454, 35102.0, 35148.82425587856, 26118.477630447665, 26143.477130457664, 26205.0, 26232.8688356557, 26657.067002654814], "unit": "score", "name": "ext.html", "value": 20322.489018882847}, {"lowerIsBetter": false, "replicates": [378.7538100234847, 384.2200333323354, 387.7440889013251, 391.3288709862585, 393.19787633228214, 15562.766558501604, 19670.90164549177, 20418.89790551049, 21447.463813404673, 21548.245811396584, 20835.687464688042, 20880.791192088076, 20917.267895623656, 21072.789272107275, 21400.785992140074, 5369.570434365241, 5891.793787217438, 6102.786402475915, 6152.446279834814, 6170.290416602081, 6028.668423236721, 6113.480354169878, 6117.051856962155, 6148.815535533919, 6154.26148862135, 5756.453136951983, 5943.108533719934, 6097.634141951475, 6141.355157708421, 6176.382361763833, 5052.520010599005, 5994.730237139339, 6028.608140470856, 6107.847303817396, 6147.170132032208, 5546.833594992157, 5626.465485778845, 5641.548676105902, 5735.627184233012, 5784.4504772046585, 13506.392212350467, 15178.848211517965, 22464.32607021792, 22987.77012229873, 24116.79416029209, 2110.915563377463, 2188.2997440818945, 2274.56783211191, 2279.0428020231543, 2318.107528601484, 9863.30956833022, 10108.595656173744, 10173.89826101737, 13526.526571570046, 13986.440542378292, 1818.7817461904656, 1833.8807977481554, 1844.6757086071511, 1849.6763066463316, 1851.5000949743494, 93405.59891601632, 94254.0, 98622.52066219013, 98663.0, 99109.99999999927, 10967.287126336763, 11612.361320127306, 11644.708882278012, 11708.765824683458, 11880.524779008916, 12931.741365172644, 13284.73430531384, 13658.77071063609, 13894.652633684238, 14220.431182752574, 6599.822058720611, 6755.088063111515, 8356.080831108573, 8854.114588541095, 8889.73330800077, 8945.23965462943, 8988.910110898938, 9047.77380565478, 9061.864072038925, 9105.635774568944, 4046.736962097454, 4486.214912390318, 4558.521355257716, 4561.407017087824, 4609.308603709471, 1934.6324198402258, 1969.0, 1982.5341044854447, 2016.959660806805, 2020.1515363547346, 854.9526829633649, 927.2813569483569, 991.2268430624125, 1026.43033116621, 1110.7892397286996, 19276.90361548183, 19516.512087197934, 19667.311644092675, 20030.599388012448, 20297.594048118954, 925.5233554719325, 1002.0380434782691, 1022.4529876516177, 1028.8919663435381, 1054.7310435838813, 8691.435056721293, 8874.822503549893, 9569.325368060701, 9744.512774361254, 10855.51150198243, 654689.0, 672970.0, 675713.0, 681756.0, 684777.0, 94748.0, 128872.0, 128991.35504322419, 130299.0, 134449.65550344568, 890.8307421589877, 958.6309270930602, 1069.5026812532055, 1076.4294923690456, 1077.590515604081, 14816.333265002886, 18171.54571135732, 18255.726164107815, 18351.724724128882, 18557.536061598028, 37493.250134996604, 47225.527744722116, 49149.754251228514, 49377.50622493729, 54964.72517637386, 65539.6723016401, 66179.0, 67135.0, 67565.0, 68292.31707683059, 8041.316488098577, 8111.837763244584, 8138.837223255383, 8145.959270203611, 8437.662493500191, 11855.525778968744, 12610.117291789853, 12711.682207944505, 12761.872381276069, 12775.297358645365, 11245.325280483197, 11479.25384849982, 11500.827487587525, 11525.711857203636, 11549.88450115488, 10933.56265749361, 11111.888881111085, 11222.438878055902, 11464.25482343645, 11750.118741094622, 11332.093432525213, 11464.25482343645, 11520.193586448868, 11903.464344104363, 11929.164958452826, 8149.918500814916, 8161.510309381456, 8224.136465671018, 8264.628091736007, 8456.365772567206, 10039.996000399902, 11021.228514003942, 11155.44222788841, 11250.606228781953, 11515.596954106566], "unit": "score", "name": "jquery.html", "value": 9448.876724087833}, {"lowerIsBetter": false, "replicates": [1483.829359623643, 1665.159094657198, 1962.5486138188217, 2064.6696528555435, 2080.074366906726, 7579.6589153488085, 8253.257206851382, 8425.567653498905, 9177.036411176821, 9186.081391860818, 8008.439409241356, 8912.732618021453, 8989.190972812445, 9003.5048072356, 9016.053314401976, 2153.6231159547096, 2533.5439620868283, 2574.66529351185, 2580.354911272182, 2590.650262214596, 2490.3151633300804, 2501.1496091328945, 2519.0805356045003, 2524.343670645627, 2528.4311030018284, 2548.2864797856537, 2555.514452254075, 2578.0847799031353, 2579.6001619748968, 2584.095566551711, 2451.3258853815255, 2456.4227406559444, 2460.0651752333997, 2474.7525247475287, 2483.627455881623, 2579.161772423951, 2603.570410882202, 2608.282722251377, 2624.8950041998296, 2635.169921474729, 8862.379633425662, 8998.14517620825, 8999.0, 9026.097390260988, 9068.455892646462, 5617.0, 5651.350094739117, 5736.541076713895, 5771.076627739583, 5810.302763668386, 3522.84147213376, 3688.2623475305045, 3767.020574650583, 3769.9057523561855, 3815.294170578424, 1077.7036315013356, 1080.475969154959, 1105.309181761399, 1106.0874778307927, 1112.6940091474828, 11899.464524096436, 12041.217320874115, 12114.21257618252, 12231.082668799876, 12256.019518438592, 4989.525995030466, 5041.571466425395, 5195.558377537876, 5202.933398653267, 5212.374515058217, 10374.688759337232, 10453.581856725647, 10500.632477863239, 10556.36661800279, 10593.84109238362, 5735.512481439125, 5763.7118144093465, 5764.913526297109, 5798.59409841307, 5798.681072540966, 4587.2201725706045, 4945.035718034948, 4991.550760431579, 5046.1169295373165, 5201.0, 3763.9623603764157, 3925.156091440354, 3989.960100399017, 4168.8332466701595, 4220.282551966173, 1365.9931700341435, 1386.736520061185, 1389.3400634698437, 1390.7009992851586, 1394.372532360442, 1238.2013601227159, 1249.956251531192, 1271.173737070893, 1424.5156646740159, 1441.1209162410921, 7346.375558077623, 8316.0, 8410.158984101663, 8651.653933842701, 8654.221120099095, 1288.452407726731, 1290.5481333499813, 1299.3503248375812, 1302.1080559816555, 1327.157255142991, 6434.0, 6470.611763294218, 6530.444912182517, 6541.509386795914, 6560.851850926068, 10565.049145576935, 10969.177311701495, 10979.83530247047, 11000.22998390121, 11025.0, 10614.840777388345, 10740.731481712863, 10758.031777140095, 10827.67516974492, 11068.667939961813, 8574.871376929352, 8594.52730099838, 8602.87095693565, 8630.136986301446, 8740.562971851383, 7999.520028798291, 8130.186981301823, 8161.673533058611, 8213.95893020555, 8245.340372770284, 9152.4966126861, 9293.814123717622, 9579.616815327308, 9743.9025609743, 10011.199104071511, 9471.384360016576, 9831.950840245752, 9836.311458197857, 9838.0, 9871.802563948537, 7529.0, 7621.466497345355, 7623.161452240138, 7679.884801728091, 7783.649735761791, 8270.627821747916, 8274.669013239403, 8297.377696672895, 8428.157184281523, 8437.62030708632, 7555.206703296075, 7696.8460630788195, 7778.261065198797, 7800.0, 7817.687292508236, 6686.829804784241, 7555.206703296295, 7572.507786993828, 7679.19368466303, 7777.844443111219, 7893.565853878092, 8000.4399692021, 8046.637901294573, 8081.353491720531, 8087.0, 7209.603471808891, 7312.963435183003, 7321.377682897013, 7337.192908780136, 7362.447816413897, 7385.187629360874, 7402.222766609429, 7426.368758655575, 7431.0, 7440.665170067468], "unit": "score", "name": "mootools.html", "value": 5126.391602823224}, {"lowerIsBetter": false, "replicates": [302.2142429682825, 337.5932001937665, 345.7389670798545, 346.43703981030825, 348.10710527496985, 6016.789412370568, 7005.089338386009, 7048.295170482949, 7052.0479735235795, 7058.470614703899, 6350.523710721703, 6936.479764017707, 6943.02797608334, 6966.790996270107, 6975.162980442341, 4235.767032813194, 4760.857174284768, 5018.498150184971, 5089.46560611136, 5205.713685747282, 5173.146430838907, 5186.818461353853, 5193.610479214055, 5200.0379929712935, 5202.531772140506, 5091.439941606421, 5126.897462050775, 5129.846104616849, 5130.845559749064, 5138.100832354342, 5126.307948426952, 5128.230765385185, 5144.09978253806, 5144.382674079097, 5154.819581314655, 4452.910941781146, 4462.44219472566, 4463.598276155161, 4477.037436951071, 4485.237509623372, 6513.088167656532, 6684.431823295025, 6707.463402927754, 6763.695633696496, 6777.220619628707, 5962.761489540413, 6024.126501657235, 6035.792841431731, 6038.939610603926, 6040.697965101727, 7482.700691972314, 7526.059242594676, 7532.0208372911175, 7539.982102416213, 7546.924530754677, 4931.975340123312, 4955.35580374549, 4956.479569645171, 4957.628177886673, 4962.082014827269, 6781.593104413701, 7607.353374963135, 7629.504082234637, 7944.165862584403, 7981.800454988614, 5027.120253955543, 5068.239764035424, 5075.568576671024, 5082.110630639697, 5093.8726531836255, 5162.6902385856965, 5166.070107380632, 5174.844754657366, 5181.948180518147, 5197.22041693749, 6030.517558595302, 6034.155218269446, 6046.879062418727, 6046.909296360558, 6056.636601803906, 6477.838054048687, 7573.129090154593, 7589.58257295854, 7595.088589369311, 7606.087269527692, 1331.3010669398643, 1694.144457049207, 1731.5238309464746, 1770.6193168468842, 1776.005436955309, 993.3096497934011, 1169.2867350915935, 1172.6807341740541, 1182.1134149387958, 1185.9347735874437, 6993.195782484978, 7038.683259253244, 7120.715171393086, 7143.392811611071, 7154.391876690435, 6927.718372101177, 7807.336376407955, 7811.414143939227, 7829.921700782919, 7836.177201393885, 5473.808416705396, 6391.552591318656, 7213.8557228855125, 7258.056452661226, 7307.3058059484265, 4143.875683729493, 4182.0, 4185.414042034117, 4195.748255104703, 4202.936955945664, 8301.667933282723, 8318.21158450936, 8321.417500774887, 8340.749777506684, 8349.54077525742, 6593.329701470912, 7943.761687149395, 8167.918320816834, 8182.059063207688, 8240.587970601446, 8175.95912020448, 8275.875861861956, 8309.00291964968, 8325.66697332112, 8329.750107496784, 7934.2859142677435, 7950.171460563987, 7977.242161994834, 7983.640736166771, 8012.759617211493, 6283.7800676976085, 7746.147923728498, 7756.914032035406, 7765.611719413893, 7788.766337009899, 6670.9332906670315, 8026.8394632105865, 8321.12628174033, 8324.125966773401, 8351.457155284887, 4896.4124305083615, 4919.704817710949, 4941.530554597308, 4941.728204948762, 4951.455339912534, 7833.09919359281, 7845.254700803414, 7854.450188486751, 7857.0, 7860.449768516149, 7371.299726525972, 7599.506032107895, 7603.0876294844975, 7608.353289970193, 7640.885386719315, 7560.924390756022, 7574.924250757643, 7602.1257555379625, 7603.2776886195725, 7625.389968802593, 6021.277446706424, 7362.77911662651, 7400.703971841282, 7433.145188303414, 7443.144038435649, 7533.246675332643, 7578.280063393969, 7584.658690359058, 7585.544867307979, 7618.847623047619, 7172.318629730167, 7412.369948554433, 7431.033965584439, 7465.328120469184, 7480.962595186989], "unit": "score", "name": "prototype.html", "value": 5484.912188260982}, {"lowerIsBetter": false, "replicates": [790.960451977401, 795.2326005404783, 797.6490344248531, 800.4036992440629, 821.2075347289866, 14794.038387504805, 15701.450449234264, 15903.761443578327, 15921.52235432936, 15946.601334966632, 13351.0, 14900.701985960275, 14915.0, 14946.40214391423, 14977.700445991073, 806.2904643913348, 973.3186769261528, 981.4601968917103, 990.5542505872351, 996.0587245053428, 958.6596758150861, 967.0281367225963, 981.1316984468725, 981.6269817469351, 991.9107280344767, 996.8255555277833, 1023.3501726403713, 1030.258214085857, 1044.0446991003223, 1056.2817284246748, 981.1071924548697, 998.2014388489216, 1026.2251999740222, 1026.558579810681, 1038.9636362727306, 665.7569986954727, 678.5013015433652, 755.561774170985, 788.0071110400936, 791.3866753266207, 15393.23033848303, 15586.532404027897, 15618.68762624741, 15654.765178522332, 15682.529524114294, 13593.456261749518, 13594.32028398576, 13709.862901370958, 13715.794263086062, 13723.519676811264, 10103.949480252626, 10169.898301016969, 10251.589936402535, 10293.279470437072, 10492.16062714981, 3615.4576813477925, 3647.1611529348133, 3657.487951686766, 3663.9740872555726, 3742.270257299828, 86748.0, 88993.11006889913, 90910.0, 91055.08944910599, 91361.0, 9811.166050885755, 9903.851442228228, 9934.552945117486, 9943.055409736064, 10125.493725313705, 22714.886425567765, 22912.0, 23051.423714406938, 23970.88014559916, 23980.7601923982, 7550.7734767957045, 7856.371490280764, 7884.211578842184, 8029.197080291924, 8092.757217283491, 4161.646260067928, 4251.829926802955, 4270.466191726035, 4284.700070995001, 4482.148391805546, 2957.639485836496, 3265.2000259936467, 3319.054069590155, 3359.6976272135626, 3374.0721301642243, 664.2460806984108, 670.3999920071606, 673.4343151752552, 676.7597502886503, 694.9478789090839, 562.9802956896489, 589.0898561722149, 610.991893180727, 614.7264467312093, 615.2340336281359, 8612.18184272493, 8673.653053877773, 8762.0, 8805.251553617885, 8901.243394311427, 615.4153554123601, 620.3951147631113, 623.1711823275033, 626.0421555020826, 632.7785275153751, 3934.1738234970976, 3977.423273625308, 4037.354023356249, 4058.3100872851687, 4185.0, 94639.0, 95960.52019739857, 97701.0, 98205.50897245469, 98929.01070989342, 76644.61677691576, 78385.21614783893, 79212.0, 80060.59969700115, 80743.59628201938, 64814.0, 65122.674386628714, 65682.01476977849, 69339.65330173317, 69896.0, 8545.401821872658, 8614.956925215334, 8948.86576701363, 8979.32655050887, 9027.961784394616, 49791.75520611869, 51196.0, 52689.47310526846, 52828.20757688561, 53399.0, 68957.0, 69614.0, 70666.29333706597, 70926.0, 72225.0, 11168.050715688933, 11168.944155279172, 11211.71970700739, 11527.250728702607, 11682.824757628472, 12185.573504927284, 12209.69475763113, 12238.326892021025, 12565.0, 12646.114771965875, 11786.410679466164, 11836.289822610326, 11935.0, 11976.940115299367, 12015.399230038638, 11720.765584688428, 11769.941150294193, 11866.347350895783, 11903.46434410471, 11935.403229838646, 11356.772864542829, 11450.255733377304, 11554.133439992202, 11785.528578856749, 11798.941005294919, 8129.8374032520205, 9781.315307928377, 9971.700848974542, 10119.544620491948, 10125.949370253102, 8502.403953498786, 9738.951305243429, 10070.0, 10101.0, 10239.590416383262], "unit": "score", "name": "yui.html", "value": 7210.545174301721}], "name": "dromaeo_css", "value": 7816.165795731508}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [115.0, 111.0, 115.0, 113.0, 115.0, 128.0, 124.0, 162.0, 116.0, 148.0], "unit": "score", "name": "ai-astar", "value": 124.7}, {"lowerIsBetter": true, "replicates": [180.0, 114.0, 180.0, 182.0, 150.0, 144.0, 116.0, 151.0, 144.0, 120.0], "unit": "score", "name": "audio-beat-detection", "value": 148.1}, {"lowerIsBetter": true, "replicates": [276.0, 254.0, 188.0, 220.0, 195.0, 197.0, 196.0, 194.0, 198.0, 198.0], "unit": "score", "name": "audio-dft", "value": 211.6}, {"lowerIsBetter": true, "replicates": [70.0, 73.0, 67.0, 107.0, 56.0, 57.0, 58.0, 59.0, 121.0, 82.0], "unit": "score", "name": "audio-fft", "value": 75.0}, {"lowerIsBetter": true, "replicates": [80.0, 82.0, 115.0, 79.0, 79.0, 82.0, 147.0, 115.0, 115.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 101.1}, {"lowerIsBetter": true, "replicates": [90.0, 88.0, 90.0, 88.0, 92.0, 88.0, 91.0, 96.0, 120.0, 87.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 93.0}, {"lowerIsBetter": true, "replicates": [96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0, 96.0, 94.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 95.0}, {"lowerIsBetter": true, "replicates": [90.0, 84.0, 137.0, 146.0, 92.0, 90.0, 94.0, 91.0, 92.0, 90.0], "unit": "score", "name": "imaging-desaturate", "value": 100.6}, {"lowerIsBetter": true, "replicates": [57.0, 58.0, 57.0, 60.0, 57.0, 87.0, 57.0, 59.0, 56.0, 58.0], "unit": "score", "name": "json-parse-financial", "value": 60.6}, {"lowerIsBetter": true, "replicates": [67.0, 66.0, 70.0, 77.0, 70.0, 74.0, 66.0, 64.0, 64.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.5}, {"lowerIsBetter": true, "replicates": [84.0, 82.0, 84.0, 83.0, 80.0, 83.0, 80.0, 82.0, 86.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.1}, {"lowerIsBetter": true, "replicates": [130.0, 134.0, 125.0, 102.0, 108.0, 108.0, 158.0, 132.0, 124.0, 128.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.9}, {"lowerIsBetter": true, "replicates": [130.0, 167.0, 136.0, 136.0, 138.0, 140.0, 137.0, 134.0, 136.0, 123.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 137.7}, {"lowerIsBetter": true, "replicates": [68.0, 63.0, 76.0, 69.0, 93.0, 94.0, 93.0, 96.0, 66.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 78.3}], "name": "kraken", "value": 1502.2}]} 11:35:05 INFO - Return code: 0 11:35:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:35:05 ERROR - # TBPL SUCCESS # 11:35:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:35:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:35:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:35:05 INFO - 2016-05-23 11:35:05.724 screenresolution[1691:12435] starting screenresolution argv=screenresolution get 11:35:05 INFO - 2016-05-23 11:35:05.727 screenresolution[1691:12435] Display 0: 1600x1200x32@60 11:35:05 INFO - 2016-05-23 11:35:05.732 screenresolution[1692:12438] starting screenresolution argv=screenresolution list 11:35:05 INFO - Available Modes on Display 0 11:35:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:35:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:35:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:35:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:35:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:35:05 INFO - Intel Iris: 11:35:05 INFO - Chipset Model: Intel Iris 11:35:05 INFO - Type: GPU 11:35:05 INFO - Bus: Built-In 11:35:05 INFO - VRAM (Dynamic, Max): 1536 MB 11:35:05 INFO - Vendor: Intel (0x8086) 11:35:05 INFO - Device ID: 0x0a2e 11:35:05 INFO - Revision ID: 0x0009 11:35:05 INFO - Displays: 11:35:05 INFO - Display: 11:35:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:35:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:35:05 INFO - Display Serial Number: 1600x1200 60 11:35:05 INFO - Main Display: Yes 11:35:05 INFO - Mirror: Off 11:35:05 INFO - Online: Yes 11:35:05 INFO - Rotation: Supported 11:35:05 INFO - Return code: 0 11:35:05 INFO - Running post-action listener: _resource_record_post_action 11:35:05 INFO - Running post-run listener: _resource_record_post_run 11:35:07 INFO - Total resource usage - Wall time: 1191s; CPU: 53.0%; Read bytes: 32517632; Write bytes: 512057856; Read time: 322; Write time: 1744 11:35:07 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 195834368; Write bytes: 197515264; Read time: 17930; Write time: 382 11:35:07 INFO - run-tests - Wall time: 1169s; CPU: 53.0%; Read bytes: 31596032; Write bytes: 303626752; Read time: 309; Write time: 1309 11:35:07 INFO - Running post-run listener: _upload_blobber_files 11:35:07 INFO - Blob upload gear active. 11:35:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:35:07 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:35:07 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:35:07 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:35:07 INFO - (blobuploader) - INFO - Open directory for files ... 11:35:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:35:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:35:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:08 INFO - (blobuploader) - INFO - Done attempting. 11:35:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:35:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:35:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:35:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:35:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:35:09 INFO - (blobuploader) - INFO - Done attempting. 11:35:09 INFO - (blobuploader) - INFO - Iteration through files over. 11:35:09 INFO - Return code: 0 11:35:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:35:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:35:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf8a49ff35e2bb4e6ab0e373deac361e4efe5a8cb1ff6cf4cc61ddac8bcca9d5db5d2db3865b275a7f747c5d4b1a4847165a05d4574dbc5f10e063c73e638d8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27a5a40104ac92e6ca8bff679c5e73c55f0ca19754d338b6553c7e3b144aa9b434b6ce4edbdb6b3535aecb7fbbc0072a3b7140e0639b9e39810606f2ae448fc2"} 11:35:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:35:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:35:09 INFO - Contents: 11:35:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf8a49ff35e2bb4e6ab0e373deac361e4efe5a8cb1ff6cf4cc61ddac8bcca9d5db5d2db3865b275a7f747c5d4b1a4847165a05d4574dbc5f10e063c73e638d8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27a5a40104ac92e6ca8bff679c5e73c55f0ca19754d338b6553c7e3b144aa9b434b6ce4edbdb6b3535aecb7fbbc0072a3b7140e0639b9e39810606f2ae448fc2"} 11:35:09 INFO - Running post-run listener: copy_logs_to_upload_dir 11:35:09 INFO - Copying logs to upload dir... 11:35:09 INFO - mkdir: /builds/slave/test/build/upload/logs 11:35:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1408.055033 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 28 secs) (at 2016-05-23 11:35:09.733011) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:09.736913) ========= 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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf8a49ff35e2bb4e6ab0e373deac361e4efe5a8cb1ff6cf4cc61ddac8bcca9d5db5d2db3865b275a7f747c5d4b1a4847165a05d4574dbc5f10e063c73e638d8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27a5a40104ac92e6ca8bff679c5e73c55f0ca19754d338b6553c7e3b144aa9b434b6ce4edbdb6b3535aecb7fbbc0072a3b7140e0639b9e39810606f2ae448fc2"} build_url:https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010565 build_url: 'https://queue.taskcluster.net/v1/task/NchvX2A6SceTu-XKKzWp8w/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cf8a49ff35e2bb4e6ab0e373deac361e4efe5a8cb1ff6cf4cc61ddac8bcca9d5db5d2db3865b275a7f747c5d4b1a4847165a05d4574dbc5f10e063c73e638d8f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27a5a40104ac92e6ca8bff679c5e73c55f0ca19754d338b6553c7e3b144aa9b434b6ce4edbdb6b3535aecb7fbbc0072a3b7140e0639b9e39810606f2ae448fc2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:09.768554) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:09.769170) ========= 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.GqqekmVBxa/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.Lm2QXa4c5e/Listeners TMPDIR=/var/folders/_p/g082hxs10zx5k7kq_ngsqt0h00000w/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.005318 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 11:35:09.819581) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 11:35:09.819942) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 11:35:09.820367) ========= ========= Total master_lag: 0.17 =========