builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0362 starttime: 1467827415.66 results: success (0) buildid: 20160706083823 builduid: 69de8c4ee53f42f2a8f36c4fdb883228 revision: df4803bab0bc40c206c6977a8cc7fcaeac677357 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.658550) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.659019) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.659310) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.681939) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.682250) ========= 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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-07-06 10:50:15-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.20M=0.001s 2016-07-06 10:50:15 (8.20 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094451 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.798607) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.799006) ========= 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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.020650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.848010) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:15.848453) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev df4803bab0bc40c206c6977a8cc7fcaeac677357 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev df4803bab0bc40c206c6977a8cc7fcaeac677357 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-07-06 10:50:15,927 truncating revision to first 12 chars 2016-07-06 10:50:15,927 Setting DEBUG logging. 2016-07-06 10:50:15,927 attempt 1/10 2016-07-06 10:50:15,927 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/df4803bab0bc?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 10:50:16,219 unpacking tar archive at: fx-team-df4803bab0bc/testing/mozharness/ program finished with exit code 0 elapsedTime=0.570734 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:16.441917) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:16.442285) ========= script_repo_revision: df4803bab0bc40c206c6977a8cc7fcaeac677357 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:16.442708) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:16.442983) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 10:50:16.461016) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-06 10:50:16.461383) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50:16 INFO - MultiFileLogger online at 20160706 10:50:16 in /builds/slave/test 10:50:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 10:50:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:50:16 INFO - {'append_to_log': False, 10:50:16 INFO - 'base_work_dir': '/builds/slave/test', 10:50:16 INFO - 'blob_upload_branch': 'Fx-Team', 10:50:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:50:16 INFO - 'branch': 'Fx-Team', 10:50:16 INFO - 'buildbot_json_path': 'buildprops.json', 10:50:16 INFO - 'config_files': ('talos/mac_config.py',), 10:50:16 INFO - 'default_actions': ('clobber', 10:50:16 INFO - 'read-buildbot-config', 10:50:16 INFO - 'download-and-extract', 10:50:16 INFO - 'populate-webroot', 10:50:16 INFO - 'create-virtualenv', 10:50:16 INFO - 'install', 10:50:16 INFO - 'run-tests'), 10:50:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:50:16 INFO - 'download_minidump_stackwalk': True, 10:50:16 INFO - 'download_symbols': 'ondemand', 10:50:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:50:16 INFO - 'tooltool.py': '/tools/tooltool.py', 10:50:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:50:16 INFO - '/tools/misc-python/virtualenv.py')}, 10:50:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:50:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:50:16 INFO - 'installer_path': 'installer.exe', 10:50:16 INFO - 'log_level': 'info', 10:50:16 INFO - 'log_name': 'talos', 10:50:16 INFO - 'log_to_console': True, 10:50:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:50:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:50:16 INFO - 'opt_config_files': (), 10:50:16 INFO - 'pip_index': False, 10:50:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:50:16 INFO - 'cmd': ('bash', 10:50:16 INFO - '-c', 10:50:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:50:16 INFO - 'enabled': True, 10:50:16 INFO - 'halt_on_failure': False, 10:50:16 INFO - 'name': 'check_screen_resolution'},), 10:50:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:50:16 INFO - 'cmd': ('bash', 10:50:16 INFO - '-c', 10:50:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:50:16 INFO - 'enabled': True, 10:50:16 INFO - 'halt_on_failure': False, 10:50:16 INFO - 'name': 'check_screen_resolution'},), 10:50:16 INFO - 'run_cmd_checks_enabled': True, 10:50:16 INFO - 'sps_profile': False, 10:50:16 INFO - 'sps_profile_interval': 0, 10:50:16 INFO - 'suite': 'dromaeojs', 10:50:16 INFO - 'system_bits': '32', 10:50:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:50:16 INFO - 'title': 't-yosemite-r7-0362', 10:50:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:50:16 INFO - 'use_talos_json': True, 10:50:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:50:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:50:16 INFO - 'work_dir': 'build'} 10:50:16 INFO - ##### 10:50:16 INFO - ##### Running clobber step. 10:50:16 INFO - ##### 10:50:16 INFO - Running pre-action listener: _resource_record_pre_action 10:50:16 INFO - Running main action method: clobber 10:50:16 INFO - rmtree: /builds/slave/test/build 10:50:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:50:18 INFO - Running post-action listener: _resource_record_post_action 10:50:18 INFO - ##### 10:50:18 INFO - ##### Finished clobber step (success) 10:50:18 INFO - ##### 10:50:18 INFO - ##### 10:50:18 INFO - ##### Running read-buildbot-config step. 10:50:18 INFO - ##### 10:50:18 INFO - Running pre-action listener: _resource_record_pre_action 10:50:18 INFO - Running main action method: read_buildbot_config 10:50:18 INFO - Using buildbot properties: 10:50:18 INFO - { 10:50:18 INFO - "project": "", 10:50:18 INFO - "product": "firefox", 10:50:18 INFO - "script_repo_revision": "production", 10:50:18 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:50:18 INFO - "repository": "", 10:50:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:50:18 INFO - "buildid": "20160706083823", 10:50:18 INFO - "pgo_build": "False", 10:50:18 INFO - "basedir": "/builds/slave/test", 10:50:18 INFO - "buildnumber": 87, 10:50:18 INFO - "slavename": "t-yosemite-r7-0362", 10:50:18 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 10:50:18 INFO - "platform": "yosemite_r7", 10:50:18 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:50:18 INFO - "branch": "fx-team", 10:50:18 INFO - "repo_path": "integration/fx-team", 10:50:18 INFO - "revision": "df4803bab0bc40c206c6977a8cc7fcaeac677357", 10:50:18 INFO - "stage_platform": "macosx64", 10:50:18 INFO - "builduid": "69de8c4ee53f42f2a8f36c4fdb883228", 10:50:18 INFO - "slavebuilddir": "test" 10:50:18 INFO - } 10:50:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 10:50:18 INFO - Running post-action listener: _resource_record_post_action 10:50:18 INFO - ##### 10:50:18 INFO - ##### Finished read-buildbot-config step (success) 10:50:18 INFO - ##### 10:50:18 INFO - ##### 10:50:18 INFO - ##### Running download-and-extract step. 10:50:18 INFO - ##### 10:50:18 INFO - Running pre-action listener: _resource_record_pre_action 10:50:18 INFO - Running main action method: download_and_extract 10:50:18 INFO - mkdir: /builds/slave/test/build/tests 10:50:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:18 INFO - https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:50:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:50:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 10:50:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:50:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 10:50:19 INFO - Downloaded 1199 bytes. 10:50:19 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 10:50:19 INFO - Using the following test package requirements: 10:50:19 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:50:19 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 10:50:19 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 10:50:19 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'jsshell-mac.zip'], 10:50:19 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 10:50:19 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 10:50:19 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 10:50:19 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 10:50:19 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 10:50:19 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 10:50:19 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 10:50:19 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:50: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:19 INFO - https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:50:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:50:20 INFO - Downloaded 18802666 bytes. 10:50:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:50:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:50:23 INFO - Return code: 0 10:50:23 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:50:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:23 INFO - https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:50:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 10:50:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:50:25 INFO - Downloaded 18802666 bytes. 10:50:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:50:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:50:28 INFO - Return code: 0 10:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:28 INFO - https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:50:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:50:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:50:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 10:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:50:29 INFO - Downloaded 14403333 bytes. 10:50:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:50:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:50:29 INFO - Return code: 0 10:50:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:29 INFO - https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:50:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:50:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:50:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 10:50:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 10:50:36 INFO - Downloaded 112489930 bytes. 10:50:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:50:36 INFO - mkdir: /builds/slave/test/properties 10:50:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:50:36 INFO - Writing to file /builds/slave/test/properties/build_url 10:50:36 INFO - Contents: 10:50:36 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 10:50:37 INFO - Running post-action listener: _resource_record_post_action 10:50:37 INFO - Running post-action listener: set_extra_try_arguments 10:50:37 INFO - ##### 10:50:37 INFO - ##### Finished download-and-extract step (success) 10:50:37 INFO - ##### 10:50:37 INFO - ##### 10:50:37 INFO - ##### Running populate-webroot step. 10:50:37 INFO - ##### 10:50:37 INFO - Running pre-action listener: _resource_record_pre_action 10:50:37 INFO - Running main action method: populate_webroot 10:50:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:50:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:50:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:50:37 INFO - '--tppagecycles', 10:50:37 INFO - '7'], 10:50:37 INFO - 'tests': ['tsvgm']}}, 10:50:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:50:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:50:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:50:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:50:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:50:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['damp', 'tps']}, 10:50:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'tests': ['damp', 'tps']}, 10:50:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['dromaeo_dom']}, 10:50:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:50:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['basic_compositor_video']}, 10:50:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:50:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'other-e10s': {'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['a11yr', 10:50:37 INFO - 'ts_paint', 10:50:37 INFO - 'tpaint', 10:50:37 INFO - 'sessionrestore', 10:50:37 INFO - 'sessionrestore_no_auto_restore', 10:50:37 INFO - 'tabpaint']}, 10:50:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['tsvgx', 10:50:37 INFO - 'tsvgr_opacity', 10:50:37 INFO - 'tart', 10:50:37 INFO - 'tscrollx', 10:50:37 INFO - 'cart']}, 10:50:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:50:37 INFO - 'tsvgr_opacity', 10:50:37 INFO - 'tart', 10:50:37 INFO - 'tscrollx', 10:50:37 INFO - 'cart']}, 10:50:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'talos_options': ['--disable-e10s'], 10:50:37 INFO - 'tests': ['tp5o']}, 10:50:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'tests': ['tp5o']}, 10:50:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'talos_options': ['--disable-e10s', 10:50:37 INFO - '--xperf_path', 10:50:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:37 INFO - 'tests': ['tp5n']}, 10:50:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:50:37 INFO - 'talos_options': ['--xperf_path', 10:50:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:50:37 INFO - 'tests': ['tp5n']}}, 10:50:37 INFO - 'talos.zip': {'path': '', 10:50:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:50:37 INFO - Running post-action listener: _resource_record_post_action 10:50:37 INFO - ##### 10:50:37 INFO - ##### Finished populate-webroot step (success) 10:50:37 INFO - ##### 10:50:37 INFO - ##### 10:50:37 INFO - ##### Running create-virtualenv step. 10:50:37 INFO - ##### 10:50:37 INFO - Running pre-action listener: _resource_record_pre_action 10:50:37 INFO - Running main action method: create_virtualenv 10:50:37 INFO - Creating virtualenv /builds/slave/test/build/venv 10:50:37 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 10:50:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:50:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:50:37 INFO - Using real prefix '/tools/python27' 10:50:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:50:38 INFO - Installing distribute.............................................................................................................................................................................................done. 10:50:41 INFO - Installing pip.................done. 10:50:41 INFO - Return code: 0 10:50:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:50: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50: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', 'pip>=1.5'] in /builds/slave/test/build 10:50: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 pip>=1.5 10:50:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:41 INFO - 'HOME': '/Users/cltbld', 10:50:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:41 INFO - 'LOGNAME': 'cltbld', 10:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:41 INFO - 'MOZ_NO_REMOTE': '1', 10:50:41 INFO - 'NO_EM_RESTART': '1', 10:50:41 INFO - 'PAGER': '/bin/cat', 10:50:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:41 INFO - 'PWD': '/builds/slave/test', 10:50:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:41 INFO - 'SHELL': '/bin/bash', 10:50:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:41 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:41 INFO - 'USER': 'cltbld', 10:50:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:41 INFO - 'XPC_FLAGS': '0x0', 10:50:41 INFO - 'XPC_SERVICE_NAME': '0', 10:50:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:41 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 10:50:41 INFO - Cleaning up... 10:50:41 INFO - Return code: 0 10:50:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:50: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50: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', '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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:50: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 psutil>=3.1.1 10:50:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:41 INFO - 'HOME': '/Users/cltbld', 10:50:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:41 INFO - 'LOGNAME': 'cltbld', 10:50:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:41 INFO - 'MOZ_NO_REMOTE': '1', 10:50:41 INFO - 'NO_EM_RESTART': '1', 10:50:41 INFO - 'PAGER': '/bin/cat', 10:50:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:41 INFO - 'PWD': '/builds/slave/test', 10:50:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:41 INFO - 'SHELL': '/bin/bash', 10:50:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:41 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:41 INFO - 'USER': 'cltbld', 10:50:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:41 INFO - 'XPC_FLAGS': '0x0', 10:50:41 INFO - 'XPC_SERVICE_NAME': '0', 10:50:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:42 INFO - Downloading/unpacking psutil>=3.1.1 10:50:42 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 10:50:42 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 10:50:42 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 10:50:42 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 10:50:42 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 10:50:42 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 10:50:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:50:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:44 INFO - Installing collected packages: psutil 10:50:44 INFO - Running setup.py install for psutil 10:50:45 INFO - building 'psutil._psutil_osx' extension 10:50:45 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 10:50:45 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 10:50:45 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 10:50:45 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 10:50:45 INFO - building 'psutil._psutil_posix' extension 10:50:45 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 10:50:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:50:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:50:45 INFO - ^ 10:50:45 INFO - 1 warning generated. 10:50:45 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 10:50:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:50:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:50:45 INFO - Successfully installed psutil 10:50:45 INFO - Cleaning up... 10:50:45 INFO - Return code: 0 10:50:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:45 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50:45 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 10:50:45 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 10:50:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:45 INFO - 'HOME': '/Users/cltbld', 10:50:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:45 INFO - 'LOGNAME': 'cltbld', 10:50:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:45 INFO - 'MOZ_NO_REMOTE': '1', 10:50:45 INFO - 'NO_EM_RESTART': '1', 10:50:45 INFO - 'PAGER': '/bin/cat', 10:50:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:45 INFO - 'PWD': '/builds/slave/test', 10:50:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:45 INFO - 'SHELL': '/bin/bash', 10:50:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:45 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:45 INFO - 'USER': 'cltbld', 10:50:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:45 INFO - 'XPC_FLAGS': '0x0', 10:50:45 INFO - 'XPC_SERVICE_NAME': '0', 10:50:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:50:46 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 10:50:46 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 10:50:46 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 10:50:46 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 10:50:46 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 10:50:46 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 10:50:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:50:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:50:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:50:48 INFO - Installing collected packages: mozsystemmonitor 10:50:48 INFO - Running setup.py install for mozsystemmonitor 10:50:48 INFO - Successfully installed mozsystemmonitor 10:50:48 INFO - Cleaning up... 10:50:48 INFO - Return code: 0 10:50:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:50:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:50:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:48 INFO - 'HOME': '/Users/cltbld', 10:50:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:48 INFO - 'LOGNAME': 'cltbld', 10:50:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:48 INFO - 'MOZ_NO_REMOTE': '1', 10:50:48 INFO - 'NO_EM_RESTART': '1', 10:50:48 INFO - 'PAGER': '/bin/cat', 10:50:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:48 INFO - 'PWD': '/builds/slave/test', 10:50:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:48 INFO - 'SHELL': '/bin/bash', 10:50:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:48 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:48 INFO - 'USER': 'cltbld', 10:50:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:48 INFO - 'XPC_FLAGS': '0x0', 10:50:48 INFO - 'XPC_SERVICE_NAME': '0', 10:50:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:49 INFO - Downloading/unpacking jsonschema==2.5.1 10:50:49 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 10:50:49 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 10:50:49 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 10:50:49 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 10:50:49 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 10:50:49 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 10:50:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:50:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:50:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:50:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:50:51 INFO - Downloading functools32-3.2.3-2.tar.gz 10:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:50:51 INFO - warning: no files found matching '*.txt' 10:50:51 INFO - no previously-included directories found matching 'build' 10:50:51 INFO - no previously-included directories found matching 'dist' 10:50:51 INFO - no previously-included directories found matching '.git*' 10:50:51 INFO - Installing collected packages: jsonschema, functools32 10:50:52 INFO - Running setup.py install for functools32 10:50:52 INFO - warning: no files found matching '*.txt' 10:50:52 INFO - no previously-included directories found matching 'build' 10:50:52 INFO - no previously-included directories found matching 'dist' 10:50:52 INFO - no previously-included directories found matching '.git*' 10:50:52 INFO - Successfully installed jsonschema functools32 10:50:52 INFO - Cleaning up... 10:50:52 INFO - Return code: 0 10:50:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:52 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50:52 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 10:50:52 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 10:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:52 INFO - 'HOME': '/Users/cltbld', 10:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:52 INFO - 'LOGNAME': 'cltbld', 10:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:52 INFO - 'MOZ_NO_REMOTE': '1', 10:50:52 INFO - 'NO_EM_RESTART': '1', 10:50:52 INFO - 'PAGER': '/bin/cat', 10:50:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:52 INFO - 'PWD': '/builds/slave/test', 10:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:52 INFO - 'SHELL': '/bin/bash', 10:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:52 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:52 INFO - 'USER': 'cltbld', 10:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:52 INFO - 'XPC_FLAGS': '0x0', 10:50:52 INFO - 'XPC_SERVICE_NAME': '0', 10:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:52 INFO - Downloading/unpacking blobuploader==1.2.4 10:50:52 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 10:50:52 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 10:50:52 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 10:50:52 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 10:50:52 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 10:50:52 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 10:50:55 INFO - Downloading blobuploader-1.2.4.tar.gz 10:50:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:50:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:50:56 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:56 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 10:50:57 INFO - Downloading docopt-0.6.1.tar.gz 10:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:50:57 INFO - Installing collected packages: blobuploader, requests, docopt 10:50:57 INFO - Running setup.py install for blobuploader 10:50:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:50:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:50:57 INFO - Running setup.py install for requests 10:50:57 INFO - Running setup.py install for docopt 10:50:57 INFO - Successfully installed blobuploader requests docopt 10:50:57 INFO - Cleaning up... 10:50:58 INFO - Return code: 0 10:50:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:50:58 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:50:58 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 10:50:58 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 10:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:50:58 INFO - 'HOME': '/Users/cltbld', 10:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:50:58 INFO - 'LOGNAME': 'cltbld', 10:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:50:58 INFO - 'MOZ_NO_REMOTE': '1', 10:50:58 INFO - 'NO_EM_RESTART': '1', 10:50:58 INFO - 'PAGER': '/bin/cat', 10:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:50:58 INFO - 'PWD': '/builds/slave/test', 10:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:50:58 INFO - 'SHELL': '/bin/bash', 10:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:50:58 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:50:58 INFO - 'USER': 'cltbld', 10:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:50:58 INFO - 'XPC_FLAGS': '0x0', 10:50:58 INFO - 'XPC_SERVICE_NAME': '0', 10:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-9Z2bYt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-FJ_5Mn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-DeETPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-pvPn2w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-srJpjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-94Udrw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:50:58 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-Kn3kt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-uHEIgp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oDFVY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CpkIc4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-A3s1qk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-c17x1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8Pcm6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-9hEgIS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-g4kUgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:50:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:50:59 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-YlabxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:51:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:51:00 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-7dYwZh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:51:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:51:00 INFO - Running setup.py install for manifestparser 10:51:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:51:00 INFO - Running setup.py install for mozcrash 10:51:00 INFO - Running setup.py install for mozdebug 10:51:00 INFO - Running setup.py install for mozdevice 10:51:00 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:51:00 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:51:00 INFO - Running setup.py install for mozfile 10:51:00 INFO - Running setup.py install for mozhttpd 10:51:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Running setup.py install for mozinfo 10:51:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Running setup.py install for mozInstall 10:51:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Running setup.py install for mozleak 10:51:01 INFO - Running setup.py install for mozlog 10:51:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Running setup.py install for moznetwork 10:51:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:51:01 INFO - Running setup.py install for mozprocess 10:51:01 INFO - Running setup.py install for mozprofile 10:51:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:51:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:51:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:51:02 INFO - Running setup.py install for mozrunner 10:51:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:51:02 INFO - Running setup.py install for mozscreenshot 10:51:02 INFO - Running setup.py install for moztest 10:51:02 INFO - Running setup.py install for mozversion 10:51:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:51:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:51:02 INFO - Cleaning up... 10:51:02 INFO - Return code: 0 10:51:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:02 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:51:02 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 10:51:02 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 10:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:02 INFO - 'HOME': '/Users/cltbld', 10:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:02 INFO - 'LOGNAME': 'cltbld', 10:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:02 INFO - 'MOZ_NO_REMOTE': '1', 10:51:02 INFO - 'NO_EM_RESTART': '1', 10:51:02 INFO - 'PAGER': '/bin/cat', 10:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:02 INFO - 'PWD': '/builds/slave/test', 10:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:02 INFO - 'SHELL': '/bin/bash', 10:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:51:02 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:02 INFO - 'USER': 'cltbld', 10:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:02 INFO - 'XPC_FLAGS': '0x0', 10:51:02 INFO - 'XPC_SERVICE_NAME': '0', 10:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-5LMeNI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-jgoXpO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-p2AFJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-_hVJgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-PlqUPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-1OVeHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oEIgCY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8eMvBa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-PWZO2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:51:03 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)) 10:51:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:51:03 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-VQsNeW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-MH9Xip-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:51:04 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)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-s8Knx5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-fwkA5x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:51:04 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)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-aXujiG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:51:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-GgZ16U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:51:04 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)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-nQtRYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:51:04 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)) 10:51:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:51:04 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-ABdVfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:51:04 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)) 10:51:04 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)) 10:51:04 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)) 10:51:04 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)) 10:51:04 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)) 10:51:04 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)) 10:51:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:51:04 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 10:51:04 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 10:51:04 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 10:51:04 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 10:51:04 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 10:51:04 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 10:51:07 INFO - Downloading blessings-1.6.tar.gz 10:51:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:51:07 INFO - Installing collected packages: blessings 10:51:07 INFO - Running setup.py install for blessings 10:51:07 INFO - Successfully installed blessings 10:51:07 INFO - Cleaning up... 10:51:07 INFO - Return code: 0 10:51:07 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:51:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:51:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:51:07 INFO - Reading from file tmpfile_stdout 10:51:07 INFO - Current package versions: 10:51:07 INFO - blessings == 1.6 10:51:07 INFO - blobuploader == 1.2.4 10:51:07 INFO - docopt == 0.6.1 10:51:07 INFO - functools32 == 3.2.3-2 10:51:07 INFO - jsonschema == 2.5.1 10:51:07 INFO - manifestparser == 1.1 10:51:07 INFO - mozInstall == 1.12 10:51:07 INFO - mozcrash == 0.17 10:51:07 INFO - mozdebug == 0.1 10:51:07 INFO - mozdevice == 0.48 10:51:07 INFO - mozfile == 1.2 10:51:07 INFO - mozhttpd == 0.7 10:51:07 INFO - mozinfo == 0.9 10:51:07 INFO - mozleak == 0.1 10:51:07 INFO - mozlog == 3.2 10:51:07 INFO - moznetwork == 0.27 10:51:07 INFO - mozprocess == 0.22 10:51:07 INFO - mozprofile == 0.28 10:51:07 INFO - mozrunner == 6.11 10:51:07 INFO - mozscreenshot == 0.1 10:51:07 INFO - mozsystemmonitor == 0.3 10:51:07 INFO - moztest == 0.7 10:51:07 INFO - mozversion == 1.4 10:51:07 INFO - psutil == 3.1.1 10:51:07 INFO - requests == 1.2.3 10:51:07 INFO - wsgiref == 0.1.2 10:51:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:07 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:51:07 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 10:51:07 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 10:51:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:51:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:07 INFO - 'HOME': '/Users/cltbld', 10:51:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:07 INFO - 'LOGNAME': 'cltbld', 10:51:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:07 INFO - 'MOZ_NO_REMOTE': '1', 10:51:07 INFO - 'NO_EM_RESTART': '1', 10:51:07 INFO - 'PAGER': '/bin/cat', 10:51:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:07 INFO - 'PWD': '/builds/slave/test', 10:51:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:07 INFO - 'SHELL': '/bin/bash', 10:51:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:51:07 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:51:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:07 INFO - 'USER': 'cltbld', 10:51:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:07 INFO - 'XPC_FLAGS': '0x0', 10:51:07 INFO - 'XPC_SERVICE_NAME': '0', 10:51:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 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)) 10:51:08 INFO - Cleaning up... 10:51:08 INFO - Return code: 0 10:51:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:51:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:51:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:51:08 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 0x1073cda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107462618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb69c654b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, '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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 10:51:08 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 10:51:08 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 10:51:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:51:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:08 INFO - 'HOME': '/Users/cltbld', 10:51:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:08 INFO - 'LOGNAME': 'cltbld', 10:51:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:08 INFO - 'MOZ_NO_REMOTE': '1', 10:51:08 INFO - 'NO_EM_RESTART': '1', 10:51:08 INFO - 'PAGER': '/bin/cat', 10:51:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:08 INFO - 'PWD': '/builds/slave/test', 10:51:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:08 INFO - 'SHELL': '/bin/bash', 10:51:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:51:08 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:51:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:08 INFO - 'USER': 'cltbld', 10:51:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:08 INFO - 'XPC_FLAGS': '0x0', 10:51:08 INFO - 'XPC_SERVICE_NAME': '0', 10:51:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:51:08 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:51:08 INFO - Cleaning up... 10:51:08 INFO - Return code: 0 10:51:08 INFO - Running post-action listener: _resource_record_post_action 10:51:08 INFO - Running post-action listener: _start_resource_monitoring 10:51:08 INFO - Starting resource monitoring. 10:51:08 INFO - ##### 10:51:08 INFO - ##### Finished create-virtualenv step (success) 10:51:08 INFO - ##### 10:51:08 INFO - ##### 10:51:08 INFO - ##### Running install step. 10:51:08 INFO - ##### 10:51:08 INFO - Running pre-action listener: _resource_record_pre_action 10:51:08 INFO - Running main action method: install 10:51:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:51:08 INFO - Reading from file tmpfile_stdout 10:51:08 INFO - Detecting whether we're running mozinstall >=1.0... 10:51:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:51:08 INFO - Reading from file tmpfile_stdout 10:51:08 INFO - Output received: 10:51:08 INFO - Usage: mozinstall [options] installer 10:51:08 INFO - Options: 10:51:08 INFO - -h, --help show this help message and exit 10:51:08 INFO - -d DEST, --destination=DEST 10:51:08 INFO - Directory to install application into. [default: 10:51:08 INFO - "/builds/slave/test"] 10:51:08 INFO - --app=APP Application being installed. [default: firefox] 10:51:08 INFO - mkdir: /builds/slave/test/build/application 10:51:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:51:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:51:42 INFO - Reading from file tmpfile_stdout 10:51:42 INFO - Output received: 10:51:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:51:42 INFO - Running post-action listener: _resource_record_post_action 10:51:42 INFO - ##### 10:51:42 INFO - ##### Finished install step (success) 10:51:42 INFO - ##### 10:51:42 INFO - ##### 10:51:42 INFO - ##### Running run-tests step. 10:51:42 INFO - ##### 10:51:42 INFO - Running pre-action listener: _resource_record_pre_action 10:51:42 INFO - Running main action method: run_tests 10:51:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:51:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:51:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:51:42 INFO - 2016-07-06 10:51:42.563 screenresolution[1951:18232] starting screenresolution argv=screenresolution get 10:51:42 INFO - 2016-07-06 10:51:42.566 screenresolution[1951:18232] Display 0: 1600x1200x32@60 10:51:42 INFO - 2016-07-06 10:51:42.572 screenresolution[1952:18241] starting screenresolution argv=screenresolution list 10:51:42 INFO - Available Modes on Display 0 10:51:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:51:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:51:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:51:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:51:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:51:42 INFO - Intel Iris: 10:51:42 INFO - Chipset Model: Intel Iris 10:51:42 INFO - Type: GPU 10:51:42 INFO - Bus: Built-In 10:51:42 INFO - VRAM (Dynamic, Max): 1536 MB 10:51:42 INFO - Vendor: Intel (0x8086) 10:51:42 INFO - Device ID: 0x0a2e 10:51:42 INFO - Revision ID: 0x0009 10:51:42 INFO - Displays: 10:51:42 INFO - Display: 10:51:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:51:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:51:42 INFO - Display Serial Number: 1600x1200 60 10:51:42 INFO - Main Display: Yes 10:51:42 INFO - Mirror: Off 10:51:42 INFO - Online: Yes 10:51:42 INFO - Rotation: Supported 10:51:42 INFO - Return code: 0 10:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:51:42 INFO - Python 2.7.3 10:51:42 INFO - Return code: 0 10:51:42 INFO - grabbing minidump binary from tooltool 10:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:51:42 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 0x107331d30>, '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 0x107096db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107329b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:51:42 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 10:51:42 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 10:51:42 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 10:51:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:51:42 INFO - Return code: 0 10:51:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:51:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:51:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:51:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:51:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 10:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0362 --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 10:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdBD1EVx4d/Render', 10:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:51:42 INFO - 'HOME': '/Users/cltbld', 10:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:51:42 INFO - 'LOGNAME': 'cltbld', 10:51:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:51:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:51:42 INFO - 'MOZ_NO_REMOTE': '1', 10:51:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:51:42 INFO - 'NO_EM_RESTART': '1', 10:51:42 INFO - 'PAGER': '/bin/cat', 10:51:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:51:42 INFO - 'PWD': '/builds/slave/test', 10:51:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:51:42 INFO - 'SHELL': '/bin/bash', 10:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UhBMWG6u1T/Listeners', 10:51:42 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 10:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:51:42 INFO - 'USER': 'cltbld', 10:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:51:42 INFO - 'XPC_FLAGS': '0x0', 10:51:42 INFO - 'XPC_SERVICE_NAME': '0', 10:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:51:42 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 10:51:43 INFO - mozversion application_buildid: 20160706083823 10:51:43 INFO - mozversion application_changeset: df4803bab0bc40c206c6977a8cc7fcaeac677357 10:51:43 INFO - mozversion application_display_name: Nightly 10:51:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:51:43 INFO - mozversion application_name: Firefox 10:51:43 INFO - mozversion application_remotingname: firefox 10:51:43 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:51:43 INFO - mozversion application_vendor: Mozilla 10:51:43 INFO - mozversion application_version: 50.0a1 10:51:43 INFO - mozversion platform_buildid: 20160706083823 10:51:43 INFO - mozversion platform_changeset: df4803bab0bc40c206c6977a8cc7fcaeac677357 10:51:43 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:51:43 INFO - mozversion platform_version: 50.0a1 10:51:43 INFO - using testdate: 1467827503 10:51:43 INFO - actual date: 1467827503 10:51:43 INFO - starting webserver on 'localhost:56019' 10:51:43 INFO - SUITE-START | Running 2 tests 10:51:43 INFO - TEST-START | dromaeo_css 10:51:43 INFO - operating with platform_type : mac_ 10:51:43 INFO - Initialising browser for dromaeo_css test... 10:51:43 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmp_EZB3i/profile http://localhost:56019/getInfo.html) 10:51:45 INFO - PROCESS | 1962 | __metrics Screen width/height:1600/1200 10:51:45 INFO - PROCESS | 1962 | colorDepth:24 10:51:45 INFO - PROCESS | 1962 | Browser inner width/height: 1024/689 10:51:45 INFO - PROCESS | 1962 | __metrics 10:51:45 INFO - PROCESS | 1962 | 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" 10:51:45 INFO - PROCESS | 1962 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:51:46 INFO - TEST-INFO | 1962: exit 0 10:51:46 INFO - Browser initialized. 10:51:46 INFO - Running cycle 1/1 for dromaeo_css test... 10:51:46 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmp_EZB3i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:51:58 INFO - PROCESS | 1964 | RSS: Main: 244903936 10:51:58 INFO - PROCESS | 1964 | 10:52:49 INFO - PROCESS | 1964 | 1467827569120 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] 10:55:00 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56019/tests/dromaeo/cssquery-ext.html) 10:55:02 INFO - PROCESS | 1964 | RSS: Main: 375656448 10:55:02 INFO - PROCESS | 1964 | 10:58:05 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-ext.html (next: http://localhost:56019/tests/dromaeo/cssquery-jquery.html) 10:58:05 INFO - PROCESS | 1964 | RSS: Main: 379842560 10:58:05 INFO - PROCESS | 1964 | 11:01:08 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56019/tests/dromaeo/cssquery-mootools.html) 11:01:08 INFO - PROCESS | 1964 | RSS: Main: 374841344 11:01:08 INFO - PROCESS | 1964 | 11:04:11 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56019/tests/dromaeo/cssquery-prototype.html) 11:04:11 INFO - PROCESS | 1964 | RSS: Main: 374194176 11:04:11 INFO - PROCESS | 1964 | 11:07:15 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56019/tests/dromaeo/cssquery-yui.html) 11:07:15 INFO - PROCESS | 1964 | RSS: Main: 335818752 11:07:15 INFO - PROCESS | 1964 | 11:10:18 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:56019/tests/dromaeo/cssquery-yui.html (next: http://localhost:56019/tests/dromaeo/cssquery-dojo.html) 11:10:19 INFO - PROCESS | 1964 | RSS: Main: 422846464 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | __start_tp_report 11:10:19 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 11:10:19 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 11:10:19 INFO - PROCESS | 1964 | |i|pagename|runs| 11:10:19 INFO - PROCESS | 1964 | |0;dojo.html;3300.719438847698;3321.9335613287744;3326.4511355626323;3353.2287573858;3368.6968172864454;5591.8042868499615;5665.575081868861;5696.231008813809;5716.056850619648;5747.195392645028;5078.314427552288;5416.781224224547;5692.430756924316;5734.053881109612;5736.282964629421;5181.35233095863;5204.32343795306;5234.136367499377;5236.502532259429;5283.577313814905;4341.370501277329;5274.55166310864;5305;5310.654807437523;5311.097113490697;5156.278121063054;5198.246254293144;5213.582913366921;5224.895502089956;5284.973575132119;5278.630495865291;5295.947040529584;5307.469253074701;5322.0952438085615;5366.248725178478;5109.310243117169;5115.258287548322;5185.248139019859;5202.92195617066;5203.375594928595;5594.9720251398485;5675.602707810413;5675.63108397957;5692.089265717506;5707.343655479632;6206.25524937006;6292.622442653455;6297.622142671455;6304.054391841215;6316.463100636452;5128.692278463267;6132.356102609251;6274.937250627527;6324.525660575475;6351.682415879188;5817.360090390096;5858.062709966385;5894.204282421904;5894.440028197358;5894.616849904828;6247.812565623039;6262.060690896402;6276.529260305495;6278.560500764903;6289.465395441347;5989.460948514655;6062.484688801413;6129.854550907146;6210.223722034746;6238.844028899223;5940.108983652487;6321.146645202839;6324.9051264231075;6327.841803954938;6331.05034244867;6263.592866463665;6270.9372906270355;6298.275698294663;6309.936900631027;6320;6143.447089761943;6146.401935496803;6158.692065396713;6235.376462353819;6312.558120931582;4132.462779838662;4907.852764417074;4908.680935739166;4915.631327650369;4946.060248552835;4394.428724265825;4401.22978478765;4402.515723270438;4406.140802543473;4467;5739.139129130664;5756.251687280626;5773.6824474654295;5780.913286300709;5833.562482813806;6169.259688837368;6213.161223234805;6273.749050037947;6292.905606415908;6323.873522529524;5742.397048309951;5759.078547432391;5761.308642962871;5774.509166720792;5808.796692115756;5349.170878513836;5394.757235924393;5408.269883565747;5437.32033495813;5438.510534051954;6215.160953271251;6307.653079080695;6324.2410910691015;6330.588511746721;6414.807555773335;6279.937200627935;6283.937160628335;6307.495400367868;6330.683465826783;6353;6343.809685709251;6348.746050157942;6362.6818659067785;6372.87254254903;6373.362663733589;6097.917917222642;6104.755809767559;6123.770712314271;6176.845578860564;6251.812445626639;5944.851378715567;6264.185655864709;6285.842853928689;6287.182666253358;6316;5941.049432090845;6101.237345331833;6151.692415379123;6158.414950579688;6178.382161783786;4691.7184968901975;6060.030395136757;6096.829073522031;6129.847850912788;6141.2098563120835;5922.851928701644;5952.345242023461;5965.105234214902;5987.251593550806;6001.159837622824;5864.530837533072;5920.230370051866;6135.956887329254;6139.662318572521;6146.877062458636;4988.5260900214425;5896.852578685567;5929.96225660499;5946.494547963472;5996.520278377807 11:10:19 INFO - PROCESS | 1964 | |1;ext.html;475.5007242395484;511.5702809639904;523.502672461162;540.5540429145954;543.1445473379429;32602.510962335586;33178.50232246518;33329.00012999611;33341.33317333652;33348;33223.16942076449;33826.323473530574;33971.15072123192;43576.56423435765;44680;9661.420314781119;9819.607215711363;9840.065193806613;9853.458059806708;9855.753606159831;9655.951720241388;9709.271804614647;9746.853797193047;9750.36622619531;9759.121679048883;9761.609535618602;9764;9799.657012004582;9801.950990245039;9806.460644664541;9733.367331123454;9762.658306959293;9767.218622510252;9770.853437198448;9780.706578802576;9639.518024098767;9703.951480242553;9704;9713.514324283828;9715.951420242924;33136.50295245573;40530.39204411936;40727;40776.79611601953;40805.59194408048;3556.893293201208;3589.46158076288;3640.690541303992;3755.8122093895468;3783.9621603783885;32229;33339.6666033339;33507;33565.49651755226;33719.157021074425;3392.728581713457;3423.8801641942414;3464.7747896386654;3472.0451875734125;3500.842462089212;534898.3255083739;540893;542663;543365;544754;34296.314073718386;34606.653933460846;34638.480422793175;64636;65395.346046539875;36211.4568281476;36308.81845590755;36352.45471317932;36354.27291454156;36486.817565912;14998;15318.846811531743;15319.540413787605;15335.156566388956;15347.539573812803;12897.677558060936;12976.545820896412;12981.221126732238;12995.610131696065;13004.089713720137;6963.895541566881;7956.482828616122;7960.56216908064;8010.3191228745045;8084.272415482517;3293.374258890851;3314.0886256279714;3314.867405303809;3316.369889720945;3352.3630510202984;969.1568335548118;976.6972238606055;988.7231575158823;1040.0847254416065;1067.4449286371043;30506.84746576253;30749.692503074682;30949.91675291354;31532.842335788173;31664.683353166172;1105.7180418993105;1118.5190368141778;1139.6239241050434;1146.9849183472686;1184.810430331143;15840;16228.350865965229;16312;16513.44128908735;16667.583310417092;725449;733494;738357;738547;738878;49357;58872;60453;60748.69625651844;60891.08663370053;1271.9427625756864;1322.8412590489204;1397.3572156807982;1409.7814838700017;1439.3091316168297;30637.080887573407;31816.204594885312;32013.35973280568;32699.509507357885;32941.50587741138;51657.483425165265;52036.73981630067;52228.47771522236;52252.95494090173;52464.73767631137;561353;565040;566013;568417;569888;26943;27675.86162069177;27836;27887;27902.86048569744;48008.5199148018;48837;49331.50668493269;49625.75187124041;49888.501114989835;39507.209855803296;40600.593994060866;41029.79485102555;41471.58528414677;41681.79159104185;35603.64396356003;40017.99955001148;40109;40341;41056.76829695114;38443.42334865035;38454.80772596119;39185.608143918194;39419.605803941595;39613.40579891361;34757;35214.29571408609;35328;35356.292874142884;35450.82274588611;24930.626040609044;25039.7384758418;25454.981800727764;25956.740432595434;26366.336633663366 11:10:19 INFO - PROCESS | 1964 | |2;jquery.html;364.2369236449637;388.9027743064234;390.8592906553642;394.38869751884573;413.1674675528808;15707.293171807267;18023.279068837248;21010.844403557836;21566.676499852514;21594.352169434944;21388.037538310775;21635.134594616196;21639.67540486894;21641.3507594772;21724.674129888066;5709.257796486451;5887.381824908376;5981.790637327695;5986.3415024347505;6142.262928448592;5914.733836977346;5948.613340132878;6009.429104235091;6017.578769486137;6023.548233882477;5024.698518088908;5964.284285885678;6146.877062458748;6149.723262453201;6189.50483961282;5895.380984996556;6112.69436528174;6134.355892631276;6153.815385538441;6158.599691020092;5880.147378630118;5913.438223368773;5915.467607915309;5919.911201331984;5944.821655350346;11075.778484430346;13683.335076369867;22716.86415679226;25898.503702000176;26589.06938257171;2210.624193887043;2257.898394572248;2309.561183375153;2446.5963116085823;2480.6155045967903;9399.90600094004;9789.853152202651;9821.950890245575;10217.182625390025;10507.00183482568;1816.8728189026774;1817.37300631282;1866.0949439521821;1879.6992481203079;1938.8866135911924;80785.59607201985;82420;117190;122845.38577307056;129258;9937.602495900228;11242.55029798799;11569.363684997237;11691.473883675255;11922.1654484187;14674.413023479156;14676.266186690837;14708.33812478441;14726.190059546614;14731.04248223862;7421.4804963653105;8398.748037558884;8490.872636910452;8642.95678521616;8736.08271131522;8563.95718021406;8564.657413703506;8579.571021448903;8697.739067827975;8729.258013068833;4119.526254480713;4298.20483210607;4517.5482451755215;4595.586397224266;4642.442906851199;1700.0989475577962;1819.8271164239377;1896.9715454268196;1912.32112600025;1936.4093951344694;857.6140736668524;900.6532484993324;901.715959472764;930.511481472219;998.4823068935178;20948.581028379347;21849.344519664435;22607.09571617117;22614.547709045728;22668.319950401514;921.8847644044495;969.2618353152372;987.8863930647924;1017.801528701896;1039.3192458939407;9260.583273752698;10813.459327033617;11402.885971140182;11577.826332605018;11765.529378824922;616224;618714;631433;635696;635755;74525.25474745291;77913.61043194862;142045;143212;151992;877.4209022045419;978.3787295067683;1011.1051719228388;1027.091024443373;1028.7736697926443;19601.803981959998;20078.59449838556;20313.49216269546;20360.694589581464;20411.081501332774;51399.48600513947;54050.72974635102;54674;55872.72063639656;58451.70774146102;84082.57958710412;86612;87511;88483;90264.5486772562;7605.809854753676;8226.753197404087;8234.876476852733;8289.295409890226;8421.831563368576;12886.806697899727;13193.802092968805;13399.526052134062;13410.9329453356;13442.991775616672;11377.203595748219;11411.429428528707;11590.478428470571;11612.825807613062;11734.647960561197;11216.719582010515;11471.598494052669;11487.827682584935;11547.711307217387;11741.060715142597;10967.287126336763;11115.777684446428;11700.590479333183;11724.355160466257;11995.340256285988;7209.6034718091005;7218.783436496913;7594.772156835304;8216.548089854876;8458.703945361884;9772.853407198756;9952.701418957442;11091;11746.29522228669;12023.939880300542 11:10:19 INFO - PROCESS | 1964 | |3;mootools.html;1385.6535866033491;1534.3095606976856;1575.4328441760968;1807.8372946434818;1984.2658216459904;7879.527228366295;8764.517951512673;8969.416987895798;9027.90972090279;9064.138906803859;8716.956415217915;8942.150495702914;8945.239654629364;8946.955265223682;8950.507722075283;2091.006771783406;2171.771963943787;2305.873176975266;2340.578695834749;2387.0451819272344;2377.869217193054;2418.8790560471994;2419.1412048722714;2422.7819496245334;2423.9515209695796;2358.127492827656;2387.4866903615807;2402.25530085674;2403.5553422616786;2410.108259943823;2081.8959052047335;2277.2940388479624;2336.2757545161053;2350.2009316832186;2352.9529409411803;2333.5916214662366;2348.16640092768;2349.8120150387927;2351.3298709867604;2369.7748713872156;8738.344624153215;8809.163129502687;8813;8816.603252853636;8830.293576513863;5241.266222728821;5424.105022671254;5424.918626220609;5426.430224826376;5432.158015507603;3501.842417091237;3583.8387272572536;3694.7967861767593;3702.333579955607;3703.9444408333898;1075;1091.3615534912085;1102.3000394749313;1115.5370521233679;1118.1222740148944;11824.527018919232;11866.406679665983;11877.465514051888;11880.405979701067;11897.524099036029;4586.656000799887;4630.6063984561015;4845.1278769821065;4872.878178045577;4875.146849301358;10000.699979000641;10009.499525023719;10013.549390277454;10015.248856335658;10033.14718248957;4579.244424669892;5409.891802163935;5418.864528386822;5422.213779002022;5422.3764267109;4404.933925991113;4565.863024109282;4800.775802170424;4920.950790492049;4949.678270912379;3971.205758848242;3983.760974341491;4046.393041043867;4094;4109.3425051991535;1041.9374837509774;1240.6092080994458;1291.754566632337;1296.9624300559515;1318.551692424561;770.2413123073768;840.7855996720797;927.526961249754;943.8579319024058;968.2302569457298;5909.970450147808;5972.134040564094;6125.632462052291;6130.540209484307;6195.039768835838;895.4806212396794;932.06327640722;951.4957072751453;971.707628853623;971.9805603887883;3976.980115099464;4021.557628660845;4102.774347410921;4118.155778065489;4125.876223713293;10514.526846291936;10770.407627580402;10773.192010599236;10966.506507207196;11005.284656497302;10812.405317707602;10819.83770243447;10827.971342722429;10832.350058996486;10836.133109351233;8078.23256790604;8403.285720713686;8425.70510032146;8441.620127094295;8452.788680283042;4902.338184345211;5803;5985.431384018511;6035;6063.090536419572;10048.69853904384;10190.184785217312;10199.031092046243;10206.744831379276;10213.927537608444;9676.612935482783;10005.449700266556;10223.028812262823;10228.590856365661;10302.22733295021;8261.504309741435;8416.495010299157;8461.06928237881;8485.57572121379;8543.615537300711;7117.3238542338395;8367.456115352483;8436.198561136682;8539.53032583189;8539.530325832138;6113.57204995646;7579.204183560647;7626.847463050819;7633.0458692663415;7712.768616941501;7534.623268836646;7545.886811697938;7582.090149182133;7601.239876012354;7621.428392870668;7033.613151276729;7109.111361079865;7193.856122877409;7198.49610527279;7214.96392518034;5128;5129.8717532062;5131.743412829269;5171.3018742469285;5246.31797866275;5111.565516931102;5137.409197942285;5228.45101264377;5233.215017747369;5370.409254981871 11:10:19 INFO - PROCESS | 1964 | |4;prototype.html;305.49288181618505;310.1625610850703;320.11169006008333;324.97725159238865;325.5832534356025;4488.214562451574;5165.535101840834;5305.355339844651;5344;5344.652597581155;4508.413906192199;5008.574271186953;5139.2548080528395;5204.219367094937;5204.375474943003;3732.4587934749443;3930.8231129599235;3983.0838907051398;4014.8193331300017;4059.675225981922;3885.630865067814;3992.281389349916;4034.821196553872;4039.3738970459626;4054.8175332109977;3267.5262086997373;3433.6738009889145;3629.2922880038404;3924.606764598569;4031.475908131939;3435.9999999999877;3823.5985221551755;4028.295048366524;4029.033032072311;4030.2191487873565;3526.982365088184;3529.294141171776;3539.699125574329;3549.083494912744;3552.946705799415;4161.729487583328;4391.560843915615;4753.31076993834;4934.335458852215;5173.663711858717;3779.3952967525065;4494.258447356182;4515.029268707243;4527.370146747168;4541.751018469914;4656.906861862778;4912.9508704913205;5158.484151584849;5389.622726409153;5394.514493695547;3541.8406171722336;3691.7046636269033;3807.0292075520842;3842.7886466244345;3854.3062248795204;5776.826695199193;5793.69641830589;5830.358660547379;5830.959164292506;5863.70681465925;3600.7217437809722;3641.435577485494;3837.4052021936645;3843.865464708722;3844.231153769257;2999.550067489894;3862.478565393636;3897.9610203897596;3899.317619416591;3908.179282350681;3971.5234171899556;4058.289799285113;4505.459344878635;4541.205289074399;4573.611243044378;5539.861503462445;5540.63985840919;5545.334559852843;5557.499825015768;5563.666180029211;1239.5971309324505;1289.232906420678;1289.2457912121513;1289.4842063174806;1301.466398776497;683.7641013850214;753.1414187825883;760.2169765141913;809.3606051219589;895.4671970177734;4802.6638135330195;4990.925136122961;5134.922976155361;5167.354080739908;5220.530152286313;5499.652585116592;5512.310961129859;5557.916631250535;5707;5717.571182161355;4824.758762061953;5006.949930500721;5045.697258164522;5049.697018178921;5070.909754402821;2442.5710959089133;2933.2226959855657;2962.762978961678;2981.701829817001;2983.254186453387;5326.493983071602;5451.837933998271;5824.184614154022;5826.970865145732;5829.358770535237;4864.440589332201;5228.4510126436935;5623.875224955025;5742.827715168552;5791.117886686844;5040.974795126001;5485.917711234414;5546.7797084641315;5819.418058194231;5868.970655146697;4938;5083.466236045162;5242.243848310706;5569.582281328998;5636.633618814764;4732.100900828831;5403.486668766461;5551.25058117149;5592.706990134026;5594.944050559442;4604.447466304065;5212.853172302088;5229.555487783429;5569.2120115632515;5823.5923485355615;3780.2061567071223;3808.676262517717;3823.5411750590106;3827.961720382872;3844.8269827857252;5562.666240025612;5653.462921022497;5657.557322882639;5678.296511046752;5709.400512946081;4594.517575654508;5245.711485868162;5453.554807975969;5483.670979741069;5500.477454641803;5101.897962040813;5220.608454365862;5300.3639563252655;5401.3248343957;5446;4218.502431636724;5234.24103504982;5312.840614781563;5314.548263397654;5336.706481143575;5082.390113186293;5131.89664222194;5347.278117454094;5426.395330241585;5485.6434331768305;4573.702709323883;5300.628955973045;5373.838784836461;5438.320209973754;5445.618806683494 11:10:19 INFO - PROCESS | 1964 | |5;yui.html;730.5434103685196;769.054063501893;793.6388943030921;809.3929552835374;873.4584557574301;15268;15881.967672101306;16436.0960147192;16442.67114657706;16468.505944821645;14860.405583776663;15068.095914245154;15076.47232346868;15077.246137693126;15116.017458865166;660.389140045456;725.680700491782;737.3142977031358;919.8068405634808;937.4843835890267;940.4310392212716;947.1428357336621;951.3388195204336;958.9520523973773;959.151151231162;947.9668211612595;1031.8142734307821;1033.3902997231633;1034.4414016431156;1038.901304376083;788.9092754333268;969.9903000969971;1000.0649392817738;1015.4973288222303;1026.8254396752532;754.4718696912129;756.8107973006748;760.1562265884863;764.6482617995729;771.2634434115406;15261.923690381587;15277.694446111127;15295.388184472607;15305.234738263041;15320;14240.643983900381;14439.783403248959;14444;14452.132872027607;14517.927410362987;13272.734545309137;13319.26744029078;13329.266890321029;13536.255505947169;13662.590122296348;4656.208444564432;4677.812887484497;4713.952860471386;4790.508266082049;4798.736069516175;105796.00733937624;106821;107250;107273.9272607264;107705;10113;11165.218434709492;11447.313161210353;11544.59593914209;11587.710307242387;23156.421089472897;23182.95676694553;23192;23235.883820581126;23293.068277268718;7851.9214807851195;7882.132965373804;7998.4001199910235;8139.226773456512;8214.958925205336;4591.403117594691;4633.142868569325;4640.419947506562;4732.668713190113;4767.880802979953;3549.8757543485854;3708.4808126862263;3712.3503386907723;3713.925721485555;3717.423799311111;628.8616504369031;646.0696596900449;646.6184950879004;652.6834485274733;663.857270686795;563.6899705162144;654.2868273581723;656.2027137028533;666.8766278238542;668.8394785251504;8621;8736.301095912311;8940;10400.427976461217;10620.89379106199;575.5136909311625;634.552640388525;636.9299377068519;714.989275160873;736.6464097233251;4281.807318670667;4419.9779001105435;4451.443569553806;4497.797599107983;4534.183846907522;116412;138420.30789845987;144339.94810181594;152370;154567;79638.6018069906;108405;114817.12957176137;119482;127986.36006819906;110734;112534.43732781283;112780;115249;117150;8333.999840038226;8857.3799834011;9107.087511622369;9126;9329;55805;63500;63659.3634063672;64537;65833.34166658272;87691;91884;96593;96615;98894.11058894053;12018.579349722717;12154.513819447124;12171.452284647035;12318.753624927273;12507.687307817378;13515.391807368494;13557.322133893069;13602.183868967893;14240.074395164282;14841;14678.486252981094;14687.41250349974;14741.705165896836;14826.925865370604;15357.308921098354;14351.784723228951;14406.351714173097;14488.348024338718;14547.927260364053;14609.853901461274;13956.09285396446;14272.429102836186;14282.42870285177;14306;14361.138331700131;10243.334183278062;11509.922432270645;11615.186936914673;11924.821127683264;12083.637490875288;11613.825792613286;11651.708707282385;11752.712661740266;12015.279083255033;12063.81904271419 11:10:19 INFO - PROCESS | 1964 | __end_tp_report 11:10:19 INFO - PROCESS | 1964 | __start_cc_report 11:10:19 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,1284 11:10:19 INFO - PROCESS | 1964 | __end_cc_report 11:10:19 INFO - PROCESS | 1964 | __startTimestamp1467828619206__endTimestamp 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | ------- Summary: start ------- 11:10:19 INFO - PROCESS | 1964 | Number of tests: 6 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#0] dojo.html Cycles:165 Average:5727.73 Median:5929.96 stddev:672.12 (11.3%) stddev-sans-first:646.65 11:10:19 INFO - PROCESS | 1964 | Values: 3300.7 3321.9 3326.5 3353.2 3368.7 5591.8 5665.6 5696.2 5716.1 5747.2 5078.3 5416.8 5692.4 5734.1 5736.3 5181.4 5204.3 5234.1 5236.5 5283.6 4341.4 5274.6 5305.0 5310.7 5311.1 5156.3 5198.2 5213.6 5224.9 5285.0 5278.6 5295.9 5307.5 5322.1 5366.2 5109.3 5115.3 5185.2 5202.9 5203.4 5595.0 5675.6 5675.6 5692.1 5707.3 6206.3 6292.6 6297.6 6304.1 6316.5 5128.7 6132.4 6274.9 6324.5 6351.7 5817.4 5858.1 5894.2 5894.4 5894.6 6247.8 6262.1 6276.5 6278.6 6289.5 5989.5 6062.5 6129.9 6210.2 6238.8 5940.1 6321.1 6324.9 6327.8 6331.1 6263.6 6270.9 6298.3 6309.9 6320.0 6143.4 6146.4 6158.7 6235.4 6312.6 4132.5 4907.9 4908.7 4915.6 4946.1 4394.4 4401.2 4402.5 4406.1 4467.0 5739.1 5756.3 5773.7 5780.9 5833.6 6169.3 6213.2 6273.7 6292.9 6323.9 5742.4 5759.1 5761.3 5774.5 5808.8 5349.2 5394.8 5408.3 5437.3 5438.5 6215.2 6307.7 6324.2 6330.6 6414.8 6279.9 6283.9 6307.5 6330.7 6353.0 6343.8 6348.7 6362.7 6372.9 6373.4 6097.9 6104.8 6123.8 6176.8 6251.8 5944.9 6264.2 6285.8 6287.2 6316.0 5941.0 6101.2 6151.7 6158.4 6178.4 4691.7 6060.0 6096.8 6129.8 6141.2 5922.9 5952.3 5965.1 5987.3 6001.2 5864.5 5920.2 6136.0 6139.7 6146.9 4988.5 5896.9 5930.0 5946.5 5996.5 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#1] ext.html Cycles:180 Average:72630.24 Median:38449.12 stddev:166456.05 (432.9%) stddev-sans-first:166834.84 11:10:19 INFO - PROCESS | 1964 | Values: 475.5 511.6 523.5 540.6 543.1 32602.5 33178.5 33329.0 33341.3 33348.0 33223.2 33826.3 33971.2 43576.6 44680.0 9661.4 9819.6 9840.1 9853.5 9855.8 9656.0 9709.3 9746.9 9750.4 9759.1 9761.6 9764.0 9799.7 9802.0 9806.5 9733.4 9762.7 9767.2 9770.9 9780.7 9639.5 9704.0 9704.0 9713.5 9716.0 33136.5 40530.4 40727.0 40776.8 40805.6 3556.9 3589.5 3640.7 3755.8 3784.0 32229.0 33339.7 33507.0 33565.5 33719.2 3392.7 3423.9 3464.8 3472.0 3500.8 534898.3 540893.0 542663.0 543365.0 544754.0 34296.3 34606.7 34638.5 64636.0 65395.3 36211.5 36308.8 36352.5 36354.3 36486.8 14998.0 15318.8 15319.5 15335.2 15347.5 12897.7 12976.5 12981.2 12995.6 13004.1 6963.9 7956.5 7960.6 8010.3 8084.3 3293.4 3314.1 3314.9 3316.4 3352.4 969.2 976.7 988.7 1040.1 1067.4 30506.8 30749.7 30949.9 31532.8 31664.7 1105.7 1118.5 1139.6 1147.0 1184.8 15840.0 16228.4 16312.0 16513.4 16667.6 725449.0 733494.0 738357.0 738547.0 738878.0 49357.0 58872.0 60453.0 60748.7 60891.1 1271.9 1322.8 1397.4 1409.8 1439.3 30637.1 31816.2 32013.4 32699.5 32941.5 51657.5 52036.7 52228.5 52253.0 52464.7 561353.0 565040.0 566013.0 568417.0 569888.0 26943.0 27675.9 27836.0 27887.0 27902.9 48008.5 48837.0 49331.5 49625.8 49888.5 39507.2 40600.6 41029.8 41471.6 41681.8 35603.6 40018.0 40109.0 40341.0 41056.8 38443.4 38454.8 39185.6 39419.6 39613.4 34757.0 35214.3 35328.0 35356.3 35450.8 24930.6 25039.7 25455.0 25956.7 26366.3 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#2] jquery.html Cycles:180 Average:35550.56 Median:389.88 stddev:104354.53 (26765.7%) stddev-sans-first:104613.83 11:10:19 INFO - PROCESS | 1964 | Values: 364.2 388.9 390.9 394.4 413.2 15707.3 18023.3 21010.8 21566.7 21594.4 21388.0 21635.1 21639.7 21641.4 21724.7 5709.3 5887.4 5981.8 5986.3 6142.3 5914.7 5948.6 6009.4 6017.6 6023.5 5024.7 5964.3 6146.9 6149.7 6189.5 5895.4 6112.7 6134.4 6153.8 6158.6 5880.1 5913.4 5915.5 5919.9 5944.8 11075.8 13683.3 22716.9 25898.5 26589.1 2210.6 2257.9 2309.6 2446.6 2480.6 9399.9 9789.9 9822.0 10217.2 10507.0 1816.9 1817.4 1866.1 1879.7 1938.9 80785.6 82420.0 117190.0 122845.4 129258.0 9937.6 11242.6 11569.4 11691.5 11922.2 14674.4 14676.3 14708.3 14726.2 14731.0 7421.5 8398.7 8490.9 8643.0 8736.1 8564.0 8564.7 8579.6 8697.7 8729.3 4119.5 4298.2 4517.5 4595.6 4642.4 1700.1 1819.8 1897.0 1912.3 1936.4 857.6 900.7 901.7 930.5 998.5 20948.6 21849.3 22607.1 22614.5 22668.3 921.9 969.3 987.9 1017.8 1039.3 9260.6 10813.5 11402.9 11577.8 11765.5 616224.0 618714.0 631433.0 635696.0 635755.0 74525.3 77913.6 142045.0 143212.0 151992.0 877.4 978.4 1011.1 1027.1 1028.8 19601.8 20078.6 20313.5 20360.7 20411.1 51399.5 54050.7 54674.0 55872.7 58451.7 84082.6 86612.0 87511.0 88483.0 90264.5 7605.8 8226.8 8234.9 8289.3 8421.8 12886.8 13193.8 13399.5 13410.9 13443.0 11377.2 11411.4 11590.5 11612.8 11734.6 11216.7 11471.6 11487.8 11547.7 11741.1 10967.3 11115.8 11700.6 11724.4 11995.3 7209.6 7218.8 7594.8 8216.5 8458.7 9772.9 9952.7 11091.0 11746.3 12023.9 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#3] mootools.html Cycles:180 Average:5802.13 Median:4859.00 stddev:3256.67 (67.0%) stddev-sans-first:3248.89 11:10:19 INFO - PROCESS | 1964 | Values: 1385.7 1534.3 1575.4 1807.8 1984.3 7879.5 8764.5 8969.4 9027.9 9064.1 8717.0 8942.2 8945.2 8947.0 8950.5 2091.0 2171.8 2305.9 2340.6 2387.0 2377.9 2418.9 2419.1 2422.8 2424.0 2358.1 2387.5 2402.3 2403.6 2410.1 2081.9 2277.3 2336.3 2350.2 2353.0 2333.6 2348.2 2349.8 2351.3 2369.8 8738.3 8809.2 8813.0 8816.6 8830.3 5241.3 5424.1 5424.9 5426.4 5432.2 3501.8 3583.8 3694.8 3702.3 3703.9 1075.0 1091.4 1102.3 1115.5 1118.1 11824.5 11866.4 11877.5 11880.4 11897.5 4586.7 4630.6 4845.1 4872.9 4875.1 10000.7 10009.5 10013.5 10015.2 10033.1 4579.2 5409.9 5418.9 5422.2 5422.4 4404.9 4565.9 4800.8 4921.0 4949.7 3971.2 3983.8 4046.4 4094.0 4109.3 1041.9 1240.6 1291.8 1297.0 1318.6 770.2 840.8 927.5 943.9 968.2 5910.0 5972.1 6125.6 6130.5 6195.0 895.5 932.1 951.5 971.7 972.0 3977.0 4021.6 4102.8 4118.2 4125.9 10514.5 10770.4 10773.2 10966.5 11005.3 10812.4 10819.8 10828.0 10832.4 10836.1 8078.2 8403.3 8425.7 8441.6 8452.8 4902.3 5803.0 5985.4 6035.0 6063.1 10048.7 10190.2 10199.0 10206.7 10213.9 9676.6 10005.4 10223.0 10228.6 10302.2 8261.5 8416.5 8461.1 8485.6 8543.6 7117.3 8367.5 8436.2 8539.5 8539.5 6113.6 7579.2 7626.8 7633.0 7712.8 7534.6 7545.9 7582.1 7601.2 7621.4 7033.6 7109.1 7193.9 7198.5 7215.0 5128.0 5129.9 5131.7 5171.3 5246.3 5111.6 5137.4 5228.5 5233.2 5370.4 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#4] prototype.html Cycles:180 Average:4426.69 Median:5043.34 stddev:1358.69 (26.9%) stddev-sans-first:1326.82 11:10:19 INFO - PROCESS | 1964 | Values: 305.5 310.2 320.1 325.0 325.6 4488.2 5165.5 5305.4 5344.0 5344.7 4508.4 5008.6 5139.3 5204.2 5204.4 3732.5 3930.8 3983.1 4014.8 4059.7 3885.6 3992.3 4034.8 4039.4 4054.8 3267.5 3433.7 3629.3 3924.6 4031.5 3436.0 3823.6 4028.3 4029.0 4030.2 3527.0 3529.3 3539.7 3549.1 3552.9 4161.7 4391.6 4753.3 4934.3 5173.7 3779.4 4494.3 4515.0 4527.4 4541.8 4656.9 4913.0 5158.5 5389.6 5394.5 3541.8 3691.7 3807.0 3842.8 3854.3 5776.8 5793.7 5830.4 5831.0 5863.7 3600.7 3641.4 3837.4 3843.9 3844.2 2999.6 3862.5 3898.0 3899.3 3908.2 3971.5 4058.3 4505.5 4541.2 4573.6 5539.9 5540.6 5545.3 5557.5 5563.7 1239.6 1289.2 1289.2 1289.5 1301.5 683.8 753.1 760.2 809.4 895.5 4802.7 4990.9 5134.9 5167.4 5220.5 5499.7 5512.3 5557.9 5707.0 5717.6 4824.8 5006.9 5045.7 5049.7 5070.9 2442.6 2933.2 2962.8 2981.7 2983.3 5326.5 5451.8 5824.2 5827.0 5829.4 4864.4 5228.5 5623.9 5742.8 5791.1 5041.0 5485.9 5546.8 5819.4 5869.0 4938.0 5083.5 5242.2 5569.6 5636.6 4732.1 5403.5 5551.3 5592.7 5594.9 4604.4 5212.9 5229.6 5569.2 5823.6 3780.2 3808.7 3823.5 3828.0 3844.8 5562.7 5653.5 5657.6 5678.3 5709.4 4594.5 5245.7 5453.6 5483.7 5500.5 5101.9 5220.6 5300.4 5401.3 5446.0 4218.5 5234.2 5312.8 5314.5 5336.7 5082.4 5131.9 5347.3 5426.4 5485.6 4573.7 5300.6 5373.8 5438.3 5445.6 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - PROCESS | 1964 | [#5] yui.html Cycles:180 Average:24495.62 Median:16439.38 stddev:37922.21 (230.7%) stddev-sans-first:37986.61 11:10:19 INFO - PROCESS | 1964 | Values: 730.5 769.1 793.6 809.4 873.5 15268.0 15882.0 16436.1 16442.7 16468.5 14860.4 15068.1 15076.5 15077.2 15116.0 660.4 725.7 737.3 919.8 937.5 940.4 947.1 951.3 959.0 959.2 948.0 1031.8 1033.4 1034.4 1038.9 788.9 970.0 1000.1 1015.5 1026.8 754.5 756.8 760.2 764.6 771.3 15261.9 15277.7 15295.4 15305.2 15320.0 14240.6 14439.8 14444.0 14452.1 14517.9 13272.7 13319.3 13329.3 13536.3 13662.6 4656.2 4677.8 4714.0 4790.5 4798.7 105796.0 106821.0 107250.0 107273.9 107705.0 10113.0 11165.2 11447.3 11544.6 11587.7 23156.4 23183.0 23192.0 23235.9 23293.1 7851.9 7882.1 7998.4 8139.2 8215.0 4591.4 4633.1 4640.4 4732.7 4767.9 3549.9 3708.5 3712.4 3713.9 3717.4 628.9 646.1 646.6 652.7 663.9 563.7 654.3 656.2 666.9 668.8 8621.0 8736.3 8940.0 10400.4 10620.9 575.5 634.6 636.9 715.0 736.6 4281.8 4420.0 4451.4 4497.8 4534.2 116412.0 138420.3 144339.9 152370.0 154567.0 79638.6 108405.0 114817.1 119482.0 127986.4 110734.0 112534.4 112780.0 115249.0 117150.0 8334.0 8857.4 9107.1 9126.0 9329.0 55805.0 63500.0 63659.4 64537.0 65833.3 87691.0 91884.0 96593.0 96615.0 98894.1 12018.6 12154.5 12171.5 12318.8 12507.7 13515.4 13557.3 13602.2 14240.1 14841.0 14678.5 14687.4 14741.7 14826.9 15357.3 14351.8 14406.4 14488.3 14547.9 14609.9 13956.1 14272.4 14282.4 14306.0 14361.1 10243.3 11509.9 11615.2 11924.8 12083.6 11613.8 11651.7 11752.7 12015.3 12063.8 11:10:19 INFO - PROCESS | 1964 | -------- Summary: end -------- 11:10:19 INFO - PROCESS | 1964 | 11:10:19 INFO - TEST-INFO | 1964: exit 0 11:10:19 INFO - TEST-OK | dromaeo_css | took 1116657ms 11:10:19 INFO - TEST-START | kraken 11:10:19 INFO - operating with platform_type : mac_ 11:10:19 INFO - Initialising browser for kraken test... 11:10:19 INFO - TEST-INFO | started process 1968 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpywpBAl/profile http://localhost:56019/getInfo.html) 11:10:22 INFO - PROCESS | 1968 | __metrics Screen width/height:1600/1200 11:10:22 INFO - PROCESS | 1968 | colorDepth:24 11:10:22 INFO - PROCESS | 1968 | Browser inner width/height: 1024/689 11:10:22 INFO - PROCESS | 1968 | __metrics 11:10:22 INFO - PROCESS | 1968 | 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" 11:10:22 INFO - PROCESS | 1968 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:10:22 INFO - TEST-INFO | 1968: exit 0 11:10:22 INFO - Browser initialized. 11:10:22 INFO - Running cycle 1/1 for kraken test... 11:10:22 INFO - TEST-INFO | started process 1969 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpywpBAl/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:34 INFO - PROCESS | 1969 | RSS: Main: 244883456 11:10:34 INFO - PROCESS | 1969 | 11:10:37 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56019/tests/kraken/driver.html?testName=audio-beat-detection) 11:10:38 INFO - PROCESS | 1969 | RSS: Main: 294514688 11:10:38 INFO - PROCESS | 1969 | 11:10:41 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56019/tests/kraken/driver.html?testName=audio-dft) 11:10:41 INFO - PROCESS | 1969 | RSS: Main: 304795648 11:10:41 INFO - PROCESS | 1969 | 11:10:45 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56019/tests/kraken/driver.html?testName=audio-fft) 11:10:45 INFO - PROCESS | 1969 | RSS: Main: 389935104 11:10:45 INFO - PROCESS | 1969 | 11:10:47 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56019/tests/kraken/driver.html?testName=audio-oscillator) 11:10:48 INFO - PROCESS | 1969 | RSS: Main: 290189312 11:10:48 INFO - PROCESS | 1969 | 11:10:50 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56019/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:10:51 INFO - PROCESS | 1969 | RSS: Main: 294219776 11:10:51 INFO - PROCESS | 1969 | 11:10:54 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56019/tests/kraken/driver.html?testName=imaging-darkroom) 11:10:54 INFO - PROCESS | 1969 | RSS: Main: 331464704 11:10:54 INFO - PROCESS | 1969 | 11:10:57 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56019/tests/kraken/driver.html?testName=imaging-desaturate) 11:10:58 INFO - PROCESS | 1969 | RSS: Main: 331677696 11:10:58 INFO - PROCESS | 1969 | 11:11:01 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56019/tests/kraken/driver.html?testName=json-parse-financial) 11:11:01 INFO - PROCESS | 1969 | RSS: Main: 333426688 11:11:01 INFO - PROCESS | 1969 | 11:11:03 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56019/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:11:04 INFO - PROCESS | 1969 | RSS: Main: 311652352 11:11:04 INFO - PROCESS | 1969 | 11:11:05 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:11:06 INFO - PROCESS | 1969 | RSS: Main: 318537728 11:11:06 INFO - PROCESS | 1969 | 11:11:08 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:11:09 INFO - PROCESS | 1969 | RSS: Main: 318169088 11:11:09 INFO - PROCESS | 1969 | 11:11:12 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:11:12 INFO - PROCESS | 1969 | RSS: Main: 325873664 11:11:12 INFO - PROCESS | 1969 | 11:11:15 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:11:16 INFO - PROCESS | 1969 | RSS: Main: 328208384 11:11:16 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | Cycle 1(1): loaded http://localhost:56019/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56019/tests/kraken/driver.html?testName=ai-astar) 11:11:18 INFO - PROCESS | 1969 | RSS: Main: 372023296 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | __start_tp_report 11:11:18 INFO - PROCESS | 1969 | _x_x_mozilla_page_load 11:11:18 INFO - PROCESS | 1969 | _x_x_mozilla_page_load_details 11:11:18 INFO - PROCESS | 1969 | |i|pagename|runs| 11:11:18 INFO - PROCESS | 1969 | |0;ai-astar;102;113;112;112;112;112;113;112;110;113 11:11:18 INFO - PROCESS | 1969 | |1;audio-beat-detection;149;105;108;158;107;106;125;111;174;136 11:11:18 INFO - PROCESS | 1969 | |2;audio-dft;183;184;186;183;183;184;178;182;181;181 11:11:18 INFO - PROCESS | 1969 | |3;audio-fft;80;74;59;88;58;56;64;68;104;79 11:11:18 INFO - PROCESS | 1969 | |4;audio-oscillator;84;93;93;71;74;69;96;70;68;103 11:11:18 INFO - PROCESS | 1969 | |5;imaging-gaussian-blur;88;92;134;104;87;84;95;85;86;83 11:11:18 INFO - PROCESS | 1969 | |6;imaging-darkroom;92;128;150;93;91;92;92;92;128;152 11:11:18 INFO - PROCESS | 1969 | |7;imaging-desaturate;85;83;86;104;107;83;86;83;83;82 11:11:18 INFO - PROCESS | 1969 | |8;json-parse-financial;54;54;51;53;51;52;52;53;53;53 11:11:18 INFO - PROCESS | 1969 | |9;json-stringify-tinderbox;66;73;71;75;72;68;70;70;70;72 11:11:18 INFO - PROCESS | 1969 | |10;stanford-crypto-aes;83;84;82;81;82;84;84;84;85;84 11:11:18 INFO - PROCESS | 1969 | |11;stanford-crypto-ccm;136;134;137;137;136;133;137;125;251;149 11:11:18 INFO - PROCESS | 1969 | |12;stanford-crypto-pbkdf2;161;162;159;163;165;160;160;161;164;224 11:11:18 INFO - PROCESS | 1969 | |13;stanford-crypto-sha256-iterative;64;66;67;67;65;66;66;66;65;65 11:11:18 INFO - PROCESS | 1969 | __end_tp_report 11:11:18 INFO - PROCESS | 1969 | __start_cc_report 11:11:18 INFO - PROCESS | 1969 | _x_x_mozilla_cycle_collect,1968 11:11:18 INFO - PROCESS | 1969 | __end_cc_report 11:11:18 INFO - PROCESS | 1969 | __startTimestamp1467828678440__endTimestamp 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | ------- Summary: start ------- 11:11:18 INFO - PROCESS | 1969 | Number of tests: 14 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#0] ai-astar Cycles:10 Average:111.10 Median:112.00 stddev:3.31 (3.0%) stddev-sans-first:0.93 11:11:18 INFO - PROCESS | 1969 | Values: 102.0 113.0 112.0 112.0 112.0 112.0 113.0 112.0 110.0 113.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:130.50 stddev:25.11 (19.2%) stddev-sans-first:25.44 11:11:18 INFO - PROCESS | 1969 | Values: 149.0 105.0 108.0 158.0 107.0 106.0 125.0 111.0 174.0 136.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:2.17 (1.2%) stddev-sans-first:2.30 11:11:18 INFO - PROCESS | 1969 | Values: 183.0 184.0 186.0 183.0 183.0 184.0 178.0 182.0 181.0 181.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#3] audio-fft Cycles:10 Average:73.00 Median:71.00 stddev:15.23 (21.5%) stddev-sans-first:15.94 11:11:18 INFO - PROCESS | 1969 | Values: 80.0 74.0 59.0 88.0 58.0 56.0 64.0 68.0 104.0 79.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:79.00 stddev:13.24 (16.8%) stddev-sans-first:14.02 11:11:18 INFO - PROCESS | 1969 | Values: 84.0 93.0 93.0 71.0 74.0 69.0 96.0 70.0 68.0 103.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#5] imaging-gaussian-blur Cycles:10 Average:93.80 Median:86.50 stddev:15.48 (17.9%) stddev-sans-first:16.27 11:11:18 INFO - PROCESS | 1969 | Values: 88.0 92.0 134.0 104.0 87.0 84.0 95.0 85.0 86.0 83.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#6] imaging-darkroom Cycles:10 Average:111.00 Median:92.00 stddev:25.71 (27.9%) stddev-sans-first:26.33 11:11:18 INFO - PROCESS | 1969 | Values: 92.0 128.0 150.0 93.0 91.0 92.0 92.0 92.0 128.0 152.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#7] imaging-desaturate Cycles:10 Average:88.20 Median:83.00 stddev:9.25 (11.1%) stddev-sans-first:9.74 11:11:18 INFO - PROCESS | 1969 | Values: 85.0 83.0 86.0 104.0 107.0 83.0 86.0 83.0 83.0 82.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#8] json-parse-financial Cycles:10 Average:52.60 Median:53.00 stddev:1.07 (2.0%) stddev-sans-first:1.01 11:11:18 INFO - PROCESS | 1969 | Values: 54.0 54.0 51.0 53.0 51.0 52.0 52.0 53.0 53.0 53.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#9] json-stringify-tinderbox Cycles:10 Average:70.70 Median:71.50 stddev:2.54 (3.6%) stddev-sans-first:2.05 11:11:18 INFO - PROCESS | 1969 | Values: 66.0 73.0 71.0 75.0 72.0 68.0 70.0 70.0 70.0 72.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#10] stanford-crypto-aes Cycles:10 Average:83.30 Median:84.00 stddev:1.25 (1.5%) stddev-sans-first:1.32 11:11:18 INFO - PROCESS | 1969 | Values: 83.0 84.0 82.0 81.0 82.0 84.0 84.0 84.0 85.0 84.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#11] stanford-crypto-ccm Cycles:10 Average:147.50 Median:137.00 stddev:36.83 (26.9%) stddev-sans-first:38.83 11:11:18 INFO - PROCESS | 1969 | Values: 136.0 134.0 137.0 137.0 136.0 133.0 137.0 125.0 251.0 149.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:162.50 stddev:19.80 (12.2%) stddev-sans-first:20.84 11:11:18 INFO - PROCESS | 1969 | Values: 161.0 162.0 159.0 163.0 165.0 160.0 160.0 161.0 164.0 224.0 11:11:18 INFO - PROCESS | 1969 | 11:11:18 INFO - PROCESS | 1969 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:66.00 stddev:0.95 (1.4%) stddev-sans-first:0.78 11:11:18 INFO - PROCESS | 1969 | Values: 64.0 66.0 67.0 67.0 65.0 66.0 66.0 66.0 65.0 65.0 11:11:18 INFO - PROCESS | 1969 | -------- Summary: end -------- 11:11:18 INFO - PROCESS | 1969 | 11:11:19 INFO - TEST-INFO | 1969: exit 0 11:11:19 INFO - TEST-OK | kraken | took 59411ms 11:11:19 INFO - SUITE-END | took 1176s 11:11:19 INFO - Completed test suite (00:19:36) 11:11:19 INFO - javascript benchmark 11:11:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.719438847698, 3321.9335613287744, 3326.4511355626323, 3353.2287573858, 3368.6968172864454, 5591.8042868499615, 5665.575081868861, 5696.231008813809, 5716.056850619648, 5747.195392645028, 5078.314427552288, 5416.781224224547, 5692.430756924316, 5734.053881109612, 5736.282964629421, 5181.35233095863, 5204.32343795306, 5234.136367499377, 5236.502532259429, 5283.577313814905, 4341.370501277329, 5274.55166310864, 5305.0, 5310.654807437523, 5311.097113490697, 5156.278121063054, 5198.246254293144, 5213.582913366921, 5224.895502089956, 5284.973575132119, 5278.630495865291, 5295.947040529584, 5307.469253074701, 5322.0952438085615, 5366.248725178478, 5109.310243117169, 5115.258287548322, 5185.248139019859, 5202.92195617066, 5203.375594928595, 5594.9720251398485, 5675.602707810413, 5675.63108397957, 5692.089265717506, 5707.343655479632, 6206.25524937006, 6292.622442653455, 6297.622142671455, 6304.054391841215, 6316.463100636452, 5128.692278463267, 6132.356102609251, 6274.937250627527, 6324.525660575475, 6351.682415879188, 5817.360090390096, 5858.062709966385, 5894.204282421904, 5894.440028197358, 5894.616849904828, 6247.812565623039, 6262.060690896402, 6276.529260305495, 6278.560500764903, 6289.465395441347, 5989.460948514655, 6062.484688801413, 6129.854550907146, 6210.223722034746, 6238.844028899223, 5940.108983652487, 6321.146645202839, 6324.9051264231075, 6327.841803954938, 6331.05034244867, 6263.592866463665, 6270.9372906270355, 6298.275698294663, 6309.936900631027, 6320.0, 6143.447089761943, 6146.401935496803, 6158.692065396713, 6235.376462353819, 6312.558120931582, 4132.462779838662, 4907.852764417074, 4908.680935739166, 4915.631327650369, 4946.060248552835, 4394.428724265825, 4401.22978478765, 4402.515723270438, 4406.140802543473, 4467.0, 5739.139129130664, 5756.251687280626, 5773.6824474654295, 5780.913286300709, 5833.562482813806, 6169.259688837368, 6213.161223234805, 6273.749050037947, 6292.905606415908, 6323.873522529524, 5742.397048309951, 5759.078547432391, 5761.308642962871, 5774.509166720792, 5808.796692115756, 5349.170878513836, 5394.757235924393, 5408.269883565747, 5437.32033495813, 5438.510534051954, 6215.160953271251, 6307.653079080695, 6324.2410910691015, 6330.588511746721, 6414.807555773335, 6279.937200627935, 6283.937160628335, 6307.495400367868, 6330.683465826783, 6353.0, 6343.809685709251, 6348.746050157942, 6362.6818659067785, 6372.87254254903, 6373.362663733589, 6097.917917222642, 6104.755809767559, 6123.770712314271, 6176.845578860564, 6251.812445626639, 5944.851378715567, 6264.185655864709, 6285.842853928689, 6287.182666253358, 6316.0, 5941.049432090845, 6101.237345331833, 6151.692415379123, 6158.414950579688, 6178.382161783786, 4691.7184968901975, 6060.030395136757, 6096.829073522031, 6129.847850912788, 6141.2098563120835, 5922.851928701644, 5952.345242023461, 5965.105234214902, 5987.251593550806, 6001.159837622824, 5864.530837533072, 5920.230370051866, 6135.956887329254, 6139.662318572521, 6146.877062458636, 4988.5260900214425, 5896.852578685567, 5929.96225660499, 5946.494547963472, 5996.520278377807], "value": 5684.5322483402, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.5007242395484, 511.5702809639904, 523.502672461162, 540.5540429145954, 543.1445473379429, 32602.510962335586, 33178.50232246518, 33329.00012999611, 33341.33317333652, 33348.0, 33223.16942076449, 33826.323473530574, 33971.15072123192, 43576.56423435765, 44680.0, 9661.420314781119, 9819.607215711363, 9840.065193806613, 9853.458059806708, 9855.753606159831, 9655.951720241388, 9709.271804614647, 9746.853797193047, 9750.36622619531, 9759.121679048883, 9761.609535618602, 9764.0, 9799.657012004582, 9801.950990245039, 9806.460644664541, 9733.367331123454, 9762.658306959293, 9767.218622510252, 9770.853437198448, 9780.706578802576, 9639.518024098767, 9703.951480242553, 9704.0, 9713.514324283828, 9715.951420242924, 33136.50295245573, 40530.39204411936, 40727.0, 40776.79611601953, 40805.59194408048, 3556.893293201208, 3589.46158076288, 3640.690541303992, 3755.8122093895468, 3783.9621603783885, 32229.0, 33339.6666033339, 33507.0, 33565.49651755226, 33719.157021074425, 3392.728581713457, 3423.8801641942414, 3464.7747896386654, 3472.0451875734125, 3500.842462089212, 534898.3255083739, 540893.0, 542663.0, 543365.0, 544754.0, 34296.314073718386, 34606.653933460846, 34638.480422793175, 64636.0, 65395.346046539875, 36211.4568281476, 36308.81845590755, 36352.45471317932, 36354.27291454156, 36486.817565912, 14998.0, 15318.846811531743, 15319.540413787605, 15335.156566388956, 15347.539573812803, 12897.677558060936, 12976.545820896412, 12981.221126732238, 12995.610131696065, 13004.089713720137, 6963.895541566881, 7956.482828616122, 7960.56216908064, 8010.3191228745045, 8084.272415482517, 3293.374258890851, 3314.0886256279714, 3314.867405303809, 3316.369889720945, 3352.3630510202984, 969.1568335548118, 976.6972238606055, 988.7231575158823, 1040.0847254416065, 1067.4449286371043, 30506.84746576253, 30749.692503074682, 30949.91675291354, 31532.842335788173, 31664.683353166172, 1105.7180418993105, 1118.5190368141778, 1139.6239241050434, 1146.9849183472686, 1184.810430331143, 15840.0, 16228.350865965229, 16312.0, 16513.44128908735, 16667.583310417092, 725449.0, 733494.0, 738357.0, 738547.0, 738878.0, 49357.0, 58872.0, 60453.0, 60748.69625651844, 60891.08663370053, 1271.9427625756864, 1322.8412590489204, 1397.3572156807982, 1409.7814838700017, 1439.3091316168297, 30637.080887573407, 31816.204594885312, 32013.35973280568, 32699.509507357885, 32941.50587741138, 51657.483425165265, 52036.73981630067, 52228.47771522236, 52252.95494090173, 52464.73767631137, 561353.0, 565040.0, 566013.0, 568417.0, 569888.0, 26943.0, 27675.86162069177, 27836.0, 27887.0, 27902.86048569744, 48008.5199148018, 48837.0, 49331.50668493269, 49625.75187124041, 49888.501114989835, 39507.209855803296, 40600.593994060866, 41029.79485102555, 41471.58528414677, 41681.79159104185, 35603.64396356003, 40017.99955001148, 40109.0, 40341.0, 41056.76829695114, 38443.42334865035, 38454.80772596119, 39185.608143918194, 39419.605803941595, 39613.40579891361, 34757.0, 35214.29571408609, 35328.0, 35356.292874142884, 35450.82274588611, 24930.626040609044, 25039.7384758418, 25454.981800727764, 25956.740432595434, 26366.336633663366], "value": 19011.652413662043, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.2369236449637, 388.9027743064234, 390.8592906553642, 394.38869751884573, 413.1674675528808, 15707.293171807267, 18023.279068837248, 21010.844403557836, 21566.676499852514, 21594.352169434944, 21388.037538310775, 21635.134594616196, 21639.67540486894, 21641.3507594772, 21724.674129888066, 5709.257796486451, 5887.381824908376, 5981.790637327695, 5986.3415024347505, 6142.262928448592, 5914.733836977346, 5948.613340132878, 6009.429104235091, 6017.578769486137, 6023.548233882477, 5024.698518088908, 5964.284285885678, 6146.877062458748, 6149.723262453201, 6189.50483961282, 5895.380984996556, 6112.69436528174, 6134.355892631276, 6153.815385538441, 6158.599691020092, 5880.147378630118, 5913.438223368773, 5915.467607915309, 5919.911201331984, 5944.821655350346, 11075.778484430346, 13683.335076369867, 22716.86415679226, 25898.503702000176, 26589.06938257171, 2210.624193887043, 2257.898394572248, 2309.561183375153, 2446.5963116085823, 2480.6155045967903, 9399.90600094004, 9789.853152202651, 9821.950890245575, 10217.182625390025, 10507.00183482568, 1816.8728189026774, 1817.37300631282, 1866.0949439521821, 1879.6992481203079, 1938.8866135911924, 80785.59607201985, 82420.0, 117190.0, 122845.38577307056, 129258.0, 9937.602495900228, 11242.55029798799, 11569.363684997237, 11691.473883675255, 11922.1654484187, 14674.413023479156, 14676.266186690837, 14708.33812478441, 14726.190059546614, 14731.04248223862, 7421.4804963653105, 8398.748037558884, 8490.872636910452, 8642.95678521616, 8736.08271131522, 8563.95718021406, 8564.657413703506, 8579.571021448903, 8697.739067827975, 8729.258013068833, 4119.526254480713, 4298.20483210607, 4517.5482451755215, 4595.586397224266, 4642.442906851199, 1700.0989475577962, 1819.8271164239377, 1896.9715454268196, 1912.32112600025, 1936.4093951344694, 857.6140736668524, 900.6532484993324, 901.715959472764, 930.511481472219, 998.4823068935178, 20948.581028379347, 21849.344519664435, 22607.09571617117, 22614.547709045728, 22668.319950401514, 921.8847644044495, 969.2618353152372, 987.8863930647924, 1017.801528701896, 1039.3192458939407, 9260.583273752698, 10813.459327033617, 11402.885971140182, 11577.826332605018, 11765.529378824922, 616224.0, 618714.0, 631433.0, 635696.0, 635755.0, 74525.25474745291, 77913.61043194862, 142045.0, 143212.0, 151992.0, 877.4209022045419, 978.3787295067683, 1011.1051719228388, 1027.091024443373, 1028.7736697926443, 19601.803981959998, 20078.59449838556, 20313.49216269546, 20360.694589581464, 20411.081501332774, 51399.48600513947, 54050.72974635102, 54674.0, 55872.72063639656, 58451.70774146102, 84082.57958710412, 86612.0, 87511.0, 88483.0, 90264.5486772562, 7605.809854753676, 8226.753197404087, 8234.876476852733, 8289.295409890226, 8421.831563368576, 12886.806697899727, 13193.802092968805, 13399.526052134062, 13410.9329453356, 13442.991775616672, 11377.203595748219, 11411.429428528707, 11590.478428470571, 11612.825807613062, 11734.647960561197, 11216.719582010515, 11471.598494052669, 11487.827682584935, 11547.711307217387, 11741.060715142597, 10967.287126336763, 11115.777684446428, 11700.590479333183, 11724.355160466257, 11995.340256285988, 7209.6034718091005, 7218.783436496913, 7594.772156835304, 8216.548089854876, 8458.703945361884, 9772.853407198756, 9952.701418957442, 11091.0, 11746.29522228669, 12023.939880300542], "value": 9617.314370688282, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.6535866033491, 1534.3095606976856, 1575.4328441760968, 1807.8372946434818, 1984.2658216459904, 7879.527228366295, 8764.517951512673, 8969.416987895798, 9027.90972090279, 9064.138906803859, 8716.956415217915, 8942.150495702914, 8945.239654629364, 8946.955265223682, 8950.507722075283, 2091.006771783406, 2171.771963943787, 2305.873176975266, 2340.578695834749, 2387.0451819272344, 2377.869217193054, 2418.8790560471994, 2419.1412048722714, 2422.7819496245334, 2423.9515209695796, 2358.127492827656, 2387.4866903615807, 2402.25530085674, 2403.5553422616786, 2410.108259943823, 2081.8959052047335, 2277.2940388479624, 2336.2757545161053, 2350.2009316832186, 2352.9529409411803, 2333.5916214662366, 2348.16640092768, 2349.8120150387927, 2351.3298709867604, 2369.7748713872156, 8738.344624153215, 8809.163129502687, 8813.0, 8816.603252853636, 8830.293576513863, 5241.266222728821, 5424.105022671254, 5424.918626220609, 5426.430224826376, 5432.158015507603, 3501.842417091237, 3583.8387272572536, 3694.7967861767593, 3702.333579955607, 3703.9444408333898, 1075.0, 1091.3615534912085, 1102.3000394749313, 1115.5370521233679, 1118.1222740148944, 11824.527018919232, 11866.406679665983, 11877.465514051888, 11880.405979701067, 11897.524099036029, 4586.656000799887, 4630.6063984561015, 4845.1278769821065, 4872.878178045577, 4875.146849301358, 10000.699979000641, 10009.499525023719, 10013.549390277454, 10015.248856335658, 10033.14718248957, 4579.244424669892, 5409.891802163935, 5418.864528386822, 5422.213779002022, 5422.3764267109, 4404.933925991113, 4565.863024109282, 4800.775802170424, 4920.950790492049, 4949.678270912379, 3971.205758848242, 3983.760974341491, 4046.393041043867, 4094.0, 4109.3425051991535, 1041.9374837509774, 1240.6092080994458, 1291.754566632337, 1296.9624300559515, 1318.551692424561, 770.2413123073768, 840.7855996720797, 927.526961249754, 943.8579319024058, 968.2302569457298, 5909.970450147808, 5972.134040564094, 6125.632462052291, 6130.540209484307, 6195.039768835838, 895.4806212396794, 932.06327640722, 951.4957072751453, 971.707628853623, 971.9805603887883, 3976.980115099464, 4021.557628660845, 4102.774347410921, 4118.155778065489, 4125.876223713293, 10514.526846291936, 10770.407627580402, 10773.192010599236, 10966.506507207196, 11005.284656497302, 10812.405317707602, 10819.83770243447, 10827.971342722429, 10832.350058996486, 10836.133109351233, 8078.23256790604, 8403.285720713686, 8425.70510032146, 8441.620127094295, 8452.788680283042, 4902.338184345211, 5803.0, 5985.431384018511, 6035.0, 6063.090536419572, 10048.69853904384, 10190.184785217312, 10199.031092046243, 10206.744831379276, 10213.927537608444, 9676.612935482783, 10005.449700266556, 10223.028812262823, 10228.590856365661, 10302.22733295021, 8261.504309741435, 8416.495010299157, 8461.06928237881, 8485.57572121379, 8543.615537300711, 7117.3238542338395, 8367.456115352483, 8436.198561136682, 8539.53032583189, 8539.530325832138, 6113.57204995646, 7579.204183560647, 7626.847463050819, 7633.0458692663415, 7712.768616941501, 7534.623268836646, 7545.886811697938, 7582.090149182133, 7601.239876012354, 7621.428392870668, 7033.613151276729, 7109.111361079865, 7193.856122877409, 7198.49610527279, 7214.96392518034, 5128.0, 5129.8717532062, 5131.743412829269, 5171.3018742469285, 5246.31797866275, 5111.565516931102, 5137.409197942285, 5228.45101264377, 5233.215017747369, 5370.409254981871], "value": 4650.311209564367, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.49288181618505, 310.1625610850703, 320.11169006008333, 324.97725159238865, 325.5832534356025, 4488.214562451574, 5165.535101840834, 5305.355339844651, 5344.0, 5344.652597581155, 4508.413906192199, 5008.574271186953, 5139.2548080528395, 5204.219367094937, 5204.375474943003, 3732.4587934749443, 3930.8231129599235, 3983.0838907051398, 4014.8193331300017, 4059.675225981922, 3885.630865067814, 3992.281389349916, 4034.821196553872, 4039.3738970459626, 4054.8175332109977, 3267.5262086997373, 3433.6738009889145, 3629.2922880038404, 3924.606764598569, 4031.475908131939, 3435.9999999999877, 3823.5985221551755, 4028.295048366524, 4029.033032072311, 4030.2191487873565, 3526.982365088184, 3529.294141171776, 3539.699125574329, 3549.083494912744, 3552.946705799415, 4161.729487583328, 4391.560843915615, 4753.31076993834, 4934.335458852215, 5173.663711858717, 3779.3952967525065, 4494.258447356182, 4515.029268707243, 4527.370146747168, 4541.751018469914, 4656.906861862778, 4912.9508704913205, 5158.484151584849, 5389.622726409153, 5394.514493695547, 3541.8406171722336, 3691.7046636269033, 3807.0292075520842, 3842.7886466244345, 3854.3062248795204, 5776.826695199193, 5793.69641830589, 5830.358660547379, 5830.959164292506, 5863.70681465925, 3600.7217437809722, 3641.435577485494, 3837.4052021936645, 3843.865464708722, 3844.231153769257, 2999.550067489894, 3862.478565393636, 3897.9610203897596, 3899.317619416591, 3908.179282350681, 3971.5234171899556, 4058.289799285113, 4505.459344878635, 4541.205289074399, 4573.611243044378, 5539.861503462445, 5540.63985840919, 5545.334559852843, 5557.499825015768, 5563.666180029211, 1239.5971309324505, 1289.232906420678, 1289.2457912121513, 1289.4842063174806, 1301.466398776497, 683.7641013850214, 753.1414187825883, 760.2169765141913, 809.3606051219589, 895.4671970177734, 4802.6638135330195, 4990.925136122961, 5134.922976155361, 5167.354080739908, 5220.530152286313, 5499.652585116592, 5512.310961129859, 5557.916631250535, 5707.0, 5717.571182161355, 4824.758762061953, 5006.949930500721, 5045.697258164522, 5049.697018178921, 5070.909754402821, 2442.5710959089133, 2933.2226959855657, 2962.762978961678, 2981.701829817001, 2983.254186453387, 5326.493983071602, 5451.837933998271, 5824.184614154022, 5826.970865145732, 5829.358770535237, 4864.440589332201, 5228.4510126436935, 5623.875224955025, 5742.827715168552, 5791.117886686844, 5040.974795126001, 5485.917711234414, 5546.7797084641315, 5819.418058194231, 5868.970655146697, 4938.0, 5083.466236045162, 5242.243848310706, 5569.582281328998, 5636.633618814764, 4732.100900828831, 5403.486668766461, 5551.25058117149, 5592.706990134026, 5594.944050559442, 4604.447466304065, 5212.853172302088, 5229.555487783429, 5569.2120115632515, 5823.5923485355615, 3780.2061567071223, 3808.676262517717, 3823.5411750590106, 3827.961720382872, 3844.8269827857252, 5562.666240025612, 5653.462921022497, 5657.557322882639, 5678.296511046752, 5709.400512946081, 4594.517575654508, 5245.711485868162, 5453.554807975969, 5483.670979741069, 5500.477454641803, 5101.897962040813, 5220.608454365862, 5300.3639563252655, 5401.3248343957, 5446.0, 4218.502431636724, 5234.24103504982, 5312.840614781563, 5314.548263397654, 5336.706481143575, 5082.390113186293, 5131.89664222194, 5347.278117454094, 5426.395330241585, 5485.6434331768305, 4573.702709323883, 5300.628955973045, 5373.838784836461, 5438.320209973754, 5445.618806683494], "value": 3990.9189269173066, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [730.5434103685196, 769.054063501893, 793.6388943030921, 809.3929552835374, 873.4584557574301, 15268.0, 15881.967672101306, 16436.0960147192, 16442.67114657706, 16468.505944821645, 14860.405583776663, 15068.095914245154, 15076.47232346868, 15077.246137693126, 15116.017458865166, 660.389140045456, 725.680700491782, 737.3142977031358, 919.8068405634808, 937.4843835890267, 940.4310392212716, 947.1428357336621, 951.3388195204336, 958.9520523973773, 959.151151231162, 947.9668211612595, 1031.8142734307821, 1033.3902997231633, 1034.4414016431156, 1038.901304376083, 788.9092754333268, 969.9903000969971, 1000.0649392817738, 1015.4973288222303, 1026.8254396752532, 754.4718696912129, 756.8107973006748, 760.1562265884863, 764.6482617995729, 771.2634434115406, 15261.923690381587, 15277.694446111127, 15295.388184472607, 15305.234738263041, 15320.0, 14240.643983900381, 14439.783403248959, 14444.0, 14452.132872027607, 14517.927410362987, 13272.734545309137, 13319.26744029078, 13329.266890321029, 13536.255505947169, 13662.590122296348, 4656.208444564432, 4677.812887484497, 4713.952860471386, 4790.508266082049, 4798.736069516175, 105796.00733937624, 106821.0, 107250.0, 107273.9272607264, 107705.0, 10113.0, 11165.218434709492, 11447.313161210353, 11544.59593914209, 11587.710307242387, 23156.421089472897, 23182.95676694553, 23192.0, 23235.883820581126, 23293.068277268718, 7851.9214807851195, 7882.132965373804, 7998.4001199910235, 8139.226773456512, 8214.958925205336, 4591.403117594691, 4633.142868569325, 4640.419947506562, 4732.668713190113, 4767.880802979953, 3549.8757543485854, 3708.4808126862263, 3712.3503386907723, 3713.925721485555, 3717.423799311111, 628.8616504369031, 646.0696596900449, 646.6184950879004, 652.6834485274733, 663.857270686795, 563.6899705162144, 654.2868273581723, 656.2027137028533, 666.8766278238542, 668.8394785251504, 8621.0, 8736.301095912311, 8940.0, 10400.427976461217, 10620.89379106199, 575.5136909311625, 634.552640388525, 636.9299377068519, 714.989275160873, 736.6464097233251, 4281.807318670667, 4419.9779001105435, 4451.443569553806, 4497.797599107983, 4534.183846907522, 116412.0, 138420.30789845987, 144339.94810181594, 152370.0, 154567.0, 79638.6018069906, 108405.0, 114817.12957176137, 119482.0, 127986.36006819906, 110734.0, 112534.43732781283, 112780.0, 115249.0, 117150.0, 8333.999840038226, 8857.3799834011, 9107.087511622369, 9126.0, 9329.0, 55805.0, 63500.0, 63659.3634063672, 64537.0, 65833.34166658272, 87691.0, 91884.0, 96593.0, 96615.0, 98894.11058894053, 12018.579349722717, 12154.513819447124, 12171.452284647035, 12318.753624927273, 12507.687307817378, 13515.391807368494, 13557.322133893069, 13602.183868967893, 14240.074395164282, 14841.0, 14678.486252981094, 14687.41250349974, 14741.705165896836, 14826.925865370604, 15357.308921098354, 14351.784723228951, 14406.351714173097, 14488.348024338718, 14547.927260364053, 14609.853901461274, 13956.09285396446, 14272.429102836186, 14282.42870285177, 14306.0, 14361.138331700131, 10243.334183278062, 11509.922432270645, 11615.186936914673, 11924.821127683264, 12083.637490875288, 11613.825792613286, 11651.708707282385, 11752.712661740266, 12015.279083255033, 12063.81904271419], "value": 8071.768348875798, "unit": "score"}], "name": "dromaeo_css", "value": 7334.821142689973, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 112.0, 112.0, 112.0, 113.0, 112.0, 110.0, 113.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 105.0, 108.0, 158.0, 107.0, 106.0, 125.0, 111.0, 174.0, 136.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 183.0, 183.0, 184.0, 178.0, 182.0, 181.0, 181.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 74.0, 59.0, 88.0, 58.0, 56.0, 64.0, 68.0, 104.0, 79.0], "value": 73.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 93.0, 93.0, 71.0, 74.0, 69.0, 96.0, 70.0, 68.0, 103.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 92.0, 134.0, 104.0, 87.0, 84.0, 95.0, 85.0, 86.0, 83.0], "value": 93.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 128.0, 150.0, 93.0, 91.0, 92.0, 92.0, 92.0, 128.0, 152.0], "value": 111.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 86.0, 104.0, 107.0, 83.0, 86.0, 83.0, 83.0, 82.0], "value": 88.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 51.0, 53.0, 51.0, 52.0, 52.0, 53.0, 53.0, 53.0], "value": 52.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 73.0, 71.0, 75.0, 72.0, 68.0, 70.0, 70.0, 70.0, 72.0], "value": 70.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 82.0, 81.0, 82.0, 84.0, 84.0, 84.0, 85.0, 84.0], "value": 83.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 134.0, 137.0, 137.0, 136.0, 133.0, 137.0, 125.0, 251.0, 149.0], "value": 147.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 159.0, 163.0, 165.0, 160.0, 160.0, 161.0, 164.0, 224.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 67.0, 67.0, 65.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1457.3000000000002, "alertThreshold": 2.0}]} 11:11:19 INFO - Return code: 0 11:11:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:11:19 INFO - # TBPL SUCCESS # 11:11:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:11:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:11:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:11:19 INFO - 2016-07-06 11:11:19.734 screenresolution[1973:20328] starting screenresolution argv=screenresolution get 11:11:19 INFO - 2016-07-06 11:11:19.737 screenresolution[1973:20328] Display 0: 1600x1200x32@60 11:11:19 INFO - 2016-07-06 11:11:19.743 screenresolution[1974:20331] starting screenresolution argv=screenresolution list 11:11:19 INFO - Available Modes on Display 0 11:11:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:11:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:11:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:11:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:11:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:11:19 INFO - Intel Iris: 11:11:19 INFO - Chipset Model: Intel Iris 11:11:19 INFO - Type: GPU 11:11:19 INFO - Bus: Built-In 11:11:19 INFO - VRAM (Dynamic, Max): 1536 MB 11:11:19 INFO - Vendor: Intel (0x8086) 11:11:19 INFO - Device ID: 0x0a2e 11:11:19 INFO - Revision ID: 0x0009 11:11:19 INFO - Displays: 11:11:19 INFO - Display: 11:11:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:11:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:11:19 INFO - Display Serial Number: 1600x1200 60 11:11:19 INFO - Main Display: Yes 11:11:19 INFO - Mirror: Off 11:11:19 INFO - Online: Yes 11:11:19 INFO - Rotation: Supported 11:11:19 INFO - Return code: 0 11:11:19 INFO - Running post-action listener: _resource_record_post_action 11:11:19 INFO - ##### 11:11:19 INFO - ##### Finished run-tests step (success) 11:11:19 INFO - ##### 11:11:19 INFO - Running post-run listener: _resource_record_post_run 11:11:20 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 200704; Write bytes: 705089536; Read time: 3; Write time: 1959 11:11:20 INFO - TinderboxPrint: CPU usage
53.4% 11:11:20 INFO - TinderboxPrint: I/O read bytes / time
200,704 / 3 11:11:20 INFO - TinderboxPrint: I/O write bytes / time
705,089,536 / 1,959 11:11:20 INFO - TinderboxPrint: CPU idle
2,257.2 (46.6%) 11:11:20 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 11:11:20 INFO - TinderboxPrint: CPU user
1,622.1 (33.5%) 11:11:20 INFO - TinderboxPrint: Swap in / out
406,700,032 / 0 11:11:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 407845376; Write bytes: 410702336; Read time: 29980; Write time: 846 11:11:20 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 200704; Write bytes: 293010944; Read time: 3; Write time: 1101 11:11:20 INFO - Running post-run listener: _upload_blobber_files 11:11:20 INFO - Blob upload gear active. 11:11:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:11:20 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:11:20 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:11:20 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:11:20 INFO - (blobuploader) - INFO - Open directory for files ... 11:11:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:11:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:11:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:21 INFO - (blobuploader) - INFO - Done attempting. 11:11:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:11:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:11:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:22 INFO - (blobuploader) - INFO - Done attempting. 11:11:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:11:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:11:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:11:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:11:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:11:25 INFO - (blobuploader) - INFO - Done attempting. 11:11:25 INFO - (blobuploader) - INFO - Iteration through files over. 11:11:25 INFO - Return code: 0 11:11:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:11:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:11:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bda477caf067bf682815644e67a8c57c751935b78e1e3f47eeb5779f21d02f7bc4417f7e9b827275854e8064049f658be544c87a286c66a50e0ca874f8b8bfb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a60de24c7e42d2cfec7eb9bee106a2f96ce2c9c3e480e264cb7cbfc3087af93894cfb35f386572d80300051a133ca5f9770ab1eccd2b89806b686eb4f5d5994", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3858bfe4e14b9bf0dd3347d827f29391afa640640a4fd86003f768de8a8f99a63df75449672691a4908c892956ce71b6f00c25db6a560c8ed09b9f5c81b9199b"} 11:11:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:11:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:11:25 INFO - Contents: 11:11:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bda477caf067bf682815644e67a8c57c751935b78e1e3f47eeb5779f21d02f7bc4417f7e9b827275854e8064049f658be544c87a286c66a50e0ca874f8b8bfb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a60de24c7e42d2cfec7eb9bee106a2f96ce2c9c3e480e264cb7cbfc3087af93894cfb35f386572d80300051a133ca5f9770ab1eccd2b89806b686eb4f5d5994", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3858bfe4e14b9bf0dd3347d827f29391afa640640a4fd86003f768de8a8f99a63df75449672691a4908c892956ce71b6f00c25db6a560c8ed09b9f5c81b9199b"} 11:11:25 INFO - Running post-run listener: copy_logs_to_upload_dir 11:11:25 INFO - Copying logs to upload dir... 11:11:25 INFO - mkdir: /builds/slave/test/build/upload/logs 11:11:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.059244 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-07-06 11:11:25.559254) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:11:25.563155) ========= 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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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/Fx-Team/sha512/bda477caf067bf682815644e67a8c57c751935b78e1e3f47eeb5779f21d02f7bc4417f7e9b827275854e8064049f658be544c87a286c66a50e0ca874f8b8bfb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a60de24c7e42d2cfec7eb9bee106a2f96ce2c9c3e480e264cb7cbfc3087af93894cfb35f386572d80300051a133ca5f9770ab1eccd2b89806b686eb4f5d5994", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3858bfe4e14b9bf0dd3347d827f29391afa640640a4fd86003f768de8a8f99a63df75449672691a4908c892956ce71b6f00c25db6a560c8ed09b9f5c81b9199b"} build_url:https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011567 build_url: 'https://queue.taskcluster.net/v1/task/Z9Faf4qnQlORtvDLzYF8XA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bda477caf067bf682815644e67a8c57c751935b78e1e3f47eeb5779f21d02f7bc4417f7e9b827275854e8064049f658be544c87a286c66a50e0ca874f8b8bfb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5a60de24c7e42d2cfec7eb9bee106a2f96ce2c9c3e480e264cb7cbfc3087af93894cfb35f386572d80300051a133ca5f9770ab1eccd2b89806b686eb4f5d5994", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/3858bfe4e14b9bf0dd3347d827f29391afa640640a4fd86003f768de8a8f99a63df75449672691a4908c892956ce71b6f00c25db6a560c8ed09b9f5c81b9199b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 11:11:25.596772) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:11:25.597091) ========= 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.YdBD1EVx4d/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.UhBMWG6u1T/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.005257 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 11:11:25.646087) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:11:25.646419) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 11:11:25.646764) ========= ========= Total master_lag: 0.18 =========