builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0246 starttime: 1467861860.09 results: success (0) buildid: 20160706181624 builduid: a7275eb20cf749aa9d5ace23bfe39601 revision: 39ec98bf2580a236471714c28b336d8c974cc365 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:20.088114) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:20.088500) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:20.088776) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:20.115469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-06 20:24:20.115738) ========= 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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:20-- 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% 145M=0s 2016-07-06 20:24:20 (145 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.094707 ========= master_lag: 2.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-07-06 20:24:22.544779) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:22.545135) ========= 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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.021193 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:22.590482) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 20:24:22.590831) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39ec98bf2580a236471714c28b336d8c974cc365 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 39ec98bf2580a236471714c28b336d8c974cc365 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:22,671 truncating revision to first 12 chars 2016-07-06 20:24:22,672 Setting DEBUG logging. 2016-07-06 20:24:22,672 attempt 1/10 2016-07-06 20:24:22,672 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39ec98bf2580?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-06 20:24:23,470 unpacking tar archive at: mozilla-central-39ec98bf2580/testing/mozharness/ program finished with exit code 0 elapsedTime=1.060343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 20:24:23.674930) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:23.675322) ========= script_repo_revision: 39ec98bf2580a236471714c28b336d8c974cc365 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:23.675715) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:23.675989) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 20:24:23.694105) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-06 20:24:23.694383) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:23 INFO - MultiFileLogger online at 20160706 20:24:23 in /builds/slave/test 20:24:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 20:24:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:24:23 INFO - {'append_to_log': False, 20:24:23 INFO - 'base_work_dir': '/builds/slave/test', 20:24:23 INFO - 'blob_upload_branch': 'Firefox', 20:24:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:24:23 INFO - 'branch': 'Firefox', 20:24:23 INFO - 'buildbot_json_path': 'buildprops.json', 20:24:23 INFO - 'config_files': ('talos/mac_config.py',), 20:24:23 INFO - 'default_actions': ('clobber', 20:24:23 INFO - 'read-buildbot-config', 20:24:23 INFO - 'download-and-extract', 20:24:23 INFO - 'populate-webroot', 20:24:23 INFO - 'create-virtualenv', 20:24:23 INFO - 'install', 20:24:23 INFO - 'run-tests'), 20:24:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:24:23 INFO - 'download_minidump_stackwalk': True, 20:24:23 INFO - 'download_symbols': 'ondemand', 20:24:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:24:23 INFO - 'tooltool.py': '/tools/tooltool.py', 20:24:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:24:23 INFO - '/tools/misc-python/virtualenv.py')}, 20:24:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:24:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:24:23 INFO - 'installer_path': 'installer.exe', 20:24:23 INFO - 'log_level': 'info', 20:24:23 INFO - 'log_name': 'talos', 20:24:23 INFO - 'log_to_console': True, 20:24:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:24:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:24:23 INFO - 'opt_config_files': (), 20:24:23 INFO - 'pip_index': False, 20:24:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:24:23 INFO - 'cmd': ('bash', 20:24:23 INFO - '-c', 20:24:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:24:23 INFO - 'enabled': True, 20:24:23 INFO - 'halt_on_failure': False, 20:24:23 INFO - 'name': 'check_screen_resolution'},), 20:24:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:24:23 INFO - 'cmd': ('bash', 20:24:23 INFO - '-c', 20:24:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:24:23 INFO - 'enabled': True, 20:24:23 INFO - 'halt_on_failure': False, 20:24:23 INFO - 'name': 'check_screen_resolution'},), 20:24:23 INFO - 'run_cmd_checks_enabled': True, 20:24:23 INFO - 'sps_profile': False, 20:24:23 INFO - 'sps_profile_interval': 0, 20:24:23 INFO - 'suite': 'dromaeojs', 20:24:23 INFO - 'system_bits': '32', 20:24:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:24:23 INFO - 'title': 't-yosemite-r7-0246', 20:24:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:24:23 INFO - 'use_talos_json': True, 20:24:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:24:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:24:23 INFO - 'work_dir': 'build'} 20:24:23 INFO - ##### 20:24:23 INFO - ##### Running clobber step. 20:24:23 INFO - ##### 20:24:23 INFO - Running pre-action listener: _resource_record_pre_action 20:24:23 INFO - Running main action method: clobber 20:24:23 INFO - rmtree: /builds/slave/test/build 20:24:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:24:25 INFO - Running post-action listener: _resource_record_post_action 20:24:25 INFO - ##### 20:24:25 INFO - ##### Finished clobber step (success) 20:24:25 INFO - ##### 20:24:25 INFO - ##### 20:24:25 INFO - ##### Running read-buildbot-config step. 20:24:25 INFO - ##### 20:24:25 INFO - Running pre-action listener: _resource_record_pre_action 20:24:25 INFO - Running main action method: read_buildbot_config 20:24:25 INFO - Using buildbot properties: 20:24:25 INFO - { 20:24:25 INFO - "project": "", 20:24:25 INFO - "product": "firefox", 20:24:25 INFO - "script_repo_revision": "production", 20:24:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 20:24:25 INFO - "repository": "", 20:24:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 20:24:25 INFO - "buildid": "20160706181624", 20:24:25 INFO - "pgo_build": "False", 20:24:25 INFO - "basedir": "/builds/slave/test", 20:24:25 INFO - "buildnumber": 282, 20:24:25 INFO - "slavename": "t-yosemite-r7-0246", 20:24:25 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:24:25 INFO - "platform": "yosemite_r7", 20:24:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 20:24:25 INFO - "branch": "mozilla-central", 20:24:25 INFO - "repo_path": "mozilla-central", 20:24:25 INFO - "revision": "39ec98bf2580a236471714c28b336d8c974cc365", 20:24:25 INFO - "stage_platform": "macosx64", 20:24:25 INFO - "builduid": "a7275eb20cf749aa9d5ace23bfe39601", 20:24:25 INFO - "slavebuilddir": "test" 20:24:25 INFO - } 20:24:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 20:24:25 INFO - Running post-action listener: _resource_record_post_action 20:24:25 INFO - ##### 20:24:25 INFO - ##### Finished read-buildbot-config step (success) 20:24:25 INFO - ##### 20:24:25 INFO - ##### 20:24:25 INFO - ##### Running download-and-extract step. 20:24:25 INFO - ##### 20:24:25 INFO - Running pre-action listener: _resource_record_pre_action 20:24:25 INFO - Running main action method: download_and_extract 20:24:25 INFO - mkdir: /builds/slave/test/build/tests 20:24:25 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')]} 20:24:25 INFO - https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 20:24:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:24:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 20:24:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:26 INFO - Downloaded 1199 bytes. 20:24:26 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 20:24:26 INFO - Using the following test package requirements: 20:24:26 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:24:26 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 20:24:26 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 20:24:26 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'jsshell-mac.zip'], 20:24:26 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 20:24:26 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 20:24:26 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 20:24:26 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 20:24:26 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 20:24:26 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 20:24:26 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 20:24:26 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:24:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:24:26 INFO - https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:24:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:24:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:24:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:27 INFO - Downloaded 18804849 bytes. 20:24:27 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'] 20:24:27 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 20:24:30 INFO - Return code: 0 20:24:30 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 20:24:30 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')]} 20:24:30 INFO - https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 20:24:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:24:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 20:24:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:32 INFO - Downloaded 18804849 bytes. 20:24:32 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'] 20:24:32 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 20:24:35 INFO - Return code: 0 20:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:35 INFO - https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 20:24:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:24:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 20:24:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:36 INFO - Downloaded 14403333 bytes. 20:24:36 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'] 20:24:36 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 20:24:36 INFO - Return code: 0 20:24:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 20:24:36 INFO - https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 20:24:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:24:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:24:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 20:24:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HrVQ0ptZTS-jDtNdwXOidA/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 20:24:39 INFO - Downloaded 112399887 bytes. 20:24:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:24:39 INFO - mkdir: /builds/slave/test/properties 20:24:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:24:39 INFO - Writing to file /builds/slave/test/properties/build_url 20:24:39 INFO - Contents: 20:24:39 INFO - build_url:https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 20:24:40 INFO - Running post-action listener: _resource_record_post_action 20:24:40 INFO - Running post-action listener: set_extra_try_arguments 20:24:40 INFO - ##### 20:24:40 INFO - ##### Finished download-and-extract step (success) 20:24:40 INFO - ##### 20:24:40 INFO - ##### 20:24:40 INFO - ##### Running populate-webroot step. 20:24:40 INFO - ##### 20:24:40 INFO - Running pre-action listener: _resource_record_pre_action 20:24:40 INFO - Running main action method: populate_webroot 20:24:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:24:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:24:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:24:40 INFO - '--tppagecycles', 20:24:40 INFO - '7'], 20:24:40 INFO - 'tests': ['tsvgm']}}, 20:24:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:24:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:24:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:24:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:24:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:24:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:24:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['damp', 'tps']}, 20:24:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'tests': ['damp', 'tps']}, 20:24:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['dromaeo_dom']}, 20:24:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:24:40 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['basic_compositor_video']}, 20:24:40 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:24:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'other-e10s': {'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['a11yr', 20:24:40 INFO - 'ts_paint', 20:24:40 INFO - 'tpaint', 20:24:40 INFO - 'sessionrestore', 20:24:40 INFO - 'sessionrestore_no_auto_restore', 20:24:40 INFO - 'tabpaint']}, 20:24:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['tsvgx', 20:24:40 INFO - 'tsvgr_opacity', 20:24:40 INFO - 'tart', 20:24:40 INFO - 'tscrollx', 20:24:40 INFO - 'cart']}, 20:24:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:24:40 INFO - 'tsvgr_opacity', 20:24:40 INFO - 'tart', 20:24:40 INFO - 'tscrollx', 20:24:40 INFO - 'cart']}, 20:24:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'talos_options': ['--disable-e10s'], 20:24:40 INFO - 'tests': ['tp5o']}, 20:24:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'tests': ['tp5o']}, 20:24:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'talos_options': ['--disable-e10s', 20:24:40 INFO - '--xperf_path', 20:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:24:40 INFO - 'tests': ['tp5n']}, 20:24:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:24:40 INFO - 'talos_options': ['--xperf_path', 20:24:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:24:40 INFO - 'tests': ['tp5n']}}, 20:24:40 INFO - 'talos.zip': {'path': '', 20:24:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:24:40 INFO - Running post-action listener: _resource_record_post_action 20:24:40 INFO - ##### 20:24:40 INFO - ##### Finished populate-webroot step (success) 20:24:40 INFO - ##### 20:24:40 INFO - ##### 20:24:40 INFO - ##### Running create-virtualenv step. 20:24:40 INFO - ##### 20:24:40 INFO - Running pre-action listener: _resource_record_pre_action 20:24:40 INFO - Running main action method: create_virtualenv 20:24:40 INFO - Creating virtualenv /builds/slave/test/build/venv 20:24:40 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 20:24:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:24:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:24:40 INFO - Using real prefix '/tools/python27' 20:24:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:24:41 INFO - Installing distribute.............................................................................................................................................................................................done. 20:24:44 INFO - Installing pip.................done. 20:24:44 INFO - Return code: 0 20:24:44 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:24:44 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')]} 20:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:44 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')]} 20:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:44 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:44 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 20:24:44 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 20:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:44 INFO - 'HOME': '/Users/cltbld', 20:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:44 INFO - 'LOGNAME': 'cltbld', 20:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:44 INFO - 'MOZ_NO_REMOTE': '1', 20:24:44 INFO - 'NO_EM_RESTART': '1', 20:24:44 INFO - 'PAGER': '/bin/cat', 20:24:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:44 INFO - 'PWD': '/builds/slave/test', 20:24:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:44 INFO - 'SHELL': '/bin/bash', 20:24:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:24:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:24:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:44 INFO - 'USER': 'cltbld', 20:24:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:44 INFO - 'XPC_FLAGS': '0x0', 20:24:44 INFO - 'XPC_SERVICE_NAME': '0', 20:24:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:44 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 20:24:44 INFO - Cleaning up... 20:24:44 INFO - Return code: 0 20:24:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:24:44 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')]} 20:24:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:44 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')]} 20:24:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:44 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:44 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 20:24:44 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 20:24:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:24:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:44 INFO - 'HOME': '/Users/cltbld', 20:24:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:44 INFO - 'LOGNAME': 'cltbld', 20:24:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:44 INFO - 'MOZ_NO_REMOTE': '1', 20:24:44 INFO - 'NO_EM_RESTART': '1', 20:24:44 INFO - 'PAGER': '/bin/cat', 20:24:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:44 INFO - 'PWD': '/builds/slave/test', 20:24:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:44 INFO - 'SHELL': '/bin/bash', 20:24:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:24:44 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:24:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:44 INFO - 'USER': 'cltbld', 20:24:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:44 INFO - 'XPC_FLAGS': '0x0', 20:24:44 INFO - 'XPC_SERVICE_NAME': '0', 20:24:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:44 INFO - Downloading/unpacking psutil>=3.1.1 20:24:44 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 20:24:44 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 20:24:44 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 20:24:44 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 20:24:44 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 20:24:44 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 20:24:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:24:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:24:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:24:47 INFO - Installing collected packages: psutil 20:24:47 INFO - Running setup.py install for psutil 20:24:47 INFO - building 'psutil._psutil_osx' extension 20:24:47 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:24:48 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 20:24:48 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 20:24:48 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 20:24:48 INFO - building 'psutil._psutil_posix' extension 20:24:48 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 20:24:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:24:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:24:48 INFO - ^ 20:24:48 INFO - 1 warning generated. 20:24:48 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 20:24:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:24:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:24:48 INFO - Successfully installed psutil 20:24:48 INFO - Cleaning up... 20:24:48 INFO - Return code: 0 20:24:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:24: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')]} 20:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24: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')]} 20:24:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:24:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:24:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:24:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:48 INFO - 'HOME': '/Users/cltbld', 20:24:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:48 INFO - 'LOGNAME': 'cltbld', 20:24:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:48 INFO - 'MOZ_NO_REMOTE': '1', 20:24:48 INFO - 'NO_EM_RESTART': '1', 20:24:48 INFO - 'PAGER': '/bin/cat', 20:24:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:48 INFO - 'PWD': '/builds/slave/test', 20:24:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:48 INFO - 'SHELL': '/bin/bash', 20:24:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:24:48 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:24:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:48 INFO - 'USER': 'cltbld', 20:24:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:48 INFO - 'XPC_FLAGS': '0x0', 20:24:48 INFO - 'XPC_SERVICE_NAME': '0', 20:24:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:48 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:24:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:24:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:24:51 INFO - Installing collected packages: mozsystemmonitor 20:24:51 INFO - Running setup.py install for mozsystemmonitor 20:24:51 INFO - Successfully installed mozsystemmonitor 20:24:51 INFO - Cleaning up... 20:24:51 INFO - Return code: 0 20:24:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:24:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:24:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:24:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:51 INFO - 'HOME': '/Users/cltbld', 20:24:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:51 INFO - 'LOGNAME': 'cltbld', 20:24:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:51 INFO - 'MOZ_NO_REMOTE': '1', 20:24:51 INFO - 'NO_EM_RESTART': '1', 20:24:51 INFO - 'PAGER': '/bin/cat', 20:24:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:51 INFO - 'PWD': '/builds/slave/test', 20:24:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:51 INFO - 'SHELL': '/bin/bash', 20:24:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:24:51 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:24:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:51 INFO - 'USER': 'cltbld', 20:24:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:51 INFO - 'XPC_FLAGS': '0x0', 20:24:51 INFO - 'XPC_SERVICE_NAME': '0', 20:24:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:51 INFO - Downloading/unpacking jsonschema==2.5.1 20:24: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 20:24: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 20:24: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 20:24: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 20:24: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 20:24: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 20:24:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:24:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:24:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:24:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:24:54 INFO - Downloading functools32-3.2.3-2.tar.gz 20:24:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:24:54 INFO - warning: no files found matching '*.txt' 20:24:54 INFO - no previously-included directories found matching 'build' 20:24:54 INFO - no previously-included directories found matching 'dist' 20:24:54 INFO - no previously-included directories found matching '.git*' 20:24:54 INFO - Installing collected packages: jsonschema, functools32 20:24:55 INFO - Running setup.py install for functools32 20:24:55 INFO - warning: no files found matching '*.txt' 20:24:55 INFO - no previously-included directories found matching 'build' 20:24:55 INFO - no previously-included directories found matching 'dist' 20:24:55 INFO - no previously-included directories found matching '.git*' 20:24:55 INFO - Successfully installed jsonschema functools32 20:24:55 INFO - Cleaning up... 20:24:55 INFO - Return code: 0 20:24:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:24:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:24:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:24:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:24:55 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:24:55 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 20:24:55 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 20:24:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:24:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:24:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:24:55 INFO - 'HOME': '/Users/cltbld', 20:24:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:24:55 INFO - 'LOGNAME': 'cltbld', 20:24:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:24:55 INFO - 'MOZ_NO_REMOTE': '1', 20:24:55 INFO - 'NO_EM_RESTART': '1', 20:24:55 INFO - 'PAGER': '/bin/cat', 20:24:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:24:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:24:55 INFO - 'PWD': '/builds/slave/test', 20:24:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:24:55 INFO - 'SHELL': '/bin/bash', 20:24:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:24:55 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:24:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:24:55 INFO - 'USER': 'cltbld', 20:24:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:24:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:24:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:24:55 INFO - 'XPC_FLAGS': '0x0', 20:24:55 INFO - 'XPC_SERVICE_NAME': '0', 20:24:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:24:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:24:55 INFO - Downloading/unpacking blobuploader==1.2.4 20:24:55 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 20:24:55 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 20:24:55 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 20:24:55 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 20:24:55 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 20:24:55 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 20:24:57 INFO - Downloading blobuploader-1.2.4.tar.gz 20:24:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:24:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:24:57 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 20:24:57 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 20:24:57 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 20:24:57 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 20:24:57 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 20:24:57 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 20:24:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:24:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:24:58 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 20:24:58 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 20:24:58 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 20:24:58 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 20:24:58 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 20:24:58 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 20:24:59 INFO - Downloading docopt-0.6.1.tar.gz 20:24:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:24:59 INFO - Installing collected packages: blobuploader, requests, docopt 20:24:59 INFO - Running setup.py install for blobuploader 20:24:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:24:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:24:59 INFO - Running setup.py install for requests 20:24:59 INFO - Running setup.py install for docopt 20:24:59 INFO - Successfully installed blobuploader requests docopt 20:24:59 INFO - Cleaning up... 20:25:00 INFO - Return code: 0 20:25:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:00 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:25:00 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 20:25:00 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 20:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:00 INFO - 'HOME': '/Users/cltbld', 20:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:00 INFO - 'LOGNAME': 'cltbld', 20:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:00 INFO - 'MOZ_NO_REMOTE': '1', 20:25:00 INFO - 'NO_EM_RESTART': '1', 20:25:00 INFO - 'PAGER': '/bin/cat', 20:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:00 INFO - 'PWD': '/builds/slave/test', 20:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:00 INFO - 'SHELL': '/bin/bash', 20:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:25:00 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:00 INFO - 'USER': 'cltbld', 20:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:00 INFO - 'XPC_FLAGS': '0x0', 20:25:00 INFO - 'XPC_SERVICE_NAME': '0', 20:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-zCWWtt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ae9vT9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-QnX9Cx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-Kc7xx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-1ZmuRJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-bK7IBK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:00 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-DOOcRA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-jzADrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ayx2Da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-JQHq_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-opElSt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-bbuhyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-9XQt9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-L3Qtyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-HsHH6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:25:01 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-6bdAVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:25:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:25:02 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mM_Wnz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:25:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:25:02 INFO - Running setup.py install for manifestparser 20:25:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:25:02 INFO - Running setup.py install for mozcrash 20:25:02 INFO - Running setup.py install for mozdebug 20:25:02 INFO - Running setup.py install for mozdevice 20:25:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:25:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:25:02 INFO - Running setup.py install for mozfile 20:25:02 INFO - Running setup.py install for mozhttpd 20:25:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:25:02 INFO - Running setup.py install for mozinfo 20:25:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Running setup.py install for mozInstall 20:25:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Running setup.py install for mozleak 20:25:03 INFO - Running setup.py install for mozlog 20:25:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Running setup.py install for moznetwork 20:25:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:25:03 INFO - Running setup.py install for mozprocess 20:25:03 INFO - Running setup.py install for mozprofile 20:25:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:25:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:25:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:25:04 INFO - Running setup.py install for mozrunner 20:25:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:25:04 INFO - Running setup.py install for mozscreenshot 20:25:04 INFO - Running setup.py install for moztest 20:25:04 INFO - Running setup.py install for mozversion 20:25:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:25:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:25:04 INFO - Cleaning up... 20:25:04 INFO - Return code: 0 20:25:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:04 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')]} 20:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:04 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')]} 20:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:04 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:25:04 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 20:25:04 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 20:25:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:25:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:04 INFO - 'HOME': '/Users/cltbld', 20:25:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:04 INFO - 'LOGNAME': 'cltbld', 20:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:04 INFO - 'MOZ_NO_REMOTE': '1', 20:25:04 INFO - 'NO_EM_RESTART': '1', 20:25:04 INFO - 'PAGER': '/bin/cat', 20:25:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:04 INFO - 'PWD': '/builds/slave/test', 20:25:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:04 INFO - 'SHELL': '/bin/bash', 20:25:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:25:04 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:25:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:04 INFO - 'USER': 'cltbld', 20:25:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:04 INFO - 'XPC_FLAGS': '0x0', 20:25:04 INFO - 'XPC_SERVICE_NAME': '0', 20:25:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:25:04 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-i0xKcv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-cEVA1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-GyDZCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-xWln03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-chKXpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-qkHHk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-kYMazg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-SkQklQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-gKbqaC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:25:05 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)) 20:25:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:25:05 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-D94V19-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:25:06 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)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mo6fN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:25:06 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)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-k3Ag_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-whnaCs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:25:06 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)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-ddQLEJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:25:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-mzjWXB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:25:06 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)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-8XSl7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:25:06 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)) 20:25:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:25:06 INFO - Running setup.py (path:/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/pip-MPfsJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:25:06 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)) 20:25:06 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)) 20:25:06 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)) 20:25:06 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)) 20:25:06 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)) 20:25:06 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)) 20:25:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:25:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:25:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:25:09 INFO - Downloading blessings-1.6.tar.gz 20:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:25:09 INFO - Installing collected packages: blessings 20:25:09 INFO - Running setup.py install for blessings 20:25:09 INFO - Successfully installed blessings 20:25:09 INFO - Cleaning up... 20:25:09 INFO - Return code: 0 20:25:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:25:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:25:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:25:09 INFO - Reading from file tmpfile_stdout 20:25:09 INFO - Current package versions: 20:25:09 INFO - blessings == 1.6 20:25:09 INFO - blobuploader == 1.2.4 20:25:09 INFO - docopt == 0.6.1 20:25:09 INFO - functools32 == 3.2.3-2 20:25:09 INFO - jsonschema == 2.5.1 20:25:09 INFO - manifestparser == 1.1 20:25:09 INFO - mozInstall == 1.12 20:25:09 INFO - mozcrash == 0.17 20:25:09 INFO - mozdebug == 0.1 20:25:09 INFO - mozdevice == 0.48 20:25:09 INFO - mozfile == 1.2 20:25:09 INFO - mozhttpd == 0.7 20:25:09 INFO - mozinfo == 0.9 20:25:09 INFO - mozleak == 0.1 20:25:09 INFO - mozlog == 3.2 20:25:09 INFO - moznetwork == 0.27 20:25:09 INFO - mozprocess == 0.23 20:25:09 INFO - mozprofile == 0.28 20:25:09 INFO - mozrunner == 6.12 20:25:09 INFO - mozscreenshot == 0.1 20:25:09 INFO - mozsystemmonitor == 0.3 20:25:09 INFO - moztest == 0.7 20:25:09 INFO - mozversion == 1.4 20:25:09 INFO - psutil == 3.1.1 20:25:09 INFO - requests == 1.2.3 20:25:09 INFO - wsgiref == 0.1.2 20:25:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 20:25:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:09 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:25:09 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 20:25:09 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 20:25:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:25:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:09 INFO - 'HOME': '/Users/cltbld', 20:25:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:09 INFO - 'LOGNAME': 'cltbld', 20:25:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:09 INFO - 'MOZ_NO_REMOTE': '1', 20:25:09 INFO - 'NO_EM_RESTART': '1', 20:25:09 INFO - 'PAGER': '/bin/cat', 20:25:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:09 INFO - 'PWD': '/builds/slave/test', 20:25:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:09 INFO - 'SHELL': '/bin/bash', 20:25:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:25:09 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:25:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:09 INFO - 'USER': 'cltbld', 20:25:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:09 INFO - 'XPC_FLAGS': '0x0', 20:25:09 INFO - 'XPC_SERVICE_NAME': '0', 20:25:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 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)) 20:25:10 INFO - Cleaning up... 20:25:10 INFO - Return code: 0 20:25:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:25:10 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')]} 20:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:10 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')]} 20:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:25:10 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 0x101146a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011db618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87114c9430>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, '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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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 20:25:10 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 20:25:10 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 20:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:10 INFO - 'HOME': '/Users/cltbld', 20:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:10 INFO - 'LOGNAME': 'cltbld', 20:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:10 INFO - 'MOZ_NO_REMOTE': '1', 20:25:10 INFO - 'NO_EM_RESTART': '1', 20:25:10 INFO - 'PAGER': '/bin/cat', 20:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:10 INFO - 'PWD': '/builds/slave/test', 20:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:10 INFO - 'SHELL': '/bin/bash', 20:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:25:10 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:10 INFO - 'USER': 'cltbld', 20:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:10 INFO - 'XPC_FLAGS': '0x0', 20:25:10 INFO - 'XPC_SERVICE_NAME': '0', 20:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:25:10 INFO - Cleaning up... 20:25:10 INFO - Return code: 0 20:25:10 INFO - Running post-action listener: _resource_record_post_action 20:25:10 INFO - Running post-action listener: _start_resource_monitoring 20:25:10 INFO - Starting resource monitoring. 20:25:10 INFO - ##### 20:25:10 INFO - ##### Finished create-virtualenv step (success) 20:25:10 INFO - ##### 20:25:10 INFO - ##### 20:25:10 INFO - ##### Running install step. 20:25:10 INFO - ##### 20:25:10 INFO - Running pre-action listener: _resource_record_pre_action 20:25:10 INFO - Running main action method: install 20:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:25:10 INFO - Reading from file tmpfile_stdout 20:25:10 INFO - Detecting whether we're running mozinstall >=1.0... 20:25:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:25:10 INFO - Reading from file tmpfile_stdout 20:25:10 INFO - Output received: 20:25:10 INFO - Usage: mozinstall [options] installer 20:25:10 INFO - Options: 20:25:10 INFO - -h, --help show this help message and exit 20:25:10 INFO - -d DEST, --destination=DEST 20:25:10 INFO - Directory to install application into. [default: 20:25:10 INFO - "/builds/slave/test"] 20:25:10 INFO - --app=APP Application being installed. [default: firefox] 20:25:10 INFO - mkdir: /builds/slave/test/build/application 20:25:10 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'] 20:25:10 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 20:25:45 INFO - Reading from file tmpfile_stdout 20:25:45 INFO - Output received: 20:25:45 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:25:45 INFO - Running post-action listener: _resource_record_post_action 20:25:45 INFO - ##### 20:25:45 INFO - ##### Finished install step (success) 20:25:45 INFO - ##### 20:25:45 INFO - ##### 20:25:45 INFO - ##### Running run-tests step. 20:25:45 INFO - ##### 20:25:45 INFO - Running pre-action listener: _resource_record_pre_action 20:25:45 INFO - Running main action method: run_tests 20:25:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:25:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:25:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:25:45 INFO - 2016-07-06 20:25:45.389 screenresolution[2208:24192] starting screenresolution argv=screenresolution get 20:25:45 INFO - 2016-07-06 20:25:45.392 screenresolution[2208:24192] Display 0: 1600x1200x32@60 20:25:45 INFO - 2016-07-06 20:25:45.398 screenresolution[2209:24194] starting screenresolution argv=screenresolution list 20:25:45 INFO - Available Modes on Display 0 20:25:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:25:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:25:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:25:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:25:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:25:45 INFO - Intel Iris: 20:25:45 INFO - Chipset Model: Intel Iris 20:25:45 INFO - Type: GPU 20:25:45 INFO - Bus: Built-In 20:25:45 INFO - VRAM (Dynamic, Max): 1536 MB 20:25:45 INFO - Vendor: Intel (0x8086) 20:25:45 INFO - Device ID: 0x0a2e 20:25:45 INFO - Revision ID: 0x0009 20:25:45 INFO - Displays: 20:25:45 INFO - Display: 20:25:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:25:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:25:45 INFO - Display Serial Number: 1600x1200 60 20:25:45 INFO - Main Display: Yes 20:25:45 INFO - Mirror: Off 20:25:45 INFO - Online: Yes 20:25:45 INFO - Rotation: Supported 20:25:45 INFO - Return code: 0 20:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:25:45 INFO - Python 2.7.3 20:25:45 INFO - Return code: 0 20:25:45 INFO - grabbing minidump binary from tooltool 20:25: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')]} 20:25:45 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 0x1010aad30>, '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 0x100e0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1010a2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:25:45 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 20:25:45 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 20:25:45 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 20:25:45 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:25:45 INFO - Return code: 0 20:25:45 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:25:45 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:25:45 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:25:45 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:25:45 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:25:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 20:25:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0246 --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 20:25:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.m8j8iDV8ky/Render', 20:25:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:25:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:25:45 INFO - 'HOME': '/Users/cltbld', 20:25:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:25:45 INFO - 'LOGNAME': 'cltbld', 20:25:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:25:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:25:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:25:45 INFO - 'MOZ_NO_REMOTE': '1', 20:25:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:25:45 INFO - 'NO_EM_RESTART': '1', 20:25:45 INFO - 'PAGER': '/bin/cat', 20:25:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:25:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:25:45 INFO - 'PWD': '/builds/slave/test', 20:25:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:25:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:25:45 INFO - 'SHELL': '/bin/bash', 20:25:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YiJzUUQ7HO/Listeners', 20:25:45 INFO - 'TMPDIR': '/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/', 20:25:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:25:45 INFO - 'USER': 'cltbld', 20:25:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:25:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:25:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:25:45 INFO - 'XPC_FLAGS': '0x0', 20:25:45 INFO - 'XPC_SERVICE_NAME': '0', 20:25:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:25:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0246', '--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 20:25:45 INFO - mozversion application_buildid: 20160706181624 20:25:45 INFO - mozversion application_changeset: 39ec98bf2580a236471714c28b336d8c974cc365 20:25:45 INFO - mozversion application_display_name: Nightly 20:25:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:25:45 INFO - mozversion application_name: Firefox 20:25:45 INFO - mozversion application_remotingname: firefox 20:25:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 20:25:45 INFO - mozversion application_vendor: Mozilla 20:25:45 INFO - mozversion application_version: 50.0a1 20:25:45 INFO - mozversion platform_buildid: 20160706181624 20:25:45 INFO - mozversion platform_changeset: 39ec98bf2580a236471714c28b336d8c974cc365 20:25:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 20:25:45 INFO - mozversion platform_version: 50.0a1 20:25:45 INFO - using testdate: 1467861945 20:25:45 INFO - actual date: 1467861945 20:25:45 INFO - starting webserver on 'localhost:56746' 20:25:45 INFO - SUITE-START | Running 2 tests 20:25:45 INFO - TEST-START | dromaeo_css 20:25:45 INFO - operating with platform_type : mac_ 20:25:45 INFO - Initialising browser for dromaeo_css test... 20:25:46 INFO - TEST-INFO | started process 2219 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp8c8iSr/profile http://localhost:56746/getInfo.html) 20:25:48 INFO - PROCESS | 2219 | __metrics Screen width/height:1600/1200 20:25:48 INFO - PROCESS | 2219 | colorDepth:24 20:25:48 INFO - PROCESS | 2219 | Browser inner width/height: 1024/689 20:25:48 INFO - PROCESS | 2219 | __metrics 20:25:48 INFO - PROCESS | 2219 | 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" 20:25:48 INFO - PROCESS | 2219 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:25:49 INFO - TEST-INFO | 2219: exit 0 20:25:49 INFO - Browser initialized. 20:25:49 INFO - Running cycle 1/1 for dromaeo_css test... 20:25:49 INFO - TEST-INFO | started process 2221 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmp8c8iSr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:26:01 INFO - PROCESS | 2221 | RSS: Main: 243511296 20:26:01 INFO - PROCESS | 2221 | 20:26:51 INFO - PROCESS | 2221 | 1467862011994 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] 20:29:03 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56746/tests/dromaeo/cssquery-ext.html) 20:29:05 INFO - PROCESS | 2221 | RSS: Main: 380948480 20:29:05 INFO - PROCESS | 2221 | 20:32:08 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-ext.html (next: http://localhost:56746/tests/dromaeo/cssquery-jquery.html) 20:32:09 INFO - PROCESS | 2221 | RSS: Main: 380809216 20:32:09 INFO - PROCESS | 2221 | 20:35:11 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56746/tests/dromaeo/cssquery-mootools.html) 20:35:12 INFO - PROCESS | 2221 | RSS: Main: 374501376 20:35:12 INFO - PROCESS | 2221 | 20:38:14 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56746/tests/dromaeo/cssquery-prototype.html) 20:38:15 INFO - PROCESS | 2221 | RSS: Main: 374325248 20:38:15 INFO - PROCESS | 2221 | 20:41:18 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56746/tests/dromaeo/cssquery-yui.html) 20:41:19 INFO - PROCESS | 2221 | RSS: Main: 337338368 20:41:19 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | Cycle 1(1): loaded http://localhost:56746/tests/dromaeo/cssquery-yui.html (next: http://localhost:56746/tests/dromaeo/cssquery-dojo.html) 20:44:22 INFO - PROCESS | 2221 | RSS: Main: 426254336 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | __start_tp_report 20:44:22 INFO - PROCESS | 2221 | _x_x_mozilla_page_load 20:44:22 INFO - PROCESS | 2221 | _x_x_mozilla_page_load_details 20:44:22 INFO - PROCESS | 2221 | |i|pagename|runs| 20:44:22 INFO - PROCESS | 2221 | |0;dojo.html;3258.934821303574;3277.1970867137557;3282.2495466230894;3298.142482954431;3319.8878375744125;5554.50009499145;5620.522255608268;5642.943570564293;5731.11167768996;5745.109508026263;5667.603267771247;5695.886082278352;5721.6853073081065;5724.7137643117785;5750.166225897243;4268;5135.013725979536;5154.175331946889;5174.042802081627;5251.684898906059;4438.711483753562;5256.63203575748;5264.184051472009;5264.631475796696;5267.28891599633;4261.318189089746;5147.691138531682;5156.510131537498;5161.329027226474;5276.050310944029;5214.608904332172;5251.632385732962;5262.105442074818;5274.182501712241;5324.9733751331;5064;5085.872853178663;5100.948990510122;5174;5175;5572.888542229173;5574.637648552831;5652;5655.632383895075;5669.319681638189;6098.233310320522;6106.528200251913;6118.082287656843;6125.785597504066;6129.356417576135;5075.619328550373;6169.5064394848305;6172.567920245585;6193.938060619381;6217.129601855744;5777.422257774231;5801.7389217485315;5862.2672165979675;5871.412858714094;5881.882362352814;6165.660888651168;6203.193584834033;6225;6244.1570387997945;6283.371662833772;6025.849353766191;6071;6083.209182806207;6176.227971503562;6208.627482351073;6182.876342473125;6249.468795152372;6250.937490625036;6271.216097987752;6297.023961286008;6200.968995155085;6222.564420490613;6226.782062627786;6233.252009758858;6237.220347456568;6115.635867915819;6131.908021379683;6182.258129024546;6249.78125765596;6274.874502509924;4890.828820991248;4921.040397122599;4922.458529561745;4931.038447502702;4931.753412329441;4366.781660916941;4382.189294980439;4424.80088396023;4459.063596644741;4461.420015397977;5703.62926409782;5713.800016999468;5724.055530837449;5724.398938111522;5743.684097374601;6205.441510264008;6258.530610204253;6264.436200741955;6271.404216599416;6276.372362763687;5569.721513924369;5611.6071874969175;5618.129189975561;5684.062129748545;5702.6863522506665;5392.649477783932;5394.514493695587;5396.784128634889;5397.73011349431;5405.70268635221;6184.783532576338;6216.751329946842;6219.40915613016;6230;6233;6241.032639940817;6271.278802937721;6274.278457977391;6282.780102696383;6290.591111577733;6362.681865906594;6374.139491168633;6378.394052564999;6385.7126429309865;6389.680515974275;5777.497850558801;6029.246344206974;6097.5731698780655;6190;6208.348123446973;5450.482204190595;5620.437956204511;6155.84610384744;6276.717547710273;6279.811605651837;6077.179580756541;6096.634201947897;6100.115483254992;6101.511879049576;6112.235970503687;5350.03699334108;5384.9730751346;5967.671778052249;5979.641221526722;6071.089336599545;5817.0983497557945;5882.705864706833;5896.616719913192;5935;6016.759329626766;6078.513718902565;6096.116063170904;6113.877722445437;6127.938720612737;6141.692915354304;5909.409059094056;5916.55625828056;5940.702964851654;5947.167396564571;5963.970180149072 20:44:22 INFO - PROCESS | 2221 | |1;ext.html;445.71251542754925;469.46481011646705;540.1357827476041;546.3280165396562;552.1166134185304;30093.398132037375;33167.834160829196;42635;42656.786716066454;42793.572064279346;35307.64692353082;43688.3446748299;44017.77991110048;44191.33712994308;44228.557714422845;8753.693620723276;9682.661106861262;9810.215182785396;9871.555779989882;9875.506224688772;8803.823923521526;11923;12180.330081845495;12200;12377.381130943462;12056.63830085099;12113.515459381659;12123.030157587373;12137.332446715427;12141;8767.912320876774;11862.466189021514;12012.219205751642;12083.275003499819;12107.273563586214;11605.941970290094;11986.160968732192;11996.22024568409;12002.21985570935;12014.819777703342;41101.79449102765;41486.1702765946;41566;41627.37558936619;41627.58372416267;3521.841517131735;3537.982310088433;3705.8332375043187;3902.824372903226;3903.960960390388;30144.397112057857;32867.01398958035;33004.1748956278;33487.497687534706;34294.828525857214;3295.126791400281;3301.0591981285456;3307.570015897918;3428.9884484077256;3438.0373495421322;534363;547042.2647886735;547043;549131;549415;36590.45114323287;69668.6516567414;70603.58792824115;70666.9399959001;70748;37366;37410.62589374126;37520.37357385011;37616.62383376131;39044.02389940274;15002.24988750558;15115.924420377827;15254.23728813555;15266.236688165547;15269.389224430899;11559.595414160463;11800.88199118015;12527.56153534622;12720.427580758887;12778.61664150077;7838.960805196051;8109.270165685117;8137.633806478722;8172.141925097898;8278.793030174173;2946.2781618503604;3412.146963259185;3412.539307193547;3419.6238413774463;3443.8278086095597;945.7588314979636;980.4803454169302;1088.820344643125;1092.5192915117482;1129.1982692288568;26834;30605.23486912845;30981.8450907744;31703.52444713331;32001.67998319987;1026.461107918349;1088.6026600290836;1090.7763908398758;1131.3494740524231;1196.389841181004;16211;16301.836981630031;16335.01989880611;16379.0172589645;16741;655005;696057;703498;704754;705036;59375.40624593699;60082.399176008556;60261.69869150714;60282.69858650678;60330.39669603336;1286.6204469681466;1408.1902905829395;1409.1474657832068;1429.1853643423149;1451.7894905238682;32195.712171512878;32245.355092897542;32356.83821580877;32365;33024.00927972165;50883.49116508788;50991.490085098674;51561.48438515717;51658.74170629123;51709.7414512925;549764;550904;552431;552907;554768;24600.261992140266;27510.724892751616;27840.886364545193;27847.443051139267;27991.720082799726;48046.279305809745;48591;49189.754051229516;49196.508034920626;49697.75151124221;39344.60655393524;40456.59543404528;40719.79640101899;41478.792606036775;41568.792156039024;39899.800500997306;40653.390199146445;40800;41336.793316033225;41376;36701.63298367089;37601.62398376091;39533.40699889562;39700.61047863311;39744.40383394309;33195;35192;35536.822315888254;35676.46485302671;35785.926422206336;25508.107216247343;25715.485690286463;25867;26219.297156339253;26655.33361665896 20:44:22 INFO - PROCESS | 2221 | |2;jquery.html;352.917064489845;360.3567631777277;383.8042598274879;384.5577585776357;402.47678018575846;15979.201039948015;16750.832491675093;21302.89348553255;21926.45183870404;21953.341399757992;21248.043838027286;21580.920953952318;22009.449763755914;22419.879006049712;22474.32577022688;5974.731137098841;6032.245969253854;6211.720472578722;6227.813165605039;6282.560220784547;3962.9207415851665;4171.186618609372;5196.272521846944;6200.78297259596;6261.7182226799905;5917.349091599921;6894.13823272091;6895.482838787086;6896.103506544142;6909.516333856632;6208.906866397007;6209.192804935375;6229.750809967596;6238.1266622672865;6490.286068532481;5547;5808.07070868659;5883.588148829584;5974.133750606138;6052.909206361908;13718.902487801051;13746.450141994408;13942.37259323333;13971.510997115052;13975.37110830015;2177.912883484659;2203.7355517338024;2284.234781348234;2396.9041238350446;2483.2301986384277;10146.289759716823;10604.628837990633;10609;11367.602133925355;13087.541936032194;1829;1852.370194134001;1862.962740745191;1903.9428817135508;1910.3122875764714;89933.55033224857;99698.501507492;121587.78412215853;128785;133760.66239337676;9656.662016829518;11286.153538484647;11477.885221147848;11537.769244615061;11588.188826782045;14702.926485367718;14776.704465910621;14836.480723174853;14839.18384488842;14845.480408185662;8487.618057187317;8575.485470871768;8580.313574913991;8664.61009254585;8684.305255579664;8459.323254139654;8468.915310846813;8474.406791524658;8578.613962371708;8583.442076265022;4517.6837621366185;4580.725156490555;4598.8620341389815;4656.4877863434995;4658.37111989877;1663.484319860847;1863.8508919286423;1906.742589750366;1922.7019811929174;1950.4831219726784;887.587271918548;897.5915958238986;979.3487330924855;996.4121168510613;1071.8981696738797;21325.68011479829;22322.665160022614;22377.216797412337;22411.775882241294;22512.437189070406;811.3022800391658;909.9135582119688;971.8930917599058;993.3841018568534;994.9403035817729;10927.235093543535;11258.774824503464;11691.883081169079;11770.94114529439;11779.646610601696;601364;606816;619983;622616;622942;77881;126831.36584317019;126911;128073;129962;985.6944347252374;1001.5793366785967;1029.0841151375282;1033.328336581213;1044.2742294105524;20358.79641203628;20895.850728210095;21006.47483812917;21027.053782579515;21052.26317078895;43032.78483607687;52327;59152.704236478545;59168.704156480664;59506.917257895766;85074.57462712647;86212.56893715491;86794.56602716947;87384.56307718421;87690;7191.137063552407;8154.143814899588;8203.384746143944;8348.165183481846;8565.957170214358;9498.667546635834;13362.599122026355;13496.257705825881;13666.726665466835;13712.65718357049;5529.834104976857;6293.842653933688;11012.119030477703;11391.088712903114;11701.88298117008;11448.141389395661;11497.942510287396;11545.942270288595;11551.191416601094;11637.76724465523;11781.823272650745;11953.760924781282;11959.461824218106;11977.580784672495;11985.400729963641;7504.774856754306;7525.6989720410575;7576;7751.728689496066;7796.064472263365;11211.215214934877;11636.94181529087;11719.41402929833;11776.175667703179;11925.701857453632 20:44:22 INFO - PROCESS | 2221 | |3;mootools.html;1403.8876889848814;1572.2138930534736;1598.8081430228374;1839.264294282287;1972.6547854125533;7903.644336004879;8631.438956467833;8867.334949878752;8873.91126088739;8989.820203595924;7526.849463010736;8653.134686531344;8738.650453981832;8820.735377938656;8831.337649676283;2109.398821335912;2309.515001849614;2364.49163429863;2393.246127469841;2394.365493144318;2405.494846082316;2434.3670645632174;2435.9634605480933;2438.9146379876706;2440.023990403844;2375;2381.9285421437385;2394.832361734679;2413.3604594782396;2413.4931664350506;2074.8132668059798;2284.2804516577226;2359.445530300378;2370.277065495023;2384.380061184096;2375.8336916415856;2399.460121472672;2399.6760437341086;2401.735809061002;2406.205952035824;8794.692185773467;8848.51333176675;8857.512836793974;8863.911360886437;8878.200961913459;4521.299198624219;5351.785928562853;5379.731013449351;5381.892362152735;5402.675839449606;3125.7030582094662;3212.726918211931;3288.7697861149727;3291.967080329214;3318.1870441741685;1081.3187691754144;1090.3457925244868;1093.9945300273448;1113.092829344082;1114.0363585498485;10145.847812282822;11660.475278612483;11664.533418663243;11678;11680.299182049104;4663.090697314059;4740.6681532292405;4767.404074490689;4842.176829938919;4859.611231101503;9887.209023278121;9896.554655040396;9897;9902.108810207115;9920.702378928643;5399.433059528771;5403.486668766461;5403.756830942539;5407.270018547524;5408.405075441777;3710.387786015331;4193.6645068394455;4584.404027476407;4588.518205588432;4951.90096198074;3765.6987441005203;3863.942040869389;3874.2251549690177;3927.9214415711526;4120.361343991686;1300.4148133339845;1301.0632344711792;1319.3139567424885;1325.7878739401842;1327.502186679995;821.8356328734277;938.6996161228342;947.0907928388824;968.6835205066102;978.8238117138983;4866.586340161055;6051.4553690168095;6205.844853878689;6231.75072997075;6262.812115636447;832;923.2106548900728;940.1303793990531;946.5409276500892;977.9168770654574;4052.3313653246882;4063.837446502107;4102.487189101363;4140;4144.8756537303925;10607.045365917105;10650.680479585624;11029.889701103048;11047.005769480786;11060.391678457601;10819.783604328028;10954.39750813697;10959.397233152094;10972.177086718528;10997.835032474519;7988.920110798817;8376.958115209385;8391.328693704489;8475.152484751476;8481.703140390056;5903.527717782653;5934.6142500737315;6091.994820854509;6246.718897649525;6330.778422755181;8703.608337624695;9631.71104866855;10315.690529284133;10324.174066074545;10333.431661258403;9269.165775080275;10183.643572474928;10328.793424131625;10333.586656533655;10376.948115259376;7076.646167691698;7451.739189128354;7636.198199189005;7698;7790.649420775965;8309.626066826886;8407.327413806759;8482.787930301793;8493.108223636676;8494.575271236537;7560.546367217985;7622.237776222333;7635.778275475897;7688.615569221628;7762.223777622193;7542.358899493604;7557.433192510473;7630.427717921067;7685.231476852494;7702.383809295131;7100.0414943981905;7102.751403700846;7152.678129484081;7173.0316407284345;7179.605121718356;5055.646104772631;5061.7469126544265;5115.539601435739;5142.537171654569;5219.269302297757;5042.571381432619;5129.025485157808;5181.689098654093;5183.377994640668;5292.576593872404 20:44:22 INFO - PROCESS | 2221 | |4;prototype.html;257.4349303279302;304.32895465498575;315.31891115191183;318.6781350835657;319.0858191281975;4546.886327841806;4590;4774.021325628242;5395.10980688186;5403.324584426947;5132.538071573557;5274.549498887808;5325.893482130355;5358.035553600351;5378.435264297251;4042.6361627453525;4074.490688663917;4094.263032654121;4103.651189648884;4119.8352065917325;3172.175234439051;4085.387191921206;4089.20260549193;4089.8159582818844;4091.5703851095504;4016.53809811871;4033.1731994941115;4036.4550785644;4053.736507127042;4061.2689715851134;4013.518377794654;4043.292423825819;4059.715819892594;4060.330045542497;4103.589641035873;3496.4230901901155;3498.8425520851624;3521.5950165730756;3536.929261414783;3542.5217595624517;3980.8009599520196;4618.49196588375;4619.607333376667;5324.5740340772645;5338.492843179931;4619.399478067863;4619.676622636417;4624.820670728943;4646.976765116153;4696.694714843524;4751.265787987394;5627.803026894039;5645.887082258373;5666.433356664342;5732.828015159552;3515.33208690348;3537.451694987281;3832.176082142325;3864.1598425157486;3875.8837234883;5925.466707996301;5955.153902290303;5965.821025369246;6034.758609655609;6047.455728984369;3874.612538746103;3912.8630497932436;3913.412988051815;3915.5105611798526;3930.194310166409;3348.665133486632;3811.9618803812164;3897.7271590988926;3898.8050597470015;3968.146848427602;3872.5352957645264;3904.9261453100626;4515.209838278288;4580.58774710271;4588.128255631419;4363.912721745547;4522.977385113053;5334.51989320963;5620.4660557247;5684.1063871248325;1226.9018478521698;1261.7287283234148;1291.9018833991033;1351.601092868876;1351.6755978565075;864.7924498120532;874.6588830356167;890.2032680750692;895.5029958373035;903.7333986474006;4257.148570285955;4979;4995.500449955048;5188.403333616682;5303.708296043755;4487.977560112178;4982.377202849644;5610;5756.654600723886;5887.35239123696;4927.28554359623;4955.702657840469;5157.226416037549;5186.429492755794;5187.792488300426;2102.9894850525648;2210.4142402263415;2395.964060539093;2557.5268575313626;2583.754543318382;4408.963893485028;4552.567506086916;4807.278908163804;4950.752462376867;5015.673981191284;4248.34150706641;4484.775761211991;4568.012914382254;4807.927881081787;4983.90032199354;5106.872328191751;5480.451954804487;5810.6513609183585;5868.67722275279;6022.277326720821;5291.677080729817;5451.918221226764;5663.377028526941;5989.730462129128;6022.939770602413;5610.43895610452;5644.209810626434;5780.21967034445;5792.82621521355;5850.742090450573;5929.940700592939;5939.613925094855;6087.36082711309;6098.695065246631;6106.969465152646;3433.4678124890684;3721.976456474491;3828.119532507483;3831.1762970961377;3863.265979463893;5463.31708536433;5503.669779813224;5572.637778544382;5736.397678243724;5846.210761547307;5018.974905125451;5592.02139625572;5609.663420194638;5618.915716264178;5630.155476678531;4658.627309815274;4752.809887604595;5063.87340316495;5806.76772929095;5835.8541036472725;5265.472573164826;5464.371597266365;5663.516937083747;5681.579605098726;5746;5447.96488667152;5660.29246344207;5670.121131224667;5677.687727175045;5680.545556355399;4808.591269741971;5608.037186984523;5645.520130788929;5658.886822263614;5659.594672264354 20:44:22 INFO - PROCESS | 2221 | |5;yui.html;741.2180149941811;759.8404335089631;766.5285849202738;857.3227150551066;873.292632967297;14937.029093108953;16262.674746505063;16288.918555407206;16298.266578003988;16373.935694179885;14690.779638305434;14816.777748333783;14844.777328340084;14886.25568721565;14893.255337233122;700.9824754381143;722.4870342057146;866.8353012927523;893.2407453664398;894.7181637784078;878.2841983783228;882.0385779500343;882.4043770454949;883.3772190605607;883.659790980474;923.0769230769231;935.7473482159813;935.957881895313;938.4791440750381;938.9436633801994;746.268656716421;916.2807196350899;932.5896605493595;934.7569631895689;936.3024546712709;704.4681265644406;705.4779463197249;705.7141857547701;707.2291202589203;711.6619605687309;15064.096154230781;15108.697826043417;15145.545633631009;15155;15158;12786.744265114645;14071.859281407158;14480.710385792225;14524.346404411826;14531.273436328247;11627.244229125079;13102.737945241042;13222.471101155943;13325.200487970753;13529.797053044213;4646;4685.344051832746;4756.357891684613;4814.494478079821;4825.662203645747;117777.41111294474;117894.41052794766;118184;118462;121769;10922.088634489091;11385.316880987168;11815.763684726257;11851.051915846712;11912.52349905994;22726.545469090524;23789.40526486817;23822.16622418207;23845;23850.642240366407;8452.408331416742;8512.787180320542;8659.09079546651;8736.082711315348;8738.082501337396;4023.5976402360116;4535.387722657465;4588.655850811136;4605.65457590682;4745.95254047462;3566.5363502744995;3660.848922796653;3697.408414653696;3704.9811301891655;3706.4069748840056;674.6795272245644;676.1919506190154;686.3513979289523;695.979120626382;733.9045924029842;593.6289818863211;666.1506579111672;702.367868917978;706.445440329339;711.8078118907866;9833.459159746282;9866.753331166778;9876.259280553988;9900.564418159327;9963.153131983863;656.6815094679076;692.2385376086365;692.7263730826295;695.7460526907643;707.886738121898;4650.790714417859;4778.259369797687;4780.259059845729;4784.6889952153;4848.903021939542;136058.3197084028;164424.17787911222;166283;170459;175707;143171;144058.5594144045;145065.27467362594;147188;147525;122397;123423.14865277028;125767.1134932995;127299.45401091926;128465;9266.166045055978;9298.95350523243;9339.673111441067;9406.372219315836;9410.81178376415;66090.33909661035;67139;67580.3241967574;68162.65918670375;68470.65764671145;97169;104815;105697.41453878352;105705;108138;11935.176736620793;11978.281303121492;12091.879081209076;12234.694132646755;12235.082368822195;11557.653270401901;13303.068785184943;13607.183568985893;13648.658783530094;14069.437222510986;12048.397580121135;12315.815262770886;12357.505699771908;12485.812712809497;12606.369681516071;12582.685432864251;12697.93651031739;12904.290264035193;13036.47854085826;13048.869511304765;12095.818562721743;12437.502499899903;12520.123591348882;12676.176048556814;12796.552120675731;10293.38289665627;10532.631357902437;10664.200184986003;10690.62582809629;10732.946335268274;10029.648962286577;10438.68683939483;10746.46267686597;10931.945340273247;11012.17408694335 20:44:22 INFO - PROCESS | 2221 | __end_tp_report 20:44:22 INFO - PROCESS | 2221 | __start_cc_report 20:44:22 INFO - PROCESS | 2221 | _x_x_mozilla_cycle_collect,1460 20:44:22 INFO - PROCESS | 2221 | __end_cc_report 20:44:22 INFO - PROCESS | 2221 | __startTimestamp1467863062666__endTimestamp 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | ------- Summary: start ------- 20:44:22 INFO - PROCESS | 2221 | Number of tests: 6 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#0] dojo.html Cycles:165 Average:5688.67 Median:5882.71 stddev:663.04 (11.3%) stddev-sans-first:637.09 20:44:22 INFO - PROCESS | 2221 | Values: 3258.9 3277.2 3282.2 3298.1 3319.9 5554.5 5620.5 5642.9 5731.1 5745.1 5667.6 5695.9 5721.7 5724.7 5750.2 4268.0 5135.0 5154.2 5174.0 5251.7 4438.7 5256.6 5264.2 5264.6 5267.3 4261.3 5147.7 5156.5 5161.3 5276.1 5214.6 5251.6 5262.1 5274.2 5325.0 5064.0 5085.9 5100.9 5174.0 5175.0 5572.9 5574.6 5652.0 5655.6 5669.3 6098.2 6106.5 6118.1 6125.8 6129.4 5075.6 6169.5 6172.6 6193.9 6217.1 5777.4 5801.7 5862.3 5871.4 5881.9 6165.7 6203.2 6225.0 6244.2 6283.4 6025.8 6071.0 6083.2 6176.2 6208.6 6182.9 6249.5 6250.9 6271.2 6297.0 6201.0 6222.6 6226.8 6233.3 6237.2 6115.6 6131.9 6182.3 6249.8 6274.9 4890.8 4921.0 4922.5 4931.0 4931.8 4366.8 4382.2 4424.8 4459.1 4461.4 5703.6 5713.8 5724.1 5724.4 5743.7 6205.4 6258.5 6264.4 6271.4 6276.4 5569.7 5611.6 5618.1 5684.1 5702.7 5392.6 5394.5 5396.8 5397.7 5405.7 6184.8 6216.8 6219.4 6230.0 6233.0 6241.0 6271.3 6274.3 6282.8 6290.6 6362.7 6374.1 6378.4 6385.7 6389.7 5777.5 6029.2 6097.6 6190.0 6208.3 5450.5 5620.4 6155.8 6276.7 6279.8 6077.2 6096.6 6100.1 6101.5 6112.2 5350.0 5385.0 5967.7 5979.6 6071.1 5817.1 5882.7 5896.6 5935.0 6016.8 6078.5 6096.1 6113.9 6127.9 6141.7 5909.4 5916.6 5940.7 5947.2 5964.0 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#1] ext.html Cycles:180 Average:72256.37 Median:36188.19 stddev:160837.53 (444.4%) stddev-sans-first:161198.36 20:44:22 INFO - PROCESS | 2221 | Values: 445.7 469.5 540.1 546.3 552.1 30093.4 33167.8 42635.0 42656.8 42793.6 35307.6 43688.3 44017.8 44191.3 44228.6 8753.7 9682.7 9810.2 9871.6 9875.5 8803.8 11923.0 12180.3 12200.0 12377.4 12056.6 12113.5 12123.0 12137.3 12141.0 8767.9 11862.5 12012.2 12083.3 12107.3 11605.9 11986.2 11996.2 12002.2 12014.8 41101.8 41486.2 41566.0 41627.4 41627.6 3521.8 3538.0 3705.8 3902.8 3904.0 30144.4 32867.0 33004.2 33487.5 34294.8 3295.1 3301.1 3307.6 3429.0 3438.0 534363.0 547042.3 547043.0 549131.0 549415.0 36590.5 69668.7 70603.6 70666.9 70748.0 37366.0 37410.6 37520.4 37616.6 39044.0 15002.2 15115.9 15254.2 15266.2 15269.4 11559.6 11800.9 12527.6 12720.4 12778.6 7839.0 8109.3 8137.6 8172.1 8278.8 2946.3 3412.1 3412.5 3419.6 3443.8 945.8 980.5 1088.8 1092.5 1129.2 26834.0 30605.2 30981.8 31703.5 32001.7 1026.5 1088.6 1090.8 1131.3 1196.4 16211.0 16301.8 16335.0 16379.0 16741.0 655005.0 696057.0 703498.0 704754.0 705036.0 59375.4 60082.4 60261.7 60282.7 60330.4 1286.6 1408.2 1409.1 1429.2 1451.8 32195.7 32245.4 32356.8 32365.0 33024.0 50883.5 50991.5 51561.5 51658.7 51709.7 549764.0 550904.0 552431.0 552907.0 554768.0 24600.3 27510.7 27840.9 27847.4 27991.7 48046.3 48591.0 49189.8 49196.5 49697.8 39344.6 40456.6 40719.8 41478.8 41568.8 39899.8 40653.4 40800.0 41336.8 41376.0 36701.6 37601.6 39533.4 39700.6 39744.4 33195.0 35192.0 35536.8 35676.5 35785.9 25508.1 25715.5 25867.0 26219.3 26655.3 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#2] jquery.html Cycles:180 Average:35268.95 Median:2340.57 stddev:102382.58 (4374.3%) stddev-sans-first:102636.22 20:44:22 INFO - PROCESS | 2221 | Values: 352.9 360.4 383.8 384.6 402.5 15979.2 16750.8 21302.9 21926.5 21953.3 21248.0 21580.9 22009.4 22419.9 22474.3 5974.7 6032.2 6211.7 6227.8 6282.6 3962.9 4171.2 5196.3 6200.8 6261.7 5917.3 6894.1 6895.5 6896.1 6909.5 6208.9 6209.2 6229.8 6238.1 6490.3 5547.0 5808.1 5883.6 5974.1 6052.9 13718.9 13746.5 13942.4 13971.5 13975.4 2177.9 2203.7 2284.2 2396.9 2483.2 10146.3 10604.6 10609.0 11367.6 13087.5 1829.0 1852.4 1863.0 1903.9 1910.3 89933.6 99698.5 121587.8 128785.0 133760.7 9656.7 11286.2 11477.9 11537.8 11588.2 14702.9 14776.7 14836.5 14839.2 14845.5 8487.6 8575.5 8580.3 8664.6 8684.3 8459.3 8468.9 8474.4 8578.6 8583.4 4517.7 4580.7 4598.9 4656.5 4658.4 1663.5 1863.9 1906.7 1922.7 1950.5 887.6 897.6 979.3 996.4 1071.9 21325.7 22322.7 22377.2 22411.8 22512.4 811.3 909.9 971.9 993.4 994.9 10927.2 11258.8 11691.9 11770.9 11779.6 601364.0 606816.0 619983.0 622616.0 622942.0 77881.0 126831.4 126911.0 128073.0 129962.0 985.7 1001.6 1029.1 1033.3 1044.3 20358.8 20895.9 21006.5 21027.1 21052.3 43032.8 52327.0 59152.7 59168.7 59506.9 85074.6 86212.6 86794.6 87384.6 87690.0 7191.1 8154.1 8203.4 8348.2 8566.0 9498.7 13362.6 13496.3 13666.7 13712.7 5529.8 6293.8 11012.1 11391.1 11701.9 11448.1 11497.9 11545.9 11551.2 11637.8 11781.8 11953.8 11959.5 11977.6 11985.4 7504.8 7525.7 7576.0 7751.7 7796.1 11211.2 11636.9 11719.4 11776.2 11925.7 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#3] mootools.html Cycles:180 Average:5748.09 Median:5058.70 stddev:3214.92 (63.6%) stddev-sans-first:3207.36 20:44:22 INFO - PROCESS | 2221 | Values: 1403.9 1572.2 1598.8 1839.3 1972.7 7903.6 8631.4 8867.3 8873.9 8989.8 7526.8 8653.1 8738.7 8820.7 8831.3 2109.4 2309.5 2364.5 2393.2 2394.4 2405.5 2434.4 2436.0 2438.9 2440.0 2375.0 2381.9 2394.8 2413.4 2413.5 2074.8 2284.3 2359.4 2370.3 2384.4 2375.8 2399.5 2399.7 2401.7 2406.2 8794.7 8848.5 8857.5 8863.9 8878.2 4521.3 5351.8 5379.7 5381.9 5402.7 3125.7 3212.7 3288.8 3292.0 3318.2 1081.3 1090.3 1094.0 1113.1 1114.0 10145.8 11660.5 11664.5 11678.0 11680.3 4663.1 4740.7 4767.4 4842.2 4859.6 9887.2 9896.6 9897.0 9902.1 9920.7 5399.4 5403.5 5403.8 5407.3 5408.4 3710.4 4193.7 4584.4 4588.5 4951.9 3765.7 3863.9 3874.2 3927.9 4120.4 1300.4 1301.1 1319.3 1325.8 1327.5 821.8 938.7 947.1 968.7 978.8 4866.6 6051.5 6205.8 6231.8 6262.8 832.0 923.2 940.1 946.5 977.9 4052.3 4063.8 4102.5 4140.0 4144.9 10607.0 10650.7 11029.9 11047.0 11060.4 10819.8 10954.4 10959.4 10972.2 10997.8 7988.9 8377.0 8391.3 8475.2 8481.7 5903.5 5934.6 6092.0 6246.7 6330.8 8703.6 9631.7 10315.7 10324.2 10333.4 9269.2 10183.6 10328.8 10333.6 10376.9 7076.6 7451.7 7636.2 7698.0 7790.6 8309.6 8407.3 8482.8 8493.1 8494.6 7560.5 7622.2 7635.8 7688.6 7762.2 7542.4 7557.4 7630.4 7685.2 7702.4 7100.0 7102.8 7152.7 7173.0 7179.6 5055.6 5061.7 5115.5 5142.5 5219.3 5042.6 5129.0 5181.7 5183.4 5292.6 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#4] prototype.html Cycles:180 Average:4472.17 Median:4867.94 stddev:1402.85 (28.8%) stddev-sans-first:1370.65 20:44:22 INFO - PROCESS | 2221 | Values: 257.4 304.3 315.3 318.7 319.1 4546.9 4590.0 4774.0 5395.1 5403.3 5132.5 5274.5 5325.9 5358.0 5378.4 4042.6 4074.5 4094.3 4103.7 4119.8 3172.2 4085.4 4089.2 4089.8 4091.6 4016.5 4033.2 4036.5 4053.7 4061.3 4013.5 4043.3 4059.7 4060.3 4103.6 3496.4 3498.8 3521.6 3536.9 3542.5 3980.8 4618.5 4619.6 5324.6 5338.5 4619.4 4619.7 4624.8 4647.0 4696.7 4751.3 5627.8 5645.9 5666.4 5732.8 3515.3 3537.5 3832.2 3864.2 3875.9 5925.5 5955.2 5965.8 6034.8 6047.5 3874.6 3912.9 3913.4 3915.5 3930.2 3348.7 3812.0 3897.7 3898.8 3968.1 3872.5 3904.9 4515.2 4580.6 4588.1 4363.9 4523.0 5334.5 5620.5 5684.1 1226.9 1261.7 1291.9 1351.6 1351.7 864.8 874.7 890.2 895.5 903.7 4257.1 4979.0 4995.5 5188.4 5303.7 4488.0 4982.4 5610.0 5756.7 5887.4 4927.3 4955.7 5157.2 5186.4 5187.8 2103.0 2210.4 2396.0 2557.5 2583.8 4409.0 4552.6 4807.3 4950.8 5015.7 4248.3 4484.8 4568.0 4807.9 4983.9 5106.9 5480.5 5810.7 5868.7 6022.3 5291.7 5451.9 5663.4 5989.7 6022.9 5610.4 5644.2 5780.2 5792.8 5850.7 5929.9 5939.6 6087.4 6098.7 6107.0 3433.5 3722.0 3828.1 3831.2 3863.3 5463.3 5503.7 5572.6 5736.4 5846.2 5019.0 5592.0 5609.7 5618.9 5630.2 4658.6 4752.8 5063.9 5806.8 5835.9 5265.5 5464.4 5663.5 5681.6 5746.0 5448.0 5660.3 5670.1 5677.7 5680.5 4808.6 5608.0 5645.5 5658.9 5659.6 20:44:22 INFO - PROCESS | 2221 | 20:44:22 INFO - PROCESS | 2221 | [#5] yui.html Cycles:180 Average:26921.42 Median:23805.79 stddev:44258.63 (185.9%) stddev-sans-first:44339.13 20:44:22 INFO - PROCESS | 2221 | Values: 741.2 759.8 766.5 857.3 873.3 14937.0 16262.7 16288.9 16298.3 16373.9 14690.8 14816.8 14844.8 14886.3 14893.3 701.0 722.5 866.8 893.2 894.7 878.3 882.0 882.4 883.4 883.7 923.1 935.7 936.0 938.5 938.9 746.3 916.3 932.6 934.8 936.3 704.5 705.5 705.7 707.2 711.7 15064.1 15108.7 15145.5 15155.0 15158.0 12786.7 14071.9 14480.7 14524.3 14531.3 11627.2 13102.7 13222.5 13325.2 13529.8 4646.0 4685.3 4756.4 4814.5 4825.7 117777.4 117894.4 118184.0 118462.0 121769.0 10922.1 11385.3 11815.8 11851.1 11912.5 22726.5 23789.4 23822.2 23845.0 23850.6 8452.4 8512.8 8659.1 8736.1 8738.1 4023.6 4535.4 4588.7 4605.7 4746.0 3566.5 3660.8 3697.4 3705.0 3706.4 674.7 676.2 686.4 696.0 733.9 593.6 666.2 702.4 706.4 711.8 9833.5 9866.8 9876.3 9900.6 9963.2 656.7 692.2 692.7 695.7 707.9 4650.8 4778.3 4780.3 4784.7 4848.9 136058.3 164424.2 166283.0 170459.0 175707.0 143171.0 144058.6 145065.3 147188.0 147525.0 122397.0 123423.1 125767.1 127299.5 128465.0 9266.2 9299.0 9339.7 9406.4 9410.8 66090.3 67139.0 67580.3 68162.7 68470.7 97169.0 104815.0 105697.4 105705.0 108138.0 11935.2 11978.3 12091.9 12234.7 12235.1 11557.7 13303.1 13607.2 13648.7 14069.4 12048.4 12315.8 12357.5 12485.8 12606.4 12582.7 12697.9 12904.3 13036.5 13048.9 12095.8 12437.5 12520.1 12676.2 12796.6 10293.4 10532.6 10664.2 10690.6 10732.9 10029.6 10438.7 10746.5 10931.9 11012.2 20:44:22 INFO - PROCESS | 2221 | -------- Summary: end -------- 20:44:22 INFO - PROCESS | 2221 | 20:44:23 INFO - TEST-INFO | 2221: exit 0 20:44:23 INFO - TEST-OK | dromaeo_css | took 1117260ms 20:44:23 INFO - TEST-START | kraken 20:44:23 INFO - operating with platform_type : mac_ 20:44:23 INFO - Initialising browser for kraken test... 20:44:23 INFO - TEST-INFO | started process 2225 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpAkTtaV/profile http://localhost:56746/getInfo.html) 20:44:25 INFO - PROCESS | 2225 | __metrics Screen width/height:1600/1200 20:44:25 INFO - PROCESS | 2225 | colorDepth:24 20:44:25 INFO - PROCESS | 2225 | Browser inner width/height: 1024/689 20:44:25 INFO - PROCESS | 2225 | __metrics 20:44:25 INFO - PROCESS | 2225 | 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" 20:44:25 INFO - PROCESS | 2225 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:44:26 INFO - TEST-INFO | 2225: exit 0 20:44:26 INFO - Browser initialized. 20:44:26 INFO - Running cycle 1/1 for kraken test... 20:44:26 INFO - TEST-INFO | started process 2226 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/tmpAkTtaV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:44:38 INFO - PROCESS | 2226 | RSS: Main: 243322880 20:44:38 INFO - PROCESS | 2226 | 20:44:41 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56746/tests/kraken/driver.html?testName=audio-beat-detection) 20:44:41 INFO - PROCESS | 2226 | RSS: Main: 294273024 20:44:41 INFO - PROCESS | 2226 | 20:44:44 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56746/tests/kraken/driver.html?testName=audio-dft) 20:44:45 INFO - PROCESS | 2226 | RSS: Main: 304664576 20:44:45 INFO - PROCESS | 2226 | 20:44:48 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56746/tests/kraken/driver.html?testName=audio-fft) 20:44:49 INFO - PROCESS | 2226 | RSS: Main: 389984256 20:44:49 INFO - PROCESS | 2226 | 20:44:51 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56746/tests/kraken/driver.html?testName=audio-oscillator) 20:44:51 INFO - PROCESS | 2226 | RSS: Main: 290295808 20:44:51 INFO - PROCESS | 2226 | 20:44:53 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56746/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:44:54 INFO - PROCESS | 2226 | RSS: Main: 294211584 20:44:54 INFO - PROCESS | 2226 | 20:44:57 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56746/tests/kraken/driver.html?testName=imaging-darkroom) 20:44:57 INFO - PROCESS | 2226 | RSS: Main: 331055104 20:44:57 INFO - PROCESS | 2226 | 20:45:00 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56746/tests/kraken/driver.html?testName=imaging-desaturate) 20:45:01 INFO - PROCESS | 2226 | RSS: Main: 333115392 20:45:01 INFO - PROCESS | 2226 | 20:45:04 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56746/tests/kraken/driver.html?testName=json-parse-financial) 20:45:04 INFO - PROCESS | 2226 | RSS: Main: 330354688 20:45:04 INFO - PROCESS | 2226 | 20:45:06 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56746/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:45:07 INFO - PROCESS | 2226 | RSS: Main: 311799808 20:45:07 INFO - PROCESS | 2226 | 20:45:08 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:45:09 INFO - PROCESS | 2226 | RSS: Main: 318578688 20:45:09 INFO - PROCESS | 2226 | 20:45:11 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:45:12 INFO - PROCESS | 2226 | RSS: Main: 316039168 20:45:12 INFO - PROCESS | 2226 | 20:45:15 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:45:15 INFO - PROCESS | 2226 | RSS: Main: 324947968 20:45:15 INFO - PROCESS | 2226 | 20:45:18 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:45:19 INFO - PROCESS | 2226 | RSS: Main: 327352320 20:45:19 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | Cycle 1(1): loaded http://localhost:56746/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56746/tests/kraken/driver.html?testName=ai-astar) 20:45:21 INFO - PROCESS | 2226 | RSS: Main: 370450432 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | __start_tp_report 20:45:21 INFO - PROCESS | 2226 | _x_x_mozilla_page_load 20:45:21 INFO - PROCESS | 2226 | _x_x_mozilla_page_load_details 20:45:21 INFO - PROCESS | 2226 | |i|pagename|runs| 20:45:21 INFO - PROCESS | 2226 | |0;ai-astar;100;113;114;111;113;112;112;113;112;113 20:45:21 INFO - PROCESS | 2226 | |1;audio-beat-detection;147;105;106;164;132;132;155;103;161;108 20:45:21 INFO - PROCESS | 2226 | |2;audio-dft;184;183;184;185;180;184;183;182;184;181 20:45:21 INFO - PROCESS | 2226 | |3;audio-fft;78;74;58;115;57;58;62;66;100;64 20:45:21 INFO - PROCESS | 2226 | |4;audio-oscillator;86;90;92;71;71;68;97;70;67;102 20:45:21 INFO - PROCESS | 2226 | |5;imaging-gaussian-blur;88;85;89;85;87;84;88;86;88;85 20:45:21 INFO - PROCESS | 2226 | |6;imaging-darkroom;94;93;94;93;94;94;93;95;93;94 20:45:21 INFO - PROCESS | 2226 | |7;imaging-desaturate;87;85;86;86;86;86;87;86;84;84 20:45:21 INFO - PROCESS | 2226 | |8;json-parse-financial;57;56;55;57;55;55;53;55;54;54 20:45:21 INFO - PROCESS | 2226 | |9;json-stringify-tinderbox;66;70;84;76;73;72;70;71;70;71 20:45:21 INFO - PROCESS | 2226 | |10;stanford-crypto-aes;82;83;83;84;85;83;83;82;83;79 20:45:21 INFO - PROCESS | 2226 | |11;stanford-crypto-ccm;136;132;137;145;144;140;135;135;233;143 20:45:21 INFO - PROCESS | 2226 | |12;stanford-crypto-pbkdf2;164;164;162;167;162;184;160;161;162;161 20:45:21 INFO - PROCESS | 2226 | |13;stanford-crypto-sha256-iterative;66;79;88;85;81;70;66;66;65;66 20:45:21 INFO - PROCESS | 2226 | __end_tp_report 20:45:21 INFO - PROCESS | 2226 | __start_cc_report 20:45:21 INFO - PROCESS | 2226 | _x_x_mozilla_cycle_collect,2073 20:45:21 INFO - PROCESS | 2226 | __end_cc_report 20:45:21 INFO - PROCESS | 2226 | __startTimestamp1467863121449__endTimestamp 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | ------- Summary: start ------- 20:45:21 INFO - PROCESS | 2226 | Number of tests: 14 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#0] ai-astar Cycles:10 Average:111.30 Median:113.00 stddev:4.06 (3.6%) stddev-sans-first:0.88 20:45:21 INFO - PROCESS | 2226 | Values: 100.0 113.0 114.0 111.0 113.0 112.0 112.0 113.0 112.0 113.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:139.50 stddev:24.58 (17.6%) stddev-sans-first:25.40 20:45:21 INFO - PROCESS | 2226 | Values: 147.0 105.0 106.0 164.0 132.0 132.0 155.0 103.0 161.0 108.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#2] audio-dft Cycles:10 Average:183.00 Median:184.00 stddev:1.56 (0.8%) stddev-sans-first:1.62 20:45:21 INFO - PROCESS | 2226 | Values: 184.0 183.0 184.0 185.0 180.0 184.0 183.0 182.0 184.0 181.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#3] audio-fft Cycles:10 Average:73.20 Median:63.00 stddev:19.65 (31.2%) stddev-sans-first:20.77 20:45:21 INFO - PROCESS | 2226 | Values: 78.0 74.0 58.0 115.0 57.0 58.0 62.0 66.0 100.0 64.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#4] audio-oscillator Cycles:10 Average:81.40 Median:78.50 stddev:13.37 (17.0%) stddev-sans-first:14.08 20:45:21 INFO - PROCESS | 2226 | Values: 86.0 90.0 92.0 71.0 71.0 68.0 97.0 70.0 67.0 102.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#5] imaging-gaussian-blur Cycles:10 Average:86.50 Median:87.50 stddev:1.72 (2.0%) stddev-sans-first:1.73 20:45:21 INFO - PROCESS | 2226 | Values: 88.0 85.0 89.0 85.0 87.0 84.0 88.0 86.0 88.0 85.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#6] imaging-darkroom Cycles:10 Average:93.70 Median:94.00 stddev:0.67 (0.7%) stddev-sans-first:0.71 20:45:21 INFO - PROCESS | 2226 | Values: 94.0 93.0 94.0 93.0 94.0 94.0 93.0 95.0 93.0 94.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.00 stddev:1.06 (1.2%) stddev-sans-first:1.01 20:45:21 INFO - PROCESS | 2226 | Values: 87.0 85.0 86.0 86.0 86.0 86.0 87.0 86.0 84.0 84.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:1.29 (2.3%) stddev-sans-first:1.17 20:45:21 INFO - PROCESS | 2226 | Values: 57.0 56.0 55.0 57.0 55.0 55.0 53.0 55.0 54.0 54.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#9] json-stringify-tinderbox Cycles:10 Average:72.30 Median:71.50 stddev:4.83 (6.8%) stddev-sans-first:4.56 20:45:21 INFO - PROCESS | 2226 | Values: 66.0 70.0 84.0 76.0 73.0 72.0 70.0 71.0 70.0 71.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.57 (1.9%) stddev-sans-first:1.64 20:45:21 INFO - PROCESS | 2226 | Values: 82.0 83.0 83.0 84.0 85.0 83.0 83.0 82.0 83.0 79.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:141.50 stddev:30.18 (21.3%) stddev-sans-first:31.70 20:45:21 INFO - PROCESS | 2226 | Values: 136.0 132.0 137.0 145.0 144.0 140.0 135.0 135.0 233.0 143.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.70 Median:163.00 stddev:7.07 (4.3%) stddev-sans-first:7.50 20:45:21 INFO - PROCESS | 2226 | Values: 164.0 164.0 162.0 167.0 162.0 184.0 160.0 161.0 162.0 161.0 20:45:21 INFO - PROCESS | 2226 | 20:45:21 INFO - PROCESS | 2226 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.20 Median:74.50 stddev:9.05 (12.2%) stddev-sans-first:9.22 20:45:21 INFO - PROCESS | 2226 | Values: 66.0 79.0 88.0 85.0 81.0 70.0 66.0 66.0 65.0 66.0 20:45:21 INFO - PROCESS | 2226 | -------- Summary: end -------- 20:45:21 INFO - PROCESS | 2226 | 20:45:22 INFO - TEST-INFO | 2226: exit 0 20:45:22 INFO - TEST-OK | kraken | took 59076ms 20:45:22 INFO - SUITE-END | took 1176s 20:45:22 INFO - Completed test suite (00:19:36) 20:45:22 INFO - javascript benchmark 20:45:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3258.934821303574, 3277.1970867137557, 3282.2495466230894, 3298.142482954431, 3319.8878375744125, 5554.50009499145, 5620.522255608268, 5642.943570564293, 5731.11167768996, 5745.109508026263, 5667.603267771247, 5695.886082278352, 5721.6853073081065, 5724.7137643117785, 5750.166225897243, 4268.0, 5135.013725979536, 5154.175331946889, 5174.042802081627, 5251.684898906059, 4438.711483753562, 5256.63203575748, 5264.184051472009, 5264.631475796696, 5267.28891599633, 4261.318189089746, 5147.691138531682, 5156.510131537498, 5161.329027226474, 5276.050310944029, 5214.608904332172, 5251.632385732962, 5262.105442074818, 5274.182501712241, 5324.9733751331, 5064.0, 5085.872853178663, 5100.948990510122, 5174.0, 5175.0, 5572.888542229173, 5574.637648552831, 5652.0, 5655.632383895075, 5669.319681638189, 6098.233310320522, 6106.528200251913, 6118.082287656843, 6125.785597504066, 6129.356417576135, 5075.619328550373, 6169.5064394848305, 6172.567920245585, 6193.938060619381, 6217.129601855744, 5777.422257774231, 5801.7389217485315, 5862.2672165979675, 5871.412858714094, 5881.882362352814, 6165.660888651168, 6203.193584834033, 6225.0, 6244.1570387997945, 6283.371662833772, 6025.849353766191, 6071.0, 6083.209182806207, 6176.227971503562, 6208.627482351073, 6182.876342473125, 6249.468795152372, 6250.937490625036, 6271.216097987752, 6297.023961286008, 6200.968995155085, 6222.564420490613, 6226.782062627786, 6233.252009758858, 6237.220347456568, 6115.635867915819, 6131.908021379683, 6182.258129024546, 6249.78125765596, 6274.874502509924, 4890.828820991248, 4921.040397122599, 4922.458529561745, 4931.038447502702, 4931.753412329441, 4366.781660916941, 4382.189294980439, 4424.80088396023, 4459.063596644741, 4461.420015397977, 5703.62926409782, 5713.800016999468, 5724.055530837449, 5724.398938111522, 5743.684097374601, 6205.441510264008, 6258.530610204253, 6264.436200741955, 6271.404216599416, 6276.372362763687, 5569.721513924369, 5611.6071874969175, 5618.129189975561, 5684.062129748545, 5702.6863522506665, 5392.649477783932, 5394.514493695587, 5396.784128634889, 5397.73011349431, 5405.70268635221, 6184.783532576338, 6216.751329946842, 6219.40915613016, 6230.0, 6233.0, 6241.032639940817, 6271.278802937721, 6274.278457977391, 6282.780102696383, 6290.591111577733, 6362.681865906594, 6374.139491168633, 6378.394052564999, 6385.7126429309865, 6389.680515974275, 5777.497850558801, 6029.246344206974, 6097.5731698780655, 6190.0, 6208.348123446973, 5450.482204190595, 5620.437956204511, 6155.84610384744, 6276.717547710273, 6279.811605651837, 6077.179580756541, 6096.634201947897, 6100.115483254992, 6101.511879049576, 6112.235970503687, 5350.03699334108, 5384.9730751346, 5967.671778052249, 5979.641221526722, 6071.089336599545, 5817.0983497557945, 5882.705864706833, 5896.616719913192, 5935.0, 6016.759329626766, 6078.513718902565, 6096.116063170904, 6113.877722445437, 6127.938720612737, 6141.692915354304, 5909.409059094056, 5916.55625828056, 5940.702964851654, 5947.167396564571, 5963.970180149072], "value": 5645.558762331172, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [445.71251542754925, 469.46481011646705, 540.1357827476041, 546.3280165396562, 552.1166134185304, 30093.398132037375, 33167.834160829196, 42635.0, 42656.786716066454, 42793.572064279346, 35307.64692353082, 43688.3446748299, 44017.77991110048, 44191.33712994308, 44228.557714422845, 8753.693620723276, 9682.661106861262, 9810.215182785396, 9871.555779989882, 9875.506224688772, 8803.823923521526, 11923.0, 12180.330081845495, 12200.0, 12377.381130943462, 12056.63830085099, 12113.515459381659, 12123.030157587373, 12137.332446715427, 12141.0, 8767.912320876774, 11862.466189021514, 12012.219205751642, 12083.275003499819, 12107.273563586214, 11605.941970290094, 11986.160968732192, 11996.22024568409, 12002.21985570935, 12014.819777703342, 41101.79449102765, 41486.1702765946, 41566.0, 41627.37558936619, 41627.58372416267, 3521.841517131735, 3537.982310088433, 3705.8332375043187, 3902.824372903226, 3903.960960390388, 30144.397112057857, 32867.01398958035, 33004.1748956278, 33487.497687534706, 34294.828525857214, 3295.126791400281, 3301.0591981285456, 3307.570015897918, 3428.9884484077256, 3438.0373495421322, 534363.0, 547042.2647886735, 547043.0, 549131.0, 549415.0, 36590.45114323287, 69668.6516567414, 70603.58792824115, 70666.9399959001, 70748.0, 37366.0, 37410.62589374126, 37520.37357385011, 37616.62383376131, 39044.02389940274, 15002.24988750558, 15115.924420377827, 15254.23728813555, 15266.236688165547, 15269.389224430899, 11559.595414160463, 11800.88199118015, 12527.56153534622, 12720.427580758887, 12778.61664150077, 7838.960805196051, 8109.270165685117, 8137.633806478722, 8172.141925097898, 8278.793030174173, 2946.2781618503604, 3412.146963259185, 3412.539307193547, 3419.6238413774463, 3443.8278086095597, 945.7588314979636, 980.4803454169302, 1088.820344643125, 1092.5192915117482, 1129.1982692288568, 26834.0, 30605.23486912845, 30981.8450907744, 31703.52444713331, 32001.67998319987, 1026.461107918349, 1088.6026600290836, 1090.7763908398758, 1131.3494740524231, 1196.389841181004, 16211.0, 16301.836981630031, 16335.01989880611, 16379.0172589645, 16741.0, 655005.0, 696057.0, 703498.0, 704754.0, 705036.0, 59375.40624593699, 60082.399176008556, 60261.69869150714, 60282.69858650678, 60330.39669603336, 1286.6204469681466, 1408.1902905829395, 1409.1474657832068, 1429.1853643423149, 1451.7894905238682, 32195.712171512878, 32245.355092897542, 32356.83821580877, 32365.0, 33024.00927972165, 50883.49116508788, 50991.490085098674, 51561.48438515717, 51658.74170629123, 51709.7414512925, 549764.0, 550904.0, 552431.0, 552907.0, 554768.0, 24600.261992140266, 27510.724892751616, 27840.886364545193, 27847.443051139267, 27991.720082799726, 48046.279305809745, 48591.0, 49189.754051229516, 49196.508034920626, 49697.75151124221, 39344.60655393524, 40456.59543404528, 40719.79640101899, 41478.792606036775, 41568.792156039024, 39899.800500997306, 40653.390199146445, 40800.0, 41336.793316033225, 41376.0, 36701.63298367089, 37601.62398376091, 39533.40699889562, 39700.61047863311, 39744.40383394309, 33195.0, 35192.0, 35536.822315888254, 35676.46485302671, 35785.926422206336, 25508.107216247343, 25715.485690286463, 25867.0, 26219.297156339253, 26655.33361665896], "value": 19707.65125538312, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.917064489845, 360.3567631777277, 383.8042598274879, 384.5577585776357, 402.47678018575846, 15979.201039948015, 16750.832491675093, 21302.89348553255, 21926.45183870404, 21953.341399757992, 21248.043838027286, 21580.920953952318, 22009.449763755914, 22419.879006049712, 22474.32577022688, 5974.731137098841, 6032.245969253854, 6211.720472578722, 6227.813165605039, 6282.560220784547, 3962.9207415851665, 4171.186618609372, 5196.272521846944, 6200.78297259596, 6261.7182226799905, 5917.349091599921, 6894.13823272091, 6895.482838787086, 6896.103506544142, 6909.516333856632, 6208.906866397007, 6209.192804935375, 6229.750809967596, 6238.1266622672865, 6490.286068532481, 5547.0, 5808.07070868659, 5883.588148829584, 5974.133750606138, 6052.909206361908, 13718.902487801051, 13746.450141994408, 13942.37259323333, 13971.510997115052, 13975.37110830015, 2177.912883484659, 2203.7355517338024, 2284.234781348234, 2396.9041238350446, 2483.2301986384277, 10146.289759716823, 10604.628837990633, 10609.0, 11367.602133925355, 13087.541936032194, 1829.0, 1852.370194134001, 1862.962740745191, 1903.9428817135508, 1910.3122875764714, 89933.55033224857, 99698.501507492, 121587.78412215853, 128785.0, 133760.66239337676, 9656.662016829518, 11286.153538484647, 11477.885221147848, 11537.769244615061, 11588.188826782045, 14702.926485367718, 14776.704465910621, 14836.480723174853, 14839.18384488842, 14845.480408185662, 8487.618057187317, 8575.485470871768, 8580.313574913991, 8664.61009254585, 8684.305255579664, 8459.323254139654, 8468.915310846813, 8474.406791524658, 8578.613962371708, 8583.442076265022, 4517.6837621366185, 4580.725156490555, 4598.8620341389815, 4656.4877863434995, 4658.37111989877, 1663.484319860847, 1863.8508919286423, 1906.742589750366, 1922.7019811929174, 1950.4831219726784, 887.587271918548, 897.5915958238986, 979.3487330924855, 996.4121168510613, 1071.8981696738797, 21325.68011479829, 22322.665160022614, 22377.216797412337, 22411.775882241294, 22512.437189070406, 811.3022800391658, 909.9135582119688, 971.8930917599058, 993.3841018568534, 994.9403035817729, 10927.235093543535, 11258.774824503464, 11691.883081169079, 11770.94114529439, 11779.646610601696, 601364.0, 606816.0, 619983.0, 622616.0, 622942.0, 77881.0, 126831.36584317019, 126911.0, 128073.0, 129962.0, 985.6944347252374, 1001.5793366785967, 1029.0841151375282, 1033.328336581213, 1044.2742294105524, 20358.79641203628, 20895.850728210095, 21006.47483812917, 21027.053782579515, 21052.26317078895, 43032.78483607687, 52327.0, 59152.704236478545, 59168.704156480664, 59506.917257895766, 85074.57462712647, 86212.56893715491, 86794.56602716947, 87384.56307718421, 87690.0, 7191.137063552407, 8154.143814899588, 8203.384746143944, 8348.165183481846, 8565.957170214358, 9498.667546635834, 13362.599122026355, 13496.257705825881, 13666.726665466835, 13712.65718357049, 5529.834104976857, 6293.842653933688, 11012.119030477703, 11391.088712903114, 11701.88298117008, 11448.141389395661, 11497.942510287396, 11545.942270288595, 11551.191416601094, 11637.76724465523, 11781.823272650745, 11953.760924781282, 11959.461824218106, 11977.580784672495, 11985.400729963641, 7504.774856754306, 7525.6989720410575, 7576.0, 7751.728689496066, 7796.064472263365, 11211.215214934877, 11636.94181529087, 11719.41402929833, 11776.175667703179, 11925.701857453632], "value": 9558.16159036024, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1403.8876889848814, 1572.2138930534736, 1598.8081430228374, 1839.264294282287, 1972.6547854125533, 7903.644336004879, 8631.438956467833, 8867.334949878752, 8873.91126088739, 8989.820203595924, 7526.849463010736, 8653.134686531344, 8738.650453981832, 8820.735377938656, 8831.337649676283, 2109.398821335912, 2309.515001849614, 2364.49163429863, 2393.246127469841, 2394.365493144318, 2405.494846082316, 2434.3670645632174, 2435.9634605480933, 2438.9146379876706, 2440.023990403844, 2375.0, 2381.9285421437385, 2394.832361734679, 2413.3604594782396, 2413.4931664350506, 2074.8132668059798, 2284.2804516577226, 2359.445530300378, 2370.277065495023, 2384.380061184096, 2375.8336916415856, 2399.460121472672, 2399.6760437341086, 2401.735809061002, 2406.205952035824, 8794.692185773467, 8848.51333176675, 8857.512836793974, 8863.911360886437, 8878.200961913459, 4521.299198624219, 5351.785928562853, 5379.731013449351, 5381.892362152735, 5402.675839449606, 3125.7030582094662, 3212.726918211931, 3288.7697861149727, 3291.967080329214, 3318.1870441741685, 1081.3187691754144, 1090.3457925244868, 1093.9945300273448, 1113.092829344082, 1114.0363585498485, 10145.847812282822, 11660.475278612483, 11664.533418663243, 11678.0, 11680.299182049104, 4663.090697314059, 4740.6681532292405, 4767.404074490689, 4842.176829938919, 4859.611231101503, 9887.209023278121, 9896.554655040396, 9897.0, 9902.108810207115, 9920.702378928643, 5399.433059528771, 5403.486668766461, 5403.756830942539, 5407.270018547524, 5408.405075441777, 3710.387786015331, 4193.6645068394455, 4584.404027476407, 4588.518205588432, 4951.90096198074, 3765.6987441005203, 3863.942040869389, 3874.2251549690177, 3927.9214415711526, 4120.361343991686, 1300.4148133339845, 1301.0632344711792, 1319.3139567424885, 1325.7878739401842, 1327.502186679995, 821.8356328734277, 938.6996161228342, 947.0907928388824, 968.6835205066102, 978.8238117138983, 4866.586340161055, 6051.4553690168095, 6205.844853878689, 6231.75072997075, 6262.812115636447, 832.0, 923.2106548900728, 940.1303793990531, 946.5409276500892, 977.9168770654574, 4052.3313653246882, 4063.837446502107, 4102.487189101363, 4140.0, 4144.8756537303925, 10607.045365917105, 10650.680479585624, 11029.889701103048, 11047.005769480786, 11060.391678457601, 10819.783604328028, 10954.39750813697, 10959.397233152094, 10972.177086718528, 10997.835032474519, 7988.920110798817, 8376.958115209385, 8391.328693704489, 8475.152484751476, 8481.703140390056, 5903.527717782653, 5934.6142500737315, 6091.994820854509, 6246.718897649525, 6330.778422755181, 8703.608337624695, 9631.71104866855, 10315.690529284133, 10324.174066074545, 10333.431661258403, 9269.165775080275, 10183.643572474928, 10328.793424131625, 10333.586656533655, 10376.948115259376, 7076.646167691698, 7451.739189128354, 7636.198199189005, 7698.0, 7790.649420775965, 8309.626066826886, 8407.327413806759, 8482.787930301793, 8493.108223636676, 8494.575271236537, 7560.546367217985, 7622.237776222333, 7635.778275475897, 7688.615569221628, 7762.223777622193, 7542.358899493604, 7557.433192510473, 7630.427717921067, 7685.231476852494, 7702.383809295131, 7100.0414943981905, 7102.751403700846, 7152.678129484081, 7173.0316407284345, 7179.605121718356, 5055.646104772631, 5061.7469126544265, 5115.539601435739, 5142.537171654569, 5219.269302297757, 5042.571381432619, 5129.025485157808, 5181.689098654093, 5183.377994640668, 5292.576593872404], "value": 4624.661513014164, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [257.4349303279302, 304.32895465498575, 315.31891115191183, 318.6781350835657, 319.0858191281975, 4546.886327841806, 4590.0, 4774.021325628242, 5395.10980688186, 5403.324584426947, 5132.538071573557, 5274.549498887808, 5325.893482130355, 5358.035553600351, 5378.435264297251, 4042.6361627453525, 4074.490688663917, 4094.263032654121, 4103.651189648884, 4119.8352065917325, 3172.175234439051, 4085.387191921206, 4089.20260549193, 4089.8159582818844, 4091.5703851095504, 4016.53809811871, 4033.1731994941115, 4036.4550785644, 4053.736507127042, 4061.2689715851134, 4013.518377794654, 4043.292423825819, 4059.715819892594, 4060.330045542497, 4103.589641035873, 3496.4230901901155, 3498.8425520851624, 3521.5950165730756, 3536.929261414783, 3542.5217595624517, 3980.8009599520196, 4618.49196588375, 4619.607333376667, 5324.5740340772645, 5338.492843179931, 4619.399478067863, 4619.676622636417, 4624.820670728943, 4646.976765116153, 4696.694714843524, 4751.265787987394, 5627.803026894039, 5645.887082258373, 5666.433356664342, 5732.828015159552, 3515.33208690348, 3537.451694987281, 3832.176082142325, 3864.1598425157486, 3875.8837234883, 5925.466707996301, 5955.153902290303, 5965.821025369246, 6034.758609655609, 6047.455728984369, 3874.612538746103, 3912.8630497932436, 3913.412988051815, 3915.5105611798526, 3930.194310166409, 3348.665133486632, 3811.9618803812164, 3897.7271590988926, 3898.8050597470015, 3968.146848427602, 3872.5352957645264, 3904.9261453100626, 4515.209838278288, 4580.58774710271, 4588.128255631419, 4363.912721745547, 4522.977385113053, 5334.51989320963, 5620.4660557247, 5684.1063871248325, 1226.9018478521698, 1261.7287283234148, 1291.9018833991033, 1351.601092868876, 1351.6755978565075, 864.7924498120532, 874.6588830356167, 890.2032680750692, 895.5029958373035, 903.7333986474006, 4257.148570285955, 4979.0, 4995.500449955048, 5188.403333616682, 5303.708296043755, 4487.977560112178, 4982.377202849644, 5610.0, 5756.654600723886, 5887.35239123696, 4927.28554359623, 4955.702657840469, 5157.226416037549, 5186.429492755794, 5187.792488300426, 2102.9894850525648, 2210.4142402263415, 2395.964060539093, 2557.5268575313626, 2583.754543318382, 4408.963893485028, 4552.567506086916, 4807.278908163804, 4950.752462376867, 5015.673981191284, 4248.34150706641, 4484.775761211991, 4568.012914382254, 4807.927881081787, 4983.90032199354, 5106.872328191751, 5480.451954804487, 5810.6513609183585, 5868.67722275279, 6022.277326720821, 5291.677080729817, 5451.918221226764, 5663.377028526941, 5989.730462129128, 6022.939770602413, 5610.43895610452, 5644.209810626434, 5780.21967034445, 5792.82621521355, 5850.742090450573, 5929.940700592939, 5939.613925094855, 6087.36082711309, 6098.695065246631, 6106.969465152646, 3433.4678124890684, 3721.976456474491, 3828.119532507483, 3831.1762970961377, 3863.265979463893, 5463.31708536433, 5503.669779813224, 5572.637778544382, 5736.397678243724, 5846.210761547307, 5018.974905125451, 5592.02139625572, 5609.663420194638, 5618.915716264178, 5630.155476678531, 4658.627309815274, 4752.809887604595, 5063.87340316495, 5806.76772929095, 5835.8541036472725, 5265.472573164826, 5464.371597266365, 5663.516937083747, 5681.579605098726, 5746.0, 5447.96488667152, 5660.29246344207, 5670.121131224667, 5677.687727175045, 5680.545556355399, 4808.591269741971, 5608.037186984523, 5645.520130788929, 5658.886822263614, 5659.594672264354], "value": 4025.5156261277148, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [741.2180149941811, 759.8404335089631, 766.5285849202738, 857.3227150551066, 873.292632967297, 14937.029093108953, 16262.674746505063, 16288.918555407206, 16298.266578003988, 16373.935694179885, 14690.779638305434, 14816.777748333783, 14844.777328340084, 14886.25568721565, 14893.255337233122, 700.9824754381143, 722.4870342057146, 866.8353012927523, 893.2407453664398, 894.7181637784078, 878.2841983783228, 882.0385779500343, 882.4043770454949, 883.3772190605607, 883.659790980474, 923.0769230769231, 935.7473482159813, 935.957881895313, 938.4791440750381, 938.9436633801994, 746.268656716421, 916.2807196350899, 932.5896605493595, 934.7569631895689, 936.3024546712709, 704.4681265644406, 705.4779463197249, 705.7141857547701, 707.2291202589203, 711.6619605687309, 15064.096154230781, 15108.697826043417, 15145.545633631009, 15155.0, 15158.0, 12786.744265114645, 14071.859281407158, 14480.710385792225, 14524.346404411826, 14531.273436328247, 11627.244229125079, 13102.737945241042, 13222.471101155943, 13325.200487970753, 13529.797053044213, 4646.0, 4685.344051832746, 4756.357891684613, 4814.494478079821, 4825.662203645747, 117777.41111294474, 117894.41052794766, 118184.0, 118462.0, 121769.0, 10922.088634489091, 11385.316880987168, 11815.763684726257, 11851.051915846712, 11912.52349905994, 22726.545469090524, 23789.40526486817, 23822.16622418207, 23845.0, 23850.642240366407, 8452.408331416742, 8512.787180320542, 8659.09079546651, 8736.082711315348, 8738.082501337396, 4023.5976402360116, 4535.387722657465, 4588.655850811136, 4605.65457590682, 4745.95254047462, 3566.5363502744995, 3660.848922796653, 3697.408414653696, 3704.9811301891655, 3706.4069748840056, 674.6795272245644, 676.1919506190154, 686.3513979289523, 695.979120626382, 733.9045924029842, 593.6289818863211, 666.1506579111672, 702.367868917978, 706.445440329339, 711.8078118907866, 9833.459159746282, 9866.753331166778, 9876.259280553988, 9900.564418159327, 9963.153131983863, 656.6815094679076, 692.2385376086365, 692.7263730826295, 695.7460526907643, 707.886738121898, 4650.790714417859, 4778.259369797687, 4780.259059845729, 4784.6889952153, 4848.903021939542, 136058.3197084028, 164424.17787911222, 166283.0, 170459.0, 175707.0, 143171.0, 144058.5594144045, 145065.27467362594, 147188.0, 147525.0, 122397.0, 123423.14865277028, 125767.1134932995, 127299.45401091926, 128465.0, 9266.166045055978, 9298.95350523243, 9339.673111441067, 9406.372219315836, 9410.81178376415, 66090.33909661035, 67139.0, 67580.3241967574, 68162.65918670375, 68470.65764671145, 97169.0, 104815.0, 105697.41453878352, 105705.0, 108138.0, 11935.176736620793, 11978.281303121492, 12091.879081209076, 12234.694132646755, 12235.082368822195, 11557.653270401901, 13303.068785184943, 13607.183568985893, 13648.658783530094, 14069.437222510986, 12048.397580121135, 12315.815262770886, 12357.505699771908, 12485.812712809497, 12606.369681516071, 12582.685432864251, 12697.93651031739, 12904.290264035193, 13036.47854085826, 13048.869511304765, 12095.818562721743, 12437.502499899903, 12520.123591348882, 12676.176048556814, 12796.552120675731, 10293.38289665627, 10532.631357902437, 10664.200184986003, 10690.62582809629, 10732.946335268274, 10029.648962286577, 10438.68683939483, 10746.46267686597, 10931.945340273247, 11012.17408694335], "value": 8107.3100510118575, "unit": "score"}], "name": "dromaeo_css", "value": 7372.081326370524, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 113.0, 114.0, 111.0, 113.0, 112.0, 112.0, 113.0, 112.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 105.0, 106.0, 164.0, 132.0, 132.0, 155.0, 103.0, 161.0, 108.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 184.0, 185.0, 180.0, 184.0, 183.0, 182.0, 184.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 58.0, 115.0, 57.0, 58.0, 62.0, 66.0, 100.0, 64.0], "value": 73.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 92.0, 71.0, 71.0, 68.0, 97.0, 70.0, 67.0, 102.0], "value": 81.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 87.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 93.0, 95.0, 93.0, 94.0], "value": 93.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 86.0, 86.0, 86.0, 86.0, 87.0, 86.0, 84.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 55.0, 57.0, 55.0, 55.0, 53.0, 55.0, 54.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 84.0, 76.0, 73.0, 72.0, 70.0, 71.0, 70.0, 71.0], "value": 72.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 83.0, 84.0, 85.0, 83.0, 83.0, 82.0, 83.0, 79.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 132.0, 137.0, 145.0, 144.0, 140.0, 135.0, 135.0, 233.0, 143.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 162.0, 167.0, 162.0, 184.0, 160.0, 161.0, 162.0, 161.0], "value": 164.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 88.0, 85.0, 81.0, 70.0, 66.0, 66.0, 65.0, 66.0], "value": 73.2, "unit": "score"}], "name": "kraken", "value": 1442.1000000000001, "alertThreshold": 2.0}]} 20:45:22 INFO - Return code: 0 20:45:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:45:22 INFO - # TBPL SUCCESS # 20:45:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:45:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:45:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:45:22 INFO - 2016-07-06 20:45:22.748 screenresolution[2230:26063] starting screenresolution argv=screenresolution get 20:45:22 INFO - 2016-07-06 20:45:22.751 screenresolution[2230:26063] Display 0: 1600x1200x32@60 20:45:22 INFO - 2016-07-06 20:45:22.757 screenresolution[2231:26066] starting screenresolution argv=screenresolution list 20:45:22 INFO - Available Modes on Display 0 20:45:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:45:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:45:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:45:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:45:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:45:22 INFO - Intel Iris: 20:45:22 INFO - Chipset Model: Intel Iris 20:45:22 INFO - Type: GPU 20:45:22 INFO - Bus: Built-In 20:45:22 INFO - VRAM (Dynamic, Max): 1536 MB 20:45:22 INFO - Vendor: Intel (0x8086) 20:45:22 INFO - Device ID: 0x0a2e 20:45:22 INFO - Revision ID: 0x0009 20:45:22 INFO - Displays: 20:45:22 INFO - Display: 20:45:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:45:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:45:22 INFO - Display Serial Number: 1600x1200 60 20:45:22 INFO - Main Display: Yes 20:45:22 INFO - Mirror: Off 20:45:22 INFO - Online: Yes 20:45:22 INFO - Rotation: Supported 20:45:22 INFO - Return code: 0 20:45:22 INFO - Running post-action listener: _resource_record_post_action 20:45:22 INFO - ##### 20:45:22 INFO - ##### Finished run-tests step (success) 20:45:22 INFO - ##### 20:45:22 INFO - Running post-run listener: _resource_record_post_run 20:45:23 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 798720; Write bytes: 705019392; Read time: 6; Write time: 1994 20:45:23 INFO - TinderboxPrint: CPU usage
53.4% 20:45:23 INFO - TinderboxPrint: I/O read bytes / time
798,720 / 6 20:45:23 INFO - TinderboxPrint: I/O write bytes / time
705,019,392 / 1,994 20:45:23 INFO - TinderboxPrint: CPU idle
2,261.9 (46.6%) 20:45:23 INFO - TinderboxPrint: CPU system
965.0 (19.9%) 20:45:23 INFO - TinderboxPrint: CPU user
1,622.9 (33.5%) 20:45:23 INFO - TinderboxPrint: Swap in / out
406,843,392 / 0 20:45:23 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387670528; Write bytes: 383096320; Read time: 28986; Write time: 842 20:45:23 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 798720; Write bytes: 294696960; Read time: 6; Write time: 1089 20:45:23 INFO - Running post-run listener: _upload_blobber_files 20:45:23 INFO - Blob upload gear active. 20:45:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:45:23 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 20:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:45:23 INFO - (blobuploader) - INFO - Open directory for files ... 20:45:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:45:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:45:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:24 INFO - (blobuploader) - INFO - Done attempting. 20:45:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:45:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:45:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:25 INFO - (blobuploader) - INFO - Done attempting. 20:45:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:45:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:45:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:45:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:45:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:45:27 INFO - (blobuploader) - INFO - Done attempting. 20:45:27 INFO - (blobuploader) - INFO - Iteration through files over. 20:45:27 INFO - Return code: 0 20:45:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:45:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:45:27 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec82f4dcae2713e1e9c4650c84a0708ba141ce65502fe95d40e023c840473b27de8caf224fa085bfeab2226318250d22823c6d43dce6220ff97ecf75f125afc8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ec67cdd2412da0e3b02ab8a69dae4dbd563a8c88aa3500d337cb012f51913eb02151c23e4a0b7a99b4e68476b700fa1e7031f6c017f9e416640b2e11a01d37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87a1694e5f0daafe9e4727bf704fac1d07fbb56eef83161a6ba20c1848589dabbe50cde2325266d99792eade997547d3aa42fc756cfdfef1d18998c4092eb5b"} 20:45:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:45:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:45:27 INFO - Contents: 20:45:27 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec82f4dcae2713e1e9c4650c84a0708ba141ce65502fe95d40e023c840473b27de8caf224fa085bfeab2226318250d22823c6d43dce6220ff97ecf75f125afc8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ec67cdd2412da0e3b02ab8a69dae4dbd563a8c88aa3500d337cb012f51913eb02151c23e4a0b7a99b4e68476b700fa1e7031f6c017f9e416640b2e11a01d37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87a1694e5f0daafe9e4727bf704fac1d07fbb56eef83161a6ba20c1848589dabbe50cde2325266d99792eade997547d3aa42fc756cfdfef1d18998c4092eb5b"} 20:45:27 INFO - Running post-run listener: copy_logs_to_upload_dir 20:45:27 INFO - Copying logs to upload dir... 20:45:27 INFO - mkdir: /builds/slave/test/build/upload/logs 20:45:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.141875 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-07-06 20:45:27.882812) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 20:45:27.887390) ========= 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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec82f4dcae2713e1e9c4650c84a0708ba141ce65502fe95d40e023c840473b27de8caf224fa085bfeab2226318250d22823c6d43dce6220ff97ecf75f125afc8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ec67cdd2412da0e3b02ab8a69dae4dbd563a8c88aa3500d337cb012f51913eb02151c23e4a0b7a99b4e68476b700fa1e7031f6c017f9e416640b2e11a01d37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87a1694e5f0daafe9e4727bf704fac1d07fbb56eef83161a6ba20c1848589dabbe50cde2325266d99792eade997547d3aa42fc756cfdfef1d18998c4092eb5b"} build_url:https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011319 build_url: 'https://queue.taskcluster.net/v1/task/HrVQ0ptZTS-jDtNdwXOidA/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ec82f4dcae2713e1e9c4650c84a0708ba141ce65502fe95d40e023c840473b27de8caf224fa085bfeab2226318250d22823c6d43dce6220ff97ecf75f125afc8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0ec67cdd2412da0e3b02ab8a69dae4dbd563a8c88aa3500d337cb012f51913eb02151c23e4a0b7a99b4e68476b700fa1e7031f6c017f9e416640b2e11a01d37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c87a1694e5f0daafe9e4727bf704fac1d07fbb56eef83161a6ba20c1848589dabbe50cde2325266d99792eade997547d3aa42fc756cfdfef1d18998c4092eb5b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-06 20:45:27.923221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 20:45:27.923555) ========= 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.m8j8iDV8ky/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.YiJzUUQ7HO/Listeners TMPDIR=/var/folders/dv/4dymjbqs7kl7kyyl2h4nk6x400000w/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.005308 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 20:45:27.971734) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 20:45:27.972514) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 20:45:27.972864) ========= ========= Total master_lag: 2.50 =========