builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0363 starttime: 1468190161.21 results: success (0) buildid: 20160710132925 builduid: 3736ed9763b34a63a290d9069e82464e revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.211836) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.212323) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.212668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.278428) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.278890) ========= 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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-07-10 15:36:01-- 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% 18.3M=0.001s 2016-07-10 15:36:01 (18.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.161091 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.463439) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.463815) ========= 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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.034869 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:01.520603) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 15:36:01.521031) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --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 ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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-07-10 15:36:01,603 truncating revision to first 12 chars 2016-07-10 15:36:01,603 Setting DEBUG logging. 2016-07-10 15:36:01,603 attempt 1/10 2016-07-10 15:36:01,603 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/ebd3919e8a3a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-10 15:36:02,733 unpacking tar archive at: mozilla-beta-ebd3919e8a3a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.408355 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-10 15:36:02.951747) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:02.952101) ========= script_repo_revision: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:02.952575) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:02.952895) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-10 15:36:02.975171) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-07-10 15:36:02.975883) ========= /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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:36:03 INFO - MultiFileLogger online at 20160710 15:36:03 in /builds/slave/test 15:36:03 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 15:36:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:36:03 INFO - {'append_to_log': False, 15:36:03 INFO - 'base_work_dir': '/builds/slave/test', 15:36:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:36:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:36:03 INFO - 'branch': 'Mozilla-Beta', 15:36:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:36:03 INFO - 'config_files': ('talos/mac_config.py',), 15:36:03 INFO - 'default_actions': ('clobber', 15:36:03 INFO - 'read-buildbot-config', 15:36:03 INFO - 'download-and-extract', 15:36:03 INFO - 'populate-webroot', 15:36:03 INFO - 'create-virtualenv', 15:36:03 INFO - 'install', 15:36:03 INFO - 'run-tests'), 15:36:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:36:03 INFO - 'download_minidump_stackwalk': True, 15:36:03 INFO - 'download_symbols': 'ondemand', 15:36:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:36:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:36:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:36:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:36:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:36:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:36:03 INFO - 'installer_path': 'installer.exe', 15:36:03 INFO - 'log_level': 'info', 15:36:03 INFO - 'log_name': 'talos', 15:36:03 INFO - 'log_to_console': True, 15:36:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:36:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:36:03 INFO - 'opt_config_files': (), 15:36:03 INFO - 'pip_index': False, 15:36:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:36:03 INFO - 'cmd': ('bash', 15:36:03 INFO - '-c', 15:36:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:36:03 INFO - 'enabled': True, 15:36:03 INFO - 'halt_on_failure': False, 15:36:03 INFO - 'name': 'check_screen_resolution'},), 15:36:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:36:03 INFO - 'cmd': ('bash', 15:36:03 INFO - '-c', 15:36:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:36:03 INFO - 'enabled': True, 15:36:03 INFO - 'halt_on_failure': False, 15:36:03 INFO - 'name': 'check_screen_resolution'},), 15:36:03 INFO - 'run_cmd_checks_enabled': True, 15:36:03 INFO - 'sps_profile': False, 15:36:03 INFO - 'sps_profile_interval': 0, 15:36:03 INFO - 'suite': 'dromaeojs-e10s', 15:36:03 INFO - 'system_bits': '32', 15:36:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:36:03 INFO - 'title': 't-yosemite-r7-0363', 15:36:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:36:03 INFO - 'use_talos_json': True, 15:36:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:36:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:36:03 INFO - 'work_dir': 'build'} 15:36:03 INFO - ##### 15:36:03 INFO - ##### Running clobber step. 15:36:03 INFO - ##### 15:36:03 INFO - Running pre-action listener: _resource_record_pre_action 15:36:03 INFO - Running main action method: clobber 15:36:03 INFO - rmtree: /builds/slave/test/build 15:36:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:36:05 INFO - Running post-action listener: _resource_record_post_action 15:36:05 INFO - ##### 15:36:05 INFO - ##### Running read-buildbot-config step. 15:36:05 INFO - ##### 15:36:05 INFO - Running pre-action listener: _resource_record_pre_action 15:36:05 INFO - Running main action method: read_buildbot_config 15:36:05 INFO - Using buildbot properties: 15:36:05 INFO - { 15:36:05 INFO - "project": "", 15:36:05 INFO - "product": "firefox", 15:36:05 INFO - "script_repo_revision": "production", 15:36:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:36:05 INFO - "repository": "", 15:36:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:36:05 INFO - "buildid": "20160710132925", 15:36:05 INFO - "pgo_build": "False", 15:36:05 INFO - "basedir": "/builds/slave/test", 15:36:05 INFO - "buildnumber": 14, 15:36:05 INFO - "slavename": "t-yosemite-r7-0363", 15:36:05 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 15:36:05 INFO - "platform": "yosemite_r7", 15:36:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:36:05 INFO - "branch": "mozilla-beta", 15:36:05 INFO - "repo_path": "releases/mozilla-beta", 15:36:05 INFO - "revision": "ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e", 15:36:05 INFO - "stage_platform": "macosx64", 15:36:05 INFO - "builduid": "3736ed9763b34a63a290d9069e82464e", 15:36:05 INFO - "slavebuilddir": "test" 15:36:05 INFO - } 15:36:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 15:36:05 INFO - Running post-action listener: _resource_record_post_action 15:36:05 INFO - ##### 15:36:05 INFO - ##### Running download-and-extract step. 15:36:05 INFO - ##### 15:36:05 INFO - Running pre-action listener: _resource_record_pre_action 15:36:05 INFO - Running main action method: download_and_extract 15:36:05 INFO - mkdir: /builds/slave/test/build/tests 15:36: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')]} 15:36:05 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:36:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:36:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 15:36:20 INFO - Downloaded 1165 bytes. 15:36:20 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 15:36:20 INFO - Using the following test package requirements: 15:36:20 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:36:20 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 15:36:20 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 15:36:20 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:36:20 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 15:36:20 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 15:36:20 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 15:36:20 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 15:36:20 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 15:36:20 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 15:36:20 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 15:36:20 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 15:36:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:36:20 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:36:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:36:23 INFO - Downloaded 18351523 bytes. 15:36:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:36:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:36:27 INFO - Return code: 0 15:36:27 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 15:36:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:36:27 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:36:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 15:36:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 15:36:57 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip: timed out 15:36:57 INFO - retry: attempt #1 caught exception: timed out 15:36:57 INFO - retry: Failed, sleeping 30 seconds before retrying 15:37:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #2 15:37:29 INFO - Downloaded 18351523 bytes. 15:37:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 15:37:32 INFO - Return code: 0 15:37:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:37:32 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:37:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 15:37:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 15:37:33 INFO - Downloaded 10840145 bytes. 15:37:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 15:37:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 15:37:33 INFO - Return code: 0 15:37:33 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')]} 15:37:33 INFO - https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 15:37:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 15:37:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 15:37:38 INFO - Downloaded 86188281 bytes. 15:37:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:38 INFO - mkdir: /builds/slave/test/properties 15:37:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:37:38 INFO - Writing to file /builds/slave/test/properties/build_url 15:37:38 INFO - Contents: 15:37:38 INFO - build_url:https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg 15:37:39 INFO - Running post-action listener: _resource_record_post_action 15:37:39 INFO - Running post-action listener: set_extra_try_arguments 15:37:39 INFO - ##### 15:37:39 INFO - ##### Running populate-webroot step. 15:37:39 INFO - ##### 15:37:39 INFO - Running pre-action listener: _resource_record_pre_action 15:37:39 INFO - Running main action method: populate_webroot 15:37:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:37:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:37:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:37:39 INFO - '--tppagecycles', 15:37:39 INFO - '7'], 15:37:39 INFO - 'tests': ['tsvgm']}}, 15:37:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:37:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:37:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:37:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:37:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:37:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['damp', 'tps']}, 15:37:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'tests': ['damp', 'tps']}, 15:37:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['dromaeo_dom']}, 15:37:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:37:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'other-e10s': {'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['a11yr', 15:37:39 INFO - 'ts_paint', 15:37:39 INFO - 'tpaint', 15:37:39 INFO - 'sessionrestore', 15:37:39 INFO - 'sessionrestore_no_auto_restore', 15:37:39 INFO - 'tabpaint']}, 15:37:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['tsvgx', 15:37:39 INFO - 'tsvgr_opacity', 15:37:39 INFO - 'tart', 15:37:39 INFO - 'tscrollx', 15:37:39 INFO - 'cart']}, 15:37:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:37:39 INFO - 'tsvgr_opacity', 15:37:39 INFO - 'tart', 15:37:39 INFO - 'tscrollx', 15:37:39 INFO - 'cart']}, 15:37:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'talos_options': ['--disable-e10s'], 15:37:39 INFO - 'tests': ['tp5o']}, 15:37:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'tests': ['tp5o']}, 15:37:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'talos_options': ['--disable-e10s', 15:37:39 INFO - '--xperf_path', 15:37:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:39 INFO - 'tests': ['tp5n']}, 15:37:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:37:39 INFO - 'talos_options': ['--xperf_path', 15:37:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:37:39 INFO - 'tests': ['tp5n']}}, 15:37:39 INFO - 'talos.zip': {'path': '', 15:37:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:37:39 INFO - Running post-action listener: _resource_record_post_action 15:37:39 INFO - ##### 15:37:39 INFO - ##### Running create-virtualenv step. 15:37:39 INFO - ##### 15:37:39 INFO - Running pre-action listener: _resource_record_pre_action 15:37:39 INFO - Running main action method: create_virtualenv 15:37:39 INFO - Creating virtualenv /builds/slave/test/build/venv 15:37: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 15:37:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:37:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:37:39 INFO - Using real prefix '/tools/python27' 15:37:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:37:40 INFO - Installing distribute.............................................................................................................................................................................................done. 15:37:43 INFO - Installing pip.................done. 15:37:43 INFO - Return code: 0 15:37:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:37: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')]} 15:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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')]} 15:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:37: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 15:37: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 15:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:43 INFO - 'HOME': '/Users/cltbld', 15:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:43 INFO - 'LOGNAME': 'cltbld', 15:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:43 INFO - 'MOZ_NO_REMOTE': '1', 15:37:43 INFO - 'NO_EM_RESTART': '1', 15:37:43 INFO - 'PAGER': '/bin/cat', 15:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:43 INFO - 'PWD': '/builds/slave/test', 15:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:43 INFO - 'SHELL': '/bin/bash', 15:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:37:43 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:43 INFO - 'USER': 'cltbld', 15:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:43 INFO - 'XPC_FLAGS': '0x0', 15:37:43 INFO - 'XPC_SERVICE_NAME': '0', 15:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37: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 15:37:43 INFO - Cleaning up... 15:37:43 INFO - Return code: 0 15:37:43 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:37: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')]} 15:37:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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')]} 15:37:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:37: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 15:37: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 15:37:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:37:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:43 INFO - 'HOME': '/Users/cltbld', 15:37:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:43 INFO - 'LOGNAME': 'cltbld', 15:37:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:43 INFO - 'MOZ_NO_REMOTE': '1', 15:37:43 INFO - 'NO_EM_RESTART': '1', 15:37:43 INFO - 'PAGER': '/bin/cat', 15:37:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:43 INFO - 'PWD': '/builds/slave/test', 15:37:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:43 INFO - 'SHELL': '/bin/bash', 15:37:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:37:43 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:37:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:43 INFO - 'USER': 'cltbld', 15:37:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:43 INFO - 'XPC_FLAGS': '0x0', 15:37:43 INFO - 'XPC_SERVICE_NAME': '0', 15:37:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:43 INFO - Downloading/unpacking psutil>=0.7.1 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:37:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:46 INFO - Installing collected packages: psutil 15:37:46 INFO - Running setup.py install for psutil 15:37:46 INFO - building 'psutil._psutil_osx' extension 15:37: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 15:37: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 15:37: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 15:37: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 15:37:47 INFO - building 'psutil._psutil_posix' extension 15:37: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 15:37:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:37:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:37:47 INFO - ^ 15:37:47 INFO - 1 warning generated. 15:37: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 15:37:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:37:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:37:47 INFO - Successfully installed psutil 15:37:47 INFO - Cleaning up... 15:37:47 INFO - Return code: 0 15:37:47 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:37:47 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')]} 15:37:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 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')]} 15:37:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:47 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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:37:47 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 15:37:47 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 15:37:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:37:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:47 INFO - 'HOME': '/Users/cltbld', 15:37:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:47 INFO - 'LOGNAME': 'cltbld', 15:37:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:47 INFO - 'MOZ_NO_REMOTE': '1', 15:37:47 INFO - 'NO_EM_RESTART': '1', 15:37:47 INFO - 'PAGER': '/bin/cat', 15:37:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:47 INFO - 'PWD': '/builds/slave/test', 15:37:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:47 INFO - 'SHELL': '/bin/bash', 15:37:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:37:47 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:37:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:47 INFO - 'USER': 'cltbld', 15:37:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:47 INFO - 'XPC_FLAGS': '0x0', 15:37:47 INFO - 'XPC_SERVICE_NAME': '0', 15:37:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:48 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:50 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:37:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:37: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) 15:37:50 INFO - Installing collected packages: mozsystemmonitor 15:37:50 INFO - Running setup.py install for mozsystemmonitor 15:37:50 INFO - Successfully installed mozsystemmonitor 15:37:50 INFO - Cleaning up... 15:37:50 INFO - Return code: 0 15:37:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:37:50 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')]} 15:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 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')]} 15:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37:50 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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:37:50 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 15:37:50 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 15:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:50 INFO - 'HOME': '/Users/cltbld', 15:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:50 INFO - 'LOGNAME': 'cltbld', 15:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:50 INFO - 'MOZ_NO_REMOTE': '1', 15:37:50 INFO - 'NO_EM_RESTART': '1', 15:37:50 INFO - 'PAGER': '/bin/cat', 15:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:50 INFO - 'PWD': '/builds/slave/test', 15:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:50 INFO - 'SHELL': '/bin/bash', 15:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:37:50 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:50 INFO - 'USER': 'cltbld', 15:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:50 INFO - 'XPC_FLAGS': '0x0', 15:37:50 INFO - 'XPC_SERVICE_NAME': '0', 15:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:51 INFO - Downloading/unpacking blobuploader==1.2.4 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:53 INFO - Downloading blobuploader-1.2.4.tar.gz 15:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:37:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:37:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37: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 15:37:54 INFO - Downloading docopt-0.6.1.tar.gz 15:37:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:37:54 INFO - Installing collected packages: blobuploader, requests, docopt 15:37:54 INFO - Running setup.py install for blobuploader 15:37:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:37:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:37:55 INFO - Running setup.py install for requests 15:37:55 INFO - Running setup.py install for docopt 15:37:55 INFO - Successfully installed blobuploader requests docopt 15:37:55 INFO - Cleaning up... 15:37:55 INFO - Return code: 0 15:37:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:37: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')]} 15:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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')]} 15:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:37: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:37: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 15:37: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 15:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:37:55 INFO - 'HOME': '/Users/cltbld', 15:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:37:55 INFO - 'LOGNAME': 'cltbld', 15:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:37:55 INFO - 'MOZ_NO_REMOTE': '1', 15:37:55 INFO - 'NO_EM_RESTART': '1', 15:37:55 INFO - 'PAGER': '/bin/cat', 15:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:37:55 INFO - 'PWD': '/builds/slave/test', 15:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:37:55 INFO - 'SHELL': '/bin/bash', 15:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:37:55 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:37:55 INFO - 'USER': 'cltbld', 15:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:37:55 INFO - 'XPC_FLAGS': '0x0', 15:37:55 INFO - 'XPC_SERVICE_NAME': '0', 15:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:37:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-10_FQN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-y0CPSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-zMrycK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-dIpAxl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-1YRQnV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-6S072F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-uQdrLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-NOy5Sp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-5_8vBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:37:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:37:56 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-oiDev6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-lieFmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-epYyIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-qDQ_n5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-TccVum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-u9_DWD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-u8iwsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:37:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:37:57 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-h3QBgg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:37:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:37:57 INFO - Running setup.py install for manifestparser 15:37:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:37:57 INFO - Running setup.py install for mozcrash 15:37:58 INFO - Running setup.py install for mozdebug 15:37:58 INFO - Running setup.py install for mozdevice 15:37:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozfile 15:37:58 INFO - Running setup.py install for mozhttpd 15:37:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozinfo 15:37:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozInstall 15:37:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:37:58 INFO - Running setup.py install for mozleak 15:37:59 INFO - Running setup.py install for mozlog 15:37:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for moznetwork 15:37:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for mozprocess 15:37:59 INFO - Running setup.py install for mozprofile 15:37:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for mozrunner 15:37:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:37:59 INFO - Running setup.py install for mozscreenshot 15:37:59 INFO - Running setup.py install for moztest 15:38:00 INFO - Running setup.py install for mozversion 15:38:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:38:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:38:00 INFO - Cleaning up... 15:38:00 INFO - Return code: 0 15:38:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:38: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 15:38: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 15:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:00 INFO - 'HOME': '/Users/cltbld', 15:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:00 INFO - 'LOGNAME': 'cltbld', 15:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:00 INFO - 'MOZ_NO_REMOTE': '1', 15:38:00 INFO - 'NO_EM_RESTART': '1', 15:38:00 INFO - 'PAGER': '/bin/cat', 15:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:00 INFO - 'PWD': '/builds/slave/test', 15:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:00 INFO - 'SHELL': '/bin/bash', 15:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:38:00 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:00 INFO - 'USER': 'cltbld', 15:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:00 INFO - 'XPC_FLAGS': '0x0', 15:38:00 INFO - 'XPC_SERVICE_NAME': '0', 15:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:38:00 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-bDuNPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:38: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)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:38:00 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-9Fzrid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:38: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)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:38:00 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-tlN2HC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:38: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)) 15:38:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:38:00 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-o4oZiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-9yWY1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-IgJ9Yk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-eOxvAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-2x0xvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-hpdBg0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-HInn6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-OZ1RPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-VTVGRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:38: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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-RRi6Tz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:38:01 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)) 15:38:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:38:01 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-IqodxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:38: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)) 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:38:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-OaJ9JB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:38: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)) 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:38:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-s_0GZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:38: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)) 15:38:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:38:02 INFO - Running setup.py (path:/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/pip-17K4AZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:04 INFO - Downloading blessings-1.6.tar.gz 15:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:38:04 INFO - Installing collected packages: blessings 15:38:04 INFO - Running setup.py install for blessings 15:38:05 INFO - Successfully installed blessings 15:38:05 INFO - Cleaning up... 15:38:05 INFO - Return code: 0 15:38:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:38:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:05 INFO - Reading from file tmpfile_stdout 15:38:05 INFO - Current package versions: 15:38:05 INFO - blessings == 1.6 15:38:05 INFO - blobuploader == 1.2.4 15:38:05 INFO - docopt == 0.6.1 15:38:05 INFO - manifestparser == 1.1 15:38:05 INFO - mozInstall == 1.12 15:38:05 INFO - mozcrash == 0.17 15:38:05 INFO - mozdebug == 0.1 15:38:05 INFO - mozdevice == 0.48 15:38:05 INFO - mozfile == 1.2 15:38:05 INFO - mozhttpd == 0.7 15:38:05 INFO - mozinfo == 0.9 15:38:05 INFO - mozleak == 0.1 15:38:05 INFO - mozlog == 3.1 15:38:05 INFO - moznetwork == 0.27 15:38:05 INFO - mozprocess == 0.22 15:38:05 INFO - mozprofile == 0.28 15:38:05 INFO - mozrunner == 6.11 15:38:05 INFO - mozscreenshot == 0.1 15:38:05 INFO - mozsystemmonitor == 0.0 15:38:05 INFO - moztest == 0.7 15:38:05 INFO - mozversion == 1.4 15:38:05 INFO - psutil == 3.1.1 15:38:05 INFO - requests == 1.2.3 15:38:05 INFO - wsgiref == 0.1.2 15:38:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:38: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 15:38: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 15:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:05 INFO - 'HOME': '/Users/cltbld', 15:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:05 INFO - 'LOGNAME': 'cltbld', 15:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:05 INFO - 'MOZ_NO_REMOTE': '1', 15:38:05 INFO - 'NO_EM_RESTART': '1', 15:38:05 INFO - 'PAGER': '/bin/cat', 15:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:05 INFO - 'PWD': '/builds/slave/test', 15:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:05 INFO - 'SHELL': '/bin/bash', 15:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:38:05 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:05 INFO - 'USER': 'cltbld', 15:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:05 INFO - 'XPC_FLAGS': '0x0', 15:38:05 INFO - 'XPC_SERVICE_NAME': '0', 15:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38: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)) 15:38:05 INFO - Cleaning up... 15:38:05 INFO - Return code: 0 15:38:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:38: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 15:38: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 15:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:05 INFO - 'HOME': '/Users/cltbld', 15:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:05 INFO - 'LOGNAME': 'cltbld', 15:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:05 INFO - 'MOZ_NO_REMOTE': '1', 15:38:05 INFO - 'NO_EM_RESTART': '1', 15:38:05 INFO - 'PAGER': '/bin/cat', 15:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:05 INFO - 'PWD': '/builds/slave/test', 15:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:05 INFO - 'SHELL': '/bin/bash', 15:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:38:05 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:05 INFO - 'USER': 'cltbld', 15:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:05 INFO - 'XPC_FLAGS': '0x0', 15:38:05 INFO - 'XPC_SERVICE_NAME': '0', 15:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:06 INFO - Downloading/unpacking jsonschema 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:08 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:38:08 INFO - Downloading/unpacking functools32 (from jsonschema) 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:08 INFO - Downloading functools32-3.2.3-2.tar.gz 15:38:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:38:08 INFO - warning: no files found matching '*.txt' 15:38:08 INFO - no previously-included directories found matching 'build' 15:38:08 INFO - no previously-included directories found matching 'dist' 15:38:08 INFO - no previously-included directories found matching '.git*' 15:38:08 INFO - Installing collected packages: jsonschema, functools32 15:38:09 INFO - Running setup.py install for functools32 15:38:09 INFO - warning: no files found matching '*.txt' 15:38:09 INFO - no previously-included directories found matching 'build' 15:38:09 INFO - no previously-included directories found matching 'dist' 15:38:09 INFO - no previously-included directories found matching '.git*' 15:38:09 INFO - Successfully installed jsonschema functools32 15:38:09 INFO - Cleaning up... 15:38:09 INFO - Return code: 0 15:38:09 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:38: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')]} 15:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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')]} 15:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:38: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 0x10ffb6a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ffc6420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd10de2700>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, '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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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 15:38: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 15:38: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 15:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:09 INFO - 'HOME': '/Users/cltbld', 15:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:09 INFO - 'LOGNAME': 'cltbld', 15:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:09 INFO - 'MOZ_NO_REMOTE': '1', 15:38:09 INFO - 'NO_EM_RESTART': '1', 15:38:09 INFO - 'PAGER': '/bin/cat', 15:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:09 INFO - 'PWD': '/builds/slave/test', 15:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:09 INFO - 'SHELL': '/bin/bash', 15:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:38:09 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:09 INFO - 'USER': 'cltbld', 15:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:09 INFO - 'XPC_FLAGS': '0x0', 15:38:09 INFO - 'XPC_SERVICE_NAME': '0', 15:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:38:09 INFO - Downloading/unpacking flake8 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:38:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:38:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:38:12 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:38:12 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38: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 15:38:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:38:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:38:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:38:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:38:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:38:13 INFO - no previously-included directories found matching 'docs/_build' 15:38:13 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:38:13 INFO - Running setup.py install for pep8 15:38:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:38:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:38:13 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:38:13 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:38:13 INFO - no previously-included directories found matching 'docs/_build' 15:38:13 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:38:13 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:38:13 INFO - Cleaning up... 15:38:13 INFO - Return code: 0 15:38:13 INFO - Running post-action listener: _resource_record_post_action 15:38:13 INFO - Running post-action listener: _start_resource_monitoring 15:38:13 INFO - Starting resource monitoring. 15:38:13 INFO - ##### 15:38:13 INFO - ##### Running install step. 15:38:13 INFO - ##### 15:38:13 INFO - Running pre-action listener: _resource_record_pre_action 15:38:13 INFO - Running main action method: install 15:38:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:38:13 INFO - Reading from file tmpfile_stdout 15:38:13 INFO - Detecting whether we're running mozinstall >=1.0... 15:38:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:38:14 INFO - Reading from file tmpfile_stdout 15:38:14 INFO - Output received: 15:38:14 INFO - Usage: mozinstall [options] installer 15:38:14 INFO - Options: 15:38:14 INFO - -h, --help show this help message and exit 15:38:14 INFO - -d DEST, --destination=DEST 15:38:14 INFO - Directory to install application into. [default: 15:38:14 INFO - "/builds/slave/test"] 15:38:14 INFO - --app=APP Application being installed. [default: firefox] 15:38:14 INFO - mkdir: /builds/slave/test/build/application 15:38:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:38:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 15:38:35 INFO - Reading from file tmpfile_stdout 15:38:35 INFO - Output received: 15:38:35 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:38:35 INFO - Running post-action listener: _resource_record_post_action 15:38:35 INFO - ##### 15:38:35 INFO - ##### Running run-tests step. 15:38:35 INFO - ##### 15:38:35 INFO - Running pre-action listener: _resource_record_pre_action 15:38:35 INFO - Running main action method: run_tests 15:38:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:38:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:38:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:38:35 INFO - 2016-07-10 15:38:35.716 screenresolution[1684:17943] starting screenresolution argv=screenresolution get 15:38:35 INFO - 2016-07-10 15:38:35.718 screenresolution[1684:17943] Display 0: 1600x1200x32@60 15:38:35 INFO - 2016-07-10 15:38:35.724 screenresolution[1685:17945] starting screenresolution argv=screenresolution list 15:38:35 INFO - Available Modes on Display 0 15:38:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:38:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:38:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:38:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:38:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:38:35 INFO - Intel Iris: 15:38:35 INFO - Chipset Model: Intel Iris 15:38:35 INFO - Type: GPU 15:38:35 INFO - Bus: Built-In 15:38:35 INFO - VRAM (Dynamic, Max): 1536 MB 15:38:35 INFO - Vendor: Intel (0x8086) 15:38:35 INFO - Device ID: 0x0a2e 15:38:35 INFO - Revision ID: 0x0009 15:38:35 INFO - Displays: 15:38:35 INFO - Display: 15:38:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:38:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:38:35 INFO - Display Serial Number: 1600x1200 60 15:38:35 INFO - Main Display: Yes 15:38:35 INFO - Mirror: Off 15:38:35 INFO - Online: Yes 15:38:35 INFO - Rotation: Supported 15:38:35 INFO - Return code: 0 15:38:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:38:35 INFO - Python 2.7.3 15:38:35 INFO - Return code: 0 15:38:35 INFO - grabbing minidump binary from tooltool 15:38: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')]} 15:38:35 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 0x10fe55e00>, '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 0x10fcbedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10fe50b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:38:35 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 15:38:35 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 15:38:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:38:36 INFO - Return code: 0 15:38:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:38:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:38:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:38:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:38:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:38:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:38:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:38:37 INFO - Return code: 0 15:38:37 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/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 15:38:37 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/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0363 --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 15:38:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OOKgsLVUd2/Render', 15:38:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:38:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:38:37 INFO - 'HOME': '/Users/cltbld', 15:38:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:38:37 INFO - 'LOGNAME': 'cltbld', 15:38:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:38:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:38:37 INFO - 'MOZ_NO_REMOTE': '1', 15:38:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:38:37 INFO - 'NO_EM_RESTART': '1', 15:38:37 INFO - 'PAGER': '/bin/cat', 15:38:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:38:37 INFO - 'PWD': '/builds/slave/test', 15:38:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:38:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:38:37 INFO - 'SHELL': '/bin/bash', 15:38:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5DBEOrt1wN/Listeners', 15:38:37 INFO - 'TMPDIR': '/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/', 15:38:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:38:37 INFO - 'USER': 'cltbld', 15:38:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:38:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:38:37 INFO - 'XPC_FLAGS': '0x0', 15:38:37 INFO - 'XPC_SERVICE_NAME': '0', 15:38:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:38:37 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/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0363', '--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 15:38:37 INFO - mozversion application_buildid: 20160710132925 15:38:37 INFO - mozversion application_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 15:38:37 INFO - mozversion application_display_name: Firefox 15:38:37 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:38:37 INFO - mozversion application_name: Firefox 15:38:37 INFO - mozversion application_remotingname: firefox 15:38:37 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:37 INFO - mozversion application_vendor: Mozilla 15:38:37 INFO - mozversion application_version: 48.0 15:38:37 INFO - mozversion platform_buildid: 20160710132925 15:38:37 INFO - mozversion platform_changeset: ebd3919e8a3a0c4bcc5c3933ab693b44b38f490e 15:38:37 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:38:37 INFO - mozversion platform_version: 48.0 15:38:37 INFO - using testdate: 1468190317 15:38:37 INFO - actual date: 1468190317 15:38:37 INFO - starting webserver on 'localhost:49277' 15:38:37 INFO - SUITE-START | Running 2 tests 15:38:37 INFO - TEST-START | dromaeo_css 15:38:37 INFO - operating with platform_type : mac_ 15:38:37 INFO - Initialising browser for dromaeo_css test... 15:38:37 INFO - TEST-INFO | started process 1703 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpOyrE2q/profile http://localhost:49277/getInfo.html) 15:38:40 INFO - PROCESS | 1703 | __metrics Screen width/height:1600/1200 15:38:40 INFO - PROCESS | 1703 | colorDepth:24 15:38:40 INFO - PROCESS | 1703 | Browser inner width/height: 1024/689 15:38:40 INFO - PROCESS | 1703 | __metrics 15:38:40 ERROR - PROCESS | 1703 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 15:38:41 INFO - TEST-INFO | 1703: exit 0 15:38:41 INFO - Browser initialized. 15:38:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:38:41 INFO - TEST-INFO | started process 1707 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmpOyrE2q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:53 INFO - PROCESS | 1707 | RSS: Main: 171487232 15:38:53 INFO - PROCESS | 1707 | 15:39:42 INFO - PROCESS | 1707 | 1468190382910 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] 15:41:53 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 15:41:54 INFO - PROCESS | 1707 | RSS: Main: 182116352 15:41:54 INFO - PROCESS | 1707 | 15:44:57 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 15:44:57 INFO - PROCESS | 1707 | RSS: Main: 193073152 15:44:57 INFO - PROCESS | 1707 | 15:47:59 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 15:47:59 INFO - PROCESS | 1707 | RSS: Main: 194793472 15:47:59 INFO - PROCESS | 1707 | 15:51:01 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 15:51:02 INFO - PROCESS | 1707 | RSS: Main: 198504448 15:51:02 INFO - PROCESS | 1707 | 15:54:04 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 15:54:05 INFO - PROCESS | 1707 | RSS: Main: 197615616 15:54:05 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 15:57:07 INFO - PROCESS | 1707 | RSS: Main: 203280384 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | __start_tp_report 15:57:07 INFO - PROCESS | 1707 | _x_x_mozilla_page_load 15:57:07 INFO - PROCESS | 1707 | _x_x_mozilla_page_load_details 15:57:07 INFO - PROCESS | 1707 | |i|pagename|runs| 15:57:07 INFO - PROCESS | 1707 | |0;dojo.html;3470.722342212623;3706.962930370697;3755.887323380296;3762.849486020559;3771.3400154972874;6060.666663333515;6097.207363042804;6104.084387341901;6137.9999999999945;6161.199044124258;6049.697515124249;6099.999999999989;6118.69406529674;6119.418655227757;6152.446279834814;5395.541378982791;5445.482679145494;5462.426445223254;5479;5582.134769110794;5449.4005659377435;5557.6109672322955;5561.138023606327;5564.972175139139;5572.944270557304;5520.972395138038;5531.889362212753;5549.833504994836;5559.11054231323;5560.388357280696;5550.2507161533285;5557;5557.638753481031;5585.776568937278;5594.328680558359;4483.596476317114;5231.738413079331;5461.836144915659;5541.390447050821;5570.303712035996;5572.888542229173;5774;5929.851753706149;5965.105234214859;6071.605345652519;5773.682447465388;6057.091436284549;6241;6259.280182778994;6292.87414251717;5734.16854556087;6404.391582799627;6419.293877673499;6429.2284925808735;6452.677366131722;6032.276126864805;6134.877302454015;6189.16446279751;6196.132541444202;6202.844928876814;6431.260405053479;6529.444997175293;6541.70562324687;6555.115059466911;6558.8360290993105;5139.974300128551;6262.655553944485;6278.15244941927;6279.309275979638;6285.057241413824;6266.624002559861;6284.874302513924;6358.650274234869;6389.904151437732;6398.936010639834;6261.373862613702;6297.874042519215;6300.117983482316;6307.558470906993;6307.590006649553;6234.127222188898;6312.873742525124;6316.873662526724;6317.3366796486625;6330.8417289567205;4990.126727822617;5052.9242061369105;5053.519915608012;5100.413452452941;5139.203423469368;4617.746023968647;4626.352310676508;4659.161350956861;4659.627229821606;4670.042641258534;6179.3820617937845;6204.751809927552;6241.531885108635;6249.25008998923;6261.780837670751;5420.566354691615;6269.7492100316385;6415.13395691585;6440.323766004596;6491.285958544557;6165.352637973038;6208.844778880474;6217.937820621826;6232.688365581703;6240.500759939194;5589.049861523551;5691.089425691952;5696.857578560569;5702.6863522506665;5730.19777231188;6525.184351956005;6626.039224312544;6631.568948018364;6646;6774.695138718671;6155.938440615716;6436.67816609177;6441.227052753699;6464.773732919325;6488.480921526172;6543.5419520635005;6718.496112791462;6756.223034351113;6776.254611992776;6789.2871248518195;6267.592606480564;6331.96834015827;6335.746570137143;6355.110284560073;6380.457661098673;6378.968105159444;6406.615603063831;6415.262244841716;6430;6432.6462044588;6115.6330620162935;6157.907631385622;6273.560850740587;6278.372162783685;6279.277883043508;6221.751129954751;6249.718762655601;6250.718717657807;6268.310485846644;6295.150154729053;5327.547158491571;6173.197484327009;6237.500999919905;6246.250449946036;6288.9685551573775;6122.337166850181;6163.414475624809;6232.407921247655;6286.025666021774;6318.778842740482;6167.784127555514;6245.063240513778;6253.030780229072;6255.812325630239;6269.811905642656 15:57:07 INFO - PROCESS | 1707 | |1;ext.html;541.6452223793416;553.8330040861538;555.0425515984924;581.3053401185582;593.2051051590872;39596.208075838505;40639.59360406395;40652.186956260855;40661.796691016505;40757.796211018904;40278.39582406266;40621.187576248456;41253.79373103138;41259.381109283386;41395.96510087249;9569.13877751004;9791.06984836443;9809.460479673615;9815.411075335467;9823.803523929517;9761.511924403787;9936.205103591732;10001.649942252023;10017.649382271586;10059.497025148714;9940.602375904955;9940.751481212992;9953.353032052893;9994;10009.399436033862;9867.457289849055;9931;9982.450965196947;10002.89997100027;10047.799044019152;9744.707658770249;9864.802703945881;9867.358621689567;9879.703608891672;9897.505124743733;40980.59019409827;41052.38421423681;41331.38002929959;41376.37935430941;41625.79187104045;3468.3756923753713;3505.2638945821154;3506.5266189064405;3508.333416650854;4076.2255171517613;27135.321616959536;27657;27815.58276625852;28141.57787633187;28195.436091278265;2724.6049322848276;2817.1830169250893;2817.7323154300307;2849.387381712921;2895.0880472651047;424183;425607;427398;427917;429336;38048.61951380451;66331;67488.32511674918;67490;67498.65002699918;36802.34389452481;37237.441438378446;37373.43939840904;37434.62565374311;37505;14002.299885005708;16415.93296435752;16793;16815.2433140509;16817.15914204285;13809.585712428643;13959.953003524777;14089.295535223198;14172.503962361268;14224.644383890485;8373.8325233495;8458.74623761288;8476.406651534457;8513.616887240089;8582;3539.3275277697153;3574.303010912847;3586.8744593939086;3619.7828130312264;3635.182084031072;894.4990805149138;1067.5676351077825;1094.7591529863416;1121.2277841835223;1185.158537438411;30890.382192356028;31285.530717039263;31329.92263663168;31650.36699266002;32152.517712234334;1120.2830188679252;1158.2182027131653;1167.6730515455686;1182.5860948667898;1205.4394706461537;14319.928400357932;14669.48656797007;14904.62738431548;15071.171085590398;15112.395504179929;639499;647452;660062;660273;662127;62714;63438.682806585675;63454.682726586994;63649;63756;1558.820735615396;1573.6301969037268;1583.2716950202805;1585.7859189009337;1594.5854077939587;29191.416171676025;29428.264293392836;29582.40835183241;29988.850055749583;30094.398112038074;53727.194092089434;54162.72918635382;55047.073352432475;55083.622909427584;55085.724571376886;590739;594580;604433;605850.9707451435;606970;25932.96268149253;27624.171274861787;27796.30509237285;27962.021329253377;28177.43645127045;46302.76848615735;46701.53298466972;47626;47727.76136119436;48441.27338089861;37679.24641507209;38264;38741.8377448677;39212.2157556853;39945.40081898716;37990.63236507934;38101;38241;38761.224775504896;39056.828295151194;33732;37404.81297593495;39009.024774380865;39190.412143817295;39257.607423925394;31598.210044749067;33131.66868331382;33539.83230083834;33638;33786.49320260247;24789.88445519992;24971.87514062418;25062.74937250604;25427.74572254254;25519.362015949006 15:57:07 INFO - PROCESS | 1707 | |2;jquery.html;359.36033859729685;411.74677573292416;412.8947118484786;415.3769345981028;423.05658381808564;15396.999195052314;19999.900000499976;20860.269890553835;21175;22590.98340574674;19305.420837374866;21994.89002554989;22170.224042158527;22344.888275558642;22430.102795888186;5725.169850371693;5911.231539899807;6185.381461853824;6203.2245969253845;6210;6035.668038257894;6045.455908968192;6085.543584231178;6115.908261376061;6138.7237574309265;5972.074328479071;6079.240094988127;6107.5113990880845;6114.205153330061;6150.876982460348;5995.310539287994;6061.181740465025;6095.451409373156;6134.631922084689;6145.569810113294;5004.924926126111;5407.891842163174;5610.270664813589;5941.316748573883;5965.612235204741;13667.931660341734;13945;13966.162030278216;13973.580792576238;14177.645558861008;2052.671572548385;2133.9466513337134;2182.050807898569;2339.986235375088;2360.6459031145296;10187.847182292271;10197.031282028196;10257.333273337284;10474.21443391741;10598.947005265001;1785.7678501794815;1841.9815801841944;1866.496046067554;1882.9152688129068;1903.9428817135508;116411.41794291059;118355.99999999914;145112;157715;161233;22239.55520889573;22958.770412296;23308.533829323318;23351.299461016195;23609.7639023611;36932.63067369346;37335.4399684005;38640.61359386426;38850.22299553993;38948;11709.707257318636;12009.279443233434;12041.157119001586;12043.277403355827;12081.516739330329;10029.949850250701;10030.949845250872;10041.598336066621;10072.446015469073;10194.337368071052;10850.67447976562;10955.068819150461;11017.889821101846;11051.61319353835;11065.72335691598;3598.2623562169692;4083.2037752637953;4202.306619407764;4303.397524346594;4346.413234213404;855.8416692911832;907.5008745190119;950.6102497975796;978.6232300564192;992.1170158558888;20268.493287667927;21438;21612.89193554022;21703.348899532724;21742.565148697253;1004.6433516101824;1011;1020.550957578663;1067.9519421626046;1080.0819303591884;11396.829047564293;11477.852214778455;11612;11628.94185529067;11714.941425292818;551757;669696;685671;693974;703585;109707.45146274217;135955.32022339824;137350.3132484331;137431;141797;1027.9537420816082;1051.726551096705;1061.0662616897234;1068.7007637861257;1078.800421921947;18542.0728963554;19177.94521301286;19404.61190776205;19436;19775.307864224684;50299.49700502948;54287.45712542824;54403;55529.16706249329;58430.70784646049;48419;64045.71908561709;66696;69137.30862691309;72053.27946720466;8251.257386835214;8330.083690794128;8505.277051450696;8751.824963500567;8827.07315731839;12361.258324500199;13285.40215690277;13541.72916541644;13630.386632601754;13729.656758581115;8881.822363552821;10628.84056739165;11521.366324852213;11603.88396116028;11746.530138794353;9721.125098741148;10920.235583509078;11238.269512481662;11380.829287560528;11777.587784427504;11166;11584.826227606423;11930.701732456759;11948.522059117538;12196.390180490762;8654.740357789276;8688.739337819876;8720.997085335268;8851.247643950337;9039.502827344559;8626.482411055356;9653.420794752337;10908.563657453613;11919.52321907114;11946.940265298617 15:57:07 INFO - PROCESS | 1707 | |3;mootools.html;1613.4756204233624;1623.0910690013588;1899.9145038473268;1996.9700454493186;2051.353823545584;8391.160883911605;9109.544522773867;9196.448213107209;9414.717558473258;9429.104235097664;9029.593668284926;9085.364024518287;9169.358144929858;9188.035256298075;9211.309151813624;2526.936826579341;2542.5677634802128;2550.132954795369;2560.4495033567778;2567.550678631241;2479.3553676044266;2558.5138823623547;2559.4625128722896;2593.9221823345238;2608.8304260223026;2542.9745702543014;2545.2364290712803;2565.756253155947;2566.7689907908284;2603.5964425514076;2185.8797766122857;2248.7413947396094;2535.5055764126005;2555.246202370296;2569.216389001354;2526.027479420418;2567.9101231457;2589.935251618706;2600.2849216465434;2615.8692065396654;9145.268378529701;9214.44713317203;9283.628654853797;9302.20931220847;9335.3465257432;5675.9432405675825;5687.800926967537;5805.506531944748;5951.345352011275;5963.314218864843;3724.16206353571;3778.414345776409;3822.3884178531302;3859.749116307431;3904.2777086238852;1083.8049151152788;1087.1846115413439;1087.613897066538;1124.0501775999267;1135.1883403366633;12331.260124392566;12345.13584049117;12457.563985260564;12458;12526.937365313206;4977.477364876709;5064.518870707277;5138.511841375063;5169.431362550116;5219.060569097599;10591.89408105909;10739.979701928305;10741.83887241692;10756.031957123896;10785.083267922157;5652.152177173375;5732.598718089694;5782.826515204551;5884.6469211847425;5915.024021036566;4314.89212769677;4665.160271151225;5100.948990510047;5248.711320877312;5249.08141075318;3532.752707310515;4311.202427550913;4390.100029493946;4401.427814384109;4435.423394958635;1406.1368686490441;1446.2768615692155;1451.586297905092;1452.2520901735616;1456.1117718191895;963.3016063354012;972.863799068131;991.2763682511807;999.7050869993369;1012.1295685710284;5243.475652434726;6145.446909778052;6183.598066125687;6201.255849298022;6236.750529978841;479.60911856836566;481.75912043978013;491.39803740417705;496.1466278001831;496.84101087651607;4149.647279981175;4150.045489537363;4187.895302617459;4189.832406703697;4213.264756892552;10585.41780202081;10870.021698047214;10896.673099807018;10967.890321096846;10986.670399888017;10211.489425528694;10494.003069708366;10551.525181366856;10579.576816927236;10716.410597417216;7527.736529221451;8332.958335208285;8342.332613390914;8514.063453020162;8566.18621230982;5702.116171993348;5914.556408269311;5941.197938278277;5999.940000599938;6044.727987240497;9469.810603788024;9475.194608458083;9600;9627.759306017406;9741.756456088655;9505.19205867509;10067.748306292402;10087.7478063049;10091.69724908254;10103.191744660557;7121.465890058163;7580.507267027626;7661.923380766121;7700.884486732815;7782.533048017137;8477.152284771473;8498.830023399621;8627.69803056915;8648.1784230498;8723.607437665447;7448.292412220831;7473.663685134074;7596.620168991639;7655.1962043986805;7718.073831140075;7532.472726909064;7582.203868593939;7585.962070189614;7593.810154746176;7672.923270767221;7303.890441643273;7368;7385.741499047507;7392.186859445565;7396.3343299103335;5050.924236136388;5063.3417655704525;5168.9741551292;5238.633295669267;5263.473652634706;5208.635395522277;5223.3209682741;5252.42223355423;5410.945890541044;5449.937262233826 15:57:07 INFO - PROCESS | 1707 | |4;prototype.html;337.3539671529022;337.35733427819997;339.5772263531902;345.3317829998954;347.15640992388484;6878.346557077082;6898.793036208922;6918.066061081752;6956.373926346628;7005.299470052999;6905.68924398402;6912.135983002125;6923.896141557881;6946.097007389032;6992.755253566126;3771.528558930134;4335.720962316109;5145;5155.123628983072;5157.6131790115705;4871.683340582851;4954.950450495503;5048.974755126237;5055.46917573655;5060.493950604929;4984.551390374865;4994.975025124888;5028.597712183035;5065.240213967916;5117.437081920985;4171.541130475645;5088.338515992934;5092.668976516533;5095.79616815327;5127.461616530285;4329.740215587075;4339.479262488521;4347.304431290978;4351.282038463682;4356.716813407118;6156.322804491502;6558.2458017328145;6577.717345117704;6580.11168492257;6629.071929929813;5635.3237611486475;5859.384764599741;5898.941010589882;5911.369440598935;5928.28860536734;6606.339366063403;7335.266473352675;7345.55926644403;7350.4119670426235;7365.263473652645;4357.433533640638;4704.623630109583;4710.717356958594;4721;4721.102990431841;5678.4605462480995;7807.336376407955;7817.804554886174;7848.646810893523;7860.921390786076;4624.468186158568;4767.4994125617;4790.928136077892;4797;4812.927806082912;4852.490488498727;4904.6321525885705;4925.507449255046;4931.90136197274;4935.851924442272;5829.5044921181325;5888.411158884163;5907.172995780594;5944.524438044946;5988.401159884064;6738.898916516257;6757.831054223684;7479.626018699152;7491.250874912465;7531.924680753232;1674.0792564089702;1700.4898530440819;1705.4286813917477;1705.641815218818;1709.8204688507776;1042.2912419554775;1070.0155856611937;1077.450500244865;1080.6325849211305;1087.9945600272106;6953.61755103464;7038.3665470107935;7055.717771289091;7058.223595404502;7089.326513981164;6998.615076170758;7756.224377562312;7782.221777822172;7795.259450352208;7812.960935195288;6791.388775010273;6968.512204145661;7084.079069721007;7102.431805455654;7150.499465037397;4050.4936882889638;4070.3894415837863;4079.8164082616354;4091.365838295069;4099.487564054493;6437.969924812008;7836.843263134705;8123.4313598047565;8158.714444994397;8200.671973121127;8008.79978000543;8021.639026243833;8046.919530804617;8050;8053.476524025919;7886.44794864354;8119.228673276029;8154.2253485918745;8155.877661835078;8187.672493100328;7734.497257678233;7738.729144479917;7779.883301750365;7816.804579885548;7825.843483130192;7628.160902300853;7637.88543171864;7643.464957452925;7687.577183254974;7692.269234422721;7858.939043229319;8060.717874874351;8157;8173.836523269621;8188.0993090758775;4994.02616489781;5002.149634562061;5045.217991211368;5065.164247899055;5069.670471419346;6144.600600960924;6152.261728592597;7697.499662521918;7707.766757318802;7743.341815945482;5970.31341395745;6148.846278843064;7322.82834746438;7428.517146385468;7454.254574542502;7059.929400705927;7340.339369456775;7417.332440080419;7432.182459929295;7438.516496427715;7183.676734546853;7246.347828695232;7249.202587715242;7259.6007219601315;7278.96360518194;7357.668904899399;7380.77857664271;7397;7410.740624078347;7441.144268409203;5879.794207202727;7247.855042899218;7254.600996945219;7263.673134709056;7269.091363579552 15:57:07 INFO - PROCESS | 1707 | |5;yui.html;668.7929831555865;694.524250888142;761.3566536276844;763.5952944939185;808.7290757596202;15722.92138539307;15939.920300398482;15945.521634350973;15948.441804536844;15987;14590.124592524457;14636.634084147876;16049.759253611206;16414.261358238877;16430.507084787445;972.7568107973007;972.9610815567387;982.3123813330661;984.6455276100598;984.8325784616633;809.5142914251461;946.129560804062;974.9902500975006;993.8509223616443;996.5565323431076;999.3454287441738;1043.8799538053106;1054.4569546683426;1055.1137044882337;1057.2810488867567;994.209603365319;1015.2588610314475;1042.8331466965326;1047.5024363427387;1051.5793682527012;747.8952946587482;771.090113665874;780.5706861226303;785.3206975965805;790.2018960849583;15504.844951550453;16103.516894493185;16162.030278183347;16268;16271.942323748919;12898.677533061655;12963.092583519157;13081.672958176026;13094.541691040864;13109.606711798566;9778.315517913748;10555.788884222273;10557.683269501927;10587.947060264727;10613.310134841211;3572.4105522588743;3572.464130380438;3573.857045718168;3575.928481430357;3631.5460567429072;84643;84745;85107.57446212729;85740.99999999937;87211;9550.37922535033;9631.277654175965;9665.226781857435;9804.019598040139;9871.75320616976;21936.561268774534;21961.560768784533;21968.231111911005;22001;22047.89760511968;7410.666520006613;7420.443466740016;7534.208908064575;7574.848503030019;7671.194524574951;4175.519815221228;4217;4234.9364759528635;4258.808353624095;4308.504521980012;3760;3765.642263984917;3780.4707340972286;3844.8077596120643;3850.403187505941;663.0286630086946;672.2873753820966;682.1677553384862;684.8116767888771;694.4617921111078;509.8623371689697;550.6008144095499;604.5888795618934;615.2217444932164;621.8165641135876;7913.762587122395;8326.958365208257;8431.15688431152;8494.787630309293;8566.357523185787;537.3820106877045;579.5019873626723;581.5289615411446;592.6517173430394;628.5631486117196;3844.1350696093155;3860.536735591747;3983.402489626579;4029.176451856871;4090.4477895484324;91730.0826991735;95231.52384238174;95607.52196238974;96752.03247967431;97209;62354.688226558574;76561;76650.23349766542;77616.61191694005;78541;60792.696036519534;67776;68509.62980740357;71525;72092.63953680197;6715.3620406061345;7711.228877112244;7878.290953814184;7885.487443316166;7903;45669.54330456653;46961.53038469572;47726.5227347736;48117.518824811305;50888.49111508838;61814.0727889073;63423;67754.66122669356;68245;68443;11106.222564420414;11353.37556434371;11379.975802177843;11424.257423267794;11720.88279117198;11983.820242696193;12010.459529321377;12192.817107743569;12375.443105060114;12588.748225035264;11538.88461115378;11553.364564949008;11721.648350549498;11736.413179341169;12066.457009434422;11395.202335836411;11656.650300491;11850.05199584014;11941.402929853299;11976.640700778991;10643.627473038407;11198.552057917592;11249.493772780408;11327.773444530898;11472.483738231967;9599.664011759554;9715.29982253117;9893.2707214819;10123.7469063274;10140.340877842917;8504.362172837185;9949.751256218653;9974.900250997398;9983.650572229937;10092.747681308027 15:57:07 INFO - PROCESS | 1707 | __end_tp_report 15:57:07 INFO - PROCESS | 1707 | __start_cc_report 15:57:07 INFO - PROCESS | 1707 | _x_x_mozilla_cycle_collect,391 15:57:07 INFO - PROCESS | 1707 | __end_cc_report 15:57:07 INFO - PROCESS | 1707 | __startTimestamp1468191427760__endTimestamp 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | ------- Summary: start ------- 15:57:07 INFO - PROCESS | 1707 | Number of tests: 6 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#0] dojo.html Cycles:165 Average:5962.91 Median:6217.94 stddev:626.29 (10.1%) stddev-sans-first:596.91 15:57:07 INFO - PROCESS | 1707 | Values: 3470.7 3707.0 3755.9 3762.8 3771.3 6060.7 6097.2 6104.1 6138.0 6161.2 6049.7 6100.0 6118.7 6119.4 6152.4 5395.5 5445.5 5462.4 5479.0 5582.1 5449.4 5557.6 5561.1 5565.0 5572.9 5521.0 5531.9 5549.8 5559.1 5560.4 5550.3 5557.0 5557.6 5585.8 5594.3 4483.6 5231.7 5461.8 5541.4 5570.3 5572.9 5774.0 5929.9 5965.1 6071.6 5773.7 6057.1 6241.0 6259.3 6292.9 5734.2 6404.4 6419.3 6429.2 6452.7 6032.3 6134.9 6189.2 6196.1 6202.8 6431.3 6529.4 6541.7 6555.1 6558.8 5140.0 6262.7 6278.2 6279.3 6285.1 6266.6 6284.9 6358.7 6389.9 6398.9 6261.4 6297.9 6300.1 6307.6 6307.6 6234.1 6312.9 6316.9 6317.3 6330.8 4990.1 5052.9 5053.5 5100.4 5139.2 4617.7 4626.4 4659.2 4659.6 4670.0 6179.4 6204.8 6241.5 6249.3 6261.8 5420.6 6269.7 6415.1 6440.3 6491.3 6165.4 6208.8 6217.9 6232.7 6240.5 5589.0 5691.1 5696.9 5702.7 5730.2 6525.2 6626.0 6631.6 6646.0 6774.7 6155.9 6436.7 6441.2 6464.8 6488.5 6543.5 6718.5 6756.2 6776.3 6789.3 6267.6 6332.0 6335.7 6355.1 6380.5 6379.0 6406.6 6415.3 6430.0 6432.6 6115.6 6157.9 6273.6 6278.4 6279.3 6221.8 6249.7 6250.7 6268.3 6295.2 5327.5 6173.2 6237.5 6246.3 6289.0 6122.3 6163.4 6232.4 6286.0 6318.8 6167.8 6245.1 6253.0 6255.8 6269.8 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#1] ext.html Cycles:180 Average:68751.42 Median:38252.50 stddev:152333.20 (398.2%) stddev-sans-first:152674.45 15:57:07 INFO - PROCESS | 1707 | Values: 541.6 553.8 555.0 581.3 593.2 39596.2 40639.6 40652.2 40661.8 40757.8 40278.4 40621.2 41253.8 41259.4 41396.0 9569.1 9791.1 9809.5 9815.4 9823.8 9761.5 9936.2 10001.6 10017.6 10059.5 9940.6 9940.8 9953.4 9994.0 10009.4 9867.5 9931.0 9982.5 10002.9 10047.8 9744.7 9864.8 9867.4 9879.7 9897.5 40980.6 41052.4 41331.4 41376.4 41625.8 3468.4 3505.3 3506.5 3508.3 4076.2 27135.3 27657.0 27815.6 28141.6 28195.4 2724.6 2817.2 2817.7 2849.4 2895.1 424183.0 425607.0 427398.0 427917.0 429336.0 38048.6 66331.0 67488.3 67490.0 67498.7 36802.3 37237.4 37373.4 37434.6 37505.0 14002.3 16415.9 16793.0 16815.2 16817.2 13809.6 13960.0 14089.3 14172.5 14224.6 8373.8 8458.7 8476.4 8513.6 8582.0 3539.3 3574.3 3586.9 3619.8 3635.2 894.5 1067.6 1094.8 1121.2 1185.2 30890.4 31285.5 31329.9 31650.4 32152.5 1120.3 1158.2 1167.7 1182.6 1205.4 14319.9 14669.5 14904.6 15071.2 15112.4 639499.0 647452.0 660062.0 660273.0 662127.0 62714.0 63438.7 63454.7 63649.0 63756.0 1558.8 1573.6 1583.3 1585.8 1594.6 29191.4 29428.3 29582.4 29988.9 30094.4 53727.2 54162.7 55047.1 55083.6 55085.7 590739.0 594580.0 604433.0 605851.0 606970.0 25933.0 27624.2 27796.3 27962.0 28177.4 46302.8 46701.5 47626.0 47727.8 48441.3 37679.2 38264.0 38741.8 39212.2 39945.4 37990.6 38101.0 38241.0 38761.2 39056.8 33732.0 37404.8 39009.0 39190.4 39257.6 31598.2 33131.7 33539.8 33638.0 33786.5 24789.9 24971.9 25062.7 25427.7 25519.4 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#2] jquery.html Cycles:180 Average:38350.25 Median:21658.12 stddev:110515.63 (510.3%) stddev-sans-first:110788.84 15:57:07 INFO - PROCESS | 1707 | Values: 359.4 411.7 412.9 415.4 423.1 15397.0 19999.9 20860.3 21175.0 22591.0 19305.4 21994.9 22170.2 22344.9 22430.1 5725.2 5911.2 6185.4 6203.2 6210.0 6035.7 6045.5 6085.5 6115.9 6138.7 5972.1 6079.2 6107.5 6114.2 6150.9 5995.3 6061.2 6095.5 6134.6 6145.6 5004.9 5407.9 5610.3 5941.3 5965.6 13667.9 13945.0 13966.2 13973.6 14177.6 2052.7 2133.9 2182.1 2340.0 2360.6 10187.8 10197.0 10257.3 10474.2 10598.9 1785.8 1842.0 1866.5 1882.9 1903.9 116411.4 118356.0 145112.0 157715.0 161233.0 22239.6 22958.8 23308.5 23351.3 23609.8 36932.6 37335.4 38640.6 38850.2 38948.0 11709.7 12009.3 12041.2 12043.3 12081.5 10029.9 10030.9 10041.6 10072.4 10194.3 10850.7 10955.1 11017.9 11051.6 11065.7 3598.3 4083.2 4202.3 4303.4 4346.4 855.8 907.5 950.6 978.6 992.1 20268.5 21438.0 21612.9 21703.3 21742.6 1004.6 1011.0 1020.6 1068.0 1080.1 11396.8 11477.9 11612.0 11628.9 11714.9 551757.0 669696.0 685671.0 693974.0 703585.0 109707.5 135955.3 137350.3 137431.0 141797.0 1028.0 1051.7 1061.1 1068.7 1078.8 18542.1 19177.9 19404.6 19436.0 19775.3 50299.5 54287.5 54403.0 55529.2 58430.7 48419.0 64045.7 66696.0 69137.3 72053.3 8251.3 8330.1 8505.3 8751.8 8827.1 12361.3 13285.4 13541.7 13630.4 13729.7 8881.8 10628.8 11521.4 11603.9 11746.5 9721.1 10920.2 11238.3 11380.8 11777.6 11166.0 11584.8 11930.7 11948.5 12196.4 8654.7 8688.7 8721.0 8851.2 9039.5 8626.5 9653.4 10908.6 11919.5 11946.9 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#3] mootools.html Cycles:180 Average:5925.22 Median:5082.73 stddev:3259.76 (64.1%) stddev-sans-first:3252.80 15:57:07 INFO - PROCESS | 1707 | Values: 1613.5 1623.1 1899.9 1997.0 2051.4 8391.2 9109.5 9196.4 9414.7 9429.1 9029.6 9085.4 9169.4 9188.0 9211.3 2526.9 2542.6 2550.1 2560.4 2567.6 2479.4 2558.5 2559.5 2593.9 2608.8 2543.0 2545.2 2565.8 2566.8 2603.6 2185.9 2248.7 2535.5 2555.2 2569.2 2526.0 2567.9 2589.9 2600.3 2615.9 9145.3 9214.4 9283.6 9302.2 9335.3 5675.9 5687.8 5805.5 5951.3 5963.3 3724.2 3778.4 3822.4 3859.7 3904.3 1083.8 1087.2 1087.6 1124.1 1135.2 12331.3 12345.1 12457.6 12458.0 12526.9 4977.5 5064.5 5138.5 5169.4 5219.1 10591.9 10740.0 10741.8 10756.0 10785.1 5652.2 5732.6 5782.8 5884.6 5915.0 4314.9 4665.2 5100.9 5248.7 5249.1 3532.8 4311.2 4390.1 4401.4 4435.4 1406.1 1446.3 1451.6 1452.3 1456.1 963.3 972.9 991.3 999.7 1012.1 5243.5 6145.4 6183.6 6201.3 6236.8 479.6 481.8 491.4 496.1 496.8 4149.6 4150.0 4187.9 4189.8 4213.3 10585.4 10870.0 10896.7 10967.9 10986.7 10211.5 10494.0 10551.5 10579.6 10716.4 7527.7 8333.0 8342.3 8514.1 8566.2 5702.1 5914.6 5941.2 5999.9 6044.7 9469.8 9475.2 9600.0 9627.8 9741.8 9505.2 10067.7 10087.7 10091.7 10103.2 7121.5 7580.5 7661.9 7700.9 7782.5 8477.2 8498.8 8627.7 8648.2 8723.6 7448.3 7473.7 7596.6 7655.2 7718.1 7532.5 7582.2 7586.0 7593.8 7672.9 7303.9 7368.0 7385.7 7392.2 7396.3 5050.9 5063.3 5169.0 5238.6 5263.5 5208.6 5223.3 5252.4 5410.9 5449.9 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#4] prototype.html Cycles:180 Average:6013.68 Median:6834.87 stddev:1954.02 (28.6%) stddev-sans-first:1912.49 15:57:07 INFO - PROCESS | 1707 | Values: 337.4 337.4 339.6 345.3 347.2 6878.3 6898.8 6918.1 6956.4 7005.3 6905.7 6912.1 6923.9 6946.1 6992.8 3771.5 4335.7 5145.0 5155.1 5157.6 4871.7 4955.0 5049.0 5055.5 5060.5 4984.6 4995.0 5028.6 5065.2 5117.4 4171.5 5088.3 5092.7 5095.8 5127.5 4329.7 4339.5 4347.3 4351.3 4356.7 6156.3 6558.2 6577.7 6580.1 6629.1 5635.3 5859.4 5898.9 5911.4 5928.3 6606.3 7335.3 7345.6 7350.4 7365.3 4357.4 4704.6 4710.7 4721.0 4721.1 5678.5 7807.3 7817.8 7848.6 7860.9 4624.5 4767.5 4790.9 4797.0 4812.9 4852.5 4904.6 4925.5 4931.9 4935.9 5829.5 5888.4 5907.2 5944.5 5988.4 6738.9 6757.8 7479.6 7491.3 7531.9 1674.1 1700.5 1705.4 1705.6 1709.8 1042.3 1070.0 1077.5 1080.6 1088.0 6953.6 7038.4 7055.7 7058.2 7089.3 6998.6 7756.2 7782.2 7795.3 7813.0 6791.4 6968.5 7084.1 7102.4 7150.5 4050.5 4070.4 4079.8 4091.4 4099.5 6438.0 7836.8 8123.4 8158.7 8200.7 8008.8 8021.6 8046.9 8050.0 8053.5 7886.4 8119.2 8154.2 8155.9 8187.7 7734.5 7738.7 7779.9 7816.8 7825.8 7628.2 7637.9 7643.5 7687.6 7692.3 7858.9 8060.7 8157.0 8173.8 8188.1 4994.0 5002.1 5045.2 5065.2 5069.7 6144.6 6152.3 7697.5 7707.8 7743.3 5970.3 6148.8 7322.8 7428.5 7454.3 7059.9 7340.3 7417.3 7432.2 7438.5 7183.7 7246.3 7249.2 7259.6 7279.0 7357.7 7380.8 7397.0 7410.7 7441.1 5879.8 7247.9 7254.6 7263.7 7269.1 15:57:07 INFO - PROCESS | 1707 | 15:57:07 INFO - PROCESS | 1707 | [#5] yui.html Cycles:180 Average:18448.20 Median:523.62 stddev:25758.66 (4919.3%) stddev-sans-first:25796.32 15:57:07 INFO - PROCESS | 1707 | Values: 668.8 694.5 761.4 763.6 808.7 15722.9 15939.9 15945.5 15948.4 15987.0 14590.1 14636.6 16049.8 16414.3 16430.5 972.8 973.0 982.3 984.6 984.8 809.5 946.1 975.0 993.9 996.6 999.3 1043.9 1054.5 1055.1 1057.3 994.2 1015.3 1042.8 1047.5 1051.6 747.9 771.1 780.6 785.3 790.2 15504.8 16103.5 16162.0 16268.0 16271.9 12898.7 12963.1 13081.7 13094.5 13109.6 9778.3 10555.8 10557.7 10587.9 10613.3 3572.4 3572.5 3573.9 3575.9 3631.5 84643.0 84745.0 85107.6 85741.0 87211.0 9550.4 9631.3 9665.2 9804.0 9871.8 21936.6 21961.6 21968.2 22001.0 22047.9 7410.7 7420.4 7534.2 7574.8 7671.2 4175.5 4217.0 4234.9 4258.8 4308.5 3760.0 3765.6 3780.5 3844.8 3850.4 663.0 672.3 682.2 684.8 694.5 509.9 550.6 604.6 615.2 621.8 7913.8 8327.0 8431.2 8494.8 8566.4 537.4 579.5 581.5 592.7 628.6 3844.1 3860.5 3983.4 4029.2 4090.4 91730.1 95231.5 95607.5 96752.0 97209.0 62354.7 76561.0 76650.2 77616.6 78541.0 60792.7 67776.0 68509.6 71525.0 72092.6 6715.4 7711.2 7878.3 7885.5 7903.0 45669.5 46961.5 47726.5 48117.5 50888.5 61814.1 63423.0 67754.7 68245.0 68443.0 11106.2 11353.4 11380.0 11424.3 11720.9 11983.8 12010.5 12192.8 12375.4 12588.7 11538.9 11553.4 11721.6 11736.4 12066.5 11395.2 11656.7 11850.1 11941.4 11976.6 10643.6 11198.6 11249.5 11327.8 11472.5 9599.7 9715.3 9893.3 10123.7 10140.3 8504.4 9949.8 9974.9 9983.7 10092.7 15:57:07 INFO - PROCESS | 1707 | -------- Summary: end -------- 15:57:07 INFO - PROCESS | 1707 | 15:57:08 INFO - TEST-INFO | 1707: exit 0 15:57:08 INFO - TEST-OK | dromaeo_css | took 1110896ms 15:57:08 INFO - TEST-START | kraken 15:57:08 INFO - operating with platform_type : mac_ 15:57:08 INFO - Initialising browser for kraken test... 15:57:08 INFO - TEST-INFO | started process 1713 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp_a1wfZ/profile http://localhost:49277/getInfo.html) 15:57:10 INFO - PROCESS | 1713 | __metrics Screen width/height:1600/1200 15:57:10 INFO - PROCESS | 1713 | colorDepth:24 15:57:10 INFO - PROCESS | 1713 | Browser inner width/height: 1024/689 15:57:10 INFO - PROCESS | 1713 | __metrics 15:57:10 ERROR - PROCESS | 1713 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 15:57:11 INFO - TEST-INFO | 1713: exit 0 15:57:11 INFO - Browser initialized. 15:57:11 INFO - Running cycle 1/1 for kraken test... 15:57:11 INFO - TEST-INFO | started process 1717 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/T/tmp_a1wfZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:57:23 INFO - PROCESS | 1717 | RSS: Main: 171139072 15:57:23 INFO - PROCESS | 1717 | 15:57:26 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 15:57:26 INFO - PROCESS | 1717 | RSS: Main: 183881728 15:57:26 INFO - PROCESS | 1717 | 15:57:29 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 15:57:30 INFO - PROCESS | 1717 | RSS: Main: 192315392 15:57:30 INFO - PROCESS | 1717 | 15:57:33 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 15:57:34 INFO - PROCESS | 1717 | RSS: Main: 195039232 15:57:34 INFO - PROCESS | 1717 | 15:57:36 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 15:57:37 INFO - PROCESS | 1717 | RSS: Main: 197365760 15:57:37 INFO - PROCESS | 1717 | 15:57:39 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:57:39 INFO - PROCESS | 1717 | RSS: Main: 197853184 15:57:39 INFO - PROCESS | 1717 | 15:57:42 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 15:57:43 INFO - PROCESS | 1717 | RSS: Main: 198057984 15:57:43 INFO - PROCESS | 1717 | 15:57:46 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 15:57:47 INFO - PROCESS | 1717 | RSS: Main: 198287360 15:57:47 INFO - PROCESS | 1717 | 15:57:49 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 15:57:50 INFO - PROCESS | 1717 | RSS: Main: 198496256 15:57:50 INFO - PROCESS | 1717 | 15:57:52 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:57:52 INFO - PROCESS | 1717 | RSS: Main: 198463488 15:57:52 INFO - PROCESS | 1717 | 15:57:54 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:57:55 INFO - PROCESS | 1717 | RSS: Main: 198737920 15:57:55 INFO - PROCESS | 1717 | 15:57:57 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:57:57 INFO - PROCESS | 1717 | RSS: Main: 198918144 15:57:57 INFO - PROCESS | 1717 | 15:58:00 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:58:01 INFO - PROCESS | 1717 | RSS: Main: 198266880 15:58:01 INFO - PROCESS | 1717 | 15:58:04 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:58:04 INFO - PROCESS | 1717 | RSS: Main: 198152192 15:58:04 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 15:58:06 INFO - PROCESS | 1717 | RSS: Main: 199995392 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | __start_tp_report 15:58:06 INFO - PROCESS | 1717 | _x_x_mozilla_page_load 15:58:06 INFO - PROCESS | 1717 | _x_x_mozilla_page_load_details 15:58:06 INFO - PROCESS | 1717 | |i|pagename|runs| 15:58:06 INFO - PROCESS | 1717 | |0;ai-astar;117;112;115;116;118;131;116;118;108;142 15:58:06 INFO - PROCESS | 1717 | |1;audio-beat-detection;159;156;156;159;128;171;156;123;205;121 15:58:06 INFO - PROCESS | 1717 | |2;audio-dft;228;273;203;244;168;212;272;202;271;273 15:58:06 INFO - PROCESS | 1717 | |3;audio-fft;66;81;81;128;57;80;83;56;145;86 15:58:06 INFO - PROCESS | 1717 | |4;audio-oscillator;80;83;117;84;112;119;114;82;81;115 15:58:06 INFO - PROCESS | 1717 | |5;imaging-gaussian-blur;96;130;88;93;135;126;90;86;90;85 15:58:06 INFO - PROCESS | 1717 | |6;imaging-darkroom;94;95;94;103;147;150;94;95;94;130 15:58:06 INFO - PROCESS | 1717 | |7;imaging-desaturate;88;92;90;92;90;89;93;121;147;103 15:58:06 INFO - PROCESS | 1717 | |8;json-parse-financial;58;62;63;64;65;64;64;77;62;59 15:58:06 INFO - PROCESS | 1717 | |9;json-stringify-tinderbox;63;66;65;72;67;72;72;65;65;67 15:58:06 INFO - PROCESS | 1717 | |10;stanford-crypto-aes;82;79;77;80;81;79;81;107;109;82 15:58:06 INFO - PROCESS | 1717 | |11;stanford-crypto-ccm;119;131;121;146;138;129;148;124;163;139 15:58:06 INFO - PROCESS | 1717 | |12;stanford-crypto-pbkdf2;167;162;161;168;179;162;160;165;165;166 15:58:06 INFO - PROCESS | 1717 | |13;stanford-crypto-sha256-iterative;71;77;90;79;67;65;66;70;67;69 15:58:06 INFO - PROCESS | 1717 | __end_tp_report 15:58:06 INFO - PROCESS | 1717 | __start_cc_report 15:58:06 INFO - PROCESS | 1717 | _x_x_mozilla_cycle_collect,1035 15:58:06 INFO - PROCESS | 1717 | __end_cc_report 15:58:06 INFO - PROCESS | 1717 | __startTimestamp1468191486622__endTimestamp 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | ------- Summary: start ------- 15:58:06 INFO - PROCESS | 1717 | Number of tests: 14 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#0] ai-astar Cycles:10 Average:119.30 Median:117.50 stddev:9.90 (8.4%) stddev-sans-first:10.47 15:58:06 INFO - PROCESS | 1717 | Values: 117.0 112.0 115.0 116.0 118.0 131.0 116.0 118.0 108.0 142.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#1] audio-beat-detection Cycles:10 Average:153.40 Median:157.50 stddev:25.11 (15.9%) stddev-sans-first:26.55 15:58:06 INFO - PROCESS | 1717 | Values: 159.0 156.0 156.0 159.0 128.0 171.0 156.0 123.0 205.0 121.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#2] audio-dft Cycles:10 Average:234.60 Median:257.50 stddev:37.73 (14.7%) stddev-sans-first:39.94 15:58:06 INFO - PROCESS | 1717 | Values: 228.0 273.0 203.0 244.0 168.0 212.0 272.0 202.0 271.0 273.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#3] audio-fft Cycles:10 Average:86.30 Median:80.50 stddev:28.83 (35.8%) stddev-sans-first:29.63 15:58:06 INFO - PROCESS | 1717 | Values: 66.0 81.0 81.0 128.0 57.0 80.0 83.0 56.0 145.0 86.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#4] audio-oscillator Cycles:10 Average:98.70 Median:80.50 stddev:17.73 (22.0%) stddev-sans-first:17.46 15:58:06 INFO - PROCESS | 1717 | Values: 80.0 83.0 117.0 84.0 112.0 119.0 114.0 82.0 81.0 115.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#5] imaging-gaussian-blur Cycles:10 Average:101.90 Median:89.00 stddev:19.99 (22.5%) stddev-sans-first:21.08 15:58:06 INFO - PROCESS | 1717 | Values: 96.0 130.0 88.0 93.0 135.0 126.0 90.0 86.0 90.0 85.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#6] imaging-darkroom Cycles:10 Average:109.60 Median:94.00 stddev:23.31 (24.8%) stddev-sans-first:24.03 15:58:06 INFO - PROCESS | 1717 | Values: 94.0 95.0 94.0 103.0 147.0 150.0 94.0 95.0 94.0 130.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#7] imaging-desaturate Cycles:10 Average:100.50 Median:90.00 stddev:19.14 (21.3%) stddev-sans-first:19.76 15:58:06 INFO - PROCESS | 1717 | Values: 88.0 92.0 90.0 92.0 90.0 89.0 93.0 121.0 147.0 103.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:5.16 (8.1%) stddev-sans-first:5.03 15:58:06 INFO - PROCESS | 1717 | Values: 58.0 62.0 63.0 64.0 65.0 64.0 64.0 77.0 62.0 59.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#9] json-stringify-tinderbox Cycles:10 Average:67.40 Median:67.00 stddev:3.37 (5.0%) stddev-sans-first:3.18 15:58:06 INFO - PROCESS | 1717 | Values: 63.0 66.0 65.0 72.0 67.0 72.0 72.0 65.0 65.0 67.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:80.50 stddev:11.86 (14.7%) stddev-sans-first:12.50 15:58:06 INFO - PROCESS | 1717 | Values: 82.0 79.0 77.0 80.0 81.0 79.0 81.0 107.0 109.0 82.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#11] stanford-crypto-ccm Cycles:10 Average:135.80 Median:138.50 stddev:13.81 (10.0%) stddev-sans-first:13.25 15:58:06 INFO - PROCESS | 1717 | Values: 119.0 131.0 121.0 146.0 138.0 129.0 148.0 124.0 163.0 139.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:5.44 (3.3%) stddev-sans-first:5.74 15:58:06 INFO - PROCESS | 1717 | Values: 167.0 162.0 161.0 168.0 179.0 162.0 160.0 165.0 165.0 166.0 15:58:06 INFO - PROCESS | 1717 | 15:58:06 INFO - PROCESS | 1717 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.10 Median:70.50 stddev:7.80 (11.1%) stddev-sans-first:8.26 15:58:06 INFO - PROCESS | 1717 | Values: 71.0 77.0 90.0 79.0 67.0 65.0 66.0 70.0 67.0 69.0 15:58:06 INFO - PROCESS | 1717 | -------- Summary: end -------- 15:58:06 INFO - PROCESS | 1717 | 15:58:07 INFO - TEST-INFO | 1717: exit 0 15:58:07 INFO - TEST-OK | kraken | took 59087ms 15:58:07 INFO - SUITE-END | took 1169s 15:58:07 INFO - Completed test suite (00:19:30) 15:58:07 INFO - javascript benchmark 15:58:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3470.722342212623, 3706.962930370697, 3755.887323380296, 3762.849486020559, 3771.3400154972874, 6060.666663333515, 6097.207363042804, 6104.084387341901, 6137.9999999999945, 6161.199044124258, 6049.697515124249, 6099.999999999989, 6118.69406529674, 6119.418655227757, 6152.446279834814, 5395.541378982791, 5445.482679145494, 5462.426445223254, 5479.0, 5582.134769110794, 5449.4005659377435, 5557.6109672322955, 5561.138023606327, 5564.972175139139, 5572.944270557304, 5520.972395138038, 5531.889362212753, 5549.833504994836, 5559.11054231323, 5560.388357280696, 5550.2507161533285, 5557.0, 5557.638753481031, 5585.776568937278, 5594.328680558359, 4483.596476317114, 5231.738413079331, 5461.836144915659, 5541.390447050821, 5570.303712035996, 5572.888542229173, 5774.0, 5929.851753706149, 5965.105234214859, 6071.605345652519, 5773.682447465388, 6057.091436284549, 6241.0, 6259.280182778994, 6292.87414251717, 5734.16854556087, 6404.391582799627, 6419.293877673499, 6429.2284925808735, 6452.677366131722, 6032.276126864805, 6134.877302454015, 6189.16446279751, 6196.132541444202, 6202.844928876814, 6431.260405053479, 6529.444997175293, 6541.70562324687, 6555.115059466911, 6558.8360290993105, 5139.974300128551, 6262.655553944485, 6278.15244941927, 6279.309275979638, 6285.057241413824, 6266.624002559861, 6284.874302513924, 6358.650274234869, 6389.904151437732, 6398.936010639834, 6261.373862613702, 6297.874042519215, 6300.117983482316, 6307.558470906993, 6307.590006649553, 6234.127222188898, 6312.873742525124, 6316.873662526724, 6317.3366796486625, 6330.8417289567205, 4990.126727822617, 5052.9242061369105, 5053.519915608012, 5100.413452452941, 5139.203423469368, 4617.746023968647, 4626.352310676508, 4659.161350956861, 4659.627229821606, 4670.042641258534, 6179.3820617937845, 6204.751809927552, 6241.531885108635, 6249.25008998923, 6261.780837670751, 5420.566354691615, 6269.7492100316385, 6415.13395691585, 6440.323766004596, 6491.285958544557, 6165.352637973038, 6208.844778880474, 6217.937820621826, 6232.688365581703, 6240.500759939194, 5589.049861523551, 5691.089425691952, 5696.857578560569, 5702.6863522506665, 5730.19777231188, 6525.184351956005, 6626.039224312544, 6631.568948018364, 6646.0, 6774.695138718671, 6155.938440615716, 6436.67816609177, 6441.227052753699, 6464.773732919325, 6488.480921526172, 6543.5419520635005, 6718.496112791462, 6756.223034351113, 6776.254611992776, 6789.2871248518195, 6267.592606480564, 6331.96834015827, 6335.746570137143, 6355.110284560073, 6380.457661098673, 6378.968105159444, 6406.615603063831, 6415.262244841716, 6430.0, 6432.6462044588, 6115.6330620162935, 6157.907631385622, 6273.560850740587, 6278.372162783685, 6279.277883043508, 6221.751129954751, 6249.718762655601, 6250.718717657807, 6268.310485846644, 6295.150154729053, 5327.547158491571, 6173.197484327009, 6237.500999919905, 6246.250449946036, 6288.9685551573775, 6122.337166850181, 6163.414475624809, 6232.407921247655, 6286.025666021774, 6318.778842740482, 6167.784127555514, 6245.063240513778, 6253.030780229072, 6255.812325630239, 6269.811905642656], "value": 5927.072579945894, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [541.6452223793416, 553.8330040861538, 555.0425515984924, 581.3053401185582, 593.2051051590872, 39596.208075838505, 40639.59360406395, 40652.186956260855, 40661.796691016505, 40757.796211018904, 40278.39582406266, 40621.187576248456, 41253.79373103138, 41259.381109283386, 41395.96510087249, 9569.13877751004, 9791.06984836443, 9809.460479673615, 9815.411075335467, 9823.803523929517, 9761.511924403787, 9936.205103591732, 10001.649942252023, 10017.649382271586, 10059.497025148714, 9940.602375904955, 9940.751481212992, 9953.353032052893, 9994.0, 10009.399436033862, 9867.457289849055, 9931.0, 9982.450965196947, 10002.89997100027, 10047.799044019152, 9744.707658770249, 9864.802703945881, 9867.358621689567, 9879.703608891672, 9897.505124743733, 40980.59019409827, 41052.38421423681, 41331.38002929959, 41376.37935430941, 41625.79187104045, 3468.3756923753713, 3505.2638945821154, 3506.5266189064405, 3508.333416650854, 4076.2255171517613, 27135.321616959536, 27657.0, 27815.58276625852, 28141.57787633187, 28195.436091278265, 2724.6049322848276, 2817.1830169250893, 2817.7323154300307, 2849.387381712921, 2895.0880472651047, 424183.0, 425607.0, 427398.0, 427917.0, 429336.0, 38048.61951380451, 66331.0, 67488.32511674918, 67490.0, 67498.65002699918, 36802.34389452481, 37237.441438378446, 37373.43939840904, 37434.62565374311, 37505.0, 14002.299885005708, 16415.93296435752, 16793.0, 16815.2433140509, 16817.15914204285, 13809.585712428643, 13959.953003524777, 14089.295535223198, 14172.503962361268, 14224.644383890485, 8373.8325233495, 8458.74623761288, 8476.406651534457, 8513.616887240089, 8582.0, 3539.3275277697153, 3574.303010912847, 3586.8744593939086, 3619.7828130312264, 3635.182084031072, 894.4990805149138, 1067.5676351077825, 1094.7591529863416, 1121.2277841835223, 1185.158537438411, 30890.382192356028, 31285.530717039263, 31329.92263663168, 31650.36699266002, 32152.517712234334, 1120.2830188679252, 1158.2182027131653, 1167.6730515455686, 1182.5860948667898, 1205.4394706461537, 14319.928400357932, 14669.48656797007, 14904.62738431548, 15071.171085590398, 15112.395504179929, 639499.0, 647452.0, 660062.0, 660273.0, 662127.0, 62714.0, 63438.682806585675, 63454.682726586994, 63649.0, 63756.0, 1558.820735615396, 1573.6301969037268, 1583.2716950202805, 1585.7859189009337, 1594.5854077939587, 29191.416171676025, 29428.264293392836, 29582.40835183241, 29988.850055749583, 30094.398112038074, 53727.194092089434, 54162.72918635382, 55047.073352432475, 55083.622909427584, 55085.724571376886, 590739.0, 594580.0, 604433.0, 605850.9707451435, 606970.0, 25932.96268149253, 27624.171274861787, 27796.30509237285, 27962.021329253377, 28177.43645127045, 46302.76848615735, 46701.53298466972, 47626.0, 47727.76136119436, 48441.27338089861, 37679.24641507209, 38264.0, 38741.8377448677, 39212.2157556853, 39945.40081898716, 37990.63236507934, 38101.0, 38241.0, 38761.224775504896, 39056.828295151194, 33732.0, 37404.81297593495, 39009.024774380865, 39190.412143817295, 39257.607423925394, 31598.210044749067, 33131.66868331382, 33539.83230083834, 33638.0, 33786.49320260247, 24789.88445519992, 24971.87514062418, 25062.74937250604, 25427.74572254254, 25519.362015949006], "value": 19206.950164471215, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.36033859729685, 411.74677573292416, 412.8947118484786, 415.3769345981028, 423.05658381808564, 15396.999195052314, 19999.900000499976, 20860.269890553835, 21175.0, 22590.98340574674, 19305.420837374866, 21994.89002554989, 22170.224042158527, 22344.888275558642, 22430.102795888186, 5725.169850371693, 5911.231539899807, 6185.381461853824, 6203.2245969253845, 6210.0, 6035.668038257894, 6045.455908968192, 6085.543584231178, 6115.908261376061, 6138.7237574309265, 5972.074328479071, 6079.240094988127, 6107.5113990880845, 6114.205153330061, 6150.876982460348, 5995.310539287994, 6061.181740465025, 6095.451409373156, 6134.631922084689, 6145.569810113294, 5004.924926126111, 5407.891842163174, 5610.270664813589, 5941.316748573883, 5965.612235204741, 13667.931660341734, 13945.0, 13966.162030278216, 13973.580792576238, 14177.645558861008, 2052.671572548385, 2133.9466513337134, 2182.050807898569, 2339.986235375088, 2360.6459031145296, 10187.847182292271, 10197.031282028196, 10257.333273337284, 10474.21443391741, 10598.947005265001, 1785.7678501794815, 1841.9815801841944, 1866.496046067554, 1882.9152688129068, 1903.9428817135508, 116411.41794291059, 118355.99999999914, 145112.0, 157715.0, 161233.0, 22239.55520889573, 22958.770412296, 23308.533829323318, 23351.299461016195, 23609.7639023611, 36932.63067369346, 37335.4399684005, 38640.61359386426, 38850.22299553993, 38948.0, 11709.707257318636, 12009.279443233434, 12041.157119001586, 12043.277403355827, 12081.516739330329, 10029.949850250701, 10030.949845250872, 10041.598336066621, 10072.446015469073, 10194.337368071052, 10850.67447976562, 10955.068819150461, 11017.889821101846, 11051.61319353835, 11065.72335691598, 3598.2623562169692, 4083.2037752637953, 4202.306619407764, 4303.397524346594, 4346.413234213404, 855.8416692911832, 907.5008745190119, 950.6102497975796, 978.6232300564192, 992.1170158558888, 20268.493287667927, 21438.0, 21612.89193554022, 21703.348899532724, 21742.565148697253, 1004.6433516101824, 1011.0, 1020.550957578663, 1067.9519421626046, 1080.0819303591884, 11396.829047564293, 11477.852214778455, 11612.0, 11628.94185529067, 11714.941425292818, 551757.0, 669696.0, 685671.0, 693974.0, 703585.0, 109707.45146274217, 135955.32022339824, 137350.3132484331, 137431.0, 141797.0, 1027.9537420816082, 1051.726551096705, 1061.0662616897234, 1068.7007637861257, 1078.800421921947, 18542.0728963554, 19177.94521301286, 19404.61190776205, 19436.0, 19775.307864224684, 50299.49700502948, 54287.45712542824, 54403.0, 55529.16706249329, 58430.70784646049, 48419.0, 64045.71908561709, 66696.0, 69137.30862691309, 72053.27946720466, 8251.257386835214, 8330.083690794128, 8505.277051450696, 8751.824963500567, 8827.07315731839, 12361.258324500199, 13285.40215690277, 13541.72916541644, 13630.386632601754, 13729.656758581115, 8881.822363552821, 10628.84056739165, 11521.366324852213, 11603.88396116028, 11746.530138794353, 9721.125098741148, 10920.235583509078, 11238.269512481662, 11380.829287560528, 11777.587784427504, 11166.0, 11584.826227606423, 11930.701732456759, 11948.522059117538, 12196.390180490762, 8654.740357789276, 8688.739337819876, 8720.997085335268, 8851.247643950337, 9039.502827344559, 8626.482411055356, 9653.420794752337, 10908.563657453613, 11919.52321907114, 11946.940265298617], "value": 10712.170728193856, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1613.4756204233624, 1623.0910690013588, 1899.9145038473268, 1996.9700454493186, 2051.353823545584, 8391.160883911605, 9109.544522773867, 9196.448213107209, 9414.717558473258, 9429.104235097664, 9029.593668284926, 9085.364024518287, 9169.358144929858, 9188.035256298075, 9211.309151813624, 2526.936826579341, 2542.5677634802128, 2550.132954795369, 2560.4495033567778, 2567.550678631241, 2479.3553676044266, 2558.5138823623547, 2559.4625128722896, 2593.9221823345238, 2608.8304260223026, 2542.9745702543014, 2545.2364290712803, 2565.756253155947, 2566.7689907908284, 2603.5964425514076, 2185.8797766122857, 2248.7413947396094, 2535.5055764126005, 2555.246202370296, 2569.216389001354, 2526.027479420418, 2567.9101231457, 2589.935251618706, 2600.2849216465434, 2615.8692065396654, 9145.268378529701, 9214.44713317203, 9283.628654853797, 9302.20931220847, 9335.3465257432, 5675.9432405675825, 5687.800926967537, 5805.506531944748, 5951.345352011275, 5963.314218864843, 3724.16206353571, 3778.414345776409, 3822.3884178531302, 3859.749116307431, 3904.2777086238852, 1083.8049151152788, 1087.1846115413439, 1087.613897066538, 1124.0501775999267, 1135.1883403366633, 12331.260124392566, 12345.13584049117, 12457.563985260564, 12458.0, 12526.937365313206, 4977.477364876709, 5064.518870707277, 5138.511841375063, 5169.431362550116, 5219.060569097599, 10591.89408105909, 10739.979701928305, 10741.83887241692, 10756.031957123896, 10785.083267922157, 5652.152177173375, 5732.598718089694, 5782.826515204551, 5884.6469211847425, 5915.024021036566, 4314.89212769677, 4665.160271151225, 5100.948990510047, 5248.711320877312, 5249.08141075318, 3532.752707310515, 4311.202427550913, 4390.100029493946, 4401.427814384109, 4435.423394958635, 1406.1368686490441, 1446.2768615692155, 1451.586297905092, 1452.2520901735616, 1456.1117718191895, 963.3016063354012, 972.863799068131, 991.2763682511807, 999.7050869993369, 1012.1295685710284, 5243.475652434726, 6145.446909778052, 6183.598066125687, 6201.255849298022, 6236.750529978841, 479.60911856836566, 481.75912043978013, 491.39803740417705, 496.1466278001831, 496.84101087651607, 4149.647279981175, 4150.045489537363, 4187.895302617459, 4189.832406703697, 4213.264756892552, 10585.41780202081, 10870.021698047214, 10896.673099807018, 10967.890321096846, 10986.670399888017, 10211.489425528694, 10494.003069708366, 10551.525181366856, 10579.576816927236, 10716.410597417216, 7527.736529221451, 8332.958335208285, 8342.332613390914, 8514.063453020162, 8566.18621230982, 5702.116171993348, 5914.556408269311, 5941.197938278277, 5999.940000599938, 6044.727987240497, 9469.810603788024, 9475.194608458083, 9600.0, 9627.759306017406, 9741.756456088655, 9505.19205867509, 10067.748306292402, 10087.7478063049, 10091.69724908254, 10103.191744660557, 7121.465890058163, 7580.507267027626, 7661.923380766121, 7700.884486732815, 7782.533048017137, 8477.152284771473, 8498.830023399621, 8627.69803056915, 8648.1784230498, 8723.607437665447, 7448.292412220831, 7473.663685134074, 7596.620168991639, 7655.1962043986805, 7718.073831140075, 7532.472726909064, 7582.203868593939, 7585.962070189614, 7593.810154746176, 7672.923270767221, 7303.890441643273, 7368.0, 7385.741499047507, 7392.186859445565, 7396.3343299103335, 5050.924236136388, 5063.3417655704525, 5168.9741551292, 5238.633295669267, 5263.473652634706, 5208.635395522277, 5223.3209682741, 5252.42223355423, 5410.945890541044, 5449.937262233826], "value": 4737.788055892753, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.3539671529022, 337.35733427819997, 339.5772263531902, 345.3317829998954, 347.15640992388484, 6878.346557077082, 6898.793036208922, 6918.066061081752, 6956.373926346628, 7005.299470052999, 6905.68924398402, 6912.135983002125, 6923.896141557881, 6946.097007389032, 6992.755253566126, 3771.528558930134, 4335.720962316109, 5145.0, 5155.123628983072, 5157.6131790115705, 4871.683340582851, 4954.950450495503, 5048.974755126237, 5055.46917573655, 5060.493950604929, 4984.551390374865, 4994.975025124888, 5028.597712183035, 5065.240213967916, 5117.437081920985, 4171.541130475645, 5088.338515992934, 5092.668976516533, 5095.79616815327, 5127.461616530285, 4329.740215587075, 4339.479262488521, 4347.304431290978, 4351.282038463682, 4356.716813407118, 6156.322804491502, 6558.2458017328145, 6577.717345117704, 6580.11168492257, 6629.071929929813, 5635.3237611486475, 5859.384764599741, 5898.941010589882, 5911.369440598935, 5928.28860536734, 6606.339366063403, 7335.266473352675, 7345.55926644403, 7350.4119670426235, 7365.263473652645, 4357.433533640638, 4704.623630109583, 4710.717356958594, 4721.0, 4721.102990431841, 5678.4605462480995, 7807.336376407955, 7817.804554886174, 7848.646810893523, 7860.921390786076, 4624.468186158568, 4767.4994125617, 4790.928136077892, 4797.0, 4812.927806082912, 4852.490488498727, 4904.6321525885705, 4925.507449255046, 4931.90136197274, 4935.851924442272, 5829.5044921181325, 5888.411158884163, 5907.172995780594, 5944.524438044946, 5988.401159884064, 6738.898916516257, 6757.831054223684, 7479.626018699152, 7491.250874912465, 7531.924680753232, 1674.0792564089702, 1700.4898530440819, 1705.4286813917477, 1705.641815218818, 1709.8204688507776, 1042.2912419554775, 1070.0155856611937, 1077.450500244865, 1080.6325849211305, 1087.9945600272106, 6953.61755103464, 7038.3665470107935, 7055.717771289091, 7058.223595404502, 7089.326513981164, 6998.615076170758, 7756.224377562312, 7782.221777822172, 7795.259450352208, 7812.960935195288, 6791.388775010273, 6968.512204145661, 7084.079069721007, 7102.431805455654, 7150.499465037397, 4050.4936882889638, 4070.3894415837863, 4079.8164082616354, 4091.365838295069, 4099.487564054493, 6437.969924812008, 7836.843263134705, 8123.4313598047565, 8158.714444994397, 8200.671973121127, 8008.79978000543, 8021.639026243833, 8046.919530804617, 8050.0, 8053.476524025919, 7886.44794864354, 8119.228673276029, 8154.2253485918745, 8155.877661835078, 8187.672493100328, 7734.497257678233, 7738.729144479917, 7779.883301750365, 7816.804579885548, 7825.843483130192, 7628.160902300853, 7637.88543171864, 7643.464957452925, 7687.577183254974, 7692.269234422721, 7858.939043229319, 8060.717874874351, 8157.0, 8173.836523269621, 8188.0993090758775, 4994.02616489781, 5002.149634562061, 5045.217991211368, 5065.164247899055, 5069.670471419346, 6144.600600960924, 6152.261728592597, 7697.499662521918, 7707.766757318802, 7743.341815945482, 5970.31341395745, 6148.846278843064, 7322.82834746438, 7428.517146385468, 7454.254574542502, 7059.929400705927, 7340.339369456775, 7417.332440080419, 7432.182459929295, 7438.516496427715, 7183.676734546853, 7246.347828695232, 7249.202587715242, 7259.6007219601315, 7278.96360518194, 7357.668904899399, 7380.77857664271, 7397.0, 7410.740624078347, 7441.144268409203, 5879.794207202727, 7247.855042899218, 7254.600996945219, 7263.673134709056, 7269.091363579552], "value": 5360.750429157694, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.7929831555865, 694.524250888142, 761.3566536276844, 763.5952944939185, 808.7290757596202, 15722.92138539307, 15939.920300398482, 15945.521634350973, 15948.441804536844, 15987.0, 14590.124592524457, 14636.634084147876, 16049.759253611206, 16414.261358238877, 16430.507084787445, 972.7568107973007, 972.9610815567387, 982.3123813330661, 984.6455276100598, 984.8325784616633, 809.5142914251461, 946.129560804062, 974.9902500975006, 993.8509223616443, 996.5565323431076, 999.3454287441738, 1043.8799538053106, 1054.4569546683426, 1055.1137044882337, 1057.2810488867567, 994.209603365319, 1015.2588610314475, 1042.8331466965326, 1047.5024363427387, 1051.5793682527012, 747.8952946587482, 771.090113665874, 780.5706861226303, 785.3206975965805, 790.2018960849583, 15504.844951550453, 16103.516894493185, 16162.030278183347, 16268.0, 16271.942323748919, 12898.677533061655, 12963.092583519157, 13081.672958176026, 13094.541691040864, 13109.606711798566, 9778.315517913748, 10555.788884222273, 10557.683269501927, 10587.947060264727, 10613.310134841211, 3572.4105522588743, 3572.464130380438, 3573.857045718168, 3575.928481430357, 3631.5460567429072, 84643.0, 84745.0, 85107.57446212729, 85740.99999999937, 87211.0, 9550.37922535033, 9631.277654175965, 9665.226781857435, 9804.019598040139, 9871.75320616976, 21936.561268774534, 21961.560768784533, 21968.231111911005, 22001.0, 22047.89760511968, 7410.666520006613, 7420.443466740016, 7534.208908064575, 7574.848503030019, 7671.194524574951, 4175.519815221228, 4217.0, 4234.9364759528635, 4258.808353624095, 4308.504521980012, 3760.0, 3765.642263984917, 3780.4707340972286, 3844.8077596120643, 3850.403187505941, 663.0286630086946, 672.2873753820966, 682.1677553384862, 684.8116767888771, 694.4617921111078, 509.8623371689697, 550.6008144095499, 604.5888795618934, 615.2217444932164, 621.8165641135876, 7913.762587122395, 8326.958365208257, 8431.15688431152, 8494.787630309293, 8566.357523185787, 537.3820106877045, 579.5019873626723, 581.5289615411446, 592.6517173430394, 628.5631486117196, 3844.1350696093155, 3860.536735591747, 3983.402489626579, 4029.176451856871, 4090.4477895484324, 91730.0826991735, 95231.52384238174, 95607.52196238974, 96752.03247967431, 97209.0, 62354.688226558574, 76561.0, 76650.23349766542, 77616.61191694005, 78541.0, 60792.696036519534, 67776.0, 68509.62980740357, 71525.0, 72092.63953680197, 6715.3620406061345, 7711.228877112244, 7878.290953814184, 7885.487443316166, 7903.0, 45669.54330456653, 46961.53038469572, 47726.5227347736, 48117.518824811305, 50888.49111508838, 61814.0727889073, 63423.0, 67754.66122669356, 68245.0, 68443.0, 11106.222564420414, 11353.37556434371, 11379.975802177843, 11424.257423267794, 11720.88279117198, 11983.820242696193, 12010.459529321377, 12192.817107743569, 12375.443105060114, 12588.748225035264, 11538.88461115378, 11553.364564949008, 11721.648350549498, 11736.413179341169, 12066.457009434422, 11395.202335836411, 11656.650300491, 11850.05199584014, 11941.402929853299, 11976.640700778991, 10643.627473038407, 11198.552057917592, 11249.493772780408, 11327.773444530898, 11472.483738231967, 9599.664011759554, 9715.29982253117, 9893.2707214819, 10123.7469063274, 10140.340877842917, 8504.362172837185, 9949.751256218653, 9974.900250997398, 9983.650572229937, 10092.747681308027], "value": 7103.402963213367, "unit": "score"}], "name": "dromaeo_css", "value": 7769.858559650085, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 112.0, 115.0, 116.0, 118.0, 131.0, 116.0, 118.0, 108.0, 142.0], "value": 119.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 156.0, 156.0, 159.0, 128.0, 171.0, 156.0, 123.0, 205.0, 121.0], "value": 153.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 273.0, 203.0, 244.0, 168.0, 212.0, 272.0, 202.0, 271.0, 273.0], "value": 234.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 81.0, 81.0, 128.0, 57.0, 80.0, 83.0, 56.0, 145.0, 86.0], "value": 86.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 117.0, 84.0, 112.0, 119.0, 114.0, 82.0, 81.0, 115.0], "value": 98.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 130.0, 88.0, 93.0, 135.0, 126.0, 90.0, 86.0, 90.0, 85.0], "value": 101.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 103.0, 147.0, 150.0, 94.0, 95.0, 94.0, 130.0], "value": 109.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 90.0, 92.0, 90.0, 89.0, 93.0, 121.0, 147.0, 103.0], "value": 100.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 63.0, 64.0, 65.0, 64.0, 64.0, 77.0, 62.0, 59.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 65.0, 72.0, 67.0, 72.0, 72.0, 65.0, 65.0, 67.0], "value": 67.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 79.0, 77.0, 80.0, 81.0, 79.0, 81.0, 107.0, 109.0, 82.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [119.0, 131.0, 121.0, 146.0, 138.0, 129.0, 148.0, 124.0, 163.0, 139.0], "value": 135.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 162.0, 161.0, 168.0, 179.0, 162.0, 160.0, 165.0, 165.0, 166.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 77.0, 90.0, 79.0, 67.0, 65.0, 66.0, 70.0, 67.0, 69.0], "value": 72.1, "unit": "score"}], "name": "kraken", "value": 1594.6, "alertThreshold": 2.0}]} 15:58:07 INFO - Return code: 0 15:58:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 15:58:07 ERROR - # TBPL SUCCESS # 15:58:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:58:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:58:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:58:07 INFO - 2016-07-10 15:58:07.944 screenresolution[1722:20756] starting screenresolution argv=screenresolution get 15:58:07 INFO - 2016-07-10 15:58:07.946 screenresolution[1722:20756] Display 0: 1600x1200x32@60 15:58:07 INFO - 2016-07-10 15:58:07.952 screenresolution[1723:20759] starting screenresolution argv=screenresolution list 15:58:07 INFO - Available Modes on Display 0 15:58:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:58:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:58:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:58:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:58:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:58:08 INFO - Intel Iris: 15:58:08 INFO - Chipset Model: Intel Iris 15:58:08 INFO - Type: GPU 15:58:08 INFO - Bus: Built-In 15:58:08 INFO - VRAM (Dynamic, Max): 1536 MB 15:58:08 INFO - Vendor: Intel (0x8086) 15:58:08 INFO - Device ID: 0x0a2e 15:58:08 INFO - Revision ID: 0x0009 15:58:08 INFO - Displays: 15:58:08 INFO - Display: 15:58:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:58:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:58:08 INFO - Display Serial Number: 1600x1200 60 15:58:08 INFO - Main Display: Yes 15:58:08 INFO - Mirror: Off 15:58:08 INFO - Online: Yes 15:58:08 INFO - Rotation: Supported 15:58:08 INFO - Return code: 0 15:58:08 INFO - Running post-action listener: _resource_record_post_action 15:58:08 INFO - Running post-run listener: _resource_record_post_run 15:58:09 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 31550976; Write bytes: 498038784; Read time: 194; Write time: 1633 15:58:09 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 237568; Write bytes: 196114432; Read time: 3; Write time: 460 15:58:09 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 29769216; Write bytes: 301076480; Read time: 179; Write time: 1171 15:58:09 INFO - Running post-run listener: _upload_blobber_files 15:58:09 INFO - Blob upload gear active. 15:58:09 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:58:09 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 15:58:09 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'] 15:58:09 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 15:58:09 INFO - (blobuploader) - INFO - Open directory for files ... 15:58:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:58:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:58:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:10 INFO - (blobuploader) - INFO - Done attempting. 15:58:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:58:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:58:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:58:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:58:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:58:11 INFO - (blobuploader) - INFO - Done attempting. 15:58:11 INFO - (blobuploader) - INFO - Iteration through files over. 15:58:11 INFO - Return code: 0 15:58:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:58:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:58:11 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/603f429158f50d4aee2982605baafa0dc41d3754d5b44c1c06d0776354d8ad0611f5df836d6538017fdafe7a7c093cdd86da5b2f12b10e5003f3e7a0dd7b3686", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e0a73aeaf88816f58d1bc6613709cc36c0f47853870c22380daf4537cd809b20cb4252134f715e93c2c067daf33c2a1f0c0ea98b4b941c418cd52acf2ea960"} 15:58:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:58:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:58:11 INFO - Contents: 15:58:11 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/603f429158f50d4aee2982605baafa0dc41d3754d5b44c1c06d0776354d8ad0611f5df836d6538017fdafe7a7c093cdd86da5b2f12b10e5003f3e7a0dd7b3686", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e0a73aeaf88816f58d1bc6613709cc36c0f47853870c22380daf4537cd809b20cb4252134f715e93c2c067daf33c2a1f0c0ea98b4b941c418cd52acf2ea960"} 15:58:11 INFO - Running post-run listener: copy_logs_to_upload_dir 15:58:11 INFO - Copying logs to upload dir... 15:58:11 INFO - mkdir: /builds/slave/test/build/upload/logs 15:58:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1328.693036 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2016-07-10 15:58:11.708683) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 15:58:11.712604) ========= 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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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/603f429158f50d4aee2982605baafa0dc41d3754d5b44c1c06d0776354d8ad0611f5df836d6538017fdafe7a7c093cdd86da5b2f12b10e5003f3e7a0dd7b3686", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e0a73aeaf88816f58d1bc6613709cc36c0f47853870c22380daf4537cd809b20cb4252134f715e93c2c067daf33c2a1f0c0ea98b4b941c418cd52acf2ea960"} build_url:https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011099 build_url: 'https://queue.taskcluster.net/v1/task/Bmby5uXOTIuk4OeI_zVdww/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/603f429158f50d4aee2982605baafa0dc41d3754d5b44c1c06d0776354d8ad0611f5df836d6538017fdafe7a7c093cdd86da5b2f12b10e5003f3e7a0dd7b3686", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f9e0a73aeaf88816f58d1bc6613709cc36c0f47853870c22380daf4537cd809b20cb4252134f715e93c2c067daf33c2a1f0c0ea98b4b941c418cd52acf2ea960"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-10 15:58:11.748335) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:58:11.749023) ========= 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.OOKgsLVUd2/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.5DBEOrt1wN/Listeners TMPDIR=/var/folders/hb/llxgk70n58q_fcntkch5rymc00000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-10 15:58:11.798149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 15:58:11.798575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-10 15:58:11.799305) ========= ========= Total master_lag: 0.18 =========