builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0373 starttime: 1472856656.73 results: success (0) buildid: 20160902133349 builduid: 6a6036702fa040ac87f0a4823cdb26ae revision: d0830980ffdb36a10855d02a588b4869cad6707e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.730376) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.730926) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.731258) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.757280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.757752) ========= 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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-09-02 15:50:56-- 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% 41.1M=0s 2016-09-02 15:50:56 (41.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.121957 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.903653) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.904057) ========= 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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.025785 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.953261) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:56.953671) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0830980ffdb36a10855d02a588b4869cad6707e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev d0830980ffdb36a10855d02a588b4869cad6707e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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-09-02 15:50:57,039 truncating revision to first 12 chars 2016-09-02 15:50:57,040 Setting DEBUG logging. 2016-09-02 15:50:57,040 attempt 1/10 2016-09-02 15:50:57,040 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0830980ffdb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-02 15:50:57,418 unpacking tar archive at: mozilla-central-d0830980ffdb/testing/mozharness/ program finished with exit code 0 elapsedTime=0.632140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:57.608370) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:57.608784) ========= script_repo_revision: d0830980ffdb36a10855d02a588b4869cad6707e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:57.609323) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:57.609595) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-02 15:50:57.628846) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 15:50:57.629323) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:50:57 INFO - MultiFileLogger online at 20160902 15:50:57 in /builds/slave/test 15:50:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:50:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:50:57 INFO - {'append_to_log': False, 15:50:57 INFO - 'base_work_dir': '/builds/slave/test', 15:50:57 INFO - 'blob_upload_branch': 'Firefox', 15:50:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:50:57 INFO - 'branch': 'Firefox', 15:50:57 INFO - 'buildbot_json_path': 'buildprops.json', 15:50:57 INFO - 'config_files': ('talos/mac_config.py',), 15:50:57 INFO - 'default_actions': ('clobber', 15:50:57 INFO - 'read-buildbot-config', 15:50:57 INFO - 'download-and-extract', 15:50:57 INFO - 'populate-webroot', 15:50:57 INFO - 'create-virtualenv', 15:50:57 INFO - 'install', 15:50:57 INFO - 'run-tests'), 15:50:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:50:57 INFO - 'download_minidump_stackwalk': True, 15:50:57 INFO - 'download_symbols': 'ondemand', 15:50:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:50:57 INFO - 'tooltool.py': '/tools/tooltool.py', 15:50:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:50:57 INFO - '/tools/misc-python/virtualenv.py')}, 15:50:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:50:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:50:57 INFO - 'installer_path': 'installer.exe', 15:50:57 INFO - 'log_level': 'info', 15:50:57 INFO - 'log_name': 'talos', 15:50:57 INFO - 'log_to_console': True, 15:50:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:50:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:50:57 INFO - 'opt_config_files': (), 15:50:57 INFO - 'pip_index': False, 15:50:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:57 INFO - 'cmd': ('bash', 15:50:57 INFO - '-c', 15:50:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:57 INFO - 'enabled': True, 15:50:57 INFO - 'halt_on_failure': False, 15:50:57 INFO - 'name': 'check_screen_resolution'},), 15:50:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:50:57 INFO - 'cmd': ('bash', 15:50:57 INFO - '-c', 15:50:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:50:57 INFO - 'enabled': True, 15:50:57 INFO - 'halt_on_failure': False, 15:50:57 INFO - 'name': 'check_screen_resolution'},), 15:50:57 INFO - 'run_cmd_checks_enabled': True, 15:50:57 INFO - 'sps_profile': False, 15:50:57 INFO - 'sps_profile_interval': 0, 15:50:57 INFO - 'suite': 'dromaeojs', 15:50:57 INFO - 'system_bits': '32', 15:50:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:50:57 INFO - 'title': 't-yosemite-r7-0373', 15:50:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:50:57 INFO - 'use_talos_json': True, 15:50:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:50:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:50:57 INFO - 'work_dir': 'build'} 15:50:57 INFO - [mozharness: 2016-09-02 22:50:57.832049Z] Running clobber step. 15:50:57 INFO - Running pre-action listener: _resource_record_pre_action 15:50:57 INFO - Running main action method: clobber 15:50:57 INFO - rmtree: /builds/slave/test/build 15:50:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:50:59 INFO - Running post-action listener: _resource_record_post_action 15:50:59 INFO - [mozharness: 2016-09-02 22:50:59.881195Z] Finished clobber step (success) 15:50:59 INFO - [mozharness: 2016-09-02 22:50:59.881333Z] Running read-buildbot-config step. 15:50:59 INFO - Running pre-action listener: _resource_record_pre_action 15:50:59 INFO - Running main action method: read_buildbot_config 15:50:59 INFO - Using buildbot properties: 15:50:59 INFO - { 15:50:59 INFO - "project": "", 15:50:59 INFO - "product": "firefox", 15:50:59 INFO - "script_repo_revision": "production", 15:50:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 15:50:59 INFO - "repository": "", 15:50:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 15:50:59 INFO - "buildid": "20160902133349", 15:50:59 INFO - "pgo_build": "False", 15:50:59 INFO - "basedir": "/builds/slave/test", 15:50:59 INFO - "buildnumber": 93, 15:50:59 INFO - "slavename": "t-yosemite-r7-0373", 15:50:59 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 15:50:59 INFO - "platform": "yosemite_r7", 15:50:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:50:59 INFO - "branch": "mozilla-central", 15:50:59 INFO - "repo_path": "mozilla-central", 15:50:59 INFO - "revision": "d0830980ffdb36a10855d02a588b4869cad6707e", 15:50:59 INFO - "stage_platform": "macosx64", 15:50:59 INFO - "builduid": "6a6036702fa040ac87f0a4823cdb26ae", 15:50:59 INFO - "slavebuilddir": "test" 15:50:59 INFO - } 15:50:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 15:50:59 INFO - Running post-action listener: _resource_record_post_action 15:50:59 INFO - [mozharness: 2016-09-02 22:50:59.884641Z] Finished read-buildbot-config step (success) 15:50:59 INFO - [mozharness: 2016-09-02 22:50:59.884772Z] Running download-and-extract step. 15:50:59 INFO - Running pre-action listener: _resource_record_pre_action 15:50:59 INFO - Running main action method: download_and_extract 15:50:59 INFO - mkdir: /builds/slave/test/build/tests 15:50:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:50:59 INFO - trying https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 15:50:59 INFO - Downloading https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:50:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 15:51:00 INFO - Downloaded 1199 bytes. 15:51:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 15:51:00 INFO - Using the following test package requirements: 15:51:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:51:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 15:51:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 15:51:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'jsshell-mac.zip'], 15:51:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 15:51:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 15:51:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 15:51:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 15:51:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 15:51:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 15:51:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 15:51:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:00 INFO - trying https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:51:00 INFO - Downloading https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:51:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:51:01 INFO - Downloaded 16399944 bytes. 15:51:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:51:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:08 INFO - trying https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:51:08 INFO - Downloading https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 15:51:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:51:09 INFO - Downloaded 16399944 bytes. 15:51:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 15:51:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:15 INFO - trying https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:51:15 INFO - Downloading https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 15:51:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:51:16 INFO - Downloaded 14413998 bytes. 15:51:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 15:51:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:17 INFO - trying https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:51:17 INFO - Downloading https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 15:51:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 15:51:20 INFO - Downloaded 111485906 bytes. 15:51:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:51:20 INFO - mkdir: /builds/slave/test/properties 15:51:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:51:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:51:20 INFO - Contents: 15:51:20 INFO - build_url:https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 15:51:20 INFO - Running post-action listener: _resource_record_post_action 15:51:20 INFO - Running post-action listener: set_extra_try_arguments 15:51:20 INFO - [mozharness: 2016-09-02 22:51:20.823490Z] Finished download-and-extract step (success) 15:51:20 INFO - [mozharness: 2016-09-02 22:51:20.823646Z] Running populate-webroot step. 15:51:20 INFO - Running pre-action listener: _resource_record_pre_action 15:51:20 INFO - Running main action method: populate_webroot 15:51:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:51:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:51:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:51:20 INFO - '--tppagecycles', 15:51:20 INFO - '7'], 15:51:20 INFO - 'tests': ['tsvgm']}}, 15:51:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:51:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:51:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:51:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:51:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:51:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['damp', 'tps']}, 15:51:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'tests': ['damp', 'tps']}, 15:51:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['dromaeo_dom']}, 15:51:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:51:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['basic_compositor_video']}, 15:51:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:51:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'other-e10s': {'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['a11yr', 15:51:20 INFO - 'ts_paint', 15:51:20 INFO - 'tpaint', 15:51:20 INFO - 'sessionrestore', 15:51:20 INFO - 'sessionrestore_no_auto_restore', 15:51:20 INFO - 'tabpaint']}, 15:51:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['tsvgx', 15:51:20 INFO - 'tsvgr_opacity', 15:51:20 INFO - 'tart', 15:51:20 INFO - 'tscrollx', 15:51:20 INFO - 'cart']}, 15:51:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:51:20 INFO - 'tsvgr_opacity', 15:51:20 INFO - 'tart', 15:51:20 INFO - 'tscrollx', 15:51:20 INFO - 'cart']}, 15:51:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'talos_options': ['--disable-e10s'], 15:51:20 INFO - 'tests': ['tp5o']}, 15:51:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'tests': ['tp5o']}, 15:51:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'talos_options': ['--disable-e10s', 15:51:20 INFO - '--xperf_path', 15:51:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:20 INFO - 'tests': ['tp5n']}, 15:51:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:51:20 INFO - 'talos_options': ['--xperf_path', 15:51:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:51:20 INFO - 'tests': ['tp5n']}}, 15:51:20 INFO - 'talos.zip': {'path': '', 15:51:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:51:20 INFO - Running post-action listener: _resource_record_post_action 15:51:20 INFO - [mozharness: 2016-09-02 22:51:20.842624Z] Finished populate-webroot step (success) 15:51:20 INFO - [mozharness: 2016-09-02 22:51:20.842734Z] Running create-virtualenv step. 15:51:20 INFO - Running pre-action listener: _resource_record_pre_action 15:51:20 INFO - Running main action method: create_virtualenv 15:51:20 INFO - Creating virtualenv /builds/slave/test/build/venv 15:51:20 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:51:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:51:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:51:20 INFO - Using real prefix '/tools/python27' 15:51:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:51:21 INFO - Installing distribute.............................................................................................................................................................................................done. 15:51:24 INFO - Installing pip.................done. 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 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 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:24 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:51:24 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:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:24 INFO - 'HOME': '/Users/cltbld', 15:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:24 INFO - 'LOGNAME': 'cltbld', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'PAGER': '/bin/cat', 15:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:24 INFO - 'PWD': '/builds/slave/test', 15:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:24 INFO - 'SHELL': '/bin/bash', 15:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:24 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:24 INFO - 'USER': 'cltbld', 15:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:24 INFO - 'XPC_FLAGS': '0x0', 15:51:24 INFO - 'XPC_SERVICE_NAME': '0', 15:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:24 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:51:24 INFO - Cleaning up... 15:51:24 INFO - Return code: 0 15:51:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:24 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:24 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>=3.1.1'] in /builds/slave/test/build 15:51:24 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>=3.1.1 15:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:24 INFO - 'HOME': '/Users/cltbld', 15:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:24 INFO - 'LOGNAME': 'cltbld', 15:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:24 INFO - 'MOZ_NO_REMOTE': '1', 15:51:24 INFO - 'NO_EM_RESTART': '1', 15:51:24 INFO - 'PAGER': '/bin/cat', 15:51:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:24 INFO - 'PWD': '/builds/slave/test', 15:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:24 INFO - 'SHELL': '/bin/bash', 15:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:24 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:24 INFO - 'USER': 'cltbld', 15:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:24 INFO - 'XPC_FLAGS': '0x0', 15:51:24 INFO - 'XPC_SERVICE_NAME': '0', 15:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:25 INFO - Downloading/unpacking psutil>=3.1.1 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:51:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:28 INFO - Installing collected packages: psutil 15:51:28 INFO - Running setup.py install for psutil 15:51:28 INFO - building 'psutil._psutil_osx' extension 15:51:28 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:51:29 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:51:29 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:51:29 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:51:29 INFO - building 'psutil._psutil_posix' extension 15:51:29 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:51:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:51:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:51:29 INFO - ^ 15:51:29 INFO - 1 warning generated. 15:51:29 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:51:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:51:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:51:29 INFO - Successfully installed psutil 15:51:29 INFO - Cleaning up... 15:51:29 INFO - Return code: 0 15:51:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:29 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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:29 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.3'] in /builds/slave/test/build 15:51:29 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.3 15:51:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:29 INFO - 'HOME': '/Users/cltbld', 15:51:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:29 INFO - 'LOGNAME': 'cltbld', 15:51:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:29 INFO - 'MOZ_NO_REMOTE': '1', 15:51:29 INFO - 'NO_EM_RESTART': '1', 15:51:29 INFO - 'PAGER': '/bin/cat', 15:51:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:29 INFO - 'PWD': '/builds/slave/test', 15:51:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:29 INFO - 'SHELL': '/bin/bash', 15:51:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:29 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:29 INFO - 'USER': 'cltbld', 15:51:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:29 INFO - 'XPC_FLAGS': '0x0', 15:51:29 INFO - 'XPC_SERVICE_NAME': '0', 15:51:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:51:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:51:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:51:32 INFO - Installing collected packages: mozsystemmonitor 15:51:32 INFO - Running setup.py install for mozsystemmonitor 15:51:32 INFO - Successfully installed mozsystemmonitor 15:51:32 INFO - Cleaning up... 15:51:32 INFO - Return code: 0 15:51:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:51:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:51:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:32 INFO - 'HOME': '/Users/cltbld', 15:51:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:32 INFO - 'LOGNAME': 'cltbld', 15:51:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:32 INFO - 'MOZ_NO_REMOTE': '1', 15:51:32 INFO - 'NO_EM_RESTART': '1', 15:51:32 INFO - 'PAGER': '/bin/cat', 15:51:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:32 INFO - 'PWD': '/builds/slave/test', 15:51:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:32 INFO - 'SHELL': '/bin/bash', 15:51:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:32 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:32 INFO - 'USER': 'cltbld', 15:51:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:32 INFO - 'XPC_FLAGS': '0x0', 15:51:32 INFO - 'XPC_SERVICE_NAME': '0', 15:51:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:32 INFO - Downloading/unpacking jsonschema==2.5.1 15:51:32 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:51:32 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:51:32 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:51:32 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:51:32 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:51:32 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:51:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:51:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:35 INFO - Downloading functools32-3.2.3-2.tar.gz 15:51:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:51:35 INFO - warning: no files found matching '*.txt' 15:51:35 INFO - no previously-included directories found matching 'build' 15:51:35 INFO - no previously-included directories found matching 'dist' 15:51:35 INFO - no previously-included directories found matching '.git*' 15:51:35 INFO - Installing collected packages: jsonschema, functools32 15:51:35 INFO - Running setup.py install for functools32 15:51:36 INFO - warning: no files found matching '*.txt' 15:51:36 INFO - no previously-included directories found matching 'build' 15:51:36 INFO - no previously-included directories found matching 'dist' 15:51:36 INFO - no previously-included directories found matching '.git*' 15:51:36 INFO - Successfully installed jsonschema functools32 15:51:36 INFO - Cleaning up... 15:51:36 INFO - Return code: 0 15:51:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:51:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:36 INFO - 'HOME': '/Users/cltbld', 15:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:36 INFO - 'LOGNAME': 'cltbld', 15:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:36 INFO - 'MOZ_NO_REMOTE': '1', 15:51:36 INFO - 'NO_EM_RESTART': '1', 15:51:36 INFO - 'PAGER': '/bin/cat', 15:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:36 INFO - 'PWD': '/builds/slave/test', 15:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:36 INFO - 'SHELL': '/bin/bash', 15:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:36 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:36 INFO - 'USER': 'cltbld', 15:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:36 INFO - 'XPC_FLAGS': '0x0', 15:51:36 INFO - 'XPC_SERVICE_NAME': '0', 15:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:51:36 INFO - Cleaning up... 15:51:36 INFO - Return code: 0 15:51:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:51:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:51:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:36 INFO - 'HOME': '/Users/cltbld', 15:51:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:36 INFO - 'LOGNAME': 'cltbld', 15:51:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:36 INFO - 'MOZ_NO_REMOTE': '1', 15:51:36 INFO - 'NO_EM_RESTART': '1', 15:51:36 INFO - 'PAGER': '/bin/cat', 15:51:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:36 INFO - 'PWD': '/builds/slave/test', 15:51:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:36 INFO - 'SHELL': '/bin/bash', 15:51:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:36 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:36 INFO - 'USER': 'cltbld', 15:51:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:36 INFO - 'XPC_FLAGS': '0x0', 15:51:36 INFO - 'XPC_SERVICE_NAME': '0', 15:51:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:36 INFO - Downloading/unpacking blobuploader==1.2.4 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:39 INFO - Downloading blobuploader-1.2.4.tar.gz 15:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:51:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:51:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:51:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:51:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:51:40 INFO - Downloading docopt-0.6.1.tar.gz 15:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:51:40 INFO - Installing collected packages: blobuploader, requests, docopt 15:51:40 INFO - Running setup.py install for blobuploader 15:51:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:51:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:51:40 INFO - Running setup.py install for requests 15:51:41 INFO - Running setup.py install for docopt 15:51:41 INFO - Successfully installed blobuploader requests docopt 15:51:41 INFO - Cleaning up... 15:51:41 INFO - Return code: 0 15:51:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:41 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:41 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 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:41 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:51:41 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:51:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:41 INFO - 'HOME': '/Users/cltbld', 15:51:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:41 INFO - 'LOGNAME': 'cltbld', 15:51:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:41 INFO - 'MOZ_NO_REMOTE': '1', 15:51:41 INFO - 'NO_EM_RESTART': '1', 15:51:41 INFO - 'PAGER': '/bin/cat', 15:51:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:41 INFO - 'PWD': '/builds/slave/test', 15:51:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:41 INFO - 'SHELL': '/bin/bash', 15:51:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:41 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:41 INFO - 'USER': 'cltbld', 15:51:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:41 INFO - 'XPC_FLAGS': '0x0', 15:51:41 INFO - 'XPC_SERVICE_NAME': '0', 15:51:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:41 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ttigRW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:41 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-wbX_6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:41 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Dipkjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:41 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Z13zQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-sf7PBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-C7FlmA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-w5CsWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-C86BuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-uXBO8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-0brdJ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-mlQZkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ES3JoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-HtvdlA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:42 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-2zdUBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ARzodg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-YaNb4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:43 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-6ugiUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:51:43 INFO - Running setup.py install for manifestparser 15:51:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Running setup.py install for mozcrash 15:51:43 INFO - Running setup.py install for mozdebug 15:51:43 INFO - Running setup.py install for mozdevice 15:51:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:51:43 INFO - Running setup.py install for mozfile 15:51:44 INFO - Running setup.py install for mozhttpd 15:51:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Running setup.py install for mozinfo 15:51:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Running setup.py install for mozInstall 15:51:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Running setup.py install for mozleak 15:51:44 INFO - Running setup.py install for mozlog 15:51:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:51:44 INFO - Running setup.py install for moznetwork 15:51:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozprocess 15:51:45 INFO - Running setup.py install for mozprofile 15:51:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozrunner 15:51:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Running setup.py install for mozscreenshot 15:51:45 INFO - Running setup.py install for moztest 15:51:45 INFO - Running setup.py install for mozversion 15:51:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:51:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:51:45 INFO - Cleaning up... 15:51:45 INFO - Return code: 0 15:51:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:45 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:45 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 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:45 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:51:45 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:51:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:45 INFO - 'HOME': '/Users/cltbld', 15:51:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:45 INFO - 'LOGNAME': 'cltbld', 15:51:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:45 INFO - 'MOZ_NO_REMOTE': '1', 15:51:45 INFO - 'NO_EM_RESTART': '1', 15:51:45 INFO - 'PAGER': '/bin/cat', 15:51:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:45 INFO - 'PWD': '/builds/slave/test', 15:51:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:45 INFO - 'SHELL': '/bin/bash', 15:51:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:45 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:45 INFO - 'USER': 'cltbld', 15:51:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:45 INFO - 'XPC_FLAGS': '0x0', 15:51:45 INFO - 'XPC_SERVICE_NAME': '0', 15:51:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-lPBRMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-1JaBkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-p3W268-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-CiVSna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-vdPlBv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-9_C4H_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-ap7ehP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:51:46 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:51:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:51:46 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-Nyvc23-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-pcaGKL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-hasH8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-vbRan5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-yO8Ihj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-jXbTJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-JOCCyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:51:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-XtUSiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-fW2fH7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:51:47 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:51:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:51:47 INFO - Running setup.py (path:/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/pip-3YQcm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 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:51:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:51: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:51: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:51: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:51: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:51: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:51: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:51:50 INFO - Downloading blessings-1.6.tar.gz 15:51:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:51:50 INFO - Installing collected packages: blessings 15:51:50 INFO - Running setup.py install for blessings 15:51:50 INFO - Successfully installed blessings 15:51:50 INFO - Cleaning up... 15:51:50 INFO - Return code: 0 15:51:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:51:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:51 INFO - Reading from file tmpfile_stdout 15:51:51 INFO - Current package versions: 15:51:51 INFO - blessings == 1.6 15:51:51 INFO - blobuploader == 1.2.4 15:51:51 INFO - docopt == 0.6.1 15:51:51 INFO - functools32 == 3.2.3-2 15:51:51 INFO - jsonschema == 2.5.1 15:51:51 INFO - manifestparser == 1.1 15:51:51 INFO - mozInstall == 1.12 15:51:51 INFO - mozcrash == 0.17 15:51:51 INFO - mozdebug == 0.1 15:51:51 INFO - mozdevice == 0.48 15:51:51 INFO - mozfile == 1.2 15:51:51 INFO - mozhttpd == 0.7 15:51:51 INFO - mozinfo == 0.9 15:51:51 INFO - mozleak == 0.1 15:51:51 INFO - mozlog == 3.3 15:51:51 INFO - moznetwork == 0.27 15:51:51 INFO - mozprocess == 0.23 15:51:51 INFO - mozprofile == 0.28 15:51:51 INFO - mozrunner == 6.12 15:51:51 INFO - mozscreenshot == 0.1 15:51:51 INFO - mozsystemmonitor == 0.3 15:51:51 INFO - moztest == 0.7 15:51:51 INFO - mozversion == 1.4 15:51:51 INFO - psutil == 3.1.1 15:51:51 INFO - requests == 1.2.3 15:51:51 INFO - wsgiref == 0.1.2 15:51:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 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 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:51 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:51:51 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:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:51 INFO - 'HOME': '/Users/cltbld', 15:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:51 INFO - 'LOGNAME': 'cltbld', 15:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:51 INFO - 'MOZ_NO_REMOTE': '1', 15:51:51 INFO - 'NO_EM_RESTART': '1', 15:51:51 INFO - 'PAGER': '/bin/cat', 15:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:51 INFO - 'PWD': '/builds/slave/test', 15:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:51 INFO - 'SHELL': '/bin/bash', 15:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:51 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:51 INFO - 'USER': 'cltbld', 15:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:51 INFO - 'XPC_FLAGS': '0x0', 15:51:51 INFO - 'XPC_SERVICE_NAME': '0', 15:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 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:51:51 INFO - Cleaning up... 15:51:51 INFO - Return code: 0 15:51:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:51:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:51:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:51:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f004a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f099618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9053df0180>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, '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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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:51:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:51:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:51:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:51:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:51:51 INFO - 'HOME': '/Users/cltbld', 15:51:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:51:51 INFO - 'LOGNAME': 'cltbld', 15:51:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:51:51 INFO - 'MOZ_NO_REMOTE': '1', 15:51:51 INFO - 'NO_EM_RESTART': '1', 15:51:51 INFO - 'PAGER': '/bin/cat', 15:51:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:51:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:51:51 INFO - 'PWD': '/builds/slave/test', 15:51:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:51:51 INFO - 'SHELL': '/bin/bash', 15:51:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:51:51 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:51:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:51:51 INFO - 'USER': 'cltbld', 15:51:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:51:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:51:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:51:51 INFO - 'XPC_FLAGS': '0x0', 15:51:51 INFO - 'XPC_SERVICE_NAME': '0', 15:51:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:51:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:51:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:51:51 INFO - Cleaning up... 15:51:51 INFO - Return code: 0 15:51:51 INFO - Running post-action listener: _resource_record_post_action 15:51:51 INFO - Running post-action listener: _start_resource_monitoring 15:51:51 INFO - Starting resource monitoring. 15:51:51 INFO - [mozharness: 2016-09-02 22:51:51.790183Z] Finished create-virtualenv step (success) 15:51:51 INFO - [mozharness: 2016-09-02 22:51:51.791210Z] Running install step. 15:51:51 INFO - Running pre-action listener: _resource_record_pre_action 15:51:51 INFO - Running main action method: install 15:51:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:51:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:51:52 INFO - Reading from file tmpfile_stdout 15:51:52 INFO - Detecting whether we're running mozinstall >=1.0... 15:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:51:52 INFO - Reading from file tmpfile_stdout 15:51:52 INFO - Output received: 15:51:52 INFO - Usage: mozinstall [options] installer 15:51:52 INFO - Options: 15:51:52 INFO - -h, --help show this help message and exit 15:51:52 INFO - -d DEST, --destination=DEST 15:51:52 INFO - Directory to install application into. [default: 15:51:52 INFO - "/builds/slave/test"] 15:51:52 INFO - --app=APP Application being installed. [default: firefox] 15:51:52 INFO - mkdir: /builds/slave/test/build/application 15:51:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:51:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:52:26 INFO - Reading from file tmpfile_stdout 15:52:26 INFO - Output received: 15:52:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:52:26 INFO - Running post-action listener: _resource_record_post_action 15:52:26 INFO - [mozharness: 2016-09-02 22:52:26.184532Z] Finished install step (success) 15:52:26 INFO - [mozharness: 2016-09-02 22:52:26.184701Z] Running run-tests step. 15:52:26 INFO - Running pre-action listener: _resource_record_pre_action 15:52:26 INFO - Running main action method: run_tests 15:52:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:52:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:52:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:52:26 INFO - 2016-09-02 15:52:26.199 screenresolution[1656:7517] starting screenresolution argv=screenresolution get 15:52:26 INFO - 2016-09-02 15:52:26.201 screenresolution[1656:7517] Display 0: 1600x1200x32@60 15:52:26 INFO - 2016-09-02 15:52:26.207 screenresolution[1657:7519] starting screenresolution argv=screenresolution list 15:52:26 INFO - Available Modes on Display 0 15:52:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:52:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:52:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:52:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:52:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:52:26 INFO - Intel Iris: 15:52:26 INFO - Chipset Model: Intel Iris 15:52:26 INFO - Type: GPU 15:52:26 INFO - Bus: Built-In 15:52:26 INFO - VRAM (Dynamic, Max): 1536 MB 15:52:26 INFO - Vendor: Intel (0x8086) 15:52:26 INFO - Device ID: 0x0a2e 15:52:26 INFO - Revision ID: 0x0009 15:52:26 INFO - Displays: 15:52:26 INFO - Display: 15:52:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:52:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:52:26 INFO - Display Serial Number: 1600x1200 60 15:52:26 INFO - Main Display: Yes 15:52:26 INFO - Mirror: Off 15:52:26 INFO - Online: Yes 15:52:26 INFO - Rotation: Supported 15:52:26 INFO - Return code: 0 15:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:52:26 INFO - Python 2.7.3 15:52:26 INFO - Return code: 0 15:52:26 INFO - grabbing minidump binary from tooltool 15:52:26 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:52:26 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef68d30>, '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 0x10eccddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef60b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:52:26 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:52:26 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:52:26 INFO - Calling ['/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'] with output_timeout 600 15:52:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:52:26 INFO - Return code: 0 15:52:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:52:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:52:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:52:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:52:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 15:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0373 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 15:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.5NkA6ncgEZ/Render', 15:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:52:26 INFO - 'HOME': '/Users/cltbld', 15:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:52:26 INFO - 'LOGNAME': 'cltbld', 15:52:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:52:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:52:26 INFO - 'MOZ_NO_REMOTE': '1', 15:52:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:52:26 INFO - 'NO_EM_RESTART': '1', 15:52:26 INFO - 'PAGER': '/bin/cat', 15:52:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:52:26 INFO - 'PWD': '/builds/slave/test', 15:52:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:52:26 INFO - 'SHELL': '/bin/bash', 15:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bykrBWPWsB/Listeners', 15:52:26 INFO - 'TMPDIR': '/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/', 15:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:52:26 INFO - 'USER': 'cltbld', 15:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:52:26 INFO - 'XPC_FLAGS': '0x0', 15:52:26 INFO - 'XPC_SERVICE_NAME': '0', 15:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:52:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0373', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 15:52:26 INFO - mozversion application_buildid: 20160902133349 15:52:26 INFO - mozversion application_changeset: d0830980ffdb36a10855d02a588b4869cad6707e 15:52:26 INFO - mozversion application_display_name: Nightly 15:52:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:52:26 INFO - mozversion application_name: Firefox 15:52:26 INFO - mozversion application_remotingname: firefox 15:52:26 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:52:26 INFO - mozversion application_vendor: Mozilla 15:52:26 INFO - mozversion application_version: 51.0a1 15:52:26 INFO - mozversion platform_buildid: 20160902133349 15:52:26 INFO - mozversion platform_changeset: d0830980ffdb36a10855d02a588b4869cad6707e 15:52:26 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:52:26 INFO - mozversion platform_version: 51.0a1 15:52:26 INFO - using testdate: 1472856746 15:52:26 INFO - actual date: 1472856746 15:52:26 INFO - starting webserver on 'localhost:49273' 15:52:26 INFO - SUITE-START | Running 2 tests 15:52:26 INFO - TEST-START | dromaeo_css 15:52:26 INFO - operating with platform_type : mac_ 15:52:26 INFO - Initialising browser for dromaeo_css test... 15:52:26 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpGEuCCU/profile http://localhost:49273/getInfo.html) 15:52:29 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 15:52:29 INFO - PROCESS | 1667 | colorDepth:24 15:52:29 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 15:52:29 INFO - PROCESS | 1667 | __metrics 15:52:29 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 15:52:29 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:52:30 INFO - TEST-INFO | 1667: exit 0 15:52:30 INFO - Browser initialized. 15:52:30 INFO - Running cycle 1/1 for dromaeo_css test... 15:52:30 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpGEuCCU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:52:42 INFO - PROCESS | 1670 | RSS: Main: 233734144 15:52:42 INFO - PROCESS | 1670 | 15:53:33 INFO - PROCESS | 1670 | 1472856813337 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:55:45 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 15:55:46 INFO - PROCESS | 1670 | RSS: Main: 373121024 15:55:46 INFO - PROCESS | 1670 | 15:58:49 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 15:58:50 INFO - PROCESS | 1670 | RSS: Main: 373694464 15:58:50 INFO - PROCESS | 1670 | 16:01:52 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 16:01:53 INFO - PROCESS | 1670 | RSS: Main: 360603648 16:01:53 INFO - PROCESS | 1670 | 16:04:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 16:04:56 INFO - PROCESS | 1670 | RSS: Main: 343896064 16:04:56 INFO - PROCESS | 1670 | 16:07:59 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 16:08:00 INFO - PROCESS | 1670 | RSS: Main: 335454208 16:08:00 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 16:11:03 INFO - PROCESS | 1670 | RSS: Main: 444289024 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | __start_tp_report 16:11:03 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 16:11:03 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 16:11:03 INFO - PROCESS | 1670 | |i|pagename|runs| 16:11:03 INFO - PROCESS | 1670 | |0;dojo.html;2602.9088981885634;3220.8389580520975;3348.045806945021;3396.84507267529;3430.2624935638833;5573.136163894603;5662.8301150965435;5677.772889084432;5773.134029895517;5822.446867547582;5676.120201368794;5720.685362305072;5725.312962444513;5728.112142617891;5751.482366587006;5169.508896654812;5200.479952004788;5232.7383630818495;5250;5288.629795914288;5286.418493965661;5288.418273989877;5302.3106996090455;5304.469553044703;5314.92027619586;5183.611229157809;5234.345706786652;5254.080535906221;5260.079486089919;5298.788048478056;5328.520433161034;5336.119540275849;5345.33183352081;5353.384360798519;5373.704446255454;5084.923726144111;5097.133487307166;5105.540501354859;5245.082110630625;5259.47405259471;4695.929561056552;5704.771809127671;5711.086226203787;5727.427257274281;5792.218050563204;6221.906671399933;6227.844303892393;6237.656928868911;6239.344868788802;6253.562250642457;5274;6162.784302549389;6192;6246.875062498724;6270.278917924452;5884.205632239678;5894.08641660539;5901.40985901415;5912.320083190402;5923.200367950358;6291.937080629226;6296.748130074838;6313.084602732578;6313.273973493016;6322.146510221153;5420.972895135499;6020.518358531307;6101.450869421773;6129.754809807558;6201.534884883562;6177.351378105324;6248.937510624926;6264.498840092781;6264.68676566179;6273.811785646438;6180.907530829715;6218.098375735583;6241.96879015602;6268;6280.214973128359;6046.848828779316;6132.110843927606;6144.2488890665945;6247.718852651642;6263;5012.972340670154;5014.52362025607;5020.3724534433195;5021.6735912165595;5031.496850315012;4520.141173177086;4542.750148741851;4559.840405585788;4572.634189264851;4573.588377046081;5710.114932185518;5740.1102829061565;5793.971030144822;5855.092460668603;5866.149408335853;6160;6240.095186197976;6255.812325630239;6262.342454042351;6267.561270711098;5790;5793.797217097465;5798.246227990334;5800.984827655144;5864.179014937912;5320.973395133;5396.784128634889;5444.673319600836;5461.344638643388;5474.644148130359;6251.343608920998;6375.330590287953;6376.075469056961;6378.649174295366;6393.360663933756;6211.254649442096;6220.72006759706;6227.19046523949;6231.470325022424;6242.687865606792;6187.257529096537;6214.40963108522;6345.23857137128;6358.141650877073;6364.904526432199;6127.693615319127;6133.701927635063;6142.877142457037;6153.907691384543;6161.106639537332;6210.223722034746;6236.688165591793;6243.344448832807;6248.500119990299;6270.7805226816845;6103.84740381494;6109.969450152898;6136.23297087864;6148.200733904564;6149.323574406722;5976.013957697105;6041.305249896318;6162.198914141133;6174.629522228681;6191.597546159485;4623.052274283831;5967.373425790403;6017.729202185825;6039.063945088518;6073.665948372882;5081.44104148536;6162.044883043135;6184;6184.041473571604;6189;5759.56803239767;5954.46409823118;6012.609180403259;6024.789132380346;6031.276246850406 16:11:03 INFO - PROCESS | 1670 | |1;ext.html;502.1814442458793;503.62479952435433;530.9089820419038;532.233583639559;552.7954656776993;30680.846595767045;30774.38451230974;32539.186520336974;40253.396199056966;41531.79234103825;43542.34686479705;43842.90392740183;43886.78056609721;43947.78026109873;43969.560304396946;9827.656032038862;10006.449645269508;10008.99910008997;10017.699469015904;10035.69892903214;8789.428687135347;8997.820043599124;9757.121859032686;11906.702332441671;12170.513179472811;12091.09316801239;12105;12119.515219391214;12142.635720928387;12152.817707734392;12265;12340.259584424874;12344.321062341567;12357.876421235762;12358.69103272421;11984.221025633393;12079.57721479744;12083.093767967437;12098.395080245953;12114.394280285951;40494;40753.796231018954;40799.388009179886;40835.795821021005;40900.79549602263;2933.779966502521;3289.5723555937575;3387.322535492887;3538.663826936437;3730.5336832895887;30435.391292174034;30859.919902803293;31276.374472510422;31383.686163138304;31441.899533516218;3127;3195.536647186168;3263.233140212048;3319.2365755876285;3320.7841490303053;485717;488047;490243;490432;498343;59208;59479;59492.70253648704;59497.10754338688;59551.174056281096;62348;62356.68821655863;62446.0633090504;62479.37520624827;62525.06212406817;16276.10481423533;16432.75350869738;16495.34018639241;16497;16508.25712842925;11431.428428578538;12816.55142070023;12921.28932908699;12928.806067908989;12942.935285323701;12749.298788566532;14353.91868179129;15177.24113794306;16403.42588009414;16868.915655421646;6975.82560435983;7265.600391978492;7279.088364654181;7332.120145582458;7370.226126256773;808.6078252047752;948.4168277092288;1002.9949850250848;1006.6476733143342;1019.8929112443235;25092.19575401169;25116.874415628172;27955.860220699175;28176;30338.54492182575;968.801395713877;1023.467796745688;1053.952572134256;1082.2802836114054;1192.9642110736693;11879.346635934933;14745.41018359275;15497.147656878755;15531.378744850079;15984.440544580884;654844;658845;662529;663089;665038;48558;49334.506654933706;55238.72380638071;56543;57803;1336.4787732784223;1359.9796003059962;1377.3526442571974;1382.1223523062727;1383.667919699285;30069.097927062223;30941.69058309388;31335.076711974878;31478.213044674067;31778.88773892903;50490.24264636107;50835.49164508308;50878;51130;51230.487695124066;591305;596805;597306;844254.2383897437;844753;22674.319770406913;23953.16163934254;25381.74618253794;25447.36381590401;25450.109246176296;45206.32190517079;45788.77105614584;46370.53629463662;47095.52904470912;49614.50385496243;38123.23753524858;39202.01994950149;39441.25308095726;40713;41173.79413103035;29035.98374056898;32168.678313217504;37053.888383348545;37408.81295593505;37481.6251837489;37470.43794343142;38096;38689;38837.61162388453;39019.6098039016;34141;34528.48747509753;34996.77511287093;35014.64985350114;35037;25131.994720210987;25531.9787208517;25627.743722562536;25765.742342576334;25858.870705647103 16:11:03 INFO - PROCESS | 1670 | |2;jquery.html;325.20811823210477;346.5979463821967;373.08035692019166;377.21915634636304;402;14598.416063357467;14606.488772892937;15863.682726345467;16611.75082373764;19864.602707945833;11342;18793.43619691408;19636.509087272825;19914.20343186271;19926.800731992676;5225.6864588124645;5507.642003269794;5529.281193444846;5542.307211598551;5546.750396232179;5259.421463639016;5436.3476382834115;5500.119980803072;5512.476314750113;5560.86097847553;5484.478974497416;5504.146857237115;5509.311336082989;5509.531689806351;5511.22842802008;4600.332951721982;5070.543651071384;5174.922376164361;5278.366596008465;5495.697736624484;5307.6019298552765;5329.680219186861;5331.067063263913;5341.332333458317;5350.491703288181;12970.481180752759;13094.541691040768;13146.671333216746;13350.399232034582;13417.396217170251;2143.699882016519;2190.288156349193;2311.791938725523;2463.8275320727557;2587.508373409065;10070.748231294203;10164.44095574743;10293.3823970562;12357.69105772354;15058.849411505855;1824.7627808384957;1838.9448316550524;1855.239351865742;1890.3761758619623;1911.2928216559962;97670;100566;117097.41451292689;123375.38312308471;124861;10883.945580272206;11203.663890083311;11232.663020109247;11244.775104497865;11405.429728513542;14468.348924298432;14470.348834302482;14475.710485790225;14497.855021449861;14514.056586322067;8165.38759593021;8402.411831171881;8457.830843383097;8492.320614350838;8541.487510749375;8289.58552072394;8295.294899933573;8324.084350721416;8508.957455212809;8519.659213631385;4448.510663826976;4476.4180656514445;4478.305862591304;4543.432070991126;4560.954390456053;1869.1028306412998;1918.788933217345;1919.2131226197191;1921.5292253398006;1925.0759635375098;701.7126546146483;763.8128658478709;808.5593351623379;925.546482223719;1011.7318910488726;20478.078486468145;20933.267335643483;20942.47643808917;21022;21478.892605536872;893.4817805672694;905.8097799462055;946.8011717539393;956.1872408452759;982.5824024789435;9419.434833909987;10795.6221532246;11135.443227838738;11143.387113708824;11282.379469129113;546807;563359;587589;590457;591901;95909;103613;105673.9432605664;122261;126118;871.7341210930728;917.7522069041321;929.5166513413122;936.2463217110292;973.9902600974041;18986.810131899056;19076.713849291995;19192.808071919102;19203.423897283104;19306.71039934374;50337.74831125821;51263.743681281354;54071.72964135154;55950.60123496945;57163.85672286614;72611.99583271278;74355.19270476521;74928.1798109599;75734.62132689302;75786.62106689431;7094.290570943071;7339.8532029360185;7848.370795783477;8037.598120094089;8251.83496330058;10981.286216395909;12984.870151298366;13021.218726876417;13417.262050587311;13502.797458037941;10684.412357320422;11112.444377781241;11626.651200463999;11758.412079395825;11763.705907352385;11031.558737650405;11167.88832111701;11379.829302560634;11596.536138554364;11641.53433862636;9475.47884866339;10215.927327630492;10229.641962531276;11390.487428065591;11793.587224447103;7233.710651573878;7469.663865125974;7486.326230639269;7565.546067235983;7707.267809558083;9680.951595241979;10426.947865260625;10733.677989660322;11016.614418495314;11268 16:11:03 INFO - PROCESS | 1670 | |3;mootools.html;1406.1211742660835;1548.047950510436;1726.1196789637288;1815.3011090730065;1901.2870173684869;8338.24955753982;8890.06654301298;8906.33202509811;8907.599158037887;8916.152965468278;7740.92259077409;7961.044674639052;8360.91639083609;8562.058173600915;8693.478391296527;2350.0482304666593;2380.976190238093;2387.080973825081;2392.0670938334065;2396.8322217444784;2345.226075395124;2374.6319320505263;2385.403649087728;2389.5340408620327;2396.065534441569;2361.2562042956415;2361.3624321433203;2369.052379048386;2388.10446082719;2392.83250172488;2335.509542995973;2356.493353928905;2368.265837590335;2370.2059809963685;2375.0143690368564;2338.8245881558782;2357.481354102095;2357.6934998450092;2360.1031607989025;2365.5741966446035;8627.784305392353;8708.608112634884;8761.474311541328;8804.955975220148;8835.77910552235;5141.611764823514;5317.973410132925;5318.202269659543;5320;5324.520793128599;3567.7859328440377;3591.371509985768;3660.68884144848;3668.5047518584915;3672.137047793766;1015.228426395937;1056.9471526423647;1067.7223921780394;1080.783843231349;1091.9071878890304;11577.826332605018;11658.825117623242;11680.0655947524;11684.591039313667;11715.824262636068;4480.798364073625;4543.704659197141;4736.881577960578;4745.738984355894;4748.976255118702;9831.80336393268;9841.212702983745;9841.50792460374;9850.965648606936;9852.80294394108;5272.103742363807;5272.785430119359;5277.709725965032;5280.128778751463;5280.656757310762;4051.0075031617444;4093.3655283431117;4181.51912530063;4686.671932964728;4833.10587541306;3341.8329083545727;3775.811209439517;3788.3749181384765;3935.8819235422984;3960.5049368828895;1247.5820600099069;1265.474827946392;1267.2459886367596;1272.8281681973;1276.3873340796283;822.1337563730299;822.144969232004;886.8315020146151;889.2663552569156;931.9254459643212;5323.3079699639575;5376.569874410038;5590.301212348457;5659.462351076641;5708.828735137869;744.9925500745031;905.42053086025;943.6791490893129;953.3279038277998;970.369259981018;3524.6827785499427;3647.544056992876;3647.5805282392867;3676.8161591920298;3773.113318370181;10461.68614941553;10734.355938643706;10789.83815242772;10790.460476976119;10797.974192451704;10736.624218152327;10737.033666969854;10745.086667633339;10751.301165424222;10758.73103172427;8123.7156699515235;8254.380921430917;8262.339012878956;8264.297534709496;8272.958635206785;5577.860553486195;5794;5942.851428714317;5969.671668058299;6073.119397687399;8170.673173073011;8876.556172191236;9987.051230133126;10031.899681003097;10047.145992590711;9777.071178238057;10170.491475426346;10208;10208.846867297145;10268.845967310346;5694.658320500783;7047.541909775849;7338.412926965937;7483.476156669199;7483.550986940801;7742.8451430969935;7786.649600767866;7806.765797026098;8124.959375203086;8169.714060007871;7221.819454513469;7334.816629584303;7375.889361659472;7393.112826460859;7477.962610186914;7190.352868241884;7284.307990740871;7296.306850849161;7357.301056399633;7385.29839665231;6643.701033453603;6839.179298484214;6963.059986901906;7021.789346319619;7023.367896889305;4479.910401792011;4876.927076043265;4960.7767650456535;5018.774155162953;5143.588512918883;4279.780262625137;4808.35087263215;4871.610271178368;5022.974885125551;5206.479352064764 16:11:03 INFO - PROCESS | 1670 | |4;prototype.html;296.1470963624762;296.17662897145937;305.77678294844765;309.79952683352013;314.0515642758867;4946.318251794389;5043.77303021364;5300.522952934235;5301.920471192931;5356.598255130866;5041.168207245809;5152.74236288186;5179.559737422314;5230.267762513251;5283.524482796547;4003.699722520808;4025.376066709665;4034.4553485279407;4038.474998250231;4042.6967977401814;3717.758345707534;3734.757240779354;3910.432987216852;4034.9394759078637;4057.472528571296;3338.3990881641294;4002.2595819773437;4020.1557672888584;4025.838966441339;4029.3351596986463;3617.878457678128;3974.8671628585716;4031.8992025199314;4037.9596204037875;4045.02919299369;2646.6691663542056;3445.3109378124223;3478.4956181353564;3488.7557870949045;3540.9822950885336;4207.957920420788;4896.828610998598;5073.46728593496;5076.213186956028;5086.669366491166;4223.66210703143;4574.817007319703;4597.264437689987;4630.513796051399;4653.58117769399;4993.800247990076;5061.190209566452;5233.659812112239;5365.946340536622;5494.780208791643;3391.2030672791875;3728.7762734236035;3858.459815625815;3872.4384964180035;3890.8443662253476;4312.116016216731;5101.336826212606;5188.299579556749;5431.782728690887;5711.200431939532;3446.7070299024363;3618.9276214475562;3920.2355540669864;3936.645701886787;3938.547067087264;3355.9293246679017;3676.816159192083;3969.479380593344;4009.3785463253244;4015.9397609035886;3634.60019397866;3678.8043885737243;4592.288195329729;4594.609458196024;4615.884602884955;4395.692301538862;5186.481351864783;5602.887942241132;5634.549236061105;5640.830775076754;1272.0014788391254;1286.897048236139;1319.957233785299;1324.6423465664216;1349.1702602899147;853.6713365354259;901.2024358442825;938.6573900526396;939.8815409662479;946.711253067821;4375.78121093944;5126.436092029874;5135.563477104488;5150.913879878655;5211.192265198824;5649.209110724501;5680.460356266148;5704;5706.400827913092;5745.569082318759;4859.785053736566;5063.620228482879;5111.7699703513435;5130.30740849988;5187.533122019036;2499.925002249899;2516.169664010872;2522.8612426316727;2531.6455696202547;2543.5073627844818;4187.664986801049;4343.67422443318;4691.249400095969;4887.65786394956;4986.900261994812;4224.429701990253;4264.505264420287;4515.503294637587;4797.688150270221;4894.730789806595;4330.826766929287;4362.585554372329;4777.856664300077;4867.809485731435;4954.200876504908;4088.366303223005;4226.725262857904;4356.499002614613;4693.577578017995;4713.892235324627;4112.442737032505;4190.937135942902;4239.59723826236;4266.2747332954095;4437.667174961955;4424.579664931826;4570.926271838191;4890.33980412654;4918.680285781413;4959.032988567339;2970.1535062507082;3178.3166619176986;3369.83150842452;3392.235328207503;3404.8253352593315;4236.279832428556;4555.9316610251535;4617.3146801417915;4742.976285118552;5473.057064741959;5010.949890501194;5469.179623056573;5569.888602228014;5587.776488940397;5613.887722245614;4879.609631229422;5586.245856809279;5604.439556044363;5605.439456054525;5615.6630602164005;4828.782704778364;5235.769594145297;5483.479069488392;5493.450654934634;5493.72010615606;4510.985028368722;5579.81950288348;5583.190437386637;5626.690532020778;5640.589852536866;5399.4060653327315;5473.972630136983;5479.726013699379;5498.972505137449;5538.427714124447 16:11:03 INFO - PROCESS | 1670 | |5;yui.html;575.8509935911757;735.6652723011031;763.427429427929;775.2054144501885;794.3248238996857;13886.722265554696;14094.295285235736;14715.779263311046;14748.33632486538;14761.48334808282;13270.402831872565;13299.800502992462;13371.866281337183;13381.063325567215;13680.384382702778;835.6448509383519;859.2224037246302;859.4198915731856;861.9698310559131;863.6631713631689;683.1699485125572;704.260526447231;831.5343407691681;858.5964596639569;860.1699360117485;889.4352086425102;889.7775556110972;891.61660485991;892.8392889279927;892.8705337726026;884.4030279561271;884.9026607073217;885.4819930340758;886.0120965123873;887.995560022199;525.1649876696072;538.0503411478743;541.093668105921;542.8669975855901;663.0121119531863;13573.932130339284;14061;14101.71796564063;14256.144631322153;14302.427902883872;13348.933255333759;13680.65798355039;13783.379747911367;13878.306084695725;14308.570742877626;11115.72210694731;11398.60104896333;11400.201985860911;11452.370119643416;11908.285502869856;3448.81031543265;3508.1755787389943;3540.4689296605457;3541.1501239702543;3549.94675079874;112958;117706;118696;119859;120269.99999999913;8887.955560222157;10455.529501172465;10613.469326533643;10623.415712135908;11139;19114.426567203005;19790.90104549468;21282.148714051265;21417.57164856694;21427.78572214289;7223.494355395072;7244.420446364278;7258.600776957318;7264.854702905912;7488.737894173678;3920.2355540669296;3922.9607703923166;4024.5170579530645;4122.154958233555;4147.212029714345;3268.6404495505476;3379.881704140343;3389.9491507627404;3406.625271220164;3408.335374601929;560.201712559601;649.4317472211815;655.9967200164064;676.5568552598056;677.2482544375739;558.5531574740191;645.4642646603309;653.4478365780908;664.6112024465742;664.8138521214067;8160.714374996846;8535.402521823413;8780.473171609723;8922.553872306358;9052.773680657905;629.235478893147;646.2789323988311;649.1009951217598;652.2201713191444;659.7361055577711;4319.697621166488;4437.400950871591;4444;4447.533009034069;4578.404807375086;103775;104576.43135352976;161234.19382903245;165985;168304;109892;132248;133169;134356;139205.3039734795;97783;116179;116201;117560;119946;6912.688928998307;8234.505929644241;8320.50076995358;8785.780355491164;8850.33622478304;64164.679176603815;64796.02805958009;65438.01842972264;65880.67059664671;67364.65270694657;57857;71186.6440667814;75198.6240068818;75290;95621.5218923901;12600.369981501071;12753.936230318788;12786;12808.871911280768;13006.284654344103;13982.58052258434;14541.272936352929;14877.32208202534;14918.552443426715;15214.847851521787;13455.730885382041;13565.186088834702;13865.4453821846;14273;14365.7845132321;11414.45904802841;13604.126691420091;13902.721945561234;14018;14345.64135896611;12868.420921058761;13196.868031319947;13303.268320242092;13335.66660833448;13337.53318633843;10286.588536458457;10733.892661073289;10785;10989.835152472879;11622.418879056182;11061.336319820837;11150.158315149058;11201.775964480501;11349.262297950607;11373.829392559284 16:11:03 INFO - PROCESS | 1670 | __end_tp_report 16:11:03 INFO - PROCESS | 1670 | __start_cc_report 16:11:03 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,1276 16:11:03 INFO - PROCESS | 1670 | __end_cc_report 16:11:03 INFO - PROCESS | 1670 | __startTimestamp1472857863816__endTimestamp 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | ------- Summary: start ------- 16:11:03 INFO - PROCESS | 1670 | Number of tests: 6 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5743.13 Median:5976.01 stddev:657.47 (11.0%) stddev-sans-first:611.60 16:11:03 INFO - PROCESS | 1670 | Values: 2602.9 3220.8 3348.0 3396.8 3430.3 5573.1 5662.8 5677.8 5773.1 5822.4 5676.1 5720.7 5725.3 5728.1 5751.5 5169.5 5200.5 5232.7 5250.0 5288.6 5286.4 5288.4 5302.3 5304.5 5314.9 5183.6 5234.3 5254.1 5260.1 5298.8 5328.5 5336.1 5345.3 5353.4 5373.7 5084.9 5097.1 5105.5 5245.1 5259.5 4695.9 5704.8 5711.1 5727.4 5792.2 6221.9 6227.8 6237.7 6239.3 6253.6 5274.0 6162.8 6192.0 6246.9 6270.3 5884.2 5894.1 5901.4 5912.3 5923.2 6291.9 6296.7 6313.1 6313.3 6322.1 5421.0 6020.5 6101.5 6129.8 6201.5 6177.4 6248.9 6264.5 6264.7 6273.8 6180.9 6218.1 6242.0 6268.0 6280.2 6046.8 6132.1 6144.2 6247.7 6263.0 5013.0 5014.5 5020.4 5021.7 5031.5 4520.1 4542.8 4559.8 4572.6 4573.6 5710.1 5740.1 5794.0 5855.1 5866.1 6160.0 6240.1 6255.8 6262.3 6267.6 5790.0 5793.8 5798.2 5801.0 5864.2 5321.0 5396.8 5444.7 5461.3 5474.6 6251.3 6375.3 6376.1 6378.6 6393.4 6211.3 6220.7 6227.2 6231.5 6242.7 6187.3 6214.4 6345.2 6358.1 6364.9 6127.7 6133.7 6142.9 6153.9 6161.1 6210.2 6236.7 6243.3 6248.5 6270.8 6103.8 6110.0 6136.2 6148.2 6149.3 5976.0 6041.3 6162.2 6174.6 6191.6 4623.1 5967.4 6017.7 6039.1 6073.7 5081.4 6162.0 6184.0 6184.0 6189.0 5759.6 5954.5 6012.6 6024.8 6031.3 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:73885.74 Median:18764.16 stddev:167936.63 (895.0%) stddev-sans-first:168317.36 16:11:03 INFO - PROCESS | 1670 | Values: 502.2 503.6 530.9 532.2 552.8 30680.8 30774.4 32539.2 40253.4 41531.8 43542.3 43842.9 43886.8 43947.8 43969.6 9827.7 10006.4 10009.0 10017.7 10035.7 8789.4 8997.8 9757.1 11906.7 12170.5 12091.1 12105.0 12119.5 12142.6 12152.8 12265.0 12340.3 12344.3 12357.9 12358.7 11984.2 12079.6 12083.1 12098.4 12114.4 40494.0 40753.8 40799.4 40835.8 40900.8 2933.8 3289.6 3387.3 3538.7 3730.5 30435.4 30859.9 31276.4 31383.7 31441.9 3127.0 3195.5 3263.2 3319.2 3320.8 485717.0 488047.0 490243.0 490432.0 498343.0 59208.0 59479.0 59492.7 59497.1 59551.2 62348.0 62356.7 62446.1 62479.4 62525.1 16276.1 16432.8 16495.3 16497.0 16508.3 11431.4 12816.6 12921.3 12928.8 12942.9 12749.3 14353.9 15177.2 16403.4 16868.9 6975.8 7265.6 7279.1 7332.1 7370.2 808.6 948.4 1003.0 1006.6 1019.9 25092.2 25116.9 27955.9 28176.0 30338.5 968.8 1023.5 1054.0 1082.3 1193.0 11879.3 14745.4 15497.1 15531.4 15984.4 654844.0 658845.0 662529.0 663089.0 665038.0 48558.0 49334.5 55238.7 56543.0 57803.0 1336.5 1360.0 1377.4 1382.1 1383.7 30069.1 30941.7 31335.1 31478.2 31778.9 50490.2 50835.5 50878.0 51130.0 51230.5 591305.0 596805.0 597306.0 844254.2 844753.0 22674.3 23953.2 25381.7 25447.4 25450.1 45206.3 45788.8 46370.5 47095.5 49614.5 38123.2 39202.0 39441.3 40713.0 41173.8 29036.0 32168.7 37053.9 37408.8 37481.6 37470.4 38096.0 38689.0 38837.6 39019.6 34141.0 34528.5 34996.8 35014.6 35037.0 25132.0 25532.0 25627.7 25765.7 25858.9 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:33134.00 Median:1456.36 stddev:95946.05 (6588.1%) stddev-sans-first:96183.57 16:11:03 INFO - PROCESS | 1670 | Values: 325.2 346.6 373.1 377.2 402.0 14598.4 14606.5 15863.7 16611.8 19864.6 11342.0 18793.4 19636.5 19914.2 19926.8 5225.7 5507.6 5529.3 5542.3 5546.8 5259.4 5436.3 5500.1 5512.5 5560.9 5484.5 5504.1 5509.3 5509.5 5511.2 4600.3 5070.5 5174.9 5278.4 5495.7 5307.6 5329.7 5331.1 5341.3 5350.5 12970.5 13094.5 13146.7 13350.4 13417.4 2143.7 2190.3 2311.8 2463.8 2587.5 10070.7 10164.4 10293.4 12357.7 15058.8 1824.8 1838.9 1855.2 1890.4 1911.3 97670.0 100566.0 117097.4 123375.4 124861.0 10883.9 11203.7 11232.7 11244.8 11405.4 14468.3 14470.3 14475.7 14497.9 14514.1 8165.4 8402.4 8457.8 8492.3 8541.5 8289.6 8295.3 8324.1 8509.0 8519.7 4448.5 4476.4 4478.3 4543.4 4561.0 1869.1 1918.8 1919.2 1921.5 1925.1 701.7 763.8 808.6 925.5 1011.7 20478.1 20933.3 20942.5 21022.0 21478.9 893.5 905.8 946.8 956.2 982.6 9419.4 10795.6 11135.4 11143.4 11282.4 546807.0 563359.0 587589.0 590457.0 591901.0 95909.0 103613.0 105673.9 122261.0 126118.0 871.7 917.8 929.5 936.2 974.0 18986.8 19076.7 19192.8 19203.4 19306.7 50337.7 51263.7 54071.7 55950.6 57163.9 72612.0 74355.2 74928.2 75734.6 75786.6 7094.3 7339.9 7848.4 8037.6 8251.8 10981.3 12984.9 13021.2 13417.3 13502.8 10684.4 11112.4 11626.7 11758.4 11763.7 11031.6 11167.9 11379.8 11596.5 11641.5 9475.5 10215.9 10229.6 11390.5 11793.6 7233.7 7469.7 7486.3 7565.5 7707.3 9681.0 10426.9 10733.7 11016.6 11268.0 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5623.64 Median:4918.85 stddev:3169.51 (64.4%) stddev-sans-first:3162.55 16:11:03 INFO - PROCESS | 1670 | Values: 1406.1 1548.0 1726.1 1815.3 1901.3 8338.2 8890.1 8906.3 8907.6 8916.2 7740.9 7961.0 8360.9 8562.1 8693.5 2350.0 2381.0 2387.1 2392.1 2396.8 2345.2 2374.6 2385.4 2389.5 2396.1 2361.3 2361.4 2369.1 2388.1 2392.8 2335.5 2356.5 2368.3 2370.2 2375.0 2338.8 2357.5 2357.7 2360.1 2365.6 8627.8 8708.6 8761.5 8805.0 8835.8 5141.6 5318.0 5318.2 5320.0 5324.5 3567.8 3591.4 3660.7 3668.5 3672.1 1015.2 1056.9 1067.7 1080.8 1091.9 11577.8 11658.8 11680.1 11684.6 11715.8 4480.8 4543.7 4736.9 4745.7 4749.0 9831.8 9841.2 9841.5 9851.0 9852.8 5272.1 5272.8 5277.7 5280.1 5280.7 4051.0 4093.4 4181.5 4686.7 4833.1 3341.8 3775.8 3788.4 3935.9 3960.5 1247.6 1265.5 1267.2 1272.8 1276.4 822.1 822.1 886.8 889.3 931.9 5323.3 5376.6 5590.3 5659.5 5708.8 745.0 905.4 943.7 953.3 970.4 3524.7 3647.5 3647.6 3676.8 3773.1 10461.7 10734.4 10789.8 10790.5 10798.0 10736.6 10737.0 10745.1 10751.3 10758.7 8123.7 8254.4 8262.3 8264.3 8273.0 5577.9 5794.0 5942.9 5969.7 6073.1 8170.7 8876.6 9987.1 10031.9 10047.1 9777.1 10170.5 10208.0 10208.8 10268.8 5694.7 7047.5 7338.4 7483.5 7483.6 7742.8 7786.6 7806.8 8125.0 8169.7 7221.8 7334.8 7375.9 7393.1 7478.0 7190.4 7284.3 7296.3 7357.3 7385.3 6643.7 6839.2 6963.1 7021.8 7023.4 4479.9 4876.9 4960.8 5018.8 5143.6 4279.8 4808.4 4871.6 5023.0 5206.5 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:4255.10 Median:4606.57 stddev:1290.82 (28.0%) stddev-sans-first:1259.78 16:11:03 INFO - PROCESS | 1670 | Values: 296.1 296.2 305.8 309.8 314.1 4946.3 5043.8 5300.5 5301.9 5356.6 5041.2 5152.7 5179.6 5230.3 5283.5 4003.7 4025.4 4034.5 4038.5 4042.7 3717.8 3734.8 3910.4 4034.9 4057.5 3338.4 4002.3 4020.2 4025.8 4029.3 3617.9 3974.9 4031.9 4038.0 4045.0 2646.7 3445.3 3478.5 3488.8 3541.0 4208.0 4896.8 5073.5 5076.2 5086.7 4223.7 4574.8 4597.3 4630.5 4653.6 4993.8 5061.2 5233.7 5365.9 5494.8 3391.2 3728.8 3858.5 3872.4 3890.8 4312.1 5101.3 5188.3 5431.8 5711.2 3446.7 3618.9 3920.2 3936.6 3938.5 3355.9 3676.8 3969.5 4009.4 4015.9 3634.6 3678.8 4592.3 4594.6 4615.9 4395.7 5186.5 5602.9 5634.5 5640.8 1272.0 1286.9 1320.0 1324.6 1349.2 853.7 901.2 938.7 939.9 946.7 4375.8 5126.4 5135.6 5150.9 5211.2 5649.2 5680.5 5704.0 5706.4 5745.6 4859.8 5063.6 5111.8 5130.3 5187.5 2499.9 2516.2 2522.9 2531.6 2543.5 4187.7 4343.7 4691.2 4887.7 4986.9 4224.4 4264.5 4515.5 4797.7 4894.7 4330.8 4362.6 4777.9 4867.8 4954.2 4088.4 4226.7 4356.5 4693.6 4713.9 4112.4 4190.9 4239.6 4266.3 4437.7 4424.6 4570.9 4890.3 4918.7 4959.0 2970.2 3178.3 3369.8 3392.2 3404.8 4236.3 4555.9 4617.3 4743.0 5473.1 5010.9 5469.2 5569.9 5587.8 5613.9 4879.6 5586.2 5604.4 5605.4 5615.7 4828.8 5235.8 5483.5 5493.5 5493.7 4511.0 5579.8 5583.2 5626.7 5640.6 5399.4 5474.0 5479.7 5499.0 5538.4 16:11:03 INFO - PROCESS | 1670 | 16:11:03 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:24362.07 Median:3398.29 stddev:39587.69 (1164.9%) stddev-sans-first:39658.45 16:11:03 INFO - PROCESS | 1670 | Values: 575.9 735.7 763.4 775.2 794.3 13886.7 14094.3 14715.8 14748.3 14761.5 13270.4 13299.8 13371.9 13381.1 13680.4 835.6 859.2 859.4 862.0 863.7 683.2 704.3 831.5 858.6 860.2 889.4 889.8 891.6 892.8 892.9 884.4 884.9 885.5 886.0 888.0 525.2 538.1 541.1 542.9 663.0 13573.9 14061.0 14101.7 14256.1 14302.4 13348.9 13680.7 13783.4 13878.3 14308.6 11115.7 11398.6 11400.2 11452.4 11908.3 3448.8 3508.2 3540.5 3541.2 3549.9 112958.0 117706.0 118696.0 119859.0 120270.0 8888.0 10455.5 10613.5 10623.4 11139.0 19114.4 19790.9 21282.1 21417.6 21427.8 7223.5 7244.4 7258.6 7264.9 7488.7 3920.2 3923.0 4024.5 4122.2 4147.2 3268.6 3379.9 3389.9 3406.6 3408.3 560.2 649.4 656.0 676.6 677.2 558.6 645.5 653.4 664.6 664.8 8160.7 8535.4 8780.5 8922.6 9052.8 629.2 646.3 649.1 652.2 659.7 4319.7 4437.4 4444.0 4447.5 4578.4 103775.0 104576.4 161234.2 165985.0 168304.0 109892.0 132248.0 133169.0 134356.0 139205.3 97783.0 116179.0 116201.0 117560.0 119946.0 6912.7 8234.5 8320.5 8785.8 8850.3 64164.7 64796.0 65438.0 65880.7 67364.7 57857.0 71186.6 75198.6 75290.0 95621.5 12600.4 12753.9 12786.0 12808.9 13006.3 13982.6 14541.3 14877.3 14918.6 15214.8 13455.7 13565.2 13865.4 14273.0 14365.8 11414.5 13604.1 13902.7 14018.0 14345.6 12868.4 13196.9 13303.3 13335.7 13337.5 10286.6 10733.9 10785.0 10989.8 11622.4 11061.3 11150.2 11201.8 11349.3 11373.8 16:11:03 INFO - PROCESS | 1670 | -------- Summary: end -------- 16:11:03 INFO - PROCESS | 1670 | 16:11:04 INFO - TEST-INFO | 1670: exit 0 16:11:04 INFO - TEST-OK | dromaeo_css | took 1117654ms 16:11:04 INFO - TEST-START | kraken 16:11:04 INFO - operating with platform_type : mac_ 16:11:04 INFO - Initialising browser for kraken test... 16:11:04 INFO - TEST-INFO | started process 1791 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpzx2T0K/profile http://localhost:49273/getInfo.html) 16:11:06 INFO - PROCESS | 1791 | __metrics Screen width/height:1600/1200 16:11:06 INFO - PROCESS | 1791 | colorDepth:24 16:11:06 INFO - PROCESS | 1791 | Browser inner width/height: 1024/689 16:11:06 INFO - PROCESS | 1791 | __metrics 16:11:06 INFO - PROCESS | 1791 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:11:06 INFO - PROCESS | 1791 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:07 INFO - TEST-INFO | 1791: exit 0 16:11:07 INFO - Browser initialized. 16:11:07 INFO - Running cycle 1/1 for kraken test... 16:11:07 INFO - TEST-INFO | started process 1792 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/tmpzx2T0K/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:19 INFO - PROCESS | 1792 | RSS: Main: 233631744 16:11:19 INFO - PROCESS | 1792 | 16:11:22 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 16:11:23 INFO - PROCESS | 1792 | RSS: Main: 292139008 16:11:23 INFO - PROCESS | 1792 | 16:11:25 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 16:11:26 INFO - PROCESS | 1792 | RSS: Main: 288374784 16:11:26 INFO - PROCESS | 1792 | 16:11:29 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 16:11:30 INFO - PROCESS | 1792 | RSS: Main: 379056128 16:11:30 INFO - PROCESS | 1792 | 16:11:32 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 16:11:33 INFO - PROCESS | 1792 | RSS: Main: 281034752 16:11:33 INFO - PROCESS | 1792 | 16:11:35 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:11:35 INFO - PROCESS | 1792 | RSS: Main: 285523968 16:11:35 INFO - PROCESS | 1792 | 16:11:38 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 16:11:39 INFO - PROCESS | 1792 | RSS: Main: 323309568 16:11:39 INFO - PROCESS | 1792 | 16:11:42 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 16:11:42 INFO - PROCESS | 1792 | RSS: Main: 323022848 16:11:42 INFO - PROCESS | 1792 | 16:11:45 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 16:11:46 INFO - PROCESS | 1792 | RSS: Main: 325206016 16:11:46 INFO - PROCESS | 1792 | 16:11:47 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:11:48 INFO - PROCESS | 1792 | RSS: Main: 297713664 16:11:48 INFO - PROCESS | 1792 | 16:11:50 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:11:50 INFO - PROCESS | 1792 | RSS: Main: 303521792 16:11:50 INFO - PROCESS | 1792 | 16:11:53 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:11:53 INFO - PROCESS | 1792 | RSS: Main: 310165504 16:11:53 INFO - PROCESS | 1792 | 16:11:56 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:11:57 INFO - PROCESS | 1792 | RSS: Main: 314818560 16:11:57 INFO - PROCESS | 1792 | 16:12:00 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:12:01 INFO - PROCESS | 1792 | RSS: Main: 316669952 16:12:01 INFO - PROCESS | 1792 | 16:12:02 INFO - PROCESS | 1792 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 16:12:03 INFO - PROCESS | 1792 | RSS: Main: 360583168 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | __start_tp_report 16:12:03 INFO - PROCESS | 1792 | _x_x_mozilla_page_load 16:12:03 INFO - PROCESS | 1792 | _x_x_mozilla_page_load_details 16:12:03 INFO - PROCESS | 1792 | |i|pagename|runs| 16:12:03 INFO - PROCESS | 1792 | |0;ai-astar;112;114;116;114;112;114;113;113;115;123 16:12:03 INFO - PROCESS | 1792 | |1;audio-beat-detection;149;114;128;161;109;128;145;107;161;104 16:12:03 INFO - PROCESS | 1792 | |2;audio-dft;187;186;184;184;190;185;182;188;178;185 16:12:03 INFO - PROCESS | 1792 | |3;audio-fft;74;76;80;156;58;58;58;66;144;57 16:12:03 INFO - PROCESS | 1792 | |4;audio-oscillator;82;84;131;69;71;78;120;67;70;116 16:12:03 INFO - PROCESS | 1792 | |5;imaging-gaussian-blur;87;85;88;85;88;84;88;84;87;83 16:12:03 INFO - PROCESS | 1792 | |6;imaging-darkroom;93;93;95;94;94;93;93;94;93;94 16:12:03 INFO - PROCESS | 1792 | |7;imaging-desaturate;85;85;85;84;83;83;85;86;83;87 16:12:03 INFO - PROCESS | 1792 | |8;json-parse-financial;56;56;58;56;54;54;56;55;55;54 16:12:03 INFO - PROCESS | 1792 | |9;json-stringify-tinderbox;66;69;78;76;73;69;70;71;71;71 16:12:03 INFO - PROCESS | 1792 | |10;stanford-crypto-aes;89;85;88;92;94;107;104;90;91;90 16:12:03 INFO - PROCESS | 1792 | |11;stanford-crypto-ccm;139;141;148;149;152;136;140;149;249;139 16:12:03 INFO - PROCESS | 1792 | |12;stanford-crypto-pbkdf2;171;169;168;168;172;171;219;184;177;228 16:12:03 INFO - PROCESS | 1792 | |13;stanford-crypto-sha256-iterative;66;67;67;67;70;83;73;67;68;67 16:12:03 INFO - PROCESS | 1792 | __end_tp_report 16:12:03 INFO - PROCESS | 1792 | __start_cc_report 16:12:03 INFO - PROCESS | 1792 | _x_x_mozilla_cycle_collect,2054 16:12:03 INFO - PROCESS | 1792 | __end_cc_report 16:12:03 INFO - PROCESS | 1792 | __startTimestamp1472857923145__endTimestamp 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | ------- Summary: start ------- 16:12:03 INFO - PROCESS | 1792 | Number of tests: 14 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#0] ai-astar Cycles:10 Average:114.60 Median:114.00 stddev:3.20 (2.8%) stddev-sans-first:3.26 16:12:03 INFO - PROCESS | 1792 | Values: 112.0 114.0 116.0 114.0 112.0 114.0 113.0 113.0 115.0 123.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:136.50 stddev:22.15 (16.2%) stddev-sans-first:22.47 16:12:03 INFO - PROCESS | 1792 | Values: 149.0 114.0 128.0 161.0 109.0 128.0 145.0 107.0 161.0 104.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:3.31 (1.8%) stddev-sans-first:3.43 16:12:03 INFO - PROCESS | 1792 | Values: 187.0 186.0 184.0 184.0 190.0 185.0 182.0 188.0 178.0 185.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#3] audio-fft Cycles:10 Average:82.70 Median:62.00 stddev:36.56 (59.0%) stddev-sans-first:38.64 16:12:03 INFO - PROCESS | 1792 | Values: 74.0 76.0 80.0 156.0 58.0 58.0 58.0 66.0 144.0 57.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:70.50 stddev:24.08 (34.2%) stddev-sans-first:25.41 16:12:03 INFO - PROCESS | 1792 | Values: 82.0 84.0 131.0 69.0 71.0 78.0 120.0 67.0 70.0 116.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:1.91 (2.2%) stddev-sans-first:1.99 16:12:03 INFO - PROCESS | 1792 | Values: 87.0 85.0 88.0 85.0 88.0 84.0 88.0 84.0 87.0 83.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 16:12:03 INFO - PROCESS | 1792 | Values: 93.0 93.0 95.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.35 (1.6%) stddev-sans-first:1.42 16:12:03 INFO - PROCESS | 1792 | Values: 85.0 85.0 85.0 84.0 83.0 83.0 85.0 86.0 83.0 87.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#8] json-parse-financial Cycles:10 Average:55.40 Median:56.00 stddev:1.26 (2.3%) stddev-sans-first:1.32 16:12:03 INFO - PROCESS | 1792 | Values: 56.0 56.0 58.0 56.0 54.0 54.0 56.0 55.0 55.0 54.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:71.00 stddev:3.50 (4.9%) stddev-sans-first:3.12 16:12:03 INFO - PROCESS | 1792 | Values: 66.0 69.0 78.0 76.0 73.0 69.0 70.0 71.0 71.0 71.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#10] stanford-crypto-aes Cycles:10 Average:93.00 Median:90.00 stddev:7.04 (7.8%) stddev-sans-first:7.32 16:12:03 INFO - PROCESS | 1792 | Values: 89.0 85.0 88.0 92.0 94.0 107.0 104.0 90.0 91.0 90.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:148.50 stddev:33.75 (22.7%) stddev-sans-first:35.35 16:12:03 INFO - PROCESS | 1792 | Values: 139.0 141.0 148.0 149.0 152.0 136.0 140.0 149.0 249.0 139.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.70 Median:174.50 stddev:22.14 (12.7%) stddev-sans-first:23.08 16:12:03 INFO - PROCESS | 1792 | Values: 171.0 169.0 168.0 168.0 172.0 171.0 219.0 184.0 177.0 228.0 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - PROCESS | 1792 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:67.50 stddev:5.17 (7.7%) stddev-sans-first:5.33 16:12:03 INFO - PROCESS | 1792 | Values: 66.0 67.0 67.0 67.0 70.0 83.0 73.0 67.0 68.0 67.0 16:12:03 INFO - PROCESS | 1792 | -------- Summary: end -------- 16:12:03 INFO - PROCESS | 1792 | 16:12:03 INFO - TEST-INFO | 1792: exit 0 16:12:03 INFO - TEST-OK | kraken | took 59540ms 16:12:03 INFO - SUITE-END | took 1177s 16:12:04 INFO - Completed test suite (00:19:37) 16:12:04 INFO - javascript benchmark 16:12:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2602.9088981885634, 3220.8389580520975, 3348.045806945021, 3396.84507267529, 3430.2624935638833, 5573.136163894603, 5662.8301150965435, 5677.772889084432, 5773.134029895517, 5822.446867547582, 5676.120201368794, 5720.685362305072, 5725.312962444513, 5728.112142617891, 5751.482366587006, 5169.508896654812, 5200.479952004788, 5232.7383630818495, 5250.0, 5288.629795914288, 5286.418493965661, 5288.418273989877, 5302.3106996090455, 5304.469553044703, 5314.92027619586, 5183.611229157809, 5234.345706786652, 5254.080535906221, 5260.079486089919, 5298.788048478056, 5328.520433161034, 5336.119540275849, 5345.33183352081, 5353.384360798519, 5373.704446255454, 5084.923726144111, 5097.133487307166, 5105.540501354859, 5245.082110630625, 5259.47405259471, 4695.929561056552, 5704.771809127671, 5711.086226203787, 5727.427257274281, 5792.218050563204, 6221.906671399933, 6227.844303892393, 6237.656928868911, 6239.344868788802, 6253.562250642457, 5274.0, 6162.784302549389, 6192.0, 6246.875062498724, 6270.278917924452, 5884.205632239678, 5894.08641660539, 5901.40985901415, 5912.320083190402, 5923.200367950358, 6291.937080629226, 6296.748130074838, 6313.084602732578, 6313.273973493016, 6322.146510221153, 5420.972895135499, 6020.518358531307, 6101.450869421773, 6129.754809807558, 6201.534884883562, 6177.351378105324, 6248.937510624926, 6264.498840092781, 6264.68676566179, 6273.811785646438, 6180.907530829715, 6218.098375735583, 6241.96879015602, 6268.0, 6280.214973128359, 6046.848828779316, 6132.110843927606, 6144.2488890665945, 6247.718852651642, 6263.0, 5012.972340670154, 5014.52362025607, 5020.3724534433195, 5021.6735912165595, 5031.496850315012, 4520.141173177086, 4542.750148741851, 4559.840405585788, 4572.634189264851, 4573.588377046081, 5710.114932185518, 5740.1102829061565, 5793.971030144822, 5855.092460668603, 5866.149408335853, 6160.0, 6240.095186197976, 6255.812325630239, 6262.342454042351, 6267.561270711098, 5790.0, 5793.797217097465, 5798.246227990334, 5800.984827655144, 5864.179014937912, 5320.973395133, 5396.784128634889, 5444.673319600836, 5461.344638643388, 5474.644148130359, 6251.343608920998, 6375.330590287953, 6376.075469056961, 6378.649174295366, 6393.360663933756, 6211.254649442096, 6220.72006759706, 6227.19046523949, 6231.470325022424, 6242.687865606792, 6187.257529096537, 6214.40963108522, 6345.23857137128, 6358.141650877073, 6364.904526432199, 6127.693615319127, 6133.701927635063, 6142.877142457037, 6153.907691384543, 6161.106639537332, 6210.223722034746, 6236.688165591793, 6243.344448832807, 6248.500119990299, 6270.7805226816845, 6103.84740381494, 6109.969450152898, 6136.23297087864, 6148.200733904564, 6149.323574406722, 5976.013957697105, 6041.305249896318, 6162.198914141133, 6174.629522228681, 6191.597546159485, 4623.052274283831, 5967.373425790403, 6017.729202185825, 6039.063945088518, 6073.665948372882, 5081.44104148536, 6162.044883043135, 6184.0, 6184.041473571604, 6189.0, 5759.56803239767, 5954.46409823118, 6012.609180403259, 6024.789132380346, 6031.276246850406], "value": 5700.546188377572, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.1814442458793, 503.62479952435433, 530.9089820419038, 532.233583639559, 552.7954656776993, 30680.846595767045, 30774.38451230974, 32539.186520336974, 40253.396199056966, 41531.79234103825, 43542.34686479705, 43842.90392740183, 43886.78056609721, 43947.78026109873, 43969.560304396946, 9827.656032038862, 10006.449645269508, 10008.99910008997, 10017.699469015904, 10035.69892903214, 8789.428687135347, 8997.820043599124, 9757.121859032686, 11906.702332441671, 12170.513179472811, 12091.09316801239, 12105.0, 12119.515219391214, 12142.635720928387, 12152.817707734392, 12265.0, 12340.259584424874, 12344.321062341567, 12357.876421235762, 12358.69103272421, 11984.221025633393, 12079.57721479744, 12083.093767967437, 12098.395080245953, 12114.394280285951, 40494.0, 40753.796231018954, 40799.388009179886, 40835.795821021005, 40900.79549602263, 2933.779966502521, 3289.5723555937575, 3387.322535492887, 3538.663826936437, 3730.5336832895887, 30435.391292174034, 30859.919902803293, 31276.374472510422, 31383.686163138304, 31441.899533516218, 3127.0, 3195.536647186168, 3263.233140212048, 3319.2365755876285, 3320.7841490303053, 485717.0, 488047.0, 490243.0, 490432.0, 498343.0, 59208.0, 59479.0, 59492.70253648704, 59497.10754338688, 59551.174056281096, 62348.0, 62356.68821655863, 62446.0633090504, 62479.37520624827, 62525.06212406817, 16276.10481423533, 16432.75350869738, 16495.34018639241, 16497.0, 16508.25712842925, 11431.428428578538, 12816.55142070023, 12921.28932908699, 12928.806067908989, 12942.935285323701, 12749.298788566532, 14353.91868179129, 15177.24113794306, 16403.42588009414, 16868.915655421646, 6975.82560435983, 7265.600391978492, 7279.088364654181, 7332.120145582458, 7370.226126256773, 808.6078252047752, 948.4168277092288, 1002.9949850250848, 1006.6476733143342, 1019.8929112443235, 25092.19575401169, 25116.874415628172, 27955.860220699175, 28176.0, 30338.54492182575, 968.801395713877, 1023.467796745688, 1053.952572134256, 1082.2802836114054, 1192.9642110736693, 11879.346635934933, 14745.41018359275, 15497.147656878755, 15531.378744850079, 15984.440544580884, 654844.0, 658845.0, 662529.0, 663089.0, 665038.0, 48558.0, 49334.506654933706, 55238.72380638071, 56543.0, 57803.0, 1336.4787732784223, 1359.9796003059962, 1377.3526442571974, 1382.1223523062727, 1383.667919699285, 30069.097927062223, 30941.69058309388, 31335.076711974878, 31478.213044674067, 31778.88773892903, 50490.24264636107, 50835.49164508308, 50878.0, 51130.0, 51230.487695124066, 591305.0, 596805.0, 597306.0, 844254.2383897437, 844753.0, 22674.319770406913, 23953.16163934254, 25381.74618253794, 25447.36381590401, 25450.109246176296, 45206.32190517079, 45788.77105614584, 46370.53629463662, 47095.52904470912, 49614.50385496243, 38123.23753524858, 39202.01994950149, 39441.25308095726, 40713.0, 41173.79413103035, 29035.98374056898, 32168.678313217504, 37053.888383348545, 37408.81295593505, 37481.6251837489, 37470.43794343142, 38096.0, 38689.0, 38837.61162388453, 39019.6098039016, 34141.0, 34528.48747509753, 34996.77511287093, 35014.64985350114, 35037.0, 25131.994720210987, 25531.9787208517, 25627.743722562536, 25765.742342576334, 25858.870705647103], "value": 20221.902416600617, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [325.20811823210477, 346.5979463821967, 373.08035692019166, 377.21915634636304, 402.0, 14598.416063357467, 14606.488772892937, 15863.682726345467, 16611.75082373764, 19864.602707945833, 11342.0, 18793.43619691408, 19636.509087272825, 19914.20343186271, 19926.800731992676, 5225.6864588124645, 5507.642003269794, 5529.281193444846, 5542.307211598551, 5546.750396232179, 5259.421463639016, 5436.3476382834115, 5500.119980803072, 5512.476314750113, 5560.86097847553, 5484.478974497416, 5504.146857237115, 5509.311336082989, 5509.531689806351, 5511.22842802008, 4600.332951721982, 5070.543651071384, 5174.922376164361, 5278.366596008465, 5495.697736624484, 5307.6019298552765, 5329.680219186861, 5331.067063263913, 5341.332333458317, 5350.491703288181, 12970.481180752759, 13094.541691040768, 13146.671333216746, 13350.399232034582, 13417.396217170251, 2143.699882016519, 2190.288156349193, 2311.791938725523, 2463.8275320727557, 2587.508373409065, 10070.748231294203, 10164.44095574743, 10293.3823970562, 12357.69105772354, 15058.849411505855, 1824.7627808384957, 1838.9448316550524, 1855.239351865742, 1890.3761758619623, 1911.2928216559962, 97670.0, 100566.0, 117097.41451292689, 123375.38312308471, 124861.0, 10883.945580272206, 11203.663890083311, 11232.663020109247, 11244.775104497865, 11405.429728513542, 14468.348924298432, 14470.348834302482, 14475.710485790225, 14497.855021449861, 14514.056586322067, 8165.38759593021, 8402.411831171881, 8457.830843383097, 8492.320614350838, 8541.487510749375, 8289.58552072394, 8295.294899933573, 8324.084350721416, 8508.957455212809, 8519.659213631385, 4448.510663826976, 4476.4180656514445, 4478.305862591304, 4543.432070991126, 4560.954390456053, 1869.1028306412998, 1918.788933217345, 1919.2131226197191, 1921.5292253398006, 1925.0759635375098, 701.7126546146483, 763.8128658478709, 808.5593351623379, 925.546482223719, 1011.7318910488726, 20478.078486468145, 20933.267335643483, 20942.47643808917, 21022.0, 21478.892605536872, 893.4817805672694, 905.8097799462055, 946.8011717539393, 956.1872408452759, 982.5824024789435, 9419.434833909987, 10795.6221532246, 11135.443227838738, 11143.387113708824, 11282.379469129113, 546807.0, 563359.0, 587589.0, 590457.0, 591901.0, 95909.0, 103613.0, 105673.9432605664, 122261.0, 126118.0, 871.7341210930728, 917.7522069041321, 929.5166513413122, 936.2463217110292, 973.9902600974041, 18986.810131899056, 19076.713849291995, 19192.808071919102, 19203.423897283104, 19306.71039934374, 50337.74831125821, 51263.743681281354, 54071.72964135154, 55950.60123496945, 57163.85672286614, 72611.99583271278, 74355.19270476521, 74928.1798109599, 75734.62132689302, 75786.62106689431, 7094.290570943071, 7339.8532029360185, 7848.370795783477, 8037.598120094089, 8251.83496330058, 10981.286216395909, 12984.870151298366, 13021.218726876417, 13417.262050587311, 13502.797458037941, 10684.412357320422, 11112.444377781241, 11626.651200463999, 11758.412079395825, 11763.705907352385, 11031.558737650405, 11167.88832111701, 11379.829302560634, 11596.536138554364, 11641.53433862636, 9475.47884866339, 10215.927327630492, 10229.641962531276, 11390.487428065591, 11793.587224447103, 7233.710651573878, 7469.663865125974, 7486.326230639269, 7565.546067235983, 7707.267809558083, 9680.951595241979, 10426.947865260625, 10733.677989660322, 11016.614418495314, 11268.0], "value": 9080.60137182503, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1406.1211742660835, 1548.047950510436, 1726.1196789637288, 1815.3011090730065, 1901.2870173684869, 8338.24955753982, 8890.06654301298, 8906.33202509811, 8907.599158037887, 8916.152965468278, 7740.92259077409, 7961.044674639052, 8360.91639083609, 8562.058173600915, 8693.478391296527, 2350.0482304666593, 2380.976190238093, 2387.080973825081, 2392.0670938334065, 2396.8322217444784, 2345.226075395124, 2374.6319320505263, 2385.403649087728, 2389.5340408620327, 2396.065534441569, 2361.2562042956415, 2361.3624321433203, 2369.052379048386, 2388.10446082719, 2392.83250172488, 2335.509542995973, 2356.493353928905, 2368.265837590335, 2370.2059809963685, 2375.0143690368564, 2338.8245881558782, 2357.481354102095, 2357.6934998450092, 2360.1031607989025, 2365.5741966446035, 8627.784305392353, 8708.608112634884, 8761.474311541328, 8804.955975220148, 8835.77910552235, 5141.611764823514, 5317.973410132925, 5318.202269659543, 5320.0, 5324.520793128599, 3567.7859328440377, 3591.371509985768, 3660.68884144848, 3668.5047518584915, 3672.137047793766, 1015.228426395937, 1056.9471526423647, 1067.7223921780394, 1080.783843231349, 1091.9071878890304, 11577.826332605018, 11658.825117623242, 11680.0655947524, 11684.591039313667, 11715.824262636068, 4480.798364073625, 4543.704659197141, 4736.881577960578, 4745.738984355894, 4748.976255118702, 9831.80336393268, 9841.212702983745, 9841.50792460374, 9850.965648606936, 9852.80294394108, 5272.103742363807, 5272.785430119359, 5277.709725965032, 5280.128778751463, 5280.656757310762, 4051.0075031617444, 4093.3655283431117, 4181.51912530063, 4686.671932964728, 4833.10587541306, 3341.8329083545727, 3775.811209439517, 3788.3749181384765, 3935.8819235422984, 3960.5049368828895, 1247.5820600099069, 1265.474827946392, 1267.2459886367596, 1272.8281681973, 1276.3873340796283, 822.1337563730299, 822.144969232004, 886.8315020146151, 889.2663552569156, 931.9254459643212, 5323.3079699639575, 5376.569874410038, 5590.301212348457, 5659.462351076641, 5708.828735137869, 744.9925500745031, 905.42053086025, 943.6791490893129, 953.3279038277998, 970.369259981018, 3524.6827785499427, 3647.544056992876, 3647.5805282392867, 3676.8161591920298, 3773.113318370181, 10461.68614941553, 10734.355938643706, 10789.83815242772, 10790.460476976119, 10797.974192451704, 10736.624218152327, 10737.033666969854, 10745.086667633339, 10751.301165424222, 10758.73103172427, 8123.7156699515235, 8254.380921430917, 8262.339012878956, 8264.297534709496, 8272.958635206785, 5577.860553486195, 5794.0, 5942.851428714317, 5969.671668058299, 6073.119397687399, 8170.673173073011, 8876.556172191236, 9987.051230133126, 10031.899681003097, 10047.145992590711, 9777.071178238057, 10170.491475426346, 10208.0, 10208.846867297145, 10268.845967310346, 5694.658320500783, 7047.541909775849, 7338.412926965937, 7483.476156669199, 7483.550986940801, 7742.8451430969935, 7786.649600767866, 7806.765797026098, 8124.959375203086, 8169.714060007871, 7221.819454513469, 7334.816629584303, 7375.889361659472, 7393.112826460859, 7477.962610186914, 7190.352868241884, 7284.307990740871, 7296.306850849161, 7357.301056399633, 7385.29839665231, 6643.701033453603, 6839.179298484214, 6963.059986901906, 7021.789346319619, 7023.367896889305, 4479.910401792011, 4876.927076043265, 4960.7767650456535, 5018.774155162953, 5143.588512918883, 4279.780262625137, 4808.35087263215, 4871.610271178368, 5022.974885125551, 5206.479352064764], "value": 4523.163678463033, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1470963624762, 296.17662897145937, 305.77678294844765, 309.79952683352013, 314.0515642758867, 4946.318251794389, 5043.77303021364, 5300.522952934235, 5301.920471192931, 5356.598255130866, 5041.168207245809, 5152.74236288186, 5179.559737422314, 5230.267762513251, 5283.524482796547, 4003.699722520808, 4025.376066709665, 4034.4553485279407, 4038.474998250231, 4042.6967977401814, 3717.758345707534, 3734.757240779354, 3910.432987216852, 4034.9394759078637, 4057.472528571296, 3338.3990881641294, 4002.2595819773437, 4020.1557672888584, 4025.838966441339, 4029.3351596986463, 3617.878457678128, 3974.8671628585716, 4031.8992025199314, 4037.9596204037875, 4045.02919299369, 2646.6691663542056, 3445.3109378124223, 3478.4956181353564, 3488.7557870949045, 3540.9822950885336, 4207.957920420788, 4896.828610998598, 5073.46728593496, 5076.213186956028, 5086.669366491166, 4223.66210703143, 4574.817007319703, 4597.264437689987, 4630.513796051399, 4653.58117769399, 4993.800247990076, 5061.190209566452, 5233.659812112239, 5365.946340536622, 5494.780208791643, 3391.2030672791875, 3728.7762734236035, 3858.459815625815, 3872.4384964180035, 3890.8443662253476, 4312.116016216731, 5101.336826212606, 5188.299579556749, 5431.782728690887, 5711.200431939532, 3446.7070299024363, 3618.9276214475562, 3920.2355540669864, 3936.645701886787, 3938.547067087264, 3355.9293246679017, 3676.816159192083, 3969.479380593344, 4009.3785463253244, 4015.9397609035886, 3634.60019397866, 3678.8043885737243, 4592.288195329729, 4594.609458196024, 4615.884602884955, 4395.692301538862, 5186.481351864783, 5602.887942241132, 5634.549236061105, 5640.830775076754, 1272.0014788391254, 1286.897048236139, 1319.957233785299, 1324.6423465664216, 1349.1702602899147, 853.6713365354259, 901.2024358442825, 938.6573900526396, 939.8815409662479, 946.711253067821, 4375.78121093944, 5126.436092029874, 5135.563477104488, 5150.913879878655, 5211.192265198824, 5649.209110724501, 5680.460356266148, 5704.0, 5706.400827913092, 5745.569082318759, 4859.785053736566, 5063.620228482879, 5111.7699703513435, 5130.30740849988, 5187.533122019036, 2499.925002249899, 2516.169664010872, 2522.8612426316727, 2531.6455696202547, 2543.5073627844818, 4187.664986801049, 4343.67422443318, 4691.249400095969, 4887.65786394956, 4986.900261994812, 4224.429701990253, 4264.505264420287, 4515.503294637587, 4797.688150270221, 4894.730789806595, 4330.826766929287, 4362.585554372329, 4777.856664300077, 4867.809485731435, 4954.200876504908, 4088.366303223005, 4226.725262857904, 4356.499002614613, 4693.577578017995, 4713.892235324627, 4112.442737032505, 4190.937135942902, 4239.59723826236, 4266.2747332954095, 4437.667174961955, 4424.579664931826, 4570.926271838191, 4890.33980412654, 4918.680285781413, 4959.032988567339, 2970.1535062507082, 3178.3166619176986, 3369.83150842452, 3392.235328207503, 3404.8253352593315, 4236.279832428556, 4555.9316610251535, 4617.3146801417915, 4742.976285118552, 5473.057064741959, 5010.949890501194, 5469.179623056573, 5569.888602228014, 5587.776488940397, 5613.887722245614, 4879.609631229422, 5586.245856809279, 5604.439556044363, 5605.439456054525, 5615.6630602164005, 4828.782704778364, 5235.769594145297, 5483.479069488392, 5493.450654934634, 5493.72010615606, 4510.985028368722, 5579.81950288348, 5583.190437386637, 5626.690532020778, 5640.589852536866, 5399.4060653327315, 5473.972630136983, 5479.726013699379, 5498.972505137449, 5538.427714124447], "value": 3857.557441546231, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.8509935911757, 735.6652723011031, 763.427429427929, 775.2054144501885, 794.3248238996857, 13886.722265554696, 14094.295285235736, 14715.779263311046, 14748.33632486538, 14761.48334808282, 13270.402831872565, 13299.800502992462, 13371.866281337183, 13381.063325567215, 13680.384382702778, 835.6448509383519, 859.2224037246302, 859.4198915731856, 861.9698310559131, 863.6631713631689, 683.1699485125572, 704.260526447231, 831.5343407691681, 858.5964596639569, 860.1699360117485, 889.4352086425102, 889.7775556110972, 891.61660485991, 892.8392889279927, 892.8705337726026, 884.4030279561271, 884.9026607073217, 885.4819930340758, 886.0120965123873, 887.995560022199, 525.1649876696072, 538.0503411478743, 541.093668105921, 542.8669975855901, 663.0121119531863, 13573.932130339284, 14061.0, 14101.71796564063, 14256.144631322153, 14302.427902883872, 13348.933255333759, 13680.65798355039, 13783.379747911367, 13878.306084695725, 14308.570742877626, 11115.72210694731, 11398.60104896333, 11400.201985860911, 11452.370119643416, 11908.285502869856, 3448.81031543265, 3508.1755787389943, 3540.4689296605457, 3541.1501239702543, 3549.94675079874, 112958.0, 117706.0, 118696.0, 119859.0, 120269.99999999913, 8887.955560222157, 10455.529501172465, 10613.469326533643, 10623.415712135908, 11139.0, 19114.426567203005, 19790.90104549468, 21282.148714051265, 21417.57164856694, 21427.78572214289, 7223.494355395072, 7244.420446364278, 7258.600776957318, 7264.854702905912, 7488.737894173678, 3920.2355540669296, 3922.9607703923166, 4024.5170579530645, 4122.154958233555, 4147.212029714345, 3268.6404495505476, 3379.881704140343, 3389.9491507627404, 3406.625271220164, 3408.335374601929, 560.201712559601, 649.4317472211815, 655.9967200164064, 676.5568552598056, 677.2482544375739, 558.5531574740191, 645.4642646603309, 653.4478365780908, 664.6112024465742, 664.8138521214067, 8160.714374996846, 8535.402521823413, 8780.473171609723, 8922.553872306358, 9052.773680657905, 629.235478893147, 646.2789323988311, 649.1009951217598, 652.2201713191444, 659.7361055577711, 4319.697621166488, 4437.400950871591, 4444.0, 4447.533009034069, 4578.404807375086, 103775.0, 104576.43135352976, 161234.19382903245, 165985.0, 168304.0, 109892.0, 132248.0, 133169.0, 134356.0, 139205.3039734795, 97783.0, 116179.0, 116201.0, 117560.0, 119946.0, 6912.688928998307, 8234.505929644241, 8320.50076995358, 8785.780355491164, 8850.33622478304, 64164.679176603815, 64796.02805958009, 65438.01842972264, 65880.67059664671, 67364.65270694657, 57857.0, 71186.6440667814, 75198.6240068818, 75290.0, 95621.5218923901, 12600.369981501071, 12753.936230318788, 12786.0, 12808.871911280768, 13006.284654344103, 13982.58052258434, 14541.272936352929, 14877.32208202534, 14918.552443426715, 15214.847851521787, 13455.730885382041, 13565.186088834702, 13865.4453821846, 14273.0, 14365.7845132321, 11414.45904802841, 13604.126691420091, 13902.721945561234, 14018.0, 14345.64135896611, 12868.420921058761, 13196.868031319947, 13303.268320242092, 13335.66660833448, 13337.53318633843, 10286.588536458457, 10733.892661073289, 10785.0, 10989.835152472879, 11622.418879056182, 11061.336319820837, 11150.158315149058, 11201.775964480501, 11349.262297950607, 11373.829392559284], "value": 7550.908273737554, "unit": "score"}], "name": "dromaeo_css", "value": 7188.021109555892, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 116.0, 114.0, 112.0, 114.0, 113.0, 113.0, 115.0, 123.0], "value": 114.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 128.0, 161.0, 109.0, 128.0, 145.0, 107.0, 161.0, 104.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 186.0, 184.0, 184.0, 190.0, 185.0, 182.0, 188.0, 178.0, 185.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 80.0, 156.0, 58.0, 58.0, 58.0, 66.0, 144.0, 57.0], "value": 82.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 131.0, 69.0, 71.0, 78.0, 120.0, 67.0, 70.0, 116.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 84.0, 87.0, 83.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 95.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 85.0, 84.0, 83.0, 83.0, 85.0, 86.0, 83.0, 87.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 58.0, 56.0, 54.0, 54.0, 56.0, 55.0, 55.0, 54.0], "value": 55.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 78.0, 76.0, 73.0, 69.0, 70.0, 71.0, 71.0, 71.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 92.0, 94.0, 107.0, 104.0, 90.0, 91.0, 90.0], "value": 93.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 148.0, 149.0, 152.0, 136.0, 140.0, 149.0, 249.0, 139.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 168.0, 168.0, 172.0, 171.0, 219.0, 184.0, 177.0, 228.0], "value": 182.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 67.0, 67.0, 70.0, 83.0, 73.0, 67.0, 68.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1491.9, "alertThreshold": 2.0}]} 16:12:04 INFO - Return code: 0 16:12:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:12:04 INFO - # TBPL SUCCESS # 16:12:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:12:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:12:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:12:04 INFO - 2016-09-02 16:12:04.455 screenresolution[1802:10022] starting screenresolution argv=screenresolution get 16:12:04 INFO - 2016-09-02 16:12:04.458 screenresolution[1802:10022] Display 0: 1600x1200x32@60 16:12:04 INFO - 2016-09-02 16:12:04.464 screenresolution[1803:10025] starting screenresolution argv=screenresolution list 16:12:04 INFO - Available Modes on Display 0 16:12:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:12:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:12:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:12:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:12:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:12:04 INFO - Intel Iris: 16:12:04 INFO - Chipset Model: Intel Iris 16:12:04 INFO - Type: GPU 16:12:04 INFO - Bus: Built-In 16:12:04 INFO - VRAM (Dynamic, Max): 1536 MB 16:12:04 INFO - Vendor: Intel (0x8086) 16:12:04 INFO - Device ID: 0x0a2e 16:12:04 INFO - Revision ID: 0x0009 16:12:04 INFO - Displays: 16:12:04 INFO - Display: 16:12:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:12:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:12:04 INFO - Display Serial Number: 1600x1200 60 16:12:04 INFO - Main Display: Yes 16:12:04 INFO - Mirror: Off 16:12:04 INFO - Online: Yes 16:12:04 INFO - Rotation: Supported 16:12:04 INFO - Return code: 0 16:12:04 INFO - Running post-action listener: _resource_record_post_action 16:12:04 INFO - [mozharness: 2016-09-02 23:12:04.700110Z] Finished run-tests step (success) 16:12:04 INFO - Running post-run listener: _resource_record_post_run 16:12:04 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37368832; Write bytes: 707109376; Read time: 235; Write time: 2094 16:12:04 INFO - TinderboxPrint: CPU usage
53.6% 16:12:04 INFO - TinderboxPrint: I/O read bytes / time
37,368,832 / 235 16:12:04 INFO - TinderboxPrint: I/O write bytes / time
707,109,376 / 2,094 16:12:04 INFO - TinderboxPrint: CPU idle
2,251.3 (46.4%) 16:12:04 INFO - TinderboxPrint: CPU system
968.9 (20.0%) 16:12:04 INFO - TinderboxPrint: CPU user
1,629.4 (33.6%) 16:12:04 INFO - TinderboxPrint: Swap in / out
442,920,960 / 0 16:12:04 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 400292352; Write bytes: 392128000; Read time: 29494; Write time: 870 16:12:04 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35263488; Write bytes: 298101760; Read time: 215; Write time: 1180 16:12:05 INFO - Running post-run listener: _upload_blobber_files 16:12:05 INFO - Blob upload gear active. 16:12:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:12:05 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 16:12:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:12:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:12:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:06 INFO - (blobuploader) - INFO - Done attempting. 16:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:08 INFO - (blobuploader) - INFO - Done attempting. 16:12:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:12:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:12:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:12:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:12:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:12:12 INFO - (blobuploader) - INFO - Done attempting. 16:12:12 INFO - (blobuploader) - INFO - Iteration through files over. 16:12:12 INFO - Return code: 0 16:12:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:12:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:12:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1c200d37aa26e83bcc4540b8299f49a6fb614cb5ac6ee1998f78d71c60d7bba33877bf6da33011eadb9bbdf2060950fc9997cab5808099734a838bbd5c6ec1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a148e7beee2f5feb51a061062674fa20633168c960382813f8501fbe6e44618d0cf1d4ab1a2dde9f1249ebcb753e4e9a7abe34f0d0f7898dc73b5824ed37fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa430d8a7c3fc85a60ef6a6b629ac281bd9dfa5050f7ca1d4f51e0c295bb70df524b8ed09a1bd012ed3a4b4536f8c73c7d960358a0efa104e9fe11ff67c7e60"} 16:12:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:12:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:12:12 INFO - Contents: 16:12:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1c200d37aa26e83bcc4540b8299f49a6fb614cb5ac6ee1998f78d71c60d7bba33877bf6da33011eadb9bbdf2060950fc9997cab5808099734a838bbd5c6ec1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a148e7beee2f5feb51a061062674fa20633168c960382813f8501fbe6e44618d0cf1d4ab1a2dde9f1249ebcb753e4e9a7abe34f0d0f7898dc73b5824ed37fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa430d8a7c3fc85a60ef6a6b629ac281bd9dfa5050f7ca1d4f51e0c295bb70df524b8ed09a1bd012ed3a4b4536f8c73c7d960358a0efa104e9fe11ff67c7e60"} 16:12:12 INFO - Running post-run listener: copy_logs_to_upload_dir 16:12:12 INFO - Copying logs to upload dir... 16:12:12 INFO - mkdir: /builds/slave/test/build/upload/logs 16:12:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.922368 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-02 16:12:12.591998) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 16:12:12.595463) ========= 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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1c200d37aa26e83bcc4540b8299f49a6fb614cb5ac6ee1998f78d71c60d7bba33877bf6da33011eadb9bbdf2060950fc9997cab5808099734a838bbd5c6ec1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a148e7beee2f5feb51a061062674fa20633168c960382813f8501fbe6e44618d0cf1d4ab1a2dde9f1249ebcb753e4e9a7abe34f0d0f7898dc73b5824ed37fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa430d8a7c3fc85a60ef6a6b629ac281bd9dfa5050f7ca1d4f51e0c295bb70df524b8ed09a1bd012ed3a4b4536f8c73c7d960358a0efa104e9fe11ff67c7e60"} build_url:https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011308 build_url: 'https://queue.taskcluster.net/v1/task/L8LmHO6mSFGTmFh1kM0wRA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1c200d37aa26e83bcc4540b8299f49a6fb614cb5ac6ee1998f78d71c60d7bba33877bf6da33011eadb9bbdf2060950fc9997cab5808099734a838bbd5c6ec1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/20a148e7beee2f5feb51a061062674fa20633168c960382813f8501fbe6e44618d0cf1d4ab1a2dde9f1249ebcb753e4e9a7abe34f0d0f7898dc73b5824ed37fb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ffa430d8a7c3fc85a60ef6a6b629ac281bd9dfa5050f7ca1d4f51e0c295bb70df524b8ed09a1bd012ed3a4b4536f8c73c7d960358a0efa104e9fe11ff67c7e60"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-02 16:12:12.630045) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 16:12:12.630493) ========= 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.5NkA6ncgEZ/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.bykrBWPWsB/Listeners TMPDIR=/var/folders/w3/rmzpg_rj3zjdrsx94grbz4kr00000w/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.004821 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-02 16:12:12.679057) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 16:12:12.679421) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-02 16:12:12.679772) ========= ========= Total master_lag: 0.18 =========