builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0053 starttime: 1472214704.29 results: success (0) buildid: 20160826030226 builduid: ad5b43b2d4084ef8afc7f1f3c6c73519 revision: a551f534773cf2d6933f78ce7d82a7a33a99643e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:44.291983) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:44.292624) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:44.292907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:44.318094) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 05:31:44.318506) ========= 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.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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-08-26 05:31:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-08-26 05:31:45 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.462944 ========= master_lag: 0.77 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 05:31:45.551786) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:45.552121) ========= 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.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.028820 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:45.602006) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 05:31:45.602363) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a551f534773cf2d6933f78ce7d82a7a33a99643e --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 a551f534773cf2d6933f78ce7d82a7a33a99643e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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-08-26 05:31:45,674 truncating revision to first 12 chars 2016-08-26 05:31:45,674 Setting DEBUG logging. 2016-08-26 05:31:45,674 attempt 1/10 2016-08-26 05:31:45,674 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a551f534773c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 05:31:46,558 unpacking tar archive at: mozilla-central-a551f534773c/testing/mozharness/ program finished with exit code 0 elapsedTime=1.129482 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 05:31:46.754323) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:46.754677) ========= script_repo_revision: a551f534773cf2d6933f78ce7d82a7a33a99643e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:46.755118) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:46.755394) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 05:31:46.774654) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 05:31:46.775027) ========= /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.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:31:46 INFO - MultiFileLogger online at 20160826 05:31:46 in /builds/slave/test 05:31:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 05:31:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:31:46 INFO - {'append_to_log': False, 05:31:46 INFO - 'base_work_dir': '/builds/slave/test', 05:31:46 INFO - 'blob_upload_branch': 'Firefox', 05:31:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:31:46 INFO - 'branch': 'Firefox', 05:31:46 INFO - 'buildbot_json_path': 'buildprops.json', 05:31:46 INFO - 'config_files': ('talos/mac_config.py',), 05:31:46 INFO - 'default_actions': ('clobber', 05:31:46 INFO - 'read-buildbot-config', 05:31:46 INFO - 'download-and-extract', 05:31:46 INFO - 'populate-webroot', 05:31:46 INFO - 'create-virtualenv', 05:31:46 INFO - 'install', 05:31:46 INFO - 'run-tests'), 05:31:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:31:46 INFO - 'download_minidump_stackwalk': True, 05:31:46 INFO - 'download_symbols': 'ondemand', 05:31:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:31:46 INFO - 'tooltool.py': '/tools/tooltool.py', 05:31:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:31:46 INFO - '/tools/misc-python/virtualenv.py')}, 05:31:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:31:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:31:46 INFO - 'installer_path': 'installer.exe', 05:31:46 INFO - 'log_level': 'info', 05:31:46 INFO - 'log_name': 'talos', 05:31:46 INFO - 'log_to_console': True, 05:31:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:31:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:31:46 INFO - 'opt_config_files': (), 05:31:46 INFO - 'pip_index': False, 05:31:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:46 INFO - 'cmd': ('bash', 05:31:46 INFO - '-c', 05:31:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:46 INFO - 'enabled': True, 05:31:46 INFO - 'halt_on_failure': False, 05:31:46 INFO - 'name': 'check_screen_resolution'},), 05:31:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:31:46 INFO - 'cmd': ('bash', 05:31:46 INFO - '-c', 05:31:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:31:46 INFO - 'enabled': True, 05:31:46 INFO - 'halt_on_failure': False, 05:31:46 INFO - 'name': 'check_screen_resolution'},), 05:31:46 INFO - 'run_cmd_checks_enabled': True, 05:31:46 INFO - 'sps_profile': False, 05:31:46 INFO - 'sps_profile_interval': 0, 05:31:46 INFO - 'suite': 'dromaeojs', 05:31:46 INFO - 'system_bits': '32', 05:31:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:31:46 INFO - 'title': 't-yosemite-r7-0053', 05:31:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:31:46 INFO - 'use_talos_json': True, 05:31:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:31:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:31:46 INFO - 'work_dir': 'build'} 05:31:46 INFO - [mozharness: 2016-08-26 12:31:46.971688Z] Running clobber step. 05:31:46 INFO - Running pre-action listener: _resource_record_pre_action 05:31:46 INFO - Running main action method: clobber 05:31:46 INFO - rmtree: /builds/slave/test/build 05:31:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:31:49 INFO - Running post-action listener: _resource_record_post_action 05:31:49 INFO - [mozharness: 2016-08-26 12:31:49.038926Z] Finished clobber step (success) 05:31:49 INFO - [mozharness: 2016-08-26 12:31:49.039056Z] Running read-buildbot-config step. 05:31:49 INFO - Running pre-action listener: _resource_record_pre_action 05:31:49 INFO - Running main action method: read_buildbot_config 05:31:49 INFO - Using buildbot properties: 05:31:49 INFO - { 05:31:49 INFO - "project": "", 05:31:49 INFO - "product": "firefox", 05:31:49 INFO - "script_repo_revision": "production", 05:31:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 05:31:49 INFO - "repository": "", 05:31:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 05:31:49 INFO - "buildid": "20160826030226", 05:31:49 INFO - "stage_platform": "macosx64", 05:31:49 INFO - "basedir": "/builds/slave/test", 05:31:49 INFO - "nightly_build": "True", 05:31:49 INFO - "buildnumber": 14, 05:31:49 INFO - "slavename": "t-yosemite-r7-0053", 05:31:49 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 05:31:49 INFO - "platform": "yosemite_r7", 05:31:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:31:49 INFO - "branch": "mozilla-central", 05:31:49 INFO - "repo_path": "mozilla-central", 05:31:49 INFO - "revision": "a551f534773cf2d6933f78ce7d82a7a33a99643e", 05:31:49 INFO - "pgo_build": "False", 05:31:49 INFO - "builduid": "ad5b43b2d4084ef8afc7f1f3c6c73519", 05:31:49 INFO - "slavebuilddir": "test" 05:31:49 INFO - } 05:31:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:31:49 INFO - Running post-action listener: _resource_record_post_action 05:31:49 INFO - [mozharness: 2016-08-26 12:31:49.042408Z] Finished read-buildbot-config step (success) 05:31:49 INFO - [mozharness: 2016-08-26 12:31:49.042539Z] Running download-and-extract step. 05:31:49 INFO - Running pre-action listener: _resource_record_pre_action 05:31:49 INFO - Running main action method: download_and_extract 05:31:49 INFO - mkdir: /builds/slave/test/build/tests 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:49 INFO - trying https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:49 INFO - Downloaded 1199 bytes. 05:31:49 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:31:49 INFO - Using the following test package requirements: 05:31:49 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:49 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:31:49 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:31:49 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'jsshell-mac.zip'], 05:31:49 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:31:49 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:31:49 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:31:49 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:31:49 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:31:49 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:31:49 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:31:49 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:31:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:31:49 INFO - trying https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:31:49 INFO - Downloading https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:50 INFO - Downloaded 16365646 bytes. 05:31:50 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:31:57 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 05:31:57 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')]} 05:31:57 INFO - trying https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:31:57 INFO - Downloading https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:31:58 INFO - Downloaded 16365646 bytes. 05:31:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:05 INFO - trying https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:32:05 INFO - Downloading https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:32:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:32:06 INFO - Downloaded 14413981 bytes. 05:32:06 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:32:06 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')]} 05:32:06 INFO - trying https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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 05:32:09 INFO - Downloaded 111970339 bytes. 05:32:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:09 INFO - mkdir: /builds/slave/test/properties 05:32:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:32:09 INFO - Writing to file /builds/slave/test/properties/build_url 05:32:09 INFO - Contents: 05:32:09 INFO - build_url:https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:32:10 INFO - Running post-action listener: _resource_record_post_action 05:32:10 INFO - Running post-action listener: set_extra_try_arguments 05:32:10 INFO - [mozharness: 2016-08-26 12:32:10.404896Z] Finished download-and-extract step (success) 05:32:10 INFO - [mozharness: 2016-08-26 12:32:10.405024Z] Running populate-webroot step. 05:32:10 INFO - Running pre-action listener: _resource_record_pre_action 05:32:10 INFO - Running main action method: populate_webroot 05:32:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:32:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:32:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:32:10 INFO - '--tppagecycles', 05:32:10 INFO - '7'], 05:32:10 INFO - 'tests': ['tsvgm']}}, 05:32:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:32:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:32:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:32:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:32:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:32:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['damp', 'tps']}, 05:32:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'tests': ['damp', 'tps']}, 05:32:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['dromaeo_dom']}, 05:32:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:32:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['basic_compositor_video']}, 05:32:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:32:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'other-e10s': {'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['a11yr', 05:32:10 INFO - 'ts_paint', 05:32:10 INFO - 'tpaint', 05:32:10 INFO - 'sessionrestore', 05:32:10 INFO - 'sessionrestore_no_auto_restore', 05:32:10 INFO - 'tabpaint']}, 05:32:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['tsvgx', 05:32:10 INFO - 'tsvgr_opacity', 05:32:10 INFO - 'tart', 05:32:10 INFO - 'tscrollx', 05:32:10 INFO - 'cart']}, 05:32:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:32:10 INFO - 'tsvgr_opacity', 05:32:10 INFO - 'tart', 05:32:10 INFO - 'tscrollx', 05:32:10 INFO - 'cart']}, 05:32:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'talos_options': ['--disable-e10s'], 05:32:10 INFO - 'tests': ['tp5o']}, 05:32:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'tests': ['tp5o']}, 05:32:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'talos_options': ['--disable-e10s', 05:32:10 INFO - '--xperf_path', 05:32:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:10 INFO - 'tests': ['tp5n']}, 05:32:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:32:10 INFO - 'talos_options': ['--xperf_path', 05:32:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:32:10 INFO - 'tests': ['tp5n']}}, 05:32:10 INFO - 'talos.zip': {'path': '', 05:32:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:32:10 INFO - Running post-action listener: _resource_record_post_action 05:32:10 INFO - [mozharness: 2016-08-26 12:32:10.423949Z] Finished populate-webroot step (success) 05:32:10 INFO - [mozharness: 2016-08-26 12:32:10.424076Z] Running create-virtualenv step. 05:32:10 INFO - Running pre-action listener: _resource_record_pre_action 05:32:10 INFO - Running main action method: create_virtualenv 05:32:10 INFO - Creating virtualenv /builds/slave/test/build/venv 05:32:10 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 05:32:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:32:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:32:10 INFO - Using real prefix '/tools/python27' 05:32:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:32:11 INFO - Installing distribute.............................................................................................................................................................................................done. 05:32:14 INFO - Installing pip.................done. 05:32:14 INFO - Return code: 0 05:32:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:32:14 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')]} 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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')]} 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:14 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 05:32:14 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 05:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:14 INFO - 'HOME': '/Users/cltbld', 05:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:14 INFO - 'LOGNAME': 'cltbld', 05:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:14 INFO - 'MOZ_NO_REMOTE': '1', 05:32:14 INFO - 'NO_EM_RESTART': '1', 05:32:14 INFO - 'PAGER': '/bin/cat', 05:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:14 INFO - 'PWD': '/builds/slave/test', 05:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:14 INFO - 'SHELL': '/bin/bash', 05:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:14 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:14 INFO - 'USER': 'cltbld', 05:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:14 INFO - 'XPC_FLAGS': '0x0', 05:32:14 INFO - 'XPC_SERVICE_NAME': '0', 05:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:14 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 05:32:14 INFO - Cleaning up... 05:32:14 INFO - Return code: 0 05:32:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:32:14 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')]} 05:32:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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')]} 05:32:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:14 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:14 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 05:32:14 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 05:32:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:14 INFO - 'HOME': '/Users/cltbld', 05:32:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:14 INFO - 'LOGNAME': 'cltbld', 05:32:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:14 INFO - 'MOZ_NO_REMOTE': '1', 05:32:14 INFO - 'NO_EM_RESTART': '1', 05:32:14 INFO - 'PAGER': '/bin/cat', 05:32:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:14 INFO - 'PWD': '/builds/slave/test', 05:32:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:14 INFO - 'SHELL': '/bin/bash', 05:32:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:14 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:14 INFO - 'USER': 'cltbld', 05:32:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:14 INFO - 'XPC_FLAGS': '0x0', 05:32:14 INFO - 'XPC_SERVICE_NAME': '0', 05:32:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:14 INFO - Downloading/unpacking psutil>=3.1.1 05:32:14 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 05:32:14 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 05:32:14 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 05:32:14 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 05:32:14 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 05:32:14 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 05:32:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:32:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:18 INFO - Installing collected packages: psutil 05:32:18 INFO - Running setup.py install for psutil 05:32:18 INFO - building 'psutil._psutil_osx' extension 05:32:18 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 05:32:18 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 05:32:19 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 05:32:19 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 05:32:19 INFO - building 'psutil._psutil_posix' extension 05:32:19 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 05:32:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:32:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:32:19 INFO - ^ 05:32:19 INFO - 1 warning generated. 05:32:19 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 05:32:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:32:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:32:19 INFO - Successfully installed psutil 05:32:19 INFO - Cleaning up... 05:32:19 INFO - Return code: 0 05:32:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:32:19 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')]} 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:19 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')]} 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:19 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:19 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 05:32:19 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 05:32:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:19 INFO - 'HOME': '/Users/cltbld', 05:32:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:19 INFO - 'LOGNAME': 'cltbld', 05:32:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:19 INFO - 'MOZ_NO_REMOTE': '1', 05:32:19 INFO - 'NO_EM_RESTART': '1', 05:32:19 INFO - 'PAGER': '/bin/cat', 05:32:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:19 INFO - 'PWD': '/builds/slave/test', 05:32:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:19 INFO - 'SHELL': '/bin/bash', 05:32:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:19 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:19 INFO - 'USER': 'cltbld', 05:32:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:19 INFO - 'XPC_FLAGS': '0x0', 05:32:19 INFO - 'XPC_SERVICE_NAME': '0', 05:32:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:32:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:32:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:32:22 INFO - Installing collected packages: mozsystemmonitor 05:32:22 INFO - Running setup.py install for mozsystemmonitor 05:32:22 INFO - Successfully installed mozsystemmonitor 05:32:22 INFO - Cleaning up... 05:32:22 INFO - Return code: 0 05:32:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:22 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:22 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 05:32:22 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 05:32:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:22 INFO - 'HOME': '/Users/cltbld', 05:32:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:22 INFO - 'LOGNAME': 'cltbld', 05:32:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:22 INFO - 'MOZ_NO_REMOTE': '1', 05:32:22 INFO - 'NO_EM_RESTART': '1', 05:32:22 INFO - 'PAGER': '/bin/cat', 05:32:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:22 INFO - 'PWD': '/builds/slave/test', 05:32:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:22 INFO - 'SHELL': '/bin/bash', 05:32:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:22 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:22 INFO - 'USER': 'cltbld', 05:32:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:22 INFO - 'XPC_FLAGS': '0x0', 05:32:22 INFO - 'XPC_SERVICE_NAME': '0', 05:32:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:22 INFO - Downloading/unpacking jsonschema==2.5.1 05:32:22 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 05:32:22 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 05:32:22 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 05:32:22 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 05:32:22 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 05:32:22 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 05:32:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:32:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:25 INFO - Downloading functools32-3.2.3-2.tar.gz 05:32:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:32:25 INFO - warning: no files found matching '*.txt' 05:32:25 INFO - no previously-included directories found matching 'build' 05:32:25 INFO - no previously-included directories found matching 'dist' 05:32:25 INFO - no previously-included directories found matching '.git*' 05:32:25 INFO - Installing collected packages: jsonschema, functools32 05:32:25 INFO - Running setup.py install for functools32 05:32:26 INFO - warning: no files found matching '*.txt' 05:32:26 INFO - no previously-included directories found matching 'build' 05:32:26 INFO - no previously-included directories found matching 'dist' 05:32:26 INFO - no previously-included directories found matching '.git*' 05:32:26 INFO - Successfully installed jsonschema functools32 05:32:26 INFO - Cleaning up... 05:32:26 INFO - Return code: 0 05:32:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:26 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:26 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 05:32:26 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 05:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:26 INFO - 'HOME': '/Users/cltbld', 05:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:26 INFO - 'LOGNAME': 'cltbld', 05:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:26 INFO - 'MOZ_NO_REMOTE': '1', 05:32:26 INFO - 'NO_EM_RESTART': '1', 05:32:26 INFO - 'PAGER': '/bin/cat', 05:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:26 INFO - 'PWD': '/builds/slave/test', 05:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:26 INFO - 'SHELL': '/bin/bash', 05:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:26 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:26 INFO - 'USER': 'cltbld', 05:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:26 INFO - 'XPC_FLAGS': '0x0', 05:32:26 INFO - 'XPC_SERVICE_NAME': '0', 05:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:32:26 INFO - Cleaning up... 05:32:26 INFO - Return code: 0 05:32:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:26 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:26 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 05:32:26 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 05:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:26 INFO - 'HOME': '/Users/cltbld', 05:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:26 INFO - 'LOGNAME': 'cltbld', 05:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:26 INFO - 'MOZ_NO_REMOTE': '1', 05:32:26 INFO - 'NO_EM_RESTART': '1', 05:32:26 INFO - 'PAGER': '/bin/cat', 05:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:26 INFO - 'PWD': '/builds/slave/test', 05:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:26 INFO - 'SHELL': '/bin/bash', 05:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:26 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:26 INFO - 'USER': 'cltbld', 05:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:26 INFO - 'XPC_FLAGS': '0x0', 05:32:26 INFO - 'XPC_SERVICE_NAME': '0', 05:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:26 INFO - Downloading/unpacking blobuploader==1.2.4 05:32:26 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 05:32:26 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 05:32:26 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 05:32:26 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 05:32:26 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 05:32:26 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 05:32:29 INFO - Downloading blobuploader-1.2.4.tar.gz 05:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:32:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:32:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32: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 05:32:30 INFO - Downloading docopt-0.6.1.tar.gz 05:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:32:30 INFO - Installing collected packages: blobuploader, requests, docopt 05:32:30 INFO - Running setup.py install for blobuploader 05:32:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:32:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:32:30 INFO - Running setup.py install for requests 05:32:30 INFO - Running setup.py install for docopt 05:32:31 INFO - Successfully installed blobuploader requests docopt 05:32:31 INFO - Cleaning up... 05:32:31 INFO - Return code: 0 05:32:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:31 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:31 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 05:32:31 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 05:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:31 INFO - 'HOME': '/Users/cltbld', 05:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:31 INFO - 'LOGNAME': 'cltbld', 05:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:31 INFO - 'MOZ_NO_REMOTE': '1', 05:32:31 INFO - 'NO_EM_RESTART': '1', 05:32:31 INFO - 'PAGER': '/bin/cat', 05:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:31 INFO - 'PWD': '/builds/slave/test', 05:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:31 INFO - 'SHELL': '/bin/bash', 05:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:31 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:31 INFO - 'USER': 'cltbld', 05:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:31 INFO - 'XPC_FLAGS': '0x0', 05:32:31 INFO - 'XPC_SERVICE_NAME': '0', 05:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-GJIKwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-4xRpzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-eNiSD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-EDGOVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-camsBT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:31 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-kLGM9J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-v_mco3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ku9TBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-3tDpp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-yuSiWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-jrEbEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-knjTiW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-8SJnvH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-3ymeaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:32 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-qupRN2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:33 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-t04cuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:33 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-jymwGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:32:33 INFO - Running setup.py install for manifestparser 05:32:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:32:33 INFO - Running setup.py install for mozcrash 05:32:33 INFO - Running setup.py install for mozdebug 05:32:33 INFO - Running setup.py install for mozdevice 05:32:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:32:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:32:33 INFO - Running setup.py install for mozfile 05:32:33 INFO - Running setup.py install for mozhttpd 05:32:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Running setup.py install for mozinfo 05:32:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Running setup.py install for mozInstall 05:32:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Running setup.py install for mozleak 05:32:34 INFO - Running setup.py install for mozlog 05:32:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Running setup.py install for moznetwork 05:32:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:32:34 INFO - Running setup.py install for mozprocess 05:32:35 INFO - Running setup.py install for mozprofile 05:32:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:32:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:32:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:32:35 INFO - Running setup.py install for mozrunner 05:32:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:32:35 INFO - Running setup.py install for mozscreenshot 05:32:35 INFO - Running setup.py install for moztest 05:32:35 INFO - Running setup.py install for mozversion 05:32:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:32:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:32:35 INFO - Cleaning up... 05:32:35 INFO - Return code: 0 05:32:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:32:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:35 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:35 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 05:32:35 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 05:32:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:35 INFO - 'HOME': '/Users/cltbld', 05:32:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:35 INFO - 'LOGNAME': 'cltbld', 05:32:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:35 INFO - 'MOZ_NO_REMOTE': '1', 05:32:35 INFO - 'NO_EM_RESTART': '1', 05:32:35 INFO - 'PAGER': '/bin/cat', 05:32:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:35 INFO - 'PWD': '/builds/slave/test', 05:32:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:35 INFO - 'SHELL': '/bin/bash', 05:32:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:35 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:35 INFO - 'USER': 'cltbld', 05:32:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:35 INFO - 'XPC_FLAGS': '0x0', 05:32:35 INFO - 'XPC_SERVICE_NAME': '0', 05:32:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Nk2wkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-EqMgZX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-mp648N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-F8Pi_Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-ygollg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-UImLuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-cqkuKq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-5dvTrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:32:36 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)) 05:32:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:32:36 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-Xu6Icb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-myA4E_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-CnhXdT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-hkVMQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-N_4Tm9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-8z3AX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-cunVWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-qHLNbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:32:37 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)) 05:32:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:32:37 INFO - Running setup.py (path:/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/pip-peCY3w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:32:37 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)) 05:32:37 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)) 05:32:37 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)) 05:32:37 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)) 05:32:37 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)) 05:32:37 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)) 05:32:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:32:40 INFO - Downloading blessings-1.6.tar.gz 05:32:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:32:40 INFO - Installing collected packages: blessings 05:32:40 INFO - Running setup.py install for blessings 05:32:40 INFO - Successfully installed blessings 05:32:40 INFO - Cleaning up... 05:32:40 INFO - Return code: 0 05:32:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:32:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:40 INFO - Reading from file tmpfile_stdout 05:32:40 INFO - Current package versions: 05:32:40 INFO - blessings == 1.6 05:32:40 INFO - blobuploader == 1.2.4 05:32:40 INFO - docopt == 0.6.1 05:32:40 INFO - functools32 == 3.2.3-2 05:32:40 INFO - jsonschema == 2.5.1 05:32:40 INFO - manifestparser == 1.1 05:32:40 INFO - mozInstall == 1.12 05:32:40 INFO - mozcrash == 0.17 05:32:40 INFO - mozdebug == 0.1 05:32:40 INFO - mozdevice == 0.48 05:32:40 INFO - mozfile == 1.2 05:32:40 INFO - mozhttpd == 0.7 05:32:40 INFO - mozinfo == 0.9 05:32:40 INFO - mozleak == 0.1 05:32:40 INFO - mozlog == 3.3 05:32:40 INFO - moznetwork == 0.27 05:32:40 INFO - mozprocess == 0.23 05:32:40 INFO - mozprofile == 0.28 05:32:40 INFO - mozrunner == 6.12 05:32:40 INFO - mozscreenshot == 0.1 05:32:40 INFO - mozsystemmonitor == 0.3 05:32:40 INFO - moztest == 0.7 05:32:40 INFO - mozversion == 1.4 05:32:40 INFO - psutil == 3.1.1 05:32:40 INFO - requests == 1.2.3 05:32:40 INFO - wsgiref == 0.1.2 05:32:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:32:40 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')]} 05:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:40 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')]} 05:32:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:40 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:40 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 05:32:40 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 05:32:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:40 INFO - 'HOME': '/Users/cltbld', 05:32:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:40 INFO - 'LOGNAME': 'cltbld', 05:32:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:40 INFO - 'MOZ_NO_REMOTE': '1', 05:32:40 INFO - 'NO_EM_RESTART': '1', 05:32:40 INFO - 'PAGER': '/bin/cat', 05:32:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:40 INFO - 'PWD': '/builds/slave/test', 05:32:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:40 INFO - 'SHELL': '/bin/bash', 05:32:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:40 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:40 INFO - 'USER': 'cltbld', 05:32:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:40 INFO - 'XPC_FLAGS': '0x0', 05:32:40 INFO - 'XPC_SERVICE_NAME': '0', 05:32:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 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)) 05:32:41 INFO - Cleaning up... 05:32:41 INFO - Return code: 0 05:32:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:32: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')]} 05:32:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:32:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32: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')]} 05:32:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:32:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:32:41 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 0x10986da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109902618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe9e1dc2990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, '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.dcszIEsAl6/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.bAxYoDRA3S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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 05:32:41 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 05:32:41 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 05:32:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:32:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:32:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:32:41 INFO - 'HOME': '/Users/cltbld', 05:32:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:32:41 INFO - 'LOGNAME': 'cltbld', 05:32:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:32:41 INFO - 'MOZ_NO_REMOTE': '1', 05:32:41 INFO - 'NO_EM_RESTART': '1', 05:32:41 INFO - 'PAGER': '/bin/cat', 05:32:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:32:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:32:41 INFO - 'PWD': '/builds/slave/test', 05:32:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:32:41 INFO - 'SHELL': '/bin/bash', 05:32:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:32:41 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:32:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:32:41 INFO - 'USER': 'cltbld', 05:32:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:32:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:32:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:32:41 INFO - 'XPC_FLAGS': '0x0', 05:32:41 INFO - 'XPC_SERVICE_NAME': '0', 05:32:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:32:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:32:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:32:41 INFO - Cleaning up... 05:32:41 INFO - Return code: 0 05:32:41 INFO - Running post-action listener: _resource_record_post_action 05:32:41 INFO - Running post-action listener: _start_resource_monitoring 05:32:41 INFO - Starting resource monitoring. 05:32:41 INFO - [mozharness: 2016-08-26 12:32:41.541407Z] Finished create-virtualenv step (success) 05:32:41 INFO - [mozharness: 2016-08-26 12:32:41.542281Z] Running install step. 05:32:41 INFO - Running pre-action listener: _resource_record_pre_action 05:32:41 INFO - Running main action method: install 05:32:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:32:41 INFO - Reading from file tmpfile_stdout 05:32:41 INFO - Detecting whether we're running mozinstall >=1.0... 05:32:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:32:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:32:41 INFO - Reading from file tmpfile_stdout 05:32:41 INFO - Output received: 05:32:41 INFO - Usage: mozinstall [options] installer 05:32:41 INFO - Options: 05:32:41 INFO - -h, --help show this help message and exit 05:32:41 INFO - -d DEST, --destination=DEST 05:32:41 INFO - Directory to install application into. [default: 05:32:41 INFO - "/builds/slave/test"] 05:32:41 INFO - --app=APP Application being installed. [default: firefox] 05:32:41 INFO - mkdir: /builds/slave/test/build/application 05:32:41 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'] 05:32:41 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 05:33:15 INFO - Reading from file tmpfile_stdout 05:33:15 INFO - Output received: 05:33:15 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 05:33:15 INFO - Running post-action listener: _resource_record_post_action 05:33:15 INFO - [mozharness: 2016-08-26 12:33:15.825354Z] Finished install step (success) 05:33:15 INFO - [mozharness: 2016-08-26 12:33:15.825537Z] Running run-tests step. 05:33:15 INFO - Running pre-action listener: _resource_record_pre_action 05:33:15 INFO - Running main action method: run_tests 05:33:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:33:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:33:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:33:15 INFO - 2016-08-26 05:33:15.840 screenresolution[1644:7716] starting screenresolution argv=screenresolution get 05:33:15 INFO - 2016-08-26 05:33:15.842 screenresolution[1644:7716] Display 0: 1600x1200x32@60 05:33:15 INFO - 2016-08-26 05:33:15.848 screenresolution[1645:7718] starting screenresolution argv=screenresolution list 05:33:15 INFO - Available Modes on Display 0 05:33:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:33:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:33:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:33:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:33:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:33:16 INFO - Intel Iris: 05:33:16 INFO - Chipset Model: Intel Iris 05:33:16 INFO - Type: GPU 05:33:16 INFO - Bus: Built-In 05:33:16 INFO - VRAM (Dynamic, Max): 1536 MB 05:33:16 INFO - Vendor: Intel (0x8086) 05:33:16 INFO - Device ID: 0x0a2e 05:33:16 INFO - Revision ID: 0x0009 05:33:16 INFO - Displays: 05:33:16 INFO - Display: 05:33:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:33:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:33:16 INFO - Display Serial Number: 1600x1200 60 05:33:16 INFO - Main Display: Yes 05:33:16 INFO - Mirror: Off 05:33:16 INFO - Online: Yes 05:33:16 INFO - Rotation: Supported 05:33:16 INFO - Return code: 0 05:33:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:33:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:33:16 INFO - Python 2.7.3 05:33:16 INFO - Return code: 0 05:33:16 INFO - grabbing minidump binary from tooltool 05:33:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:33:16 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 0x1097d1d30>, '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 0x109536db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1097c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:33:16 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 05:33:16 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 05:33:16 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 05:33:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:33:16 INFO - Return code: 0 05:33:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:33:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:33:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:33:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:33:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:33:16 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 05:33:16 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/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0053 --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 05:33:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dcszIEsAl6/Render', 05:33:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:33:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:33:16 INFO - 'HOME': '/Users/cltbld', 05:33:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:33:16 INFO - 'LOGNAME': 'cltbld', 05:33:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:33:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:33:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:33:16 INFO - 'MOZ_NO_REMOTE': '1', 05:33:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:33:16 INFO - 'NO_EM_RESTART': '1', 05:33:16 INFO - 'PAGER': '/bin/cat', 05:33:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:33:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:33:16 INFO - 'PWD': '/builds/slave/test', 05:33:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:33:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:33:16 INFO - 'SHELL': '/bin/bash', 05:33:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bAxYoDRA3S/Listeners', 05:33:16 INFO - 'TMPDIR': '/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/', 05:33:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:33:16 INFO - 'USER': 'cltbld', 05:33:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:33:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:33:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:33:16 INFO - 'XPC_FLAGS': '0x0', 05:33:16 INFO - 'XPC_SERVICE_NAME': '0', 05:33:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:33:16 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/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0053', '--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 05:33:16 INFO - mozversion application_buildid: 20160826030226 05:33:16 INFO - mozversion application_changeset: a551f534773cf2d6933f78ce7d82a7a33a99643e 05:33:16 INFO - mozversion application_display_name: Nightly 05:33:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:33:16 INFO - mozversion application_name: Firefox 05:33:16 INFO - mozversion application_remotingname: firefox 05:33:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:33:16 INFO - mozversion application_vendor: Mozilla 05:33:16 INFO - mozversion application_version: 51.0a1 05:33:16 INFO - mozversion platform_buildid: 20160826030226 05:33:16 INFO - mozversion platform_changeset: a551f534773cf2d6933f78ce7d82a7a33a99643e 05:33:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:33:16 INFO - mozversion platform_version: 51.0a1 05:33:16 INFO - using testdate: 1472214796 05:33:16 INFO - actual date: 1472214796 05:33:16 INFO - starting webserver on 'localhost:49271' 05:33:16 INFO - SUITE-START | Running 2 tests 05:33:16 INFO - TEST-START | dromaeo_css 05:33:16 INFO - operating with platform_type : mac_ 05:33:16 INFO - Initialising browser for dromaeo_css test... 05:33:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpehHgFk/profile http://localhost:49271/getInfo.html) 05:33:18 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 05:33:18 INFO - PROCESS | 1655 | colorDepth:24 05:33:18 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 05:33:18 INFO - PROCESS | 1655 | __metrics 05:33:18 INFO - PROCESS | 1655 | 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" 05:33:18 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:33:19 INFO - TEST-INFO | 1655: exit 0 05:33:19 INFO - Browser initialized. 05:33:19 INFO - Running cycle 1/1 for dromaeo_css test... 05:33:19 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpehHgFk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:33:31 INFO - PROCESS | 1659 | RSS: Main: 233615360 05:33:31 INFO - PROCESS | 1659 | 05:34:23 INFO - PROCESS | 1659 | 1472214863167 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] 05:36:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 05:36:36 INFO - PROCESS | 1659 | RSS: Main: 349536256 05:36:36 INFO - PROCESS | 1659 | 05:39:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 05:39:40 INFO - PROCESS | 1659 | RSS: Main: 374054912 05:39:40 INFO - PROCESS | 1659 | 05:42:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 05:42:43 INFO - PROCESS | 1659 | RSS: Main: 343621632 05:42:43 INFO - PROCESS | 1659 | 05:45:45 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 05:45:46 INFO - PROCESS | 1659 | RSS: Main: 336207872 05:45:46 INFO - PROCESS | 1659 | 05:48:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 05:48:50 INFO - PROCESS | 1659 | RSS: Main: 336412672 05:48:50 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 05:51:53 INFO - PROCESS | 1659 | RSS: Main: 481157120 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | __start_tp_report 05:51:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:51:53 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:51:53 INFO - PROCESS | 1659 | |i|pagename|runs| 05:51:53 INFO - PROCESS | 1659 | |0;dojo.html;3395.7622966392355;3401.5577974863268;3406.5912090549123;3446.603640581334;3486.7559270851034;4829.661923665345;5720.22776925115;5832.650040997532;5854.238948936638;5945.019071853145;5846.21076154719;5905.616134951236;5906.26171728534;5913;5913.053911374181;5310.283111779899;5348.946510534904;5386.542143917772;5395.757190926398;5439.945600544003;5432.266644003049;5445.945540544603;5453.836384908459;5461;5471.699056551908;5323.520883120518;5389.541888939445;5390.51485366317;5413.133898576229;5441.673499590018;5437.646552974044;5459.071957767169;5461.42655021225;5463.9180412293845;5529.972350138264;5179.948200517984;5288.312519372495;5316.494932981361;5384.7846086156505;5448;5867.559933005042;5872.236609240814;5888.911666325009;5921.289445266552;5963.344032156459;6289.685515724242;6295.748170073238;6330.87338253237;6342.873142537123;6352.904706429407;5872.471477566996;6248.094026366197;6256.436920677161;6259.311475737665;6367.108604795332;5993.640381577119;5999.820005399845;6021.488173505301;6052.515798736091;6060.575759696867;6457.031445283245;6463.57986730861;6466.902996455057;6474.384933431316;6476.514261430318;6125.448709616156;6203.596766210182;6243.188385509855;6309.3690630936535;6313.526485513605;6317.936820631827;6322.241331040213;6348.587341822767;6356.4279214870885;6474.417302442708;6324;6340.985442329205;6362.363763623601;6364.64994425311;6374.9681251594375;6109.526129009804;6123.173371594867;6161.7535298588455;6316.715747791361;6345.397187267202;4990.0768357853185;4992.051510213048;4996.400431948189;4997.750112494361;5003.199488081962;3564.7326450516316;4505.053938672915;4517.186906356887;4519.7740112994215;4533.342665313577;5863.970680146572;5896.292444906553;5897.2038774765715;5917.497012753878;5937.732802023833;6246.77643324739;6275.184226050585;6311.747530098836;6312.116303717483;6313.526485513605;5771.5671324649975;5844;5905.704714764244;5909.674967876808;5964.224650795456;5522.502974732293;5561.304836895388;5570.303712035996;5582.33012038558;5639.520640745501;6453.225612926479;6502.902456463157;6515.28331883502;6529.640869752209;6549.410553050248;6234.532410069172;6307.684615769102;6326.051092336186;6326.810195694136;6349.6507692077375;6382.393672601095;6386.552941294251;6430.2926678066315;6511.34886511345;6567.9014814776865;6319.494440444661;6336.936630633634;6343.397377249154;6346;6346.619202847844;6179.81460556184;6184.5052395809125;6315.5894866833505;6316.810495685137;6538.346165383614;6158.661273629993;6225.657588832658;6229.781957631461;6235.002399616221;6284.685765711604;6258.499320054294;6260.937390626035;6271.529635277281;6271.749130034747;6275.278342990615;6028.487578555872;6158.538109641705;6167.074938759042;6170.753169873155;6175.567710260419;6123.252118380239;6151.384861513813;6199.628022318675;6231.563790534619;6296.874062518816;6071.241094863142;6079.635221886701;6091.269047714302;6155.538334625011;6167.352427994996 05:51:53 INFO - PROCESS | 1659 | |1;ext.html;488.83868323453265;516.6512603992305;526.2106839740391;533.0192445899546;567.7870798450581;30433.847830760842;31468.842655786717;39828.20343593127;40298;40310;40973.79513102438;41265.58734412655;41269.58730412695;41364.58635413645;41456.792716036376;9564;9717.174040206592;9727.854082188773;9732.2214222862;9737.464439455827;8311.667533298662;9686.176674982635;9770.804583908353;9804.117629413351;9804.117629413351;9753.804923901518;9805.411675299469;9808.656697015605;9820.852687209697;9837.754056148582;9745.269104817131;9793;9802.705918822447;9814.90185098147;9816.116549510578;9678.56446459911;9736.75658108546;9742.902570974271;9758;9771.21830253578;39742.20515589701;39914.40128398076;39980.40029399561;40001.19997600061;40086.198276034316;3379.831008449568;3473.7394695397948;3497.3005398920063;3499.475078738184;3580.8183299511097;31362;31740;31785;31823.681763182536;31870.840645796623;3213.8714451421915;3364.327134573071;3390.0677313738674;3414.9146271343166;3421.3157368526395;486212;486258;486517;487532;491948;56739;57092.143617845766;57108;57131.42868571261;57132.71433642805;51977;57922.71038644864;59344.70327648334;59470.70264648649;59825;15673.529794106194;15786.289616967264;15866.127362995147;15866.603334916488;15888.523344299689;12263.448144833505;12332.445039973223;12342.938285308695;12343.321117338633;12352.75294494123;15144.318505667272;15666.921665391828;15674.9216253918;15692.723496854878;15770;6037.094435834572;6513.2509761377105;6909;6949.339812717783;7086.610236436943;852.3351785607236;918.5085979001202;946.7680418297562;963.0658261486348;1058.30680904008;28825.711742882722;29054.837806487503;29065.564016539767;29952.801887924674;30109;1069.4866464097277;1083.4582708645678;1136.1933027550367;1167.511685019413;1169.736809217936;12024.33866137371;12146.574869879689;14316.856831431553;15202.277956398899;15519.379224831106;633885;641845;642322;643390;645537;56756.71621641948;56802.14796778052;56897.14654280189;56913.01608887421;57057;1134.4101067444717;1210.7215340471564;1263.431455844855;1378.5244090788663;1409.2460533614467;27503.31241718973;28316.15051548457;30543;30586.694133059274;32012.6798732019;36130.45804312885;47814.28278575894;47907.44879236467;47922;47939.041219174724;450832;451010;451073;451288;452942;26383.34041648974;27200.319992000357;27266.454670906074;39442;39871.60128398679;44813.32780008368;44824.65526034224;45016.77491612521;45500.54499454963;47212;38577.6142238574;38676.033099172746;38916;39800.40299395569;40464.1907161861;38279.61720382872;38816.80591597024;38901.805490972365;40088.39867402049;40318.39522407225;30119.548206777356;36217.63782362248;37652.81173594115;38594.6140538591;38673;28415;32790.5081423774;34068.65931340655;34782.65217347794;34918;22227.555448891257;25107.372315692253;25115.744212788923;25861.482770344865;26042.73957260479 05:51:53 INFO - PROCESS | 1659 | |2;jquery.html;351.5131542813203;374.87816459650605;376.55566431610697;384.571203108534;403.38081045595015;15671.216439178039;15985.680286394265;19210.519737006583;20932.790672093273;21077.683834742453;17813.82186178138;21476.355709328705;21824;21826.23608173714;22142.778572214273;6344.2386913570435;6371.362863713614;6401.295857455676;6861.691223894924;7191.137063552354;4686.367340409052;6009.759609615632;6977.33715297046;7086.964565177167;7089.078419805418;4805.927911081337;8133;8139.511629302231;8152.1440248773615;8158.755237342889;7800.180980996999;8059.838803223903;8095.635696393618;8102;8118.594070296491;7578.696852125963;7619.4285428593075;7759.068911730572;7763.223677632248;7847.882281765778;23506.76493235063;28532.714672853213;28891;29733.405331893242;30004.54993175104;2432.939176520601;2488.950220995588;2671.505771432272;2675.919722408331;3221.5328777327154;16749.24628391713;16766.497005089866;17116.82883171165;17644.382446614305;17704.937703737818;1958.7159861820098;2028.9594208115755;2092.4455019419865;2097.6031652931256;2177.42298290953;131276.37447251097;132524;143034;147845;153446;16630.33478660864;17557.64884702299;20908.68636970447;21189.470263243544;21382.786172138392;34977.825110874284;35097.47353789695;35196.47205291923;35214.823925880206;35428.822855885555;8807.867881981776;10381.792364152825;10555.525001374957;10738.624148154777;10740.30188037775;6971.965140174267;7315.122185337794;8801.251893589117;8944.642214311485;9015.098490150933;9148.8627670585;9569.090936361035;9636.614535418505;9647;9748.805023899482;2992.7755418343713;3143.883921207984;3649.8905032849057;3750.643688849555;3846.519185101862;820.9753707388788;896.7758060484879;904.3850181876255;991.0773100185617;1015.8780946286493;21227.575448490945;21673.783262167177;21786.782132178792;21907.014184361742;21993.890030550065;932.1098351074708;947.2138125355938;951.8905325887612;972.0801417201112;983.8819341679044;10982.430710019276;11283.866304429004;11462.369569673581;11515.539378424937;11537.961583457529;559822;573105;575507;578917;590534;127712;130425;131425;131746.34126829496;136275.95586066216;956.4930586789013;962.2494454325637;996.8903420623726;997.8952210017989;998.5257002923553;18959.620807584048;19152;19256.711149333052;19298.32455864038;19330.613387732446;36600.63399365972;53784.84860605532;54688.90622187613;55643.27032202146;55719.72140139274;68860.62278754296;73556.63221683857;75525.62237188779;79735.60132199302;81115.59442202751;8019.558924259221;8242.958785206276;8303.04514980785;8353.33173346143;8534;12539.310337931502;12944.61166164979;13079.078424965968;13357;13446.663833404244;11168.497417616065;11367;11603.825942610698;11666;11726.824097638713;11029.283096598696;11293.548258069914;11458.08335333188;11831.467583958914;11861.347625880659;11538.480768365276;11613.477393517482;11757.588484403004;11853.288802671867;11900.464479098287;8241.711540096068;8268.545230012407;8299.045609754956;8332.6666933322;8472.110428404929;9516.95241523788;11368.090552755595;11530.250533715618;11554.017908477874;11595 05:51:53 INFO - PROCESS | 1659 | |3;mootools.html;1484.5694748522928;1498.086167437863;1687.4431437625585;1842.8986405747696;1866.0296645744213;8382.664693412264;8924.107589241072;8925.55372231389;8934.687285944994;8946.23956963658;7810.336121429671;8066.03207615087;8477.745667629966;8664.610092545834;8712.346574006942;2376.643503474484;2400.531896280226;2401.327628264089;2402.4474370894704;2407.987960060197;2175.3365223606793;2289.748127705951;2367.550165468564;2388.2477019738726;2413.1433341163897;2081.84386171037;2168.3278183763005;2312.8612283262974;2393.0069021356203;2399.3521749127726;2373.371056669984;2382.821288403368;2403.291029146406;2404.386881345263;2411.44536756547;2385.49904520051;2387.403149212697;2388.6655868178295;2389.259329607827;2394.078279862248;8651.091635378258;8855.955720221422;8863.379563430562;8865.423747456394;8874.600642971081;5203.557697595709;5351.25082488452;5352.277442545245;5354.598405119632;5356.678599284055;3054.1906394805396;3514.2268700885766;3666.834992425347;3724.1992971510986;3752.1182522107283;1059.9841002384971;1106.0874778307848;1110.0509064749604;1111.660943412259;1133.2633788037758;11542.422878856023;11657.708557286136;11664.825027624593;11677.649670509983;11687.298762074302;4157.854475093418;4369.9126017479475;4615.930761038587;4809.879753006133;4811.879703007383;9854.211663066937;9856.950715246521;9860.950695246478;9867.11195992364;9900.752481187883;5340.732963351817;5354.30394048779;5368.758405871745;5368.785248590091;5381.192821076792;4795.520447955247;4868.148074087091;4890.070886531547;4897.7795999180125;4917.704937703749;3831.1571454280015;3855.4409610606303;3884.4755957945317;3918.804059796999;3941.7043721720984;1174.2602160638744;1265.487477571585;1266.5630357526638;1273.7070473790895;1286.6590353556314;784.5606460382204;794.412135020081;869.2698133567707;890.8351954888367;948.6015873333216;5488.204210389471;5630.887382252332;5687.759104336834;5864.237649105589;5901.085331773582;715.9785206443711;883.6288758721224;887.2236792806294;923.8959443464995;953.0993211415284;3772.358699021173;3784.4323351496946;3803.4294855771855;3879.8254078566533;3907;9826.508674566243;10094.394336339843;10523.052925236765;10903.781924361469;10927.071198948019;10806.783864322826;10814.945925270324;10820.56717731282;10823.350598964087;10834.458277086114;7767.961160194276;8167.14245004266;8319.168083191633;8328;8331.375146863887;5647.915281270866;5818.6217895836635;5844.327902291121;5857;5876.853078673067;9194.701324668833;9840.065193806577;9869.555869986012;10130.79738405213;10240.334378265388;10143.239257055602;10207.54066067014;10207.7448063799;10211.642592509525;10319.226058045526;7445.9627701861145;7505.324520793155;7589.241075892587;7592.772216833504;7606.353459955744;8155.306798922158;8157.673693052211;8258.876116858371;8261.297789687704;8276.875846862422;7356.521826081287;7436.516626419266;7437.776866694008;7572.583507907118;7646.541207527566;5742.397048309867;7274.8908766369605;7323.414126869944;7341.6329183541675;7366.6684999174095;6944.305569443218;6954.547954382949;6964.721411143701;7045.330693584101;7059.505834591735;4842.7820748065715;4878.682885612424;4928.088303663691;4955.925661115014;5029.471905450021;3981.8606348777653;4179.791010449526;4503.662225333048;5093.337866077386;5191.351081114861 05:51:53 INFO - PROCESS | 1659 | |4;prototype.html;254.97195308516066;282.9038127036807;311.17383347213143;315.26071362654574;324.15880789351627;4752.524747525245;5367.46325367463;5371.328583927009;5441.537469315113;5442.455754424555;5086.465921078289;5231.816886408977;5266.52601265886;5297.258383826267;5370.597205209606;3306.7514090418504;3838.827252773625;4002.939955900664;4013.4983127109113;4025.335819589764;3635.327464419078;3653.2145588698427;3790.6732643574665;3887.9611203888026;4020.477337946063;3217.3778370627865;3660.8535658573624;3713.3316003119426;3999.820008099628;4027.436158937751;4022.224664981168;4023.698222633285;4032.451442730408;4052.696047796427;4080.204360149772;2808.36811717364;3019.4715924713305;3380.2394461246267;3498.5801703795705;3531.7174626029855;4369.722707969023;5078.949210507884;5086.745662716887;5125.718085505296;5127.026324731684;4386.254336762758;4546.113507865967;4588.5585810642015;4598.425196850394;4611.584957353854;4569.840055598038;5380.435054319319;5438.646487978269;5554.944450555524;5587.814945481036;3289.654586268455;3486.808225547594;3617.6382361763876;3845.9230815383535;3866.574676785551;5263.157894736823;5541.722913854332;5659.377468478464;5799.0863015205305;5869.706514674249;3407.744419168572;3839.520059992501;3930.390789427643;3933.8819835404984;3938.5667576566557;3607.6211997740384;3642.0894776305922;3674.7427680062137;3908.5733707197237;3924.6656136913016;3991.640752332304;4269.252880745919;4405.383246345515;4667.929981050287;4684.180268453007;5646.6894320811925;5656.4060773619;5664.51851592611;5665;5667.291588551431;1161.7386088130104;1201.5434135028634;1294.838145231846;1352.75650382932;1370.595674276091;681.6319187638619;732.4067505320708;786.7836345005169;825.7398919340388;847.6905929335747;3779.829907654107;3808.809559522013;3919.374019407004;4195.832166713359;4310.486953133186;3826.253880493333;4395.955880515576;4826.152569983657;4833.830815921426;4840.177169881129;4211.020820214494;4243.901951575305;4342.653777329038;4387.561243875587;4398.186335527937;2091.4039498742786;2203.5703037907456;2327.7672232776927;2451.963220551693;2515.955693610715;4560.475545312265;4647.907041859144;4850.514948505192;4974.726390048582;4976.656302798297;4261.914761704748;4643.74459404736;4722.70125715431;4927.285543596158;4932.457429682804;3941.6875562802475;4843.861692502259;4940.382452193476;4949.554540091337;4974.801007959786;4088.6115818997146;4455.532169122195;4505.189065968026;4821.083994041121;4852.495726324851;4100.5899410058755;4542.636589072932;4781.641376896816;4792;4814.807407703653;4621.6302695784925;4707.905841883212;5301.946980530145;5701.572382071278;5764.365919748908;3935.271974684578;3948.368261078214;3970.861019864291;3971.041574027368;3972.927309626327;4971.726555039508;5175.896482070413;5821.243238378984;5826.970865145647;5856.360219138648;4760.666753327372;4935.407751069894;5030.4466508684745;5307.951505888007;5574.735327474405;4771.4731285988155;4818.927716084331;5495.5878309127775;5532.778688852562;5648.661080335193;4579.542045795527;5237.476252374732;5466.726663666721;5504.22940788298;5515.462887071992;4644.5123262056995;4857.732824694675;5170.974145129401;5496.917546236889;5631.732860106444;5481.040817856939;5490.313710786152;5497.581064331681;5523.321189223143;5531.280933478783 05:51:53 INFO - PROCESS | 1659 | |5;yui.html;618.2056057965515;702.7856503766351;727.984461675962;764.3999460423569;809.7327881799007;15082.245887705612;15506.457273995413;15691.293891774869;15721.528354149365;15731.921340393297;14034.157950522977;14256.857431425682;14257.073290236154;14273.072250303723;14687.412503499874;678.3182901184302;691.5124836989935;840.1094839470165;844.9028361738386;873.5457562067721;853.4196746212573;862.0689655172399;868.7610907000594;869.2871845087031;870.1559487297343;919.6643225222779;924.3806649544821;925.282905748044;927.9953600232024;928.0487500312166;706.6325510734389;891.8840550728429;917.3716004536914;921.2215677752322;921.8478950973081;683.3712984054681;685.4242436353487;685.9725610975555;686.3513979289573;687.9783521470612;14630;14634.780478292834;14643.34104965279;14664.926675366554;14668.926655366762;13425.530106446227;13500.191495605513;13760.380782864695;13763.862361376358;13891.381849374546;11523.135764817589;11655.533778648844;11739.434508941935;11755.235909665844;11889.583864564698;4404.69167158299;4457.64338852891;4483.968687201962;4502.57225563571;4597.402337696078;116655;117979;119875.80124198734;121297.78702212953;121729.39135304355;10822.837657435144;10859.782804344028;10902.454877256265;10935.28920620157;11284.210105292716;21718.782812171678;22008.669869951962;22200;22682.886585567296;22709.545809084055;7791.727289544952;7968.920310796933;8205.01539815226;8212.014558252928;8288.336933045342;4393.846215382447;4459.888502787457;4499.550044995474;4527.660425468103;4690.381818272691;3325.185329594265;3428.6914177723625;3432.845521951518;3434.175797808558;3441.3633477806684;654.4012228810681;655.9016147577902;665.6205962601271;670.8423520472685;678.5318130489946;617.8177437655902;624.7032659486707;625.1060982794556;639.667372966055;654.6988385342701;8427.747167584983;9034.277257819358;9204.907950920538;9222.907770922338;9252.259819214447;539.4955716405095;576.3026737647493;602.9005214139618;609.8780243951228;616.4174854762207;4388.802503887333;4429.446319210098;4453.702177398761;4585.862424127282;4909.926351104737;63024.684876575324;97414.5129274349;136155;148464.25767871307;157912;136835;139115.30442347925;140581;144864;145264.27367863094;106396;116754.83245167609;120042.99355096805;120428.3978580119;122995;9341.953290233505;9370.95314523423;9566.66516671941;9948.452835093849;10051.246156538142;63725;64505;64974.10155390692;65121.67439162963;66585.33414665791;98674;99078;100067.49561278493;101808.3020670577;104064;12369.93815030919;13096.934515327363;13376.22570445242;13507.189568625521;13652;14957.17735524513;15231.086134831945;15290.694186116438;15443.209839567724;15538.533843984698;14115.788263175855;14323.14061156334;14361.85105191603;14415.207163606103;14566.563003109924;13808.585742427744;14151.363188656329;14236.572902812932;14524;14781.482648107694;13823.723525529635;14078.859211408164;14357.9282103593;14454.638634034232;14643.633909152357;10800.459977001276;11150.219484635998;11243.66269011931;11339.603113890975;11480.770384592095;11020.44897755125;11400.380408939913;11424.828627570427;11505.367204803817;11695.064394848561 05:51:53 INFO - PROCESS | 1659 | __end_tp_report 05:51:53 INFO - PROCESS | 1659 | __start_cc_report 05:51:53 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1488 05:51:53 INFO - PROCESS | 1659 | __end_cc_report 05:51:53 INFO - PROCESS | 1659 | __startTimestamp1472215913868__endTimestamp 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:51:53 INFO - PROCESS | 1659 | Number of tests: 6 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5875.85 Median:6123.25 stddev:659.42 (10.8%) stddev-sans-first:632.09 05:51:53 INFO - PROCESS | 1659 | Values: 3395.8 3401.6 3406.6 3446.6 3486.8 4829.7 5720.2 5832.7 5854.2 5945.0 5846.2 5905.6 5906.3 5913.0 5913.1 5310.3 5348.9 5386.5 5395.8 5439.9 5432.3 5445.9 5453.8 5461.0 5471.7 5323.5 5389.5 5390.5 5413.1 5441.7 5437.6 5459.1 5461.4 5463.9 5530.0 5179.9 5288.3 5316.5 5384.8 5448.0 5867.6 5872.2 5888.9 5921.3 5963.3 6289.7 6295.7 6330.9 6342.9 6352.9 5872.5 6248.1 6256.4 6259.3 6367.1 5993.6 5999.8 6021.5 6052.5 6060.6 6457.0 6463.6 6466.9 6474.4 6476.5 6125.4 6203.6 6243.2 6309.4 6313.5 6317.9 6322.2 6348.6 6356.4 6474.4 6324.0 6341.0 6362.4 6364.6 6375.0 6109.5 6123.2 6161.8 6316.7 6345.4 4990.1 4992.1 4996.4 4997.8 5003.2 3564.7 4505.1 4517.2 4519.8 4533.3 5864.0 5896.3 5897.2 5917.5 5937.7 6246.8 6275.2 6311.7 6312.1 6313.5 5771.6 5844.0 5905.7 5909.7 5964.2 5522.5 5561.3 5570.3 5582.3 5639.5 6453.2 6502.9 6515.3 6529.6 6549.4 6234.5 6307.7 6326.1 6326.8 6349.7 6382.4 6386.6 6430.3 6511.3 6567.9 6319.5 6336.9 6343.4 6346.0 6346.6 6179.8 6184.5 6315.6 6316.8 6538.3 6158.7 6225.7 6229.8 6235.0 6284.7 6258.5 6260.9 6271.5 6271.7 6275.3 6028.5 6158.5 6167.1 6170.8 6175.6 6123.3 6151.4 6199.6 6231.6 6296.9 6071.2 6079.6 6091.3 6155.5 6167.4 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:66110.65 Median:40043.70 stddev:142373.93 (355.5%) stddev-sans-first:142688.08 05:51:53 INFO - PROCESS | 1659 | Values: 488.8 516.7 526.2 533.0 567.8 30433.8 31468.8 39828.2 40298.0 40310.0 40973.8 41265.6 41269.6 41364.6 41456.8 9564.0 9717.2 9727.9 9732.2 9737.5 8311.7 9686.2 9770.8 9804.1 9804.1 9753.8 9805.4 9808.7 9820.9 9837.8 9745.3 9793.0 9802.7 9814.9 9816.1 9678.6 9736.8 9742.9 9758.0 9771.2 39742.2 39914.4 39980.4 40001.2 40086.2 3379.8 3473.7 3497.3 3499.5 3580.8 31362.0 31740.0 31785.0 31823.7 31870.8 3213.9 3364.3 3390.1 3414.9 3421.3 486212.0 486258.0 486517.0 487532.0 491948.0 56739.0 57092.1 57108.0 57131.4 57132.7 51977.0 57922.7 59344.7 59470.7 59825.0 15673.5 15786.3 15866.1 15866.6 15888.5 12263.4 12332.4 12342.9 12343.3 12352.8 15144.3 15666.9 15674.9 15692.7 15770.0 6037.1 6513.3 6909.0 6949.3 7086.6 852.3 918.5 946.8 963.1 1058.3 28825.7 29054.8 29065.6 29952.8 30109.0 1069.5 1083.5 1136.2 1167.5 1169.7 12024.3 12146.6 14316.9 15202.3 15519.4 633885.0 641845.0 642322.0 643390.0 645537.0 56756.7 56802.1 56897.1 56913.0 57057.0 1134.4 1210.7 1263.4 1378.5 1409.2 27503.3 28316.2 30543.0 30586.7 32012.7 36130.5 47814.3 47907.4 47922.0 47939.0 450832.0 451010.0 451073.0 451288.0 452942.0 26383.3 27200.3 27266.5 39442.0 39871.6 44813.3 44824.7 45016.8 45500.5 47212.0 38577.6 38676.0 38916.0 39800.4 40464.2 38279.6 38816.8 38901.8 40088.4 40318.4 30119.5 36217.6 37652.8 38594.6 38673.0 28415.0 32790.5 34068.7 34782.7 34918.0 22227.6 25107.4 25115.7 25861.5 26042.7 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:36637.12 Median:19099.68 stddev:96708.36 (506.3%) stddev-sans-first:96941.27 05:51:53 INFO - PROCESS | 1659 | Values: 351.5 374.9 376.6 384.6 403.4 15671.2 15985.7 19210.5 20932.8 21077.7 17813.8 21476.4 21824.0 21826.2 22142.8 6344.2 6371.4 6401.3 6861.7 7191.1 4686.4 6009.8 6977.3 7087.0 7089.1 4805.9 8133.0 8139.5 8152.1 8158.8 7800.2 8059.8 8095.6 8102.0 8118.6 7578.7 7619.4 7759.1 7763.2 7847.9 23506.8 28532.7 28891.0 29733.4 30004.5 2432.9 2489.0 2671.5 2675.9 3221.5 16749.2 16766.5 17116.8 17644.4 17704.9 1958.7 2029.0 2092.4 2097.6 2177.4 131276.4 132524.0 143034.0 147845.0 153446.0 16630.3 17557.6 20908.7 21189.5 21382.8 34977.8 35097.5 35196.5 35214.8 35428.8 8807.9 10381.8 10555.5 10738.6 10740.3 6972.0 7315.1 8801.3 8944.6 9015.1 9148.9 9569.1 9636.6 9647.0 9748.8 2992.8 3143.9 3649.9 3750.6 3846.5 821.0 896.8 904.4 991.1 1015.9 21227.6 21673.8 21786.8 21907.0 21993.9 932.1 947.2 951.9 972.1 983.9 10982.4 11283.9 11462.4 11515.5 11538.0 559822.0 573105.0 575507.0 578917.0 590534.0 127712.0 130425.0 131425.0 131746.3 136276.0 956.5 962.2 996.9 997.9 998.5 18959.6 19152.0 19256.7 19298.3 19330.6 36600.6 53784.8 54688.9 55643.3 55719.7 68860.6 73556.6 75525.6 79735.6 81115.6 8019.6 8243.0 8303.0 8353.3 8534.0 12539.3 12944.6 13079.1 13357.0 13446.7 11168.5 11367.0 11603.8 11666.0 11726.8 11029.3 11293.5 11458.1 11831.5 11861.3 11538.5 11613.5 11757.6 11853.3 11900.5 8241.7 8268.5 8299.0 8332.7 8472.1 9517.0 11368.1 11530.3 11554.0 11595.0 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5673.73 Median:4922.90 stddev:3193.00 (64.9%) stddev-sans-first:3186.44 05:51:53 INFO - PROCESS | 1659 | Values: 1484.6 1498.1 1687.4 1842.9 1866.0 8382.7 8924.1 8925.6 8934.7 8946.2 7810.3 8066.0 8477.7 8664.6 8712.3 2376.6 2400.5 2401.3 2402.4 2408.0 2175.3 2289.7 2367.6 2388.2 2413.1 2081.8 2168.3 2312.9 2393.0 2399.4 2373.4 2382.8 2403.3 2404.4 2411.4 2385.5 2387.4 2388.7 2389.3 2394.1 8651.1 8856.0 8863.4 8865.4 8874.6 5203.6 5351.3 5352.3 5354.6 5356.7 3054.2 3514.2 3666.8 3724.2 3752.1 1060.0 1106.1 1110.1 1111.7 1133.3 11542.4 11657.7 11664.8 11677.6 11687.3 4157.9 4369.9 4615.9 4809.9 4811.9 9854.2 9857.0 9861.0 9867.1 9900.8 5340.7 5354.3 5368.8 5368.8 5381.2 4795.5 4868.1 4890.1 4897.8 4917.7 3831.2 3855.4 3884.5 3918.8 3941.7 1174.3 1265.5 1266.6 1273.7 1286.7 784.6 794.4 869.3 890.8 948.6 5488.2 5630.9 5687.8 5864.2 5901.1 716.0 883.6 887.2 923.9 953.1 3772.4 3784.4 3803.4 3879.8 3907.0 9826.5 10094.4 10523.1 10903.8 10927.1 10806.8 10814.9 10820.6 10823.4 10834.5 7768.0 8167.1 8319.2 8328.0 8331.4 5647.9 5818.6 5844.3 5857.0 5876.9 9194.7 9840.1 9869.6 10130.8 10240.3 10143.2 10207.5 10207.7 10211.6 10319.2 7446.0 7505.3 7589.2 7592.8 7606.4 8155.3 8157.7 8258.9 8261.3 8276.9 7356.5 7436.5 7437.8 7572.6 7646.5 5742.4 7274.9 7323.4 7341.6 7366.7 6944.3 6954.5 6964.7 7045.3 7059.5 4842.8 4878.7 4928.1 4955.9 5029.5 3981.9 4179.8 4503.7 5093.3 5191.4 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4243.89 Median:4616.61 stddev:1313.16 (28.4%) stddev-sans-first:1282.26 05:51:53 INFO - PROCESS | 1659 | Values: 255.0 282.9 311.2 315.3 324.2 4752.5 5367.5 5371.3 5441.5 5442.5 5086.5 5231.8 5266.5 5297.3 5370.6 3306.8 3838.8 4002.9 4013.5 4025.3 3635.3 3653.2 3790.7 3888.0 4020.5 3217.4 3660.9 3713.3 3999.8 4027.4 4022.2 4023.7 4032.5 4052.7 4080.2 2808.4 3019.5 3380.2 3498.6 3531.7 4369.7 5078.9 5086.7 5125.7 5127.0 4386.3 4546.1 4588.6 4598.4 4611.6 4569.8 5380.4 5438.6 5554.9 5587.8 3289.7 3486.8 3617.6 3845.9 3866.6 5263.2 5541.7 5659.4 5799.1 5869.7 3407.7 3839.5 3930.4 3933.9 3938.6 3607.6 3642.1 3674.7 3908.6 3924.7 3991.6 4269.3 4405.4 4667.9 4684.2 5646.7 5656.4 5664.5 5665.0 5667.3 1161.7 1201.5 1294.8 1352.8 1370.6 681.6 732.4 786.8 825.7 847.7 3779.8 3808.8 3919.4 4195.8 4310.5 3826.3 4396.0 4826.2 4833.8 4840.2 4211.0 4243.9 4342.7 4387.6 4398.2 2091.4 2203.6 2327.8 2452.0 2516.0 4560.5 4647.9 4850.5 4974.7 4976.7 4261.9 4643.7 4722.7 4927.3 4932.5 3941.7 4843.9 4940.4 4949.6 4974.8 4088.6 4455.5 4505.2 4821.1 4852.5 4100.6 4542.6 4781.6 4792.0 4814.8 4621.6 4707.9 5301.9 5701.6 5764.4 3935.3 3948.4 3970.9 3971.0 3972.9 4971.7 5175.9 5821.2 5827.0 5856.4 4760.7 4935.4 5030.4 5308.0 5574.7 4771.5 4818.9 5495.6 5532.8 5648.7 4579.5 5237.5 5466.7 5504.2 5515.5 4644.5 4857.7 5171.0 5496.9 5631.7 5481.0 5490.3 5497.6 5523.3 5531.3 05:51:53 INFO - PROCESS | 1659 | 05:51:53 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25336.24 Median:22696.22 stddev:40364.94 (177.8%) stddev-sans-first:40435.51 05:51:53 INFO - PROCESS | 1659 | Values: 618.2 702.8 728.0 764.4 809.7 15082.2 15506.5 15691.3 15721.5 15731.9 14034.2 14256.9 14257.1 14273.1 14687.4 678.3 691.5 840.1 844.9 873.5 853.4 862.1 868.8 869.3 870.2 919.7 924.4 925.3 928.0 928.0 706.6 891.9 917.4 921.2 921.8 683.4 685.4 686.0 686.4 688.0 14630.0 14634.8 14643.3 14664.9 14668.9 13425.5 13500.2 13760.4 13763.9 13891.4 11523.1 11655.5 11739.4 11755.2 11889.6 4404.7 4457.6 4484.0 4502.6 4597.4 116655.0 117979.0 119875.8 121297.8 121729.4 10822.8 10859.8 10902.5 10935.3 11284.2 21718.8 22008.7 22200.0 22682.9 22709.5 7791.7 7968.9 8205.0 8212.0 8288.3 4393.8 4459.9 4499.6 4527.7 4690.4 3325.2 3428.7 3432.8 3434.2 3441.4 654.4 655.9 665.6 670.8 678.5 617.8 624.7 625.1 639.7 654.7 8427.7 9034.3 9204.9 9222.9 9252.3 539.5 576.3 602.9 609.9 616.4 4388.8 4429.4 4453.7 4585.9 4909.9 63024.7 97414.5 136155.0 148464.3 157912.0 136835.0 139115.3 140581.0 144864.0 145264.3 106396.0 116754.8 120043.0 120428.4 122995.0 9342.0 9371.0 9566.7 9948.5 10051.2 63725.0 64505.0 64974.1 65121.7 66585.3 98674.0 99078.0 100067.5 101808.3 104064.0 12369.9 13096.9 13376.2 13507.2 13652.0 14957.2 15231.1 15290.7 15443.2 15538.5 14115.8 14323.1 14361.9 14415.2 14566.6 13808.6 14151.4 14236.6 14524.0 14781.5 13823.7 14078.9 14357.9 14454.6 14643.6 10800.5 11150.2 11243.7 11339.6 11480.8 11020.4 11400.4 11424.8 11505.4 11695.1 05:51:53 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:51:53 INFO - PROCESS | 1659 | 05:51:54 INFO - TEST-INFO | 1659: exit 0 05:51:54 INFO - TEST-OK | dromaeo_css | took 1118045ms 05:51:54 INFO - TEST-START | kraken 05:51:54 INFO - operating with platform_type : mac_ 05:51:54 INFO - Initialising browser for kraken test... 05:51:54 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpKJICQw/profile http://localhost:49271/getInfo.html) 05:51:56 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 05:51:56 INFO - PROCESS | 1664 | colorDepth:24 05:51:56 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 05:51:56 INFO - PROCESS | 1664 | __metrics 05:51:56 INFO - PROCESS | 1664 | 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" 05:51:56 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:51:57 INFO - TEST-INFO | 1664: exit 0 05:51:57 INFO - Browser initialized. 05:51:57 INFO - Running cycle 1/1 for kraken test... 05:51:57 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/T/tmpKJICQw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:52:09 INFO - PROCESS | 1666 | RSS: Main: 233611264 05:52:09 INFO - PROCESS | 1666 | 05:52:12 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 05:52:13 INFO - PROCESS | 1666 | RSS: Main: 290996224 05:52:13 INFO - PROCESS | 1666 | 05:52:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 05:52:16 INFO - PROCESS | 1666 | RSS: Main: 287997952 05:52:16 INFO - PROCESS | 1666 | 05:52:20 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 05:52:20 INFO - PROCESS | 1666 | RSS: Main: 378675200 05:52:20 INFO - PROCESS | 1666 | 05:52:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 05:52:23 INFO - PROCESS | 1666 | RSS: Main: 280514560 05:52:23 INFO - PROCESS | 1666 | 05:52:25 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:52:26 INFO - PROCESS | 1666 | RSS: Main: 283922432 05:52:26 INFO - PROCESS | 1666 | 05:52:28 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 05:52:29 INFO - PROCESS | 1666 | RSS: Main: 324218880 05:52:29 INFO - PROCESS | 1666 | 05:52:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 05:52:33 INFO - PROCESS | 1666 | RSS: Main: 324444160 05:52:33 INFO - PROCESS | 1666 | 05:52:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 05:52:36 INFO - PROCESS | 1666 | RSS: Main: 322695168 05:52:36 INFO - PROCESS | 1666 | 05:52:38 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:52:38 INFO - PROCESS | 1666 | RSS: Main: 297353216 05:52:38 INFO - PROCESS | 1666 | 05:52:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:52:41 INFO - PROCESS | 1666 | RSS: Main: 303390720 05:52:41 INFO - PROCESS | 1666 | 05:52:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:52:44 INFO - PROCESS | 1666 | RSS: Main: 300597248 05:52:44 INFO - PROCESS | 1666 | 05:52:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:52:47 INFO - PROCESS | 1666 | RSS: Main: 322629632 05:52:47 INFO - PROCESS | 1666 | 05:52:50 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:52:51 INFO - PROCESS | 1666 | RSS: Main: 318836736 05:52:51 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 05:52:53 INFO - PROCESS | 1666 | RSS: Main: 361979904 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | __start_tp_report 05:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 05:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 05:52:53 INFO - PROCESS | 1666 | |i|pagename|runs| 05:52:53 INFO - PROCESS | 1666 | |0;ai-astar;112;113;128;113;112;114;111;112;117;165 05:52:53 INFO - PROCESS | 1666 | |1;audio-beat-detection;147;119;158;160;107;150;159;129;159;107 05:52:53 INFO - PROCESS | 1666 | |2;audio-dft;186;182;186;184;184;179;181;184;182;185 05:52:53 INFO - PROCESS | 1666 | |3;audio-fft;79;73;75;153;58;58;59;67;139;56 05:52:53 INFO - PROCESS | 1666 | |4;audio-oscillator;81;81;135;69;74;84;119;55;85;142 05:52:53 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;87;84;86;83;86;83;87;84;104;131 05:52:53 INFO - PROCESS | 1666 | |6;imaging-darkroom;94;93;94;94;93;93;93;95;93;93 05:52:53 INFO - PROCESS | 1666 | |7;imaging-desaturate;90;94;90;90;90;87;91;99;134;86 05:52:53 INFO - PROCESS | 1666 | |8;json-parse-financial;55;56;54;54;51;54;53;53;52;52 05:52:53 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;66;66;73;80;78;72;69;69;69;69 05:52:53 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;85;89;94;109;105;104;85;84;86;85 05:52:53 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;140;138;149;127;145;154;152;152;183;140 05:52:53 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;169;171;171;170;170;169;167;229;182;170 05:52:53 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;72;87;89;87;87;72;67;66;67;68 05:52:53 INFO - PROCESS | 1666 | __end_tp_report 05:52:53 INFO - PROCESS | 1666 | __start_cc_report 05:52:53 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2053 05:52:53 INFO - PROCESS | 1666 | __end_cc_report 05:52:53 INFO - PROCESS | 1666 | __startTimestamp1472215973464__endTimestamp 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | ------- Summary: start ------- 05:52:53 INFO - PROCESS | 1666 | Number of tests: 14 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:119.70 Median:113.50 stddev:16.68 (14.7%) stddev-sans-first:17.46 05:52:53 INFO - PROCESS | 1666 | Values: 112.0 113.0 128.0 113.0 112.0 114.0 111.0 112.0 117.0 165.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:139.50 Median:154.00 stddev:21.94 (14.2%) stddev-sans-first:23.10 05:52:53 INFO - PROCESS | 1666 | Values: 147.0 119.0 158.0 160.0 107.0 150.0 159.0 129.0 159.0 107.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:183.30 Median:184.00 stddev:2.26 (1.2%) stddev-sans-first:2.18 05:52:53 INFO - PROCESS | 1666 | Values: 186.0 182.0 186.0 184.0 184.0 179.0 181.0 184.0 182.0 185.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:81.70 Median:63.00 stddev:34.98 (55.5%) stddev-sans-first:37.08 05:52:53 INFO - PROCESS | 1666 | Values: 79.0 73.0 75.0 153.0 58.0 58.0 59.0 67.0 139.0 56.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:77.50 stddev:29.16 (37.6%) stddev-sans-first:30.63 05:52:53 INFO - PROCESS | 1666 | Values: 81.0 81.0 135.0 69.0 74.0 84.0 119.0 55.0 85.0 142.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:85.00 stddev:15.18 (17.9%) stddev-sans-first:16.02 05:52:53 INFO - PROCESS | 1666 | Values: 87.0 84.0 86.0 83.0 86.0 83.0 87.0 84.0 104.0 131.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.50 stddev:0.71 (0.8%) stddev-sans-first:0.73 05:52:53 INFO - PROCESS | 1666 | Values: 94.0 93.0 94.0 94.0 93.0 93.0 93.0 95.0 93.0 93.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:90.00 stddev:14.14 (15.7%) stddev-sans-first:14.87 05:52:53 INFO - PROCESS | 1666 | Values: 90.0 94.0 90.0 90.0 90.0 87.0 91.0 99.0 134.0 86.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:54.00 stddev:1.51 (2.8%) stddev-sans-first:1.48 05:52:53 INFO - PROCESS | 1666 | Values: 55.0 56.0 54.0 54.0 51.0 54.0 53.0 53.0 52.0 52.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:70.50 stddev:4.72 (6.7%) stddev-sans-first:4.64 05:52:53 INFO - PROCESS | 1666 | Values: 66.0 66.0 73.0 80.0 78.0 72.0 69.0 69.0 69.0 69.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:92.60 Median:85.00 stddev:9.77 (11.5%) stddev-sans-first:9.96 05:52:53 INFO - PROCESS | 1666 | Values: 85.0 89.0 94.0 109.0 105.0 104.0 85.0 84.0 86.0 85.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:150.50 stddev:14.80 (9.8%) stddev-sans-first:15.41 05:52:53 INFO - PROCESS | 1666 | Values: 140.0 138.0 149.0 127.0 145.0 154.0 152.0 152.0 183.0 140.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:170.50 stddev:18.78 (11.0%) stddev-sans-first:19.71 05:52:53 INFO - PROCESS | 1666 | Values: 169.0 171.0 171.0 170.0 170.0 169.0 167.0 229.0 182.0 170.0 05:52:53 INFO - PROCESS | 1666 | 05:52:53 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.20 Median:79.50 stddev:9.94 (12.5%) stddev-sans-first:10.43 05:52:53 INFO - PROCESS | 1666 | Values: 72.0 87.0 89.0 87.0 87.0 72.0 67.0 66.0 67.0 68.0 05:52:53 INFO - PROCESS | 1666 | -------- Summary: end -------- 05:52:53 INFO - PROCESS | 1666 | 05:52:54 INFO - TEST-INFO | 1666: exit 0 05:52:54 INFO - TEST-OK | kraken | took 59896ms 05:52:54 INFO - SUITE-END | took 1177s 05:52:54 INFO - Completed test suite (00:19:38) 05:52:54 INFO - javascript benchmark 05:52:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3395.7622966392355, 3401.5577974863268, 3406.5912090549123, 3446.603640581334, 3486.7559270851034, 4829.661923665345, 5720.22776925115, 5832.650040997532, 5854.238948936638, 5945.019071853145, 5846.21076154719, 5905.616134951236, 5906.26171728534, 5913.0, 5913.053911374181, 5310.283111779899, 5348.946510534904, 5386.542143917772, 5395.757190926398, 5439.945600544003, 5432.266644003049, 5445.945540544603, 5453.836384908459, 5461.0, 5471.699056551908, 5323.520883120518, 5389.541888939445, 5390.51485366317, 5413.133898576229, 5441.673499590018, 5437.646552974044, 5459.071957767169, 5461.42655021225, 5463.9180412293845, 5529.972350138264, 5179.948200517984, 5288.312519372495, 5316.494932981361, 5384.7846086156505, 5448.0, 5867.559933005042, 5872.236609240814, 5888.911666325009, 5921.289445266552, 5963.344032156459, 6289.685515724242, 6295.748170073238, 6330.87338253237, 6342.873142537123, 6352.904706429407, 5872.471477566996, 6248.094026366197, 6256.436920677161, 6259.311475737665, 6367.108604795332, 5993.640381577119, 5999.820005399845, 6021.488173505301, 6052.515798736091, 6060.575759696867, 6457.031445283245, 6463.57986730861, 6466.902996455057, 6474.384933431316, 6476.514261430318, 6125.448709616156, 6203.596766210182, 6243.188385509855, 6309.3690630936535, 6313.526485513605, 6317.936820631827, 6322.241331040213, 6348.587341822767, 6356.4279214870885, 6474.417302442708, 6324.0, 6340.985442329205, 6362.363763623601, 6364.64994425311, 6374.9681251594375, 6109.526129009804, 6123.173371594867, 6161.7535298588455, 6316.715747791361, 6345.397187267202, 4990.0768357853185, 4992.051510213048, 4996.400431948189, 4997.750112494361, 5003.199488081962, 3564.7326450516316, 4505.053938672915, 4517.186906356887, 4519.7740112994215, 4533.342665313577, 5863.970680146572, 5896.292444906553, 5897.2038774765715, 5917.497012753878, 5937.732802023833, 6246.77643324739, 6275.184226050585, 6311.747530098836, 6312.116303717483, 6313.526485513605, 5771.5671324649975, 5844.0, 5905.704714764244, 5909.674967876808, 5964.224650795456, 5522.502974732293, 5561.304836895388, 5570.303712035996, 5582.33012038558, 5639.520640745501, 6453.225612926479, 6502.902456463157, 6515.28331883502, 6529.640869752209, 6549.410553050248, 6234.532410069172, 6307.684615769102, 6326.051092336186, 6326.810195694136, 6349.6507692077375, 6382.393672601095, 6386.552941294251, 6430.2926678066315, 6511.34886511345, 6567.9014814776865, 6319.494440444661, 6336.936630633634, 6343.397377249154, 6346.0, 6346.619202847844, 6179.81460556184, 6184.5052395809125, 6315.5894866833505, 6316.810495685137, 6538.346165383614, 6158.661273629993, 6225.657588832658, 6229.781957631461, 6235.002399616221, 6284.685765711604, 6258.499320054294, 6260.937390626035, 6271.529635277281, 6271.749130034747, 6275.278342990615, 6028.487578555872, 6158.538109641705, 6167.074938759042, 6170.753169873155, 6175.567710260419, 6123.252118380239, 6151.384861513813, 6199.628022318675, 6231.563790534619, 6296.874062518816, 6071.241094863142, 6079.635221886701, 6091.269047714302, 6155.538334625011, 6167.352427994996], "value": 5832.670051208312, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.83868323453265, 516.6512603992305, 526.2106839740391, 533.0192445899546, 567.7870798450581, 30433.847830760842, 31468.842655786717, 39828.20343593127, 40298.0, 40310.0, 40973.79513102438, 41265.58734412655, 41269.58730412695, 41364.58635413645, 41456.792716036376, 9564.0, 9717.174040206592, 9727.854082188773, 9732.2214222862, 9737.464439455827, 8311.667533298662, 9686.176674982635, 9770.804583908353, 9804.117629413351, 9804.117629413351, 9753.804923901518, 9805.411675299469, 9808.656697015605, 9820.852687209697, 9837.754056148582, 9745.269104817131, 9793.0, 9802.705918822447, 9814.90185098147, 9816.116549510578, 9678.56446459911, 9736.75658108546, 9742.902570974271, 9758.0, 9771.21830253578, 39742.20515589701, 39914.40128398076, 39980.40029399561, 40001.19997600061, 40086.198276034316, 3379.831008449568, 3473.7394695397948, 3497.3005398920063, 3499.475078738184, 3580.8183299511097, 31362.0, 31740.0, 31785.0, 31823.681763182536, 31870.840645796623, 3213.8714451421915, 3364.327134573071, 3390.0677313738674, 3414.9146271343166, 3421.3157368526395, 486212.0, 486258.0, 486517.0, 487532.0, 491948.0, 56739.0, 57092.143617845766, 57108.0, 57131.42868571261, 57132.71433642805, 51977.0, 57922.71038644864, 59344.70327648334, 59470.70264648649, 59825.0, 15673.529794106194, 15786.289616967264, 15866.127362995147, 15866.603334916488, 15888.523344299689, 12263.448144833505, 12332.445039973223, 12342.938285308695, 12343.321117338633, 12352.75294494123, 15144.318505667272, 15666.921665391828, 15674.9216253918, 15692.723496854878, 15770.0, 6037.094435834572, 6513.2509761377105, 6909.0, 6949.339812717783, 7086.610236436943, 852.3351785607236, 918.5085979001202, 946.7680418297562, 963.0658261486348, 1058.30680904008, 28825.711742882722, 29054.837806487503, 29065.564016539767, 29952.801887924674, 30109.0, 1069.4866464097277, 1083.4582708645678, 1136.1933027550367, 1167.511685019413, 1169.736809217936, 12024.33866137371, 12146.574869879689, 14316.856831431553, 15202.277956398899, 15519.379224831106, 633885.0, 641845.0, 642322.0, 643390.0, 645537.0, 56756.71621641948, 56802.14796778052, 56897.14654280189, 56913.01608887421, 57057.0, 1134.4101067444717, 1210.7215340471564, 1263.431455844855, 1378.5244090788663, 1409.2460533614467, 27503.31241718973, 28316.15051548457, 30543.0, 30586.694133059274, 32012.6798732019, 36130.45804312885, 47814.28278575894, 47907.44879236467, 47922.0, 47939.041219174724, 450832.0, 451010.0, 451073.0, 451288.0, 452942.0, 26383.34041648974, 27200.319992000357, 27266.454670906074, 39442.0, 39871.60128398679, 44813.32780008368, 44824.65526034224, 45016.77491612521, 45500.54499454963, 47212.0, 38577.6142238574, 38676.033099172746, 38916.0, 39800.40299395569, 40464.1907161861, 38279.61720382872, 38816.80591597024, 38901.805490972365, 40088.39867402049, 40318.39522407225, 30119.548206777356, 36217.63782362248, 37652.81173594115, 38594.6140538591, 38673.0, 28415.0, 32790.5081423774, 34068.65931340655, 34782.65217347794, 34918.0, 22227.555448891257, 25107.372315692253, 25115.744212788923, 25861.482770344865, 26042.73957260479], "value": 19498.394460811407, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.5131542813203, 374.87816459650605, 376.55566431610697, 384.571203108534, 403.38081045595015, 15671.216439178039, 15985.680286394265, 19210.519737006583, 20932.790672093273, 21077.683834742453, 17813.82186178138, 21476.355709328705, 21824.0, 21826.23608173714, 22142.778572214273, 6344.2386913570435, 6371.362863713614, 6401.295857455676, 6861.691223894924, 7191.137063552354, 4686.367340409052, 6009.759609615632, 6977.33715297046, 7086.964565177167, 7089.078419805418, 4805.927911081337, 8133.0, 8139.511629302231, 8152.1440248773615, 8158.755237342889, 7800.180980996999, 8059.838803223903, 8095.635696393618, 8102.0, 8118.594070296491, 7578.696852125963, 7619.4285428593075, 7759.068911730572, 7763.223677632248, 7847.882281765778, 23506.76493235063, 28532.714672853213, 28891.0, 29733.405331893242, 30004.54993175104, 2432.939176520601, 2488.950220995588, 2671.505771432272, 2675.919722408331, 3221.5328777327154, 16749.24628391713, 16766.497005089866, 17116.82883171165, 17644.382446614305, 17704.937703737818, 1958.7159861820098, 2028.9594208115755, 2092.4455019419865, 2097.6031652931256, 2177.42298290953, 131276.37447251097, 132524.0, 143034.0, 147845.0, 153446.0, 16630.33478660864, 17557.64884702299, 20908.68636970447, 21189.470263243544, 21382.786172138392, 34977.825110874284, 35097.47353789695, 35196.47205291923, 35214.823925880206, 35428.822855885555, 8807.867881981776, 10381.792364152825, 10555.525001374957, 10738.624148154777, 10740.30188037775, 6971.965140174267, 7315.122185337794, 8801.251893589117, 8944.642214311485, 9015.098490150933, 9148.8627670585, 9569.090936361035, 9636.614535418505, 9647.0, 9748.805023899482, 2992.7755418343713, 3143.883921207984, 3649.8905032849057, 3750.643688849555, 3846.519185101862, 820.9753707388788, 896.7758060484879, 904.3850181876255, 991.0773100185617, 1015.8780946286493, 21227.575448490945, 21673.783262167177, 21786.782132178792, 21907.014184361742, 21993.890030550065, 932.1098351074708, 947.2138125355938, 951.8905325887612, 972.0801417201112, 983.8819341679044, 10982.430710019276, 11283.866304429004, 11462.369569673581, 11515.539378424937, 11537.961583457529, 559822.0, 573105.0, 575507.0, 578917.0, 590534.0, 127712.0, 130425.0, 131425.0, 131746.34126829496, 136275.95586066216, 956.4930586789013, 962.2494454325637, 996.8903420623726, 997.8952210017989, 998.5257002923553, 18959.620807584048, 19152.0, 19256.711149333052, 19298.32455864038, 19330.613387732446, 36600.63399365972, 53784.84860605532, 54688.90622187613, 55643.27032202146, 55719.72140139274, 68860.62278754296, 73556.63221683857, 75525.62237188779, 79735.60132199302, 81115.59442202751, 8019.558924259221, 8242.958785206276, 8303.04514980785, 8353.33173346143, 8534.0, 12539.310337931502, 12944.61166164979, 13079.078424965968, 13357.0, 13446.663833404244, 11168.497417616065, 11367.0, 11603.825942610698, 11666.0, 11726.824097638713, 11029.283096598696, 11293.548258069914, 11458.08335333188, 11831.467583958914, 11861.347625880659, 11538.480768365276, 11613.477393517482, 11757.588484403004, 11853.288802671867, 11900.464479098287, 8241.711540096068, 8268.545230012407, 8299.045609754956, 8332.6666933322, 8472.110428404929, 9516.95241523788, 11368.090552755595, 11530.250533715618, 11554.017908477874, 11595.0], "value": 11081.52987215097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1484.5694748522928, 1498.086167437863, 1687.4431437625585, 1842.8986405747696, 1866.0296645744213, 8382.664693412264, 8924.107589241072, 8925.55372231389, 8934.687285944994, 8946.23956963658, 7810.336121429671, 8066.03207615087, 8477.745667629966, 8664.610092545834, 8712.346574006942, 2376.643503474484, 2400.531896280226, 2401.327628264089, 2402.4474370894704, 2407.987960060197, 2175.3365223606793, 2289.748127705951, 2367.550165468564, 2388.2477019738726, 2413.1433341163897, 2081.84386171037, 2168.3278183763005, 2312.8612283262974, 2393.0069021356203, 2399.3521749127726, 2373.371056669984, 2382.821288403368, 2403.291029146406, 2404.386881345263, 2411.44536756547, 2385.49904520051, 2387.403149212697, 2388.6655868178295, 2389.259329607827, 2394.078279862248, 8651.091635378258, 8855.955720221422, 8863.379563430562, 8865.423747456394, 8874.600642971081, 5203.557697595709, 5351.25082488452, 5352.277442545245, 5354.598405119632, 5356.678599284055, 3054.1906394805396, 3514.2268700885766, 3666.834992425347, 3724.1992971510986, 3752.1182522107283, 1059.9841002384971, 1106.0874778307848, 1110.0509064749604, 1111.660943412259, 1133.2633788037758, 11542.422878856023, 11657.708557286136, 11664.825027624593, 11677.649670509983, 11687.298762074302, 4157.854475093418, 4369.9126017479475, 4615.930761038587, 4809.879753006133, 4811.879703007383, 9854.211663066937, 9856.950715246521, 9860.950695246478, 9867.11195992364, 9900.752481187883, 5340.732963351817, 5354.30394048779, 5368.758405871745, 5368.785248590091, 5381.192821076792, 4795.520447955247, 4868.148074087091, 4890.070886531547, 4897.7795999180125, 4917.704937703749, 3831.1571454280015, 3855.4409610606303, 3884.4755957945317, 3918.804059796999, 3941.7043721720984, 1174.2602160638744, 1265.487477571585, 1266.5630357526638, 1273.7070473790895, 1286.6590353556314, 784.5606460382204, 794.412135020081, 869.2698133567707, 890.8351954888367, 948.6015873333216, 5488.204210389471, 5630.887382252332, 5687.759104336834, 5864.237649105589, 5901.085331773582, 715.9785206443711, 883.6288758721224, 887.2236792806294, 923.8959443464995, 953.0993211415284, 3772.358699021173, 3784.4323351496946, 3803.4294855771855, 3879.8254078566533, 3907.0, 9826.508674566243, 10094.394336339843, 10523.052925236765, 10903.781924361469, 10927.071198948019, 10806.783864322826, 10814.945925270324, 10820.56717731282, 10823.350598964087, 10834.458277086114, 7767.961160194276, 8167.14245004266, 8319.168083191633, 8328.0, 8331.375146863887, 5647.915281270866, 5818.6217895836635, 5844.327902291121, 5857.0, 5876.853078673067, 9194.701324668833, 9840.065193806577, 9869.555869986012, 10130.79738405213, 10240.334378265388, 10143.239257055602, 10207.54066067014, 10207.7448063799, 10211.642592509525, 10319.226058045526, 7445.9627701861145, 7505.324520793155, 7589.241075892587, 7592.772216833504, 7606.353459955744, 8155.306798922158, 8157.673693052211, 8258.876116858371, 8261.297789687704, 8276.875846862422, 7356.521826081287, 7436.516626419266, 7437.776866694008, 7572.583507907118, 7646.541207527566, 5742.397048309867, 7274.8908766369605, 7323.414126869944, 7341.6329183541675, 7366.6684999174095, 6944.305569443218, 6954.547954382949, 6964.721411143701, 7045.330693584101, 7059.505834591735, 4842.7820748065715, 4878.682885612424, 4928.088303663691, 4955.925661115014, 5029.471905450021, 3981.8606348777653, 4179.791010449526, 4503.662225333048, 5093.337866077386, 5191.351081114861], "value": 4553.526124351829, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [254.97195308516066, 282.9038127036807, 311.17383347213143, 315.26071362654574, 324.15880789351627, 4752.524747525245, 5367.46325367463, 5371.328583927009, 5441.537469315113, 5442.455754424555, 5086.465921078289, 5231.816886408977, 5266.52601265886, 5297.258383826267, 5370.597205209606, 3306.7514090418504, 3838.827252773625, 4002.939955900664, 4013.4983127109113, 4025.335819589764, 3635.327464419078, 3653.2145588698427, 3790.6732643574665, 3887.9611203888026, 4020.477337946063, 3217.3778370627865, 3660.8535658573624, 3713.3316003119426, 3999.820008099628, 4027.436158937751, 4022.224664981168, 4023.698222633285, 4032.451442730408, 4052.696047796427, 4080.204360149772, 2808.36811717364, 3019.4715924713305, 3380.2394461246267, 3498.5801703795705, 3531.7174626029855, 4369.722707969023, 5078.949210507884, 5086.745662716887, 5125.718085505296, 5127.026324731684, 4386.254336762758, 4546.113507865967, 4588.5585810642015, 4598.425196850394, 4611.584957353854, 4569.840055598038, 5380.435054319319, 5438.646487978269, 5554.944450555524, 5587.814945481036, 3289.654586268455, 3486.808225547594, 3617.6382361763876, 3845.9230815383535, 3866.574676785551, 5263.157894736823, 5541.722913854332, 5659.377468478464, 5799.0863015205305, 5869.706514674249, 3407.744419168572, 3839.520059992501, 3930.390789427643, 3933.8819835404984, 3938.5667576566557, 3607.6211997740384, 3642.0894776305922, 3674.7427680062137, 3908.5733707197237, 3924.6656136913016, 3991.640752332304, 4269.252880745919, 4405.383246345515, 4667.929981050287, 4684.180268453007, 5646.6894320811925, 5656.4060773619, 5664.51851592611, 5665.0, 5667.291588551431, 1161.7386088130104, 1201.5434135028634, 1294.838145231846, 1352.75650382932, 1370.595674276091, 681.6319187638619, 732.4067505320708, 786.7836345005169, 825.7398919340388, 847.6905929335747, 3779.829907654107, 3808.809559522013, 3919.374019407004, 4195.832166713359, 4310.486953133186, 3826.253880493333, 4395.955880515576, 4826.152569983657, 4833.830815921426, 4840.177169881129, 4211.020820214494, 4243.901951575305, 4342.653777329038, 4387.561243875587, 4398.186335527937, 2091.4039498742786, 2203.5703037907456, 2327.7672232776927, 2451.963220551693, 2515.955693610715, 4560.475545312265, 4647.907041859144, 4850.514948505192, 4974.726390048582, 4976.656302798297, 4261.914761704748, 4643.74459404736, 4722.70125715431, 4927.285543596158, 4932.457429682804, 3941.6875562802475, 4843.861692502259, 4940.382452193476, 4949.554540091337, 4974.801007959786, 4088.6115818997146, 4455.532169122195, 4505.189065968026, 4821.083994041121, 4852.495726324851, 4100.5899410058755, 4542.636589072932, 4781.641376896816, 4792.0, 4814.807407703653, 4621.6302695784925, 4707.905841883212, 5301.946980530145, 5701.572382071278, 5764.365919748908, 3935.271974684578, 3948.368261078214, 3970.861019864291, 3971.041574027368, 3972.927309626327, 4971.726555039508, 5175.896482070413, 5821.243238378984, 5826.970865145647, 5856.360219138648, 4760.666753327372, 4935.407751069894, 5030.4466508684745, 5307.951505888007, 5574.735327474405, 4771.4731285988155, 4818.927716084331, 5495.5878309127775, 5532.778688852562, 5648.661080335193, 4579.542045795527, 5237.476252374732, 5466.726663666721, 5504.22940788298, 5515.462887071992, 4644.5123262056995, 4857.732824694675, 5170.974145129401, 5496.917546236889, 5631.732860106444, 5481.040817856939, 5490.313710786152, 5497.581064331681, 5523.321189223143, 5531.280933478783], "value": 3825.252244268537, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [618.2056057965515, 702.7856503766351, 727.984461675962, 764.3999460423569, 809.7327881799007, 15082.245887705612, 15506.457273995413, 15691.293891774869, 15721.528354149365, 15731.921340393297, 14034.157950522977, 14256.857431425682, 14257.073290236154, 14273.072250303723, 14687.412503499874, 678.3182901184302, 691.5124836989935, 840.1094839470165, 844.9028361738386, 873.5457562067721, 853.4196746212573, 862.0689655172399, 868.7610907000594, 869.2871845087031, 870.1559487297343, 919.6643225222779, 924.3806649544821, 925.282905748044, 927.9953600232024, 928.0487500312166, 706.6325510734389, 891.8840550728429, 917.3716004536914, 921.2215677752322, 921.8478950973081, 683.3712984054681, 685.4242436353487, 685.9725610975555, 686.3513979289573, 687.9783521470612, 14630.0, 14634.780478292834, 14643.34104965279, 14664.926675366554, 14668.926655366762, 13425.530106446227, 13500.191495605513, 13760.380782864695, 13763.862361376358, 13891.381849374546, 11523.135764817589, 11655.533778648844, 11739.434508941935, 11755.235909665844, 11889.583864564698, 4404.69167158299, 4457.64338852891, 4483.968687201962, 4502.57225563571, 4597.402337696078, 116655.0, 117979.0, 119875.80124198734, 121297.78702212953, 121729.39135304355, 10822.837657435144, 10859.782804344028, 10902.454877256265, 10935.28920620157, 11284.210105292716, 21718.782812171678, 22008.669869951962, 22200.0, 22682.886585567296, 22709.545809084055, 7791.727289544952, 7968.920310796933, 8205.01539815226, 8212.014558252928, 8288.336933045342, 4393.846215382447, 4459.888502787457, 4499.550044995474, 4527.660425468103, 4690.381818272691, 3325.185329594265, 3428.6914177723625, 3432.845521951518, 3434.175797808558, 3441.3633477806684, 654.4012228810681, 655.9016147577902, 665.6205962601271, 670.8423520472685, 678.5318130489946, 617.8177437655902, 624.7032659486707, 625.1060982794556, 639.667372966055, 654.6988385342701, 8427.747167584983, 9034.277257819358, 9204.907950920538, 9222.907770922338, 9252.259819214447, 539.4955716405095, 576.3026737647493, 602.9005214139618, 609.8780243951228, 616.4174854762207, 4388.802503887333, 4429.446319210098, 4453.702177398761, 4585.862424127282, 4909.926351104737, 63024.684876575324, 97414.5129274349, 136155.0, 148464.25767871307, 157912.0, 136835.0, 139115.30442347925, 140581.0, 144864.0, 145264.27367863094, 106396.0, 116754.83245167609, 120042.99355096805, 120428.3978580119, 122995.0, 9341.953290233505, 9370.95314523423, 9566.66516671941, 9948.452835093849, 10051.246156538142, 63725.0, 64505.0, 64974.10155390692, 65121.67439162963, 66585.33414665791, 98674.0, 99078.0, 100067.49561278493, 101808.3020670577, 104064.0, 12369.93815030919, 13096.934515327363, 13376.22570445242, 13507.189568625521, 13652.0, 14957.17735524513, 15231.086134831945, 15290.694186116438, 15443.209839567724, 15538.533843984698, 14115.788263175855, 14323.14061156334, 14361.85105191603, 14415.207163606103, 14566.563003109924, 13808.585742427744, 14151.363188656329, 14236.572902812932, 14524.0, 14781.482648107694, 13823.723525529635, 14078.859211408164, 14357.9282103593, 14454.638634034232, 14643.633909152357, 10800.459977001276, 11150.219484635998, 11243.66269011931, 11339.603113890975, 11480.770384592095, 11020.44897755125, 11400.380408939913, 11424.828627570427, 11505.367204803817, 11695.064394848561], "value": 7921.7602401758295, "unit": "score"}], "name": "dromaeo_css", "value": 7471.212272819679, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 128.0, 113.0, 112.0, 114.0, 111.0, 112.0, 117.0, 165.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 119.0, 158.0, 160.0, 107.0, 150.0, 159.0, 129.0, 159.0, 107.0], "value": 139.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 182.0, 186.0, 184.0, 184.0, 179.0, 181.0, 184.0, 182.0, 185.0], "value": 183.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 75.0, 153.0, 58.0, 58.0, 59.0, 67.0, 139.0, 56.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 135.0, 69.0, 74.0, 84.0, 119.0, 55.0, 85.0, 142.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 86.0, 83.0, 86.0, 83.0, 87.0, 84.0, 104.0, 131.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 94.0, 93.0, 93.0, 93.0, 95.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 94.0, 90.0, 90.0, 90.0, 87.0, 91.0, 99.0, 134.0, 86.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 54.0, 54.0, 51.0, 54.0, 53.0, 53.0, 52.0, 52.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 73.0, 80.0, 78.0, 72.0, 69.0, 69.0, 69.0, 69.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 94.0, 109.0, 105.0, 104.0, 85.0, 84.0, 86.0, 85.0], "value": 92.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 149.0, 127.0, 145.0, 154.0, 152.0, 152.0, 183.0, 140.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 171.0, 170.0, 170.0, 169.0, 167.0, 229.0, 182.0, 170.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 87.0, 89.0, 87.0, 87.0, 72.0, 67.0, 66.0, 67.0, 68.0], "value": 76.2, "unit": "score"}], "name": "kraken", "value": 1514.9, "alertThreshold": 2.0}]} 05:52:54 INFO - Return code: 0 05:52:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:52:54 INFO - # TBPL SUCCESS # 05:52:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:52:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:52:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:52:54 INFO - 2016-08-26 05:52:54.793 screenresolution[1670:9793] starting screenresolution argv=screenresolution get 05:52:54 INFO - 2016-08-26 05:52:54.797 screenresolution[1670:9793] Display 0: 1600x1200x32@60 05:52:54 INFO - 2016-08-26 05:52:54.803 screenresolution[1671:9796] starting screenresolution argv=screenresolution list 05:52:54 INFO - Available Modes on Display 0 05:52:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:52:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:52:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:52:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:52:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:52:55 INFO - Intel Iris: 05:52:55 INFO - Chipset Model: Intel Iris 05:52:55 INFO - Type: GPU 05:52:55 INFO - Bus: Built-In 05:52:55 INFO - VRAM (Dynamic, Max): 1536 MB 05:52:55 INFO - Vendor: Intel (0x8086) 05:52:55 INFO - Device ID: 0x0a2e 05:52:55 INFO - Revision ID: 0x0009 05:52:55 INFO - Displays: 05:52:55 INFO - Display: 05:52:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:52:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:52:55 INFO - Display Serial Number: 1600x1200 60 05:52:55 INFO - Main Display: Yes 05:52:55 INFO - Mirror: Off 05:52:55 INFO - Online: Yes 05:52:55 INFO - Rotation: Supported 05:52:55 INFO - Return code: 0 05:52:55 INFO - Running post-action listener: _resource_record_post_action 05:52:55 INFO - [mozharness: 2016-08-26 12:52:55.044765Z] Finished run-tests step (success) 05:52:55 INFO - Running post-run listener: _resource_record_post_run 05:52:55 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 32930816; Write bytes: 708215808; Read time: 313; Write time: 1971 05:52:55 INFO - TinderboxPrint: CPU usage
53.6% 05:52:55 INFO - TinderboxPrint: I/O read bytes / time
32,930,816 / 313 05:52:55 INFO - TinderboxPrint: I/O write bytes / time
708,215,808 / 1,971 05:52:55 INFO - TinderboxPrint: CPU idle
2,251.1 (46.4%) 05:52:55 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 05:52:55 INFO - TinderboxPrint: CPU user
1,631.8 (33.6%) 05:52:55 INFO - TinderboxPrint: Swap in / out
436,035,584 / 0 05:52:55 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404032000; Write bytes: 398960640; Read time: 29880; Write time: 741 05:52:55 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30739456; Write bytes: 296602624; Read time: 284; Write time: 1196 05:52:55 INFO - Running post-run listener: _upload_blobber_files 05:52:55 INFO - Blob upload gear active. 05:52:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:52:55 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 05:52:55 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'] 05:52:55 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 05:52:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:52:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:52:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:52:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:57 INFO - (blobuploader) - INFO - Done attempting. 05:52:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:52:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:52:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:52:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:52:57 INFO - (blobuploader) - INFO - Done attempting. 05:52:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:52:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:52:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:53:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:53:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:53:01 INFO - (blobuploader) - INFO - Done attempting. 05:53:01 INFO - (blobuploader) - INFO - Iteration through files over. 05:53:01 INFO - Return code: 0 05:53:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:53:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:53:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b3990de74511a868ccbb5785a762d062fd1d94ca03aefb4cc6374158307472524e0e9813aec7477c7a9d8b10c8a11789b22b0384cc83efef91e9d12be8e143", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f70d1837c86a61bc4e2d7b66c60f2b13c0863c87791c11121ea34cce414593cb35c83c7c7deec9cf0d545353e81d22d1f6dedd82e3bdacdb0fce58c3b094384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df4590e7502d047781dda6783143accb6e0a388dd95ab0b5120fd8af6bda9bd6770c28a4e9be3a55645bf71c7a7420766763d54944512a1a35b2b21513f513e2"} 05:53:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:53:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:53:01 INFO - Contents: 05:53:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b3990de74511a868ccbb5785a762d062fd1d94ca03aefb4cc6374158307472524e0e9813aec7477c7a9d8b10c8a11789b22b0384cc83efef91e9d12be8e143", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f70d1837c86a61bc4e2d7b66c60f2b13c0863c87791c11121ea34cce414593cb35c83c7c7deec9cf0d545353e81d22d1f6dedd82e3bdacdb0fce58c3b094384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df4590e7502d047781dda6783143accb6e0a388dd95ab0b5120fd8af6bda9bd6770c28a4e9be3a55645bf71c7a7420766763d54944512a1a35b2b21513f513e2"} 05:53:01 INFO - Running post-run listener: copy_logs_to_upload_dir 05:53:01 INFO - Copying logs to upload dir... 05:53:01 INFO - mkdir: /builds/slave/test/build/upload/logs 05:53:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.328943 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 05:53:01.140469) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 05:53:01.144363) ========= 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.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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/b8b3990de74511a868ccbb5785a762d062fd1d94ca03aefb4cc6374158307472524e0e9813aec7477c7a9d8b10c8a11789b22b0384cc83efef91e9d12be8e143", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f70d1837c86a61bc4e2d7b66c60f2b13c0863c87791c11121ea34cce414593cb35c83c7c7deec9cf0d545353e81d22d1f6dedd82e3bdacdb0fce58c3b094384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df4590e7502d047781dda6783143accb6e0a388dd95ab0b5120fd8af6bda9bd6770c28a4e9be3a55645bf71c7a7420766763d54944512a1a35b2b21513f513e2"} build_url:https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011161 build_url: 'https://queue.taskcluster.net/v1/task/S2xfZII1TO6En5ZAaPdJeQ/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/b8b3990de74511a868ccbb5785a762d062fd1d94ca03aefb4cc6374158307472524e0e9813aec7477c7a9d8b10c8a11789b22b0384cc83efef91e9d12be8e143", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f70d1837c86a61bc4e2d7b66c60f2b13c0863c87791c11121ea34cce414593cb35c83c7c7deec9cf0d545353e81d22d1f6dedd82e3bdacdb0fce58c3b094384c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df4590e7502d047781dda6783143accb6e0a388dd95ab0b5120fd8af6bda9bd6770c28a4e9be3a55645bf71c7a7420766763d54944512a1a35b2b21513f513e2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 05:53:01.177313) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 05:53:01.177620) ========= 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.dcszIEsAl6/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.bAxYoDRA3S/Listeners TMPDIR=/var/folders/j1/s9lsnw715dxdjl4nwk5gg7qw00000w/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.005208 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 05:53:01.228294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 05:53:01.228642) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 05:53:01.228979) ========= ========= Total master_lag: 0.92 =========