builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0005 starttime: 1468497323.21 results: success (0) buildid: 20160714024443 builduid: 4f017d5d2b8644d4b68ec5aa1b29fc9c revision: cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.211904) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.212368) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.212668) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.236312) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.236651) ========= 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.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-14 04:55:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.9M=0.001s 2016-07-14 04:55:23 (12.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085534 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.344282) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.344606) ========= 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.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.035420 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.405222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:23.405579) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 --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 cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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-14 04:55:23,482 truncating revision to first 12 chars 2016-07-14 04:55:23,483 Setting DEBUG logging. 2016-07-14 04:55:23,483 attempt 1/10 2016-07-14 04:55:23,483 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cd9da00ffcc3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-14 04:55:24,132 unpacking tar archive at: mozilla-central-cd9da00ffcc3/testing/mozharness/ program finished with exit code 0 elapsedTime=0.931637 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:24.359053) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:24.359372) ========= script_repo_revision: cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:24.359762) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:24.360040) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-14 04:55:24.379193) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-14 04:55:24.379460) ========= /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.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55:24 INFO - MultiFileLogger online at 20160714 04:55:24 in /builds/slave/test 04:55:24 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 04:55:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:55:24 INFO - {'append_to_log': False, 04:55:24 INFO - 'base_work_dir': '/builds/slave/test', 04:55:24 INFO - 'blob_upload_branch': 'Firefox', 04:55:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:55:24 INFO - 'branch': 'Firefox', 04:55:24 INFO - 'buildbot_json_path': 'buildprops.json', 04:55:24 INFO - 'config_files': ('talos/mac_config.py',), 04:55:24 INFO - 'default_actions': ('clobber', 04:55:24 INFO - 'read-buildbot-config', 04:55:24 INFO - 'download-and-extract', 04:55:24 INFO - 'populate-webroot', 04:55:24 INFO - 'create-virtualenv', 04:55:24 INFO - 'install', 04:55:24 INFO - 'run-tests'), 04:55:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:55:24 INFO - 'download_minidump_stackwalk': True, 04:55:24 INFO - 'download_symbols': 'ondemand', 04:55:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:55:24 INFO - 'tooltool.py': '/tools/tooltool.py', 04:55:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:55:24 INFO - '/tools/misc-python/virtualenv.py')}, 04:55:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:55:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:55:24 INFO - 'installer_path': 'installer.exe', 04:55:24 INFO - 'log_level': 'info', 04:55:24 INFO - 'log_name': 'talos', 04:55:24 INFO - 'log_to_console': True, 04:55:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:55:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:55:24 INFO - 'opt_config_files': (), 04:55:24 INFO - 'pip_index': False, 04:55:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:24 INFO - 'cmd': ('bash', 04:55:24 INFO - '-c', 04:55:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:24 INFO - 'enabled': True, 04:55:24 INFO - 'halt_on_failure': False, 04:55:24 INFO - 'name': 'check_screen_resolution'},), 04:55:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:55:24 INFO - 'cmd': ('bash', 04:55:24 INFO - '-c', 04:55:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:55:24 INFO - 'enabled': True, 04:55:24 INFO - 'halt_on_failure': False, 04:55:24 INFO - 'name': 'check_screen_resolution'},), 04:55:24 INFO - 'run_cmd_checks_enabled': True, 04:55:24 INFO - 'sps_profile': False, 04:55:24 INFO - 'sps_profile_interval': 0, 04:55:24 INFO - 'suite': 'dromaeojs', 04:55:24 INFO - 'system_bits': '32', 04:55:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:55:24 INFO - 'title': 't-yosemite-r7-0005', 04:55:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:55:24 INFO - 'use_talos_json': True, 04:55:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:55:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:55:24 INFO - 'work_dir': 'build'} 04:55:24 INFO - ##### 04:55:24 INFO - ##### Running clobber step. 04:55:24 INFO - ##### 04:55:24 INFO - Running pre-action listener: _resource_record_pre_action 04:55:24 INFO - Running main action method: clobber 04:55:24 INFO - rmtree: /builds/slave/test/build 04:55:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:55:26 INFO - Running post-action listener: _resource_record_post_action 04:55:26 INFO - ##### 04:55:26 INFO - ##### Finished clobber step (success) 04:55:26 INFO - ##### 04:55:26 INFO - ##### 04:55:26 INFO - ##### Running read-buildbot-config step. 04:55:26 INFO - ##### 04:55:26 INFO - Running pre-action listener: _resource_record_pre_action 04:55:26 INFO - Running main action method: read_buildbot_config 04:55:26 INFO - Using buildbot properties: 04:55:26 INFO - { 04:55:26 INFO - "project": "", 04:55:26 INFO - "product": "firefox", 04:55:26 INFO - "script_repo_revision": "production", 04:55:26 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 04:55:26 INFO - "repository": "", 04:55:26 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 04:55:26 INFO - "buildid": "20160714024443", 04:55:26 INFO - "pgo_build": "False", 04:55:26 INFO - "basedir": "/builds/slave/test", 04:55:26 INFO - "buildnumber": 11, 04:55:26 INFO - "slavename": "t-yosemite-r7-0005", 04:55:26 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:55:26 INFO - "platform": "yosemite_r7", 04:55:26 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 04:55:26 INFO - "branch": "mozilla-central", 04:55:26 INFO - "repo_path": "mozilla-central", 04:55:26 INFO - "revision": "cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3", 04:55:26 INFO - "stage_platform": "macosx64", 04:55:26 INFO - "builduid": "4f017d5d2b8644d4b68ec5aa1b29fc9c", 04:55:26 INFO - "slavebuilddir": "test" 04:55:26 INFO - } 04:55:26 INFO - Found installer url https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 04:55:26 INFO - Running post-action listener: _resource_record_post_action 04:55:26 INFO - ##### 04:55:26 INFO - ##### Finished read-buildbot-config step (success) 04:55:26 INFO - ##### 04:55:26 INFO - ##### 04:55:26 INFO - ##### Running download-and-extract step. 04:55:26 INFO - ##### 04:55:26 INFO - Running pre-action listener: _resource_record_pre_action 04:55:26 INFO - Running main action method: download_and_extract 04:55:26 INFO - mkdir: /builds/slave/test/build/tests 04:55: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')]} 04:55:26 INFO - https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:55:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:55:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 04:55:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:27 INFO - Downloaded 1199 bytes. 04:55:27 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 04:55:27 INFO - Using the following test package requirements: 04:55:27 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:55:27 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 04:55:27 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 04:55:27 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'jsshell-mac.zip'], 04:55:27 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 04:55:27 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 04:55:27 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 04:55:27 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 04:55:27 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 04:55:27 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 04:55:27 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 04:55:27 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:27 INFO - https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:29 INFO - Downloaded 18827683 bytes. 04:55:29 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'] 04:55:29 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 04:55:32 INFO - Return code: 0 04:55:32 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 04:55:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:55:32 INFO - https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:55:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:55:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 04:55:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:33 INFO - Downloaded 18827683 bytes. 04:55:33 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'] 04:55:33 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 04:55:37 INFO - Return code: 0 04:55:37 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')]} 04:55:37 INFO - https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:55:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:55:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 04:55:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:38 INFO - Downloaded 14403317 bytes. 04:55:38 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'] 04:55:38 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 04:55:38 INFO - Return code: 0 04:55:38 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')]} 04:55:38 INFO - https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 04:55:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:55:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:55:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 04:55:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FaxDy4CSQYOc20rCXj-jAg/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 04:55:41 INFO - Downloaded 112782273 bytes. 04:55:41 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:55:41 INFO - mkdir: /builds/slave/test/properties 04:55:41 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:55:41 INFO - Writing to file /builds/slave/test/properties/build_url 04:55:41 INFO - Contents: 04:55:41 INFO - build_url:https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 04:55:42 INFO - Running post-action listener: _resource_record_post_action 04:55:42 INFO - Running post-action listener: set_extra_try_arguments 04:55:42 INFO - ##### 04:55:42 INFO - ##### Finished download-and-extract step (success) 04:55:42 INFO - ##### 04:55:42 INFO - ##### 04:55:42 INFO - ##### Running populate-webroot step. 04:55:42 INFO - ##### 04:55:42 INFO - Running pre-action listener: _resource_record_pre_action 04:55:42 INFO - Running main action method: populate_webroot 04:55:42 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:55:42 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:55:42 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:55:42 INFO - '--tppagecycles', 04:55:42 INFO - '7'], 04:55:42 INFO - 'tests': ['tsvgm']}}, 04:55:42 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:55:42 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:55:42 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:55:42 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:55:42 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:42 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:55:42 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['damp', 'tps']}, 04:55:42 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'tests': ['damp', 'tps']}, 04:55:42 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['dromaeo_dom']}, 04:55:42 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:55:42 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['basic_compositor_video']}, 04:55:42 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:55:42 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'other-e10s': {'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['a11yr', 04:55:42 INFO - 'ts_paint', 04:55:42 INFO - 'tpaint', 04:55:42 INFO - 'sessionrestore', 04:55:42 INFO - 'sessionrestore_no_auto_restore', 04:55:42 INFO - 'tabpaint']}, 04:55:42 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['tsvgx', 04:55:42 INFO - 'tsvgr_opacity', 04:55:42 INFO - 'tart', 04:55:42 INFO - 'tscrollx', 04:55:42 INFO - 'cart']}, 04:55:42 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:55:42 INFO - 'tsvgr_opacity', 04:55:42 INFO - 'tart', 04:55:42 INFO - 'tscrollx', 04:55:42 INFO - 'cart']}, 04:55:42 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'talos_options': ['--disable-e10s'], 04:55:42 INFO - 'tests': ['tp5o']}, 04:55:42 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'tests': ['tp5o']}, 04:55:42 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'talos_options': ['--disable-e10s', 04:55:42 INFO - '--xperf_path', 04:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:42 INFO - 'tests': ['tp5n']}, 04:55:42 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:55:42 INFO - 'talos_options': ['--xperf_path', 04:55:42 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:55:42 INFO - 'tests': ['tp5n']}}, 04:55:42 INFO - 'talos.zip': {'path': '', 04:55:42 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:55:42 INFO - Running post-action listener: _resource_record_post_action 04:55:42 INFO - ##### 04:55:42 INFO - ##### Finished populate-webroot step (success) 04:55:42 INFO - ##### 04:55:42 INFO - ##### 04:55:42 INFO - ##### Running create-virtualenv step. 04:55:42 INFO - ##### 04:55:42 INFO - Running pre-action listener: _resource_record_pre_action 04:55:42 INFO - Running main action method: create_virtualenv 04:55:42 INFO - Creating virtualenv /builds/slave/test/build/venv 04:55:42 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 04:55:42 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:55:42 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:55:42 INFO - Using real prefix '/tools/python27' 04:55:42 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:55:43 INFO - Installing distribute.............................................................................................................................................................................................done. 04:55:46 INFO - Installing pip.................done. 04:55:46 INFO - Return code: 0 04:55:46 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:55:46 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')]} 04:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:46 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')]} 04:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:46 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55:46 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 04:55:46 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 04:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:46 INFO - 'HOME': '/Users/cltbld', 04:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:46 INFO - 'LOGNAME': 'cltbld', 04:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:46 INFO - 'MOZ_NO_REMOTE': '1', 04:55:46 INFO - 'NO_EM_RESTART': '1', 04:55:46 INFO - 'PAGER': '/bin/cat', 04:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:46 INFO - 'PWD': '/builds/slave/test', 04:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:46 INFO - 'SHELL': '/bin/bash', 04:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:55:46 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:46 INFO - 'USER': 'cltbld', 04:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:46 INFO - 'XPC_FLAGS': '0x0', 04:55:46 INFO - 'XPC_SERVICE_NAME': '0', 04:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:46 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 04:55:46 INFO - Cleaning up... 04:55:46 INFO - Return code: 0 04:55:46 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:55:46 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')]} 04:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:46 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')]} 04:55:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:46 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55:46 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 04:55:46 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 04:55:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:55:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:46 INFO - 'HOME': '/Users/cltbld', 04:55:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:46 INFO - 'LOGNAME': 'cltbld', 04:55:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:46 INFO - 'MOZ_NO_REMOTE': '1', 04:55:46 INFO - 'NO_EM_RESTART': '1', 04:55:46 INFO - 'PAGER': '/bin/cat', 04:55:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:46 INFO - 'PWD': '/builds/slave/test', 04:55:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:46 INFO - 'SHELL': '/bin/bash', 04:55:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:55:46 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:55:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:46 INFO - 'USER': 'cltbld', 04:55:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:46 INFO - 'XPC_FLAGS': '0x0', 04:55:46 INFO - 'XPC_SERVICE_NAME': '0', 04:55:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:46 INFO - Downloading/unpacking psutil>=3.1.1 04:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:55:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:49 INFO - Installing collected packages: psutil 04:55:49 INFO - Running setup.py install for psutil 04:55:50 INFO - building 'psutil._psutil_osx' extension 04:55:50 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 04:55:50 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 04:55:50 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 04:55:50 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 04:55:50 INFO - building 'psutil._psutil_posix' extension 04:55:50 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 04:55:50 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:55:50 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:55:50 INFO - ^ 04:55:50 INFO - 1 warning generated. 04:55:50 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 04:55:51 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:55:51 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:55:51 INFO - Successfully installed psutil 04:55:51 INFO - Cleaning up... 04:55:51 INFO - Return code: 0 04:55:51 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:55: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')]} 04:55:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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')]} 04:55:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:55: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 mozsystemmonitor==0.3 04:55:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:55:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:51 INFO - 'HOME': '/Users/cltbld', 04:55:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:51 INFO - 'LOGNAME': 'cltbld', 04:55:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:51 INFO - 'MOZ_NO_REMOTE': '1', 04:55:51 INFO - 'NO_EM_RESTART': '1', 04:55:51 INFO - 'PAGER': '/bin/cat', 04:55:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:51 INFO - 'PWD': '/builds/slave/test', 04:55:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:51 INFO - 'SHELL': '/bin/bash', 04:55:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:55:51 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:55:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:51 INFO - 'USER': 'cltbld', 04:55:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:51 INFO - 'XPC_FLAGS': '0x0', 04:55:51 INFO - 'XPC_SERVICE_NAME': '0', 04:55:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:51 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55:53 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:55:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:55:53 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:55:53 INFO - Installing collected packages: mozsystemmonitor 04:55:53 INFO - Running setup.py install for mozsystemmonitor 04:55:54 INFO - Successfully installed mozsystemmonitor 04:55:54 INFO - Cleaning up... 04:55:54 INFO - Return code: 0 04:55:54 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:55:54 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')]} 04:55:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:54 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')]} 04:55:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:54 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55:54 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 04:55:54 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 04:55:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:55:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:54 INFO - 'HOME': '/Users/cltbld', 04:55:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:54 INFO - 'LOGNAME': 'cltbld', 04:55:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:54 INFO - 'MOZ_NO_REMOTE': '1', 04:55:54 INFO - 'NO_EM_RESTART': '1', 04:55:54 INFO - 'PAGER': '/bin/cat', 04:55:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:54 INFO - 'PWD': '/builds/slave/test', 04:55:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:54 INFO - 'SHELL': '/bin/bash', 04:55:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:55:54 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:55:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:54 INFO - 'USER': 'cltbld', 04:55:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:54 INFO - 'XPC_FLAGS': '0x0', 04:55:54 INFO - 'XPC_SERVICE_NAME': '0', 04:55:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:54 INFO - Downloading/unpacking jsonschema==2.5.1 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55:56 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:55:56 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:55:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:55:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:55:57 INFO - Downloading functools32-3.2.3-2.tar.gz 04:55:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:55:57 INFO - warning: no files found matching '*.txt' 04:55:57 INFO - no previously-included directories found matching 'build' 04:55:57 INFO - no previously-included directories found matching 'dist' 04:55:57 INFO - no previously-included directories found matching '.git*' 04:55:57 INFO - Installing collected packages: jsonschema, functools32 04:55:57 INFO - Running setup.py install for functools32 04:55:57 INFO - warning: no files found matching '*.txt' 04:55:57 INFO - no previously-included directories found matching 'build' 04:55:57 INFO - no previously-included directories found matching 'dist' 04:55:57 INFO - no previously-included directories found matching '.git*' 04:55:57 INFO - Successfully installed jsonschema functools32 04:55:57 INFO - Cleaning up... 04:55:57 INFO - Return code: 0 04:55:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 04:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:55:57 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:55:57 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 04:55:57 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 04:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:55:57 INFO - 'HOME': '/Users/cltbld', 04:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:55:57 INFO - 'LOGNAME': 'cltbld', 04:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:55:57 INFO - 'MOZ_NO_REMOTE': '1', 04:55:57 INFO - 'NO_EM_RESTART': '1', 04:55:57 INFO - 'PAGER': '/bin/cat', 04:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:55:57 INFO - 'PWD': '/builds/slave/test', 04:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:55:57 INFO - 'SHELL': '/bin/bash', 04:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:55:57 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:55:57 INFO - 'USER': 'cltbld', 04:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:55:57 INFO - 'XPC_FLAGS': '0x0', 04:55:57 INFO - 'XPC_SERVICE_NAME': '0', 04:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:55:57 INFO - Downloading/unpacking blobuploader==1.2.4 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:55: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 04:56:00 INFO - Downloading blobuploader-1.2.4.tar.gz 04:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:56:00 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:56:00 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 04:56:00 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 04:56:00 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 04:56:00 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 04:56:00 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 04:56:00 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 04:56:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:56:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:56:01 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 04:56:01 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 04:56:01 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 04:56:01 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 04:56:01 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 04:56:01 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 04:56:01 INFO - Downloading docopt-0.6.1.tar.gz 04:56:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:56:01 INFO - Installing collected packages: blobuploader, requests, docopt 04:56:01 INFO - Running setup.py install for blobuploader 04:56:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:56:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:56:01 INFO - Running setup.py install for requests 04:56:02 INFO - Running setup.py install for docopt 04:56:02 INFO - Successfully installed blobuploader requests docopt 04:56:02 INFO - Cleaning up... 04:56:02 INFO - Return code: 0 04:56:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:02 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:56:02 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 04:56:02 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 04:56:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:56:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:02 INFO - 'HOME': '/Users/cltbld', 04:56:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:02 INFO - 'LOGNAME': 'cltbld', 04:56:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:02 INFO - 'MOZ_NO_REMOTE': '1', 04:56:02 INFO - 'NO_EM_RESTART': '1', 04:56:02 INFO - 'PAGER': '/bin/cat', 04:56:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:02 INFO - 'PWD': '/builds/slave/test', 04:56:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:02 INFO - 'SHELL': '/bin/bash', 04:56:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:56:02 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:56:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:02 INFO - 'USER': 'cltbld', 04:56:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:02 INFO - 'XPC_FLAGS': '0x0', 04:56:02 INFO - 'XPC_SERVICE_NAME': '0', 04:56:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:02 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-sq83Us-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TxMfJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-m0GRLU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-gYivep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-WNHMaQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-GPfB5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-q9yY85-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-z2xtxD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-5kNLYO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-8OueDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:03 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Cvy0Ek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-UIdvP7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-tlprww-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-dBNfn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-i0fyWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-FDPUa_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:04 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-xzcdZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:04 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:56:04 INFO - Running setup.py install for manifestparser 04:56:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:56:04 INFO - Running setup.py install for mozcrash 04:56:04 INFO - Running setup.py install for mozdebug 04:56:05 INFO - Running setup.py install for mozdevice 04:56:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozfile 04:56:05 INFO - Running setup.py install for mozhttpd 04:56:05 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozinfo 04:56:05 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozInstall 04:56:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:56:05 INFO - Running setup.py install for mozleak 04:56:05 INFO - Running setup.py install for mozlog 04:56:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for moznetwork 04:56:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozprocess 04:56:06 INFO - Running setup.py install for mozprofile 04:56:06 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozrunner 04:56:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:56:06 INFO - Running setup.py install for mozscreenshot 04:56:06 INFO - Running setup.py install for moztest 04:56:07 INFO - Running setup.py install for mozversion 04:56:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:56:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:56:07 INFO - Cleaning up... 04:56:07 INFO - Return code: 0 04:56:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:07 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:56:07 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 04:56:07 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 04:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:07 INFO - 'HOME': '/Users/cltbld', 04:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:07 INFO - 'LOGNAME': 'cltbld', 04:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:07 INFO - 'MOZ_NO_REMOTE': '1', 04:56:07 INFO - 'NO_EM_RESTART': '1', 04:56:07 INFO - 'PAGER': '/bin/cat', 04:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:07 INFO - 'PWD': '/builds/slave/test', 04:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:07 INFO - 'SHELL': '/bin/bash', 04:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:56:07 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:07 INFO - 'USER': 'cltbld', 04:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:07 INFO - 'XPC_FLAGS': '0x0', 04:56:07 INFO - 'XPC_SERVICE_NAME': '0', 04:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:56:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Ga8TeC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:56:07 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)) 04:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:56:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-3EPi_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:56:07 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)) 04:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:56:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-MaI_Y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:56:07 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)) 04:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:56:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-TwFVXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:56:07 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)) 04:56:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:56:07 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-_e1jZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-7Q2oBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-ZKNps8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-45ZiGl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-RbJEEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-dHaRkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-MzJu0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Ss0pky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-Fq67qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:56:08 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)) 04:56:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:56:08 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-tjY31_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:56:09 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)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:09 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-kpytkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:56:09 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)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:56:09 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-SJ6lJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:56:09 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)) 04:56:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:56:09 INFO - Running setup.py (path:/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/pip-wXhB_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:56:09 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)) 04:56:09 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)) 04:56:09 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)) 04:56:09 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)) 04:56:09 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)) 04:56:09 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)) 04:56:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:56:11 INFO - Downloading blessings-1.6.tar.gz 04:56:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:56:11 INFO - Installing collected packages: blessings 04:56:11 INFO - Running setup.py install for blessings 04:56:12 INFO - Successfully installed blessings 04:56:12 INFO - Cleaning up... 04:56:12 INFO - Return code: 0 04:56:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:56:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:12 INFO - Reading from file tmpfile_stdout 04:56:12 INFO - Current package versions: 04:56:12 INFO - blessings == 1.6 04:56:12 INFO - blobuploader == 1.2.4 04:56:12 INFO - docopt == 0.6.1 04:56:12 INFO - functools32 == 3.2.3-2 04:56:12 INFO - jsonschema == 2.5.1 04:56:12 INFO - manifestparser == 1.1 04:56:12 INFO - mozInstall == 1.12 04:56:12 INFO - mozcrash == 0.17 04:56:12 INFO - mozdebug == 0.1 04:56:12 INFO - mozdevice == 0.48 04:56:12 INFO - mozfile == 1.2 04:56:12 INFO - mozhttpd == 0.7 04:56:12 INFO - mozinfo == 0.9 04:56:12 INFO - mozleak == 0.1 04:56:12 INFO - mozlog == 3.2 04:56:12 INFO - moznetwork == 0.27 04:56:12 INFO - mozprocess == 0.23 04:56:12 INFO - mozprofile == 0.28 04:56:12 INFO - mozrunner == 6.12 04:56:12 INFO - mozscreenshot == 0.1 04:56:12 INFO - mozsystemmonitor == 0.3 04:56:12 INFO - moztest == 0.7 04:56:12 INFO - mozversion == 1.4 04:56:12 INFO - psutil == 3.1.1 04:56:12 INFO - requests == 1.2.3 04:56:12 INFO - wsgiref == 0.1.2 04:56:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:56:12 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')]} 04:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:12 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')]} 04:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:12 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:56:12 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 04:56:12 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 04:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:12 INFO - 'HOME': '/Users/cltbld', 04:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:12 INFO - 'LOGNAME': 'cltbld', 04:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:12 INFO - 'MOZ_NO_REMOTE': '1', 04:56:12 INFO - 'NO_EM_RESTART': '1', 04:56:12 INFO - 'PAGER': '/bin/cat', 04:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:12 INFO - 'PWD': '/builds/slave/test', 04:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:12 INFO - 'SHELL': '/bin/bash', 04:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:56:12 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:12 INFO - 'USER': 'cltbld', 04:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:12 INFO - 'XPC_FLAGS': '0x0', 04:56:12 INFO - 'XPC_SERVICE_NAME': '0', 04:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 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)) 04:56:12 INFO - Cleaning up... 04:56:12 INFO - Return code: 0 04:56:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:56:12 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')]} 04:56:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:56:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:12 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')]} 04:56:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:56:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:56:12 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 0x104279a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10430e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f88f0cb0920>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, '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.nsrZWYcFew/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.EzV8HcbIXH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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 04:56:12 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 04:56:12 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 04:56:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:56:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:12 INFO - 'HOME': '/Users/cltbld', 04:56:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:12 INFO - 'LOGNAME': 'cltbld', 04:56:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:12 INFO - 'MOZ_NO_REMOTE': '1', 04:56:12 INFO - 'NO_EM_RESTART': '1', 04:56:12 INFO - 'PAGER': '/bin/cat', 04:56:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:12 INFO - 'PWD': '/builds/slave/test', 04:56:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:12 INFO - 'SHELL': '/bin/bash', 04:56:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:56:12 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:56:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:12 INFO - 'USER': 'cltbld', 04:56:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:12 INFO - 'XPC_FLAGS': '0x0', 04:56:12 INFO - 'XPC_SERVICE_NAME': '0', 04:56:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:56:13 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:56:13 INFO - Cleaning up... 04:56:13 INFO - Return code: 0 04:56:13 INFO - Running post-action listener: _resource_record_post_action 04:56:13 INFO - Running post-action listener: _start_resource_monitoring 04:56:13 INFO - Starting resource monitoring. 04:56:13 INFO - ##### 04:56:13 INFO - ##### Finished create-virtualenv step (success) 04:56:13 INFO - ##### 04:56:13 INFO - ##### 04:56:13 INFO - ##### Running install step. 04:56:13 INFO - ##### 04:56:13 INFO - Running pre-action listener: _resource_record_pre_action 04:56:13 INFO - Running main action method: install 04:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:56:13 INFO - Reading from file tmpfile_stdout 04:56:13 INFO - Detecting whether we're running mozinstall >=1.0... 04:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:56:13 INFO - Reading from file tmpfile_stdout 04:56:13 INFO - Output received: 04:56:13 INFO - Usage: mozinstall [options] installer 04:56:13 INFO - Options: 04:56:13 INFO - -h, --help show this help message and exit 04:56:13 INFO - -d DEST, --destination=DEST 04:56:13 INFO - Directory to install application into. [default: 04:56:13 INFO - "/builds/slave/test"] 04:56:13 INFO - --app=APP Application being installed. [default: firefox] 04:56:13 INFO - mkdir: /builds/slave/test/build/application 04:56:13 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'] 04:56:13 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 04:56:47 INFO - Reading from file tmpfile_stdout 04:56:47 INFO - Output received: 04:56:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:56:47 INFO - Running post-action listener: _resource_record_post_action 04:56:47 INFO - ##### 04:56:47 INFO - ##### Finished install step (success) 04:56:47 INFO - ##### 04:56:47 INFO - ##### 04:56:47 INFO - ##### Running run-tests step. 04:56:47 INFO - ##### 04:56:47 INFO - Running pre-action listener: _resource_record_pre_action 04:56:47 INFO - Running main action method: run_tests 04:56:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:56:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:56:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:56:47 INFO - 2016-07-14 04:56:47.647 screenresolution[1640:7590] starting screenresolution argv=screenresolution get 04:56:47 INFO - 2016-07-14 04:56:47.650 screenresolution[1640:7590] Display 0: 1600x1200x32@60 04:56:47 INFO - 2016-07-14 04:56:47.655 screenresolution[1641:7592] starting screenresolution argv=screenresolution list 04:56:47 INFO - Available Modes on Display 0 04:56:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:56:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:56:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:56:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:56:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:56:47 INFO - Intel Iris: 04:56:47 INFO - Chipset Model: Intel Iris 04:56:47 INFO - Type: GPU 04:56:47 INFO - Bus: Built-In 04:56:47 INFO - VRAM (Dynamic, Max): 1536 MB 04:56:47 INFO - Vendor: Intel (0x8086) 04:56:47 INFO - Device ID: 0x0a2e 04:56:47 INFO - Revision ID: 0x0009 04:56:47 INFO - Displays: 04:56:47 INFO - Display: 04:56:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:56:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:56:47 INFO - Display Serial Number: 1600x1200 60 04:56:47 INFO - Main Display: Yes 04:56:47 INFO - Mirror: Off 04:56:47 INFO - Online: Yes 04:56:47 INFO - Rotation: Supported 04:56:47 INFO - Return code: 0 04:56:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:56:47 INFO - Python 2.7.3 04:56:47 INFO - Return code: 0 04:56:47 INFO - grabbing minidump binary from tooltool 04:56:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:56:47 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 0x1041ddd30>, '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 0x103f42db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1041d5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:56:47 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 04:56:47 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 04:56:47 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 04:56:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:56:48 INFO - Return code: 0 04:56:48 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:56:48 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:56:48 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:56:48 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:56:48 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:56:48 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/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 04:56:48 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/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0005 --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 04:56:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nsrZWYcFew/Render', 04:56:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:56:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:56:48 INFO - 'HOME': '/Users/cltbld', 04:56:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:56:48 INFO - 'LOGNAME': 'cltbld', 04:56:48 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:56:48 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:56:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:56:48 INFO - 'MOZ_NO_REMOTE': '1', 04:56:48 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:56:48 INFO - 'NO_EM_RESTART': '1', 04:56:48 INFO - 'PAGER': '/bin/cat', 04:56:48 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:56:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:56:48 INFO - 'PWD': '/builds/slave/test', 04:56:48 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:56:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:56:48 INFO - 'SHELL': '/bin/bash', 04:56:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EzV8HcbIXH/Listeners', 04:56:48 INFO - 'TMPDIR': '/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/', 04:56:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:56:48 INFO - 'USER': 'cltbld', 04:56:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:56:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:56:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:56:48 INFO - 'XPC_FLAGS': '0x0', 04:56:48 INFO - 'XPC_SERVICE_NAME': '0', 04:56:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:56:48 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/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0005', '--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 04:56:48 INFO - mozversion application_buildid: 20160714024443 04:56:48 INFO - mozversion application_changeset: cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 04:56:48 INFO - mozversion application_display_name: Nightly 04:56:48 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:56:48 INFO - mozversion application_name: Firefox 04:56:48 INFO - mozversion application_remotingname: firefox 04:56:48 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:56:48 INFO - mozversion application_vendor: Mozilla 04:56:48 INFO - mozversion application_version: 50.0a1 04:56:48 INFO - mozversion platform_buildid: 20160714024443 04:56:48 INFO - mozversion platform_changeset: cd9da00ffcc3f37ae32b9401dfbeaa892e314ab3 04:56:48 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:56:48 INFO - mozversion platform_version: 50.0a1 04:56:48 INFO - using testdate: 1468497408 04:56:48 INFO - actual date: 1468497408 04:56:48 INFO - starting webserver on 'localhost:49270' 04:56:48 INFO - SUITE-START | Running 2 tests 04:56:48 INFO - TEST-START | dromaeo_css 04:56:48 INFO - operating with platform_type : mac_ 04:56:48 INFO - Initialising browser for dromaeo_css test... 04:56:48 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp6jtLAP/profile http://localhost:49270/getInfo.html) 04:56:50 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 04:56:50 INFO - PROCESS | 1651 | colorDepth:24 04:56:50 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 04:56:50 INFO - PROCESS | 1651 | __metrics 04:56:50 INFO - PROCESS | 1651 | 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" 04:56:50 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:56:51 INFO - TEST-INFO | 1651: exit 0 04:56:51 INFO - Browser initialized. 04:56:51 INFO - Running cycle 1/1 for dromaeo_css test... 04:56:51 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmp6jtLAP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:57:03 INFO - PROCESS | 1653 | RSS: Main: 243707904 04:57:03 INFO - PROCESS | 1653 | 04:57:54 INFO - PROCESS | 1653 | 1468497474230 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:00:06 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 05:00:07 INFO - PROCESS | 1653 | RSS: Main: 391159808 05:00:07 INFO - PROCESS | 1653 | 05:03:10 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 05:03:11 INFO - PROCESS | 1653 | RSS: Main: 384217088 05:03:11 INFO - PROCESS | 1653 | 05:06:13 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 05:06:13 INFO - PROCESS | 1653 | RSS: Main: 378015744 05:06:13 INFO - PROCESS | 1653 | 05:09:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 05:09:17 INFO - PROCESS | 1653 | RSS: Main: 373411840 05:09:17 INFO - PROCESS | 1653 | 05:12:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 05:12:20 INFO - PROCESS | 1653 | RSS: Main: 342257664 05:12:20 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 05:15:24 INFO - PROCESS | 1653 | RSS: Main: 395358208 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | __start_tp_report 05:15:24 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 05:15:24 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 05:15:24 INFO - PROCESS | 1653 | |i|pagename|runs| 05:15:24 INFO - PROCESS | 1653 | |0;dojo.html;3324.1689577605594;3331.7667763256572;3332.9333413331733;3343.882964096257;3398.235397035667;5597;5653.858653533659;5718.857028574288;5745.052066409043;5781.9999999999945;5718.6568805871675;5743.885122297552;5746.0806270996645;5747.770089196427;5758.683272420015;5184.429712731606;5225.242339860718;5258.579313654917;5261.526462618363;5324.1215199492035;5285.867853303679;5303.124984377592;5307.495787900162;5309.469053094698;5317.734113294339;5206.114960456713;5216.165413533835;5231.372235331765;5261.105612045962;5310.867228319304;5296.232046353276;5341.278927344798;5345.278387417688;5359.624826262163;5370.865728356784;5141.794328226866;5158.9484105158845;5184.988927159206;5249.7112658804135;5288.524032837025;5696.23100881384;5696.658200507983;5724.627899186581;5747.540196784247;5753.539716822645;5562.3047119110115;6275.780347687854;6286.937130628681;6289.622622642656;6294.181756371689;5269.947300526984;6206.782762603332;6253.96873015632;6300.779472718478;6326.49388048955;5854.824355269348;5876.324222714443;5892.057270836688;5897.941020589739;5901.380355062743;6261.217347831521;6267.71795269214;6280.717367718464;6284.308726040132;6294.685265736694;5966.880662386815;6039.54703397247;6105.277797093398;6107.992181290127;6158.353372895871;6239.064140378889;6248.4688801452385;6264.2796078451565;6268.49852011838;6268.717907694165;6170.352113028157;6200.627962322275;6204.81385558434;6212.0371342441995;6233.96883015582;6077.483413909778;6129.938700612936;6131.662758548234;6139.9828168395225;6229.968850155811;4899.755012249373;4937.23472861707;4942.5304596063315;4946.505349465025;4949.604031677457;4442.933355999663;4470.128324976598;4499.752513611783;4513.458384993822;4577.473590537064;5785.88428231433;5799.217105690678;5806.44838740319;5806.82579522615;5821.708914554255;6125.601835880653;6204.348543402968;6213.596116252429;6217.595856269328;6232.470240029557;4652.813887444464;5014.7994080236385;5490.945090549123;5617.8033768817895;5676.7794924090695;5365.6244062916;5377.435369286247;5381.569474442035;5386.973065134727;5390.272313237662;6200.224971878515;6228.128062071313;6253.96873015632;6338.778142764981;6343.524235682342;6311.084892690626;6319.46284565817;6321.33625969285;6321.747130114744;6334.239891213084;6252.374762523711;6345.587536810092;6345.746170153327;6350.714217860115;6368;5145.125328694056;5218.425973142876;6133.824358647991;6218.378162183745;6236.407541283571;5461.590380721383;5502.889942201054;6253.812385628438;6274.686265686789;6284.591501552567;5966.463018328372;6028.854517641809;6126.448619624255;6134.877302454015;6150.7847225346895;6050.878982420415;6103.5195042504665;6107.053406721965;6141.017437210025;6156.815295541141;5936.554758393224;5969.462748352669;5975.312839023568;5988.790392336247;6026.608270462406;5939.940600593939;5969.76120955157;6096.498867793138;6103.51950425029;6109.633312616532;5951.761929522944;6010.729517171825;6014.879702405839;6027.306859711014;6053.364396738455 05:15:24 INFO - PROCESS | 1653 | |1;ext.html;524.9081410753118;532.100749732951;537.4249552978313;542.5903442900609;560.2101037537074;30448.086557403287;31062.37875242494;42206.78896605513;43528;43637.7818110909;44757.77621111898;45114;45125.54874451255;45153.77423112888;45223.77388113063;9624.518774061287;9786.657466988658;9793.510324483746;9800.705978820646;9806.509674516281;8800.911990880073;9016.188543031127;11858.407079646026;12042.277463352184;12044.337561434118;12145.878541214563;12227.449764760608;12230.266184028942;12238.020958323357;12282.14025018249;8830.69092581763;11790.823137652942;12097.637070887888;12172.026237900947;12172.2696638202;11725.882741172565;12098.81851772224;12103;12114.21257618252;12152.392380381034;42337.364939525934;42680;42683.57316426858;42754.78622606898;42816.57183428157;3528.9823550882084;3595.964040359589;4021.416894550274;4041.676665866753;4215.578442155791;32394.514082288784;33668.98993030213;33857.83071084654;33933.15167120817;34052.48921266183;3226.2579606690356;3400.7109395701395;3404.6254911959663;3411.624721280657;3437.003269051972;500110.99999999633;502418;503541;505421.4728926332;505655;64435.355646442935;64627.35372646307;64965.675171623836;64979.02531462032;64979.35020649733;67887;67901;67991;68054;68063.31936680668;16354.591135221715;16576.502704918872;16586.834131658772;16594.917025415038;16614.501564953072;11605.825912611317;11853.288802671696;12630.431630576646;12770.233785972685;12857.807132893015;16880.071596062335;17812;17842.929424234586;18113.637727245645;18179.818201818078;7358.96320518394;7386.335229829341;7485.625718714043;7542.924570754222;7564.524917641117;781.6638845296463;977.0815433492494;1010.3483253301579;1119.1103073056938;1190.9583164589199;28783.56824647632;31373.52939705906;31408.528872066938;31988.840055799574;32247.51628725571;1098.368438148068;1100.801855665988;1113.154002957758;1128.424503503203;1129.6611016694958;16032.638549042651;16302.184890755414;16342.019478831307;16365.018098914103;16696.74954875678;660826;733147;743160;743836;976860;62703.68648156821;63290;63761.36238637554;63835;63979.68010160013;1297.5653156192593;1331.121459836542;1421.985780142206;1437.4537675683173;1444.978325325121;31357;31853.522197166596;32319.01036495707;33515.66484335125;33939.830300849324;48653.83750056235;49540.50459495359;59155.70422147862;60057;60104.69947650234;626808;634393;634770;635809;637801;22352.77647223507;24407;24821.255362339158;26877.865610672605;27235.455290894468;35767.64232357643;41628.79185604052;41959.58040419557;41996;42110;40660.59339406568;41441;41746.791266043474;43002.35496467618;43257.13485730331;40996.79501602592;41584;42004.54991213757;42405.787971059945;42975.785121074194;39595.80202098971;39624.80187599044;40534.986625334604;40620;40670.59329406668;35051.12372190614;35488.7578934736;35551.82224088863;36117.097072572345;36150.819245904655;26006.219813405627;26082.86958565195;26617.669116543708;26625.068122615616;26992 05:15:24 INFO - PROCESS | 1653 | |2;jquery.html;347.4128722459045;355.53780085888343;387.2042951734187;398.6491887139317;404.69889305881605;19951.501212469695;20069.397918062445;20135.697964530525;20596.176152953863;20616.866072366032;20255.49361265969;20635.968201589934;20902.268420605284;21484.57030859382;21615.783842161574;5786.537077033849;5919.141724449953;6111.816645500642;6837;6850.554713943603;6503;6729.394354508093;6738.258791532928;6817.216020157671;6822.590644561342;6674.399304062634;6712.328767123297;6724.83187920301;6727.260001399842;6735.225449073346;6430.999999999976;6665.233498147678;6668.233153187397;6699.196096468406;6726.461883049345;5766.163906233573;5786.971065144648;5793.710314484259;5800.680962547058;5804.62269952456;26245.868770656216;26430.86784566065;26790.196294111207;27028.97282703805;27096.45807083847;2254.4701995031155;2391.5814732421754;2591.5335239656856;2716.755492005729;2890.8410037447934;14967.550973470814;15870.20648967547;16141.757873631905;16208.270627821776;16491.257893394704;1891.215145714527;1916.8658193926292;1943.893085880276;1956.2761778142037;2016.41523958052;95173.5723964141;110029.44985275023;112588;115412.42293788561;118312.81687183103;9034.819303614022;10552.683419497427;11167.776644467065;11171.329720216812;11330.94334528322;13730.794038089436;13810.240436775874;13834.65413364674;13839.654008649864;13840.653983650489;8046.994125734283;8213.178682131858;8222.506649601042;8304.70933517324;8305.667773289122;6767.796966091025;8032.799180020546;8034.437589368805;8164.7550573482895;8210.261076503144;6677.232118306457;6789.762358317435;6803.5032292895485;6818.318168183241;6819.897701534481;1838.8712790104564;1865.272543707955;1876.035863335926;1877.7652793400825;1881.5390229393886;906.9501177435172;937.7561833923229;992.717075633441;1037.9429131397694;1050.0497050169608;22547.661785073236;22860.7713922862;22879.656805147937;22934.853257337065;23248.883755581453;939.7509659940122;943.42451104826;988.3921388346115;1059.2320567588438;1062.9787404251872;11406.942965285121;11904.702382440335;12094.758104837854;12158.878411215952;12172.391380430943;550320;556352;561336;561947;567773;110097;111780.88219117705;117803.82196177928;118281.40859295649;118858.8114118865;937.4141161773891;938.544805769215;953.9761505962407;980.9656662016795;1003.5684655598017;19236.71144932855;19238.61522769565;19318.8068119317;19482.805171948097;19487.415377538695;42619.786901065294;49643;55900.16149757838;56725.149122764014;57218.42781572131;66765.66617166882;68083.63832723416;72571.63714181395;79037;84717;5756.712164391847;8014.078380986261;8097.8380432392205;8145.551994640352;8301.58492075406;8566.571671416279;8844.955775221082;8928.419652722552;9352.148621502469;9384.577694003923;7664.923350766421;8115.675372985014;8174.591270436574;8204.425690201628;8376.03675577292;7740.6903723852665;7924.286814186751;7976.042874855055;8024.759257222293;8030.237127473114;8068;8095.676172953016;8142.837143256983;8342.29090527312;8358.83282334362;11577.594784182513;12208.53497580296;12297.385130743249;12520.499180032697;12639.431225595054;10422.791544169226;10891.128709703364;11318.773624527628;11621.767564648491;11637.476313565741 05:15:24 INFO - PROCESS | 1653 | |3;mootools.html;1381.4128995177048;1416.8016477693122;1567.1694002178845;1708.8205738397469;1874.512626717054;7854.1360450350485;8368.456050356723;8398.20217079378;8672.956635216815;8948.105189481048;8729.127087291268;8820.60307286172;8898.243649289801;8900.1544853239;8905.465672059665;2349.659299401586;2379.357573455166;2385.451346190377;2386.7493913139133;2389.199618127929;2122.8089471947524;2336.8714720690355;2408.9036438542526;2413.722421921484;2417.4319035026756;2202.119152339061;2225.9220927267547;2354.799842013422;2386.451116243265;2391.8445301055376;2092.089940875724;2377.3937645900273;2388.092524840567;2388.4625959159225;2389.319044072431;2148.6669566217106;2170.1536400803698;2174.5759576882515;2175.0212404418075;2301.9884900575557;8688.869666955;8772.342074344386;8896.644134234622;8916.643334266622;8917.0637083106;5197.48025197481;5355.437679043684;5356.758945847408;5357.223202635635;5362.35651721792;2943.7792165587452;3571.339302229096;3687.7049836013052;3710.3321402147603;3723.385641369198;1103.0899507905929;1110.1451882050776;1123.9831402528969;1125.4147843121614;1786.490850107713;11346.262492937933;11724.941375293069;11751.236169648946;11763.117766167572;11770.646880593597;4716.957711232861;4731.574158325767;4820.638452116105;4821.445533763592;4904.558589726942;10143.898561014295;10164.288499804943;10168.847467287997;10176.898231017594;10182.592696292213;5373.462653734595;5375.414252795435;5379.946200538023;5392.7057506198225;5394.838154855361;4882.5605695487575;4908.558229759339;4922.483139270396;4928.802847886116;4937.604991600663;3243.9026829195163;3244.4322243607276;3898.5126859142606;3983.203359328146;4007.3988901664984;1202.903767698582;1206.4872429217548;1217.792975194219;1223.0521345956777;1229.2931564350533;932.1098351074708;933.6638810028385;936.8688383626297;941.5621735892706;985.3939827006483;5921.437463440966;5988.9101663475085;6069.848253793691;6147.108669242934;6171.352008039181;435.45568039950064;436.9868903932887;443.82690750607287;447.8522087711113;465.2393336894202;3965.031385276013;4054.412110243998;4104.938425923614;4121.855735049259;4128.2156390285745;10315.896841031643;10828.566857325619;10831.241813073168;10849.837252441219;10896.509657065293;10394.532246048946;10929.453527323602;10930.01629853317;10931.672049838358;10936.835947460795;8163.918360816316;8210.261076503144;8407.117252688502;8416.326693864476;8443.83112337762;6048.304444988707;6070.969645151746;6171.228596425447;6199.132121502903;6207.379262073757;9905.900940990497;10192.643257485952;10224.335418197794;10240.334378265388;10263.948680256552;9321.86017209756;9835.60657573689;10227.488625568543;10256.846147307648;10426.530806113895;7081.716731330689;8038.39712021589;8312.002559692875;8317.085120636604;8330.291925186428;8206.507609543447;8374.87437688423;8406.873896891673;8491.74524764258;8514.829703406022;7373.29953654402;7506.737264195727;7563.054618172729;7588.81027974305;7612.80967975805;7446.73936412223;7449.366803821736;7493.875918612252;7499.362554182955;7536.811579710332;6900.137482814648;7082.433405327664;7089.7873063808165;7096.254893236136;7187.496875218684;5145.4340022598235;5149.995750828552;5155.226715992631;5166.612504062286;5304.708241046779;5214;5225.216217567243;5242.9475705243985;5262.710550919583;5441.102218133963 05:15:24 INFO - PROCESS | 1653 | |4;prototype.html;299.0356101572429;302.9924251893703;311.8970739655914;315.3550988229071;316.6516831485366;4332.5954095477;5025.3718285214345;5262.342207224097;5285.788568457262;5297.576193904488;4014.5583985761614;4750.524947505248;5007.649464537484;5199.246109314147;5284.867878303045;4031.657309128728;4065.6340929316357;4067.4712287402594;4093.2427500912354;4121.52602450719;3669.9633003669887;3762.7930463824478;4084.816183271745;4093.6111069448502;4118.629323360897;3547.0245682437408;3742.3637981543066;3826.253880493305;4006.736194050367;4035.9596404036024;3290.3090351026194;3858.498395208633;3991.640752332275;4006.6794656427414;4024.1750441159493;2732.8223665461683;2972.5689774982725;3078.6909817636774;3535.3636345457808;3538.929221415583;4341.914521369657;4447.2439685253585;4552.635789136861;4992.3114820178125;5092.719900405476;3784.5836957934607;3816.053812555589;3857.8842634721004;3993.7603743775467;4577.56513131252;4507.661925355578;5417.214503896952;5478.972605136989;5487.670739755627;5494.395616482184;3196.5524826524306;3732.693557254966;3872.244912242086;3872.7925082702873;3888.4556162137324;4750.596199323062;4798.712077275374;4842.394700662418;5785.47930686236;5786.826395208151;3291.6214635316765;3868.9613103869165;3899.7855117968784;3904.7461914976;3906.257811015898;3726.720495962814;3967.781772002568;3984.541777695602;4010.3382941814857;4024.3561030235605;4219.578042195817;4533.297338912474;4606.700564463299;4613.077384523109;4638.7216766993415;5276.551493123127;5472.097103977879;5537.8061767837935;5549.611527193058;5556.833295001156;1247.25788156047;1247.8003519436847;1309.7445998030482;1333.3466601365262;1334.5853395400907;862.6635612111281;892.3530440430636;897.5736525150501;898.4429653614801;899.4288626721948;4137.9861933825805;5127.589792816566;5153.433122356612;5163.845084647466;5222.477752224747;5471.343438787291;5627.887442251132;5659.6321239119325;5661.377248502661;5679;5015.022070696179;5102.36220472441;5122.846314610568;5123.820666276737;5142.948570514247;2474.369035895871;2645.894164233409;2916.7895323440957;2951.5094877086935;2974.330775575478;4545.409096817392;5029.84910452687;5201;5749.741261643236;5808.273965754281;4986.950130498722;5329.934013197376;5752.913706294409;5756.769729210785;5759.149221135481;4391.451068616423;5442.972785136049;5461.306994831615;5747.885042299047;5790.120619185156;4437.445319335083;5279;5363.436839131835;5559.527440167631;5664.801731939362;5216.895662086813;5439.755211015435;5510.366307874646;5551.444855514578;5567.5545956322585;5272.657277277118;5741.569382296261;5761.366249712444;5785.450382213683;5799.594028417971;3176.888808891678;3538.5753709553987;3841.117852252432;3846.5576458707606;3866.284737323604;4670.9766451167525;5090.41460232078;5154.5103215194495;5391.865203369947;5659.603827732019;4875.536824001714;5278.524932755917;5563.944360556343;5598.188262701804;5615.781766202245;4857.0528746894015;5268.9736551317;5530.474604912527;5535.391106978149;5558.666480011212;4481.91036179268;5229.058769421541;5279.812042290454;5374.811881584125;5513.5389121881935;5628.268325117709;5629.88740225185;5641.943580564306;5659.236003139524;5659.320881494247;5152.690838549699;5464.26232458613;5474.863128421821;5501.477359650827;5518.558515318685 05:15:24 INFO - PROCESS | 1653 | |5;yui.html;746.0525133080987;748.775367389783;802.6548584108834;826.6528058215549;891.6879092317689;11497.770044599103;13721.725565488698;16054.277557509911;16056.197190140505;16075;15322.233888305596;15885.523434296989;15895.205239738025;16011.03933763975;16011.839881601181;715.0775499605529;736.8489459660757;871.6469829718958;884.6594061286387;898.5417437107089;779.7543773711291;902.8690839828221;912.9726108216764;916.5005072235648;935.4621092871625;962.2975228083503;970.9077637624414;971.0677749360623;972.3776782858976;973.1728031173517;768.1051574915217;933.225422898999;946.8721722567434;946.8863736351648;951.5242378810594;649.2014821769203;659.3110199841177;714.3320994869813;714.7284032067781;715.9713611455536;15204.771928421083;15237.771433428508;15249.161296128708;15262.23688815566;15263.160526171056;14318.785218221736;14473.276336183255;14521.491747788883;14525.78211326831;14535;12942.805857912139;13327.466901323935;13404.530841420503;13549.983751218699;13595.456181752817;4586.839460618862;4672.2057250267535;4765.2375619900995;4856.198727210006;4896.730679812609;107244;108226;109220.4538977308;109815;109816.80366392707;11040.171987101;11208.943955280334;11297.71755706114;11452.427378631202;11908.523659053713;22539.084177845067;23218.53562928732;23446.17938372183;23626.05495780184;23657.763422365555;7321.926780732231;8296.936692306603;8346;8468.957655211685;8493.490390576586;4544.409226800495;4547;4597.218472859622;4597.862064138081;4743.810247590058;3611;3707.5921648618632;3742.73800833939;3747.3816820224356;3750.2874453854224;595.3064679648162;665.7650059140225;696.3837004251172;703.1035429827011;705.8059033765653;636.4971672378769;684.6576711644178;701.8140192848899;708.9436739258467;715.8031541326072;9659.227261819038;9931.602735890629;9942.304038717364;9942.751431214278;10004.599816007423;677.8916471568922;696.5263620737946;701.8631366883409;715.141829804237;717.275551692794;4053.777042262645;4730.124926888537;4777.593904518086;4785.569298763058;4866.367372241586;143212.28393857964;146949;157958.2102089482;160193;163013;136851;136853.3157334227;137973.3101334487;140002;141335;73965.26034739584;107917;109801.90198097916;113178.30232546518;116885.24672129925;8783.560821959005;9233.353665243638;9240.95379523098;9397.85903211466;9447.24422046221;64745;65520.01719974109;66018;66947.99578006237;67092.66453667701;91444.5427772857;96009;99265;100686.49656751669;101866;12172.75654486923;12374.81437778452;12461.439235234617;12488.812667809809;13188.604341869759;13334.466621335037;13806.93096534511;13965.650858728613;14008.019438639198;14257.857421425653;9644.565994530123;10879.891201087888;13500.5274815381;13869.79195312051;14495;11552.537898483966;14057.507987220399;14269.286535672967;14445.422183112978;14544.49094281695;11463.942680286546;13786.862131378557;13998.720025599634;14103.153810771386;14551.708965820835;11255.380954047607;11595.853177943107;11628.825567616323;11641.941790290994;11785.175037747618;10716.948124881892;11318.906002389254;11523.884761152281;11746;12018.218815776947 05:15:24 INFO - PROCESS | 1653 | __end_tp_report 05:15:24 INFO - PROCESS | 1653 | __start_cc_report 05:15:24 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1392 05:15:24 INFO - PROCESS | 1653 | __end_cc_report 05:15:24 INFO - PROCESS | 1653 | __startTimestamp1468498524313__endTimestamp 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | ------- Summary: start ------- 05:15:24 INFO - PROCESS | 1653 | Number of tests: 6 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5725.04 Median:5939.94 stddev:635.74 (10.7%) stddev-sans-first:609.15 05:15:24 INFO - PROCESS | 1653 | Values: 3324.2 3331.8 3332.9 3343.9 3398.2 5597.0 5653.9 5718.9 5745.1 5782.0 5718.7 5743.9 5746.1 5747.8 5758.7 5184.4 5225.2 5258.6 5261.5 5324.1 5285.9 5303.1 5307.5 5309.5 5317.7 5206.1 5216.2 5231.4 5261.1 5310.9 5296.2 5341.3 5345.3 5359.6 5370.9 5141.8 5158.9 5185.0 5249.7 5288.5 5696.2 5696.7 5724.6 5747.5 5753.5 5562.3 6275.8 6286.9 6289.6 6294.2 5269.9 6206.8 6254.0 6300.8 6326.5 5854.8 5876.3 5892.1 5897.9 5901.4 6261.2 6267.7 6280.7 6284.3 6294.7 5966.9 6039.5 6105.3 6108.0 6158.4 6239.1 6248.5 6264.3 6268.5 6268.7 6170.4 6200.6 6204.8 6212.0 6234.0 6077.5 6129.9 6131.7 6140.0 6230.0 4899.8 4937.2 4942.5 4946.5 4949.6 4442.9 4470.1 4499.8 4513.5 4577.5 5785.9 5799.2 5806.4 5806.8 5821.7 6125.6 6204.3 6213.6 6217.6 6232.5 4652.8 5014.8 5490.9 5617.8 5676.8 5365.6 5377.4 5381.6 5387.0 5390.3 6200.2 6228.1 6254.0 6338.8 6343.5 6311.1 6319.5 6321.3 6321.7 6334.2 6252.4 6345.6 6345.7 6350.7 6368.0 5145.1 5218.4 6133.8 6218.4 6236.4 5461.6 5502.9 6253.8 6274.7 6284.6 5966.5 6028.9 6126.4 6134.9 6150.8 6050.9 6103.5 6107.1 6141.0 6156.8 5936.6 5969.5 5975.3 5988.8 6026.6 5939.9 5969.8 6096.5 6103.5 6109.6 5951.8 6010.7 6014.9 6027.3 6053.4 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:77058.75 Median:39610.30 stddev:174007.24 (439.3%) stddev-sans-first:174400.49 05:15:24 INFO - PROCESS | 1653 | Values: 524.9 532.1 537.4 542.6 560.2 30448.1 31062.4 42206.8 43528.0 43637.8 44757.8 45114.0 45125.5 45153.8 45223.8 9624.5 9786.7 9793.5 9800.7 9806.5 8800.9 9016.2 11858.4 12042.3 12044.3 12145.9 12227.4 12230.3 12238.0 12282.1 8830.7 11790.8 12097.6 12172.0 12172.3 11725.9 12098.8 12103.0 12114.2 12152.4 42337.4 42680.0 42683.6 42754.8 42816.6 3529.0 3596.0 4021.4 4041.7 4215.6 32394.5 33669.0 33857.8 33933.2 34052.5 3226.3 3400.7 3404.6 3411.6 3437.0 500111.0 502418.0 503541.0 505421.5 505655.0 64435.4 64627.4 64965.7 64979.0 64979.4 67887.0 67901.0 67991.0 68054.0 68063.3 16354.6 16576.5 16586.8 16594.9 16614.5 11605.8 11853.3 12630.4 12770.2 12857.8 16880.1 17812.0 17842.9 18113.6 18179.8 7359.0 7386.3 7485.6 7542.9 7564.5 781.7 977.1 1010.3 1119.1 1191.0 28783.6 31373.5 31408.5 31988.8 32247.5 1098.4 1100.8 1113.2 1128.4 1129.7 16032.6 16302.2 16342.0 16365.0 16696.7 660826.0 733147.0 743160.0 743836.0 976860.0 62703.7 63290.0 63761.4 63835.0 63979.7 1297.6 1331.1 1422.0 1437.5 1445.0 31357.0 31853.5 32319.0 33515.7 33939.8 48653.8 49540.5 59155.7 60057.0 60104.7 626808.0 634393.0 634770.0 635809.0 637801.0 22352.8 24407.0 24821.3 26877.9 27235.5 35767.6 41628.8 41959.6 41996.0 42110.0 40660.6 41441.0 41746.8 43002.4 43257.1 40996.8 41584.0 42004.5 42405.8 42975.8 39595.8 39624.8 40535.0 40620.0 40670.6 35051.1 35488.8 35551.8 36117.1 36150.8 26006.2 26082.9 26617.7 26625.1 26992.0 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33430.65 Median:553336.00 stddev:93199.01 (16.8%) stddev-sans-first:93427.36 05:15:24 INFO - PROCESS | 1653 | Values: 347.4 355.5 387.2 398.6 404.7 19951.5 20069.4 20135.7 20596.2 20616.9 20255.5 20636.0 20902.3 21484.6 21615.8 5786.5 5919.1 6111.8 6837.0 6850.6 6503.0 6729.4 6738.3 6817.2 6822.6 6674.4 6712.3 6724.8 6727.3 6735.2 6431.0 6665.2 6668.2 6699.2 6726.5 5766.2 5787.0 5793.7 5800.7 5804.6 26245.9 26430.9 26790.2 27029.0 27096.5 2254.5 2391.6 2591.5 2716.8 2890.8 14967.6 15870.2 16141.8 16208.3 16491.3 1891.2 1916.9 1943.9 1956.3 2016.4 95173.6 110029.4 112588.0 115412.4 118312.8 9034.8 10552.7 11167.8 11171.3 11330.9 13730.8 13810.2 13834.7 13839.7 13840.7 8047.0 8213.2 8222.5 8304.7 8305.7 6767.8 8032.8 8034.4 8164.8 8210.3 6677.2 6789.8 6803.5 6818.3 6819.9 1838.9 1865.3 1876.0 1877.8 1881.5 907.0 937.8 992.7 1037.9 1050.0 22547.7 22860.8 22879.7 22934.9 23248.9 939.8 943.4 988.4 1059.2 1063.0 11406.9 11904.7 12094.8 12158.9 12172.4 550320.0 556352.0 561336.0 561947.0 567773.0 110097.0 111780.9 117803.8 118281.4 118858.8 937.4 938.5 954.0 981.0 1003.6 19236.7 19238.6 19318.8 19482.8 19487.4 42619.8 49643.0 55900.2 56725.1 57218.4 66765.7 68083.6 72571.6 79037.0 84717.0 5756.7 8014.1 8097.8 8145.6 8301.6 8566.6 8845.0 8928.4 9352.1 9384.6 7664.9 8115.7 8174.6 8204.4 8376.0 7740.7 7924.3 7976.0 8024.8 8030.2 8068.0 8095.7 8142.8 8342.3 8358.8 11577.6 12208.5 12297.4 12520.5 12639.4 10422.8 10891.1 11318.8 11621.8 11637.5 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5775.38 Median:4852.00 stddev:3259.89 (67.2%) stddev-sans-first:3252.31 05:15:24 INFO - PROCESS | 1653 | Values: 1381.4 1416.8 1567.2 1708.8 1874.5 7854.1 8368.5 8398.2 8673.0 8948.1 8729.1 8820.6 8898.2 8900.2 8905.5 2349.7 2379.4 2385.5 2386.7 2389.2 2122.8 2336.9 2408.9 2413.7 2417.4 2202.1 2225.9 2354.8 2386.5 2391.8 2092.1 2377.4 2388.1 2388.5 2389.3 2148.7 2170.2 2174.6 2175.0 2302.0 8688.9 8772.3 8896.6 8916.6 8917.1 5197.5 5355.4 5356.8 5357.2 5362.4 2943.8 3571.3 3687.7 3710.3 3723.4 1103.1 1110.1 1124.0 1125.4 1786.5 11346.3 11724.9 11751.2 11763.1 11770.6 4717.0 4731.6 4820.6 4821.4 4904.6 10143.9 10164.3 10168.8 10176.9 10182.6 5373.5 5375.4 5379.9 5392.7 5394.8 4882.6 4908.6 4922.5 4928.8 4937.6 3243.9 3244.4 3898.5 3983.2 4007.4 1202.9 1206.5 1217.8 1223.1 1229.3 932.1 933.7 936.9 941.6 985.4 5921.4 5988.9 6069.8 6147.1 6171.4 435.5 437.0 443.8 447.9 465.2 3965.0 4054.4 4104.9 4121.9 4128.2 10315.9 10828.6 10831.2 10849.8 10896.5 10394.5 10929.5 10930.0 10931.7 10936.8 8163.9 8210.3 8407.1 8416.3 8443.8 6048.3 6071.0 6171.2 6199.1 6207.4 9905.9 10192.6 10224.3 10240.3 10263.9 9321.9 9835.6 10227.5 10256.8 10426.5 7081.7 8038.4 8312.0 8317.1 8330.3 8206.5 8374.9 8406.9 8491.7 8514.8 7373.3 7506.7 7563.1 7588.8 7612.8 7446.7 7449.4 7493.9 7499.4 7536.8 6900.1 7082.4 7089.8 7096.3 7187.5 5145.4 5150.0 5155.2 5166.6 5304.7 5214.0 5225.2 5242.9 5262.7 5441.1 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4407.74 Median:5011.34 stddev:1358.37 (27.1%) stddev-sans-first:1326.71 05:15:24 INFO - PROCESS | 1653 | Values: 299.0 303.0 311.9 315.4 316.7 4332.6 5025.4 5262.3 5285.8 5297.6 4014.6 4750.5 5007.6 5199.2 5284.9 4031.7 4065.6 4067.5 4093.2 4121.5 3670.0 3762.8 4084.8 4093.6 4118.6 3547.0 3742.4 3826.3 4006.7 4036.0 3290.3 3858.5 3991.6 4006.7 4024.2 2732.8 2972.6 3078.7 3535.4 3538.9 4341.9 4447.2 4552.6 4992.3 5092.7 3784.6 3816.1 3857.9 3993.8 4577.6 4507.7 5417.2 5479.0 5487.7 5494.4 3196.6 3732.7 3872.2 3872.8 3888.5 4750.6 4798.7 4842.4 5785.5 5786.8 3291.6 3869.0 3899.8 3904.7 3906.3 3726.7 3967.8 3984.5 4010.3 4024.4 4219.6 4533.3 4606.7 4613.1 4638.7 5276.6 5472.1 5537.8 5549.6 5556.8 1247.3 1247.8 1309.7 1333.3 1334.6 862.7 892.4 897.6 898.4 899.4 4138.0 5127.6 5153.4 5163.8 5222.5 5471.3 5627.9 5659.6 5661.4 5679.0 5015.0 5102.4 5122.8 5123.8 5142.9 2474.4 2645.9 2916.8 2951.5 2974.3 4545.4 5029.8 5201.0 5749.7 5808.3 4987.0 5329.9 5752.9 5756.8 5759.1 4391.5 5443.0 5461.3 5747.9 5790.1 4437.4 5279.0 5363.4 5559.5 5664.8 5216.9 5439.8 5510.4 5551.4 5567.6 5272.7 5741.6 5761.4 5785.5 5799.6 3176.9 3538.6 3841.1 3846.6 3866.3 4671.0 5090.4 5154.5 5391.9 5659.6 4875.5 5278.5 5563.9 5598.2 5615.8 4857.1 5269.0 5530.5 5535.4 5558.7 4481.9 5229.1 5279.8 5374.8 5513.5 5628.3 5629.9 5641.9 5659.2 5659.3 5152.7 5464.3 5474.9 5501.5 5518.6 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25547.95 Median:13634.38 stddev:40856.89 (299.7%) stddev-sans-first:40929.06 05:15:24 INFO - PROCESS | 1653 | Values: 746.1 748.8 802.7 826.7 891.7 11497.8 13721.7 16054.3 16056.2 16075.0 15322.2 15885.5 15895.2 16011.0 16011.8 715.1 736.8 871.6 884.7 898.5 779.8 902.9 913.0 916.5 935.5 962.3 970.9 971.1 972.4 973.2 768.1 933.2 946.9 946.9 951.5 649.2 659.3 714.3 714.7 716.0 15204.8 15237.8 15249.2 15262.2 15263.2 14318.8 14473.3 14521.5 14525.8 14535.0 12942.8 13327.5 13404.5 13550.0 13595.5 4586.8 4672.2 4765.2 4856.2 4896.7 107244.0 108226.0 109220.5 109815.0 109816.8 11040.2 11208.9 11297.7 11452.4 11908.5 22539.1 23218.5 23446.2 23626.1 23657.8 7321.9 8296.9 8346.0 8469.0 8493.5 4544.4 4547.0 4597.2 4597.9 4743.8 3611.0 3707.6 3742.7 3747.4 3750.3 595.3 665.8 696.4 703.1 705.8 636.5 684.7 701.8 708.9 715.8 9659.2 9931.6 9942.3 9942.8 10004.6 677.9 696.5 701.9 715.1 717.3 4053.8 4730.1 4777.6 4785.6 4866.4 143212.3 146949.0 157958.2 160193.0 163013.0 136851.0 136853.3 137973.3 140002.0 141335.0 73965.3 107917.0 109801.9 113178.3 116885.2 8783.6 9233.4 9241.0 9397.9 9447.2 64745.0 65520.0 66018.0 66948.0 67092.7 91444.5 96009.0 99265.0 100686.5 101866.0 12172.8 12374.8 12461.4 12488.8 13188.6 13334.5 13806.9 13965.7 14008.0 14257.9 9644.6 10879.9 13500.5 13869.8 14495.0 11552.5 14057.5 14269.3 14445.4 14544.5 11463.9 13786.9 13998.7 14103.2 14551.7 11255.4 11595.9 11628.8 11641.9 11785.2 10716.9 11318.9 11523.9 11746.0 12018.2 05:15:24 INFO - PROCESS | 1653 | -------- Summary: end -------- 05:15:24 INFO - PROCESS | 1653 | 05:15:24 INFO - TEST-INFO | 1653: exit 0 05:15:24 INFO - TEST-OK | dromaeo_css | took 1116652ms 05:15:24 INFO - TEST-START | kraken 05:15:24 INFO - operating with platform_type : mac_ 05:15:24 INFO - Initialising browser for kraken test... 05:15:24 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpiNtmoX/profile http://localhost:49270/getInfo.html) 05:15:27 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 05:15:27 INFO - PROCESS | 1660 | colorDepth:24 05:15:27 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 05:15:27 INFO - PROCESS | 1660 | __metrics 05:15:27 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 05:15:27 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/CaptivePortalWatcher.jsm, line 60: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:15:28 INFO - TEST-INFO | 1660: exit 0 05:15:28 INFO - Browser initialized. 05:15:28 INFO - Running cycle 1/1 for kraken test... 05:15:28 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/T/tmpiNtmoX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:15:40 INFO - PROCESS | 1661 | RSS: Main: 243752960 05:15:40 INFO - PROCESS | 1661 | 05:15:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 05:15:43 INFO - PROCESS | 1661 | RSS: Main: 294068224 05:15:43 INFO - PROCESS | 1661 | 05:15:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 05:15:46 INFO - PROCESS | 1661 | RSS: Main: 304889856 05:15:46 INFO - PROCESS | 1661 | 05:15:50 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 05:15:51 INFO - PROCESS | 1661 | RSS: Main: 389029888 05:15:51 INFO - PROCESS | 1661 | 05:15:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 05:15:53 INFO - PROCESS | 1661 | RSS: Main: 289046528 05:15:53 INFO - PROCESS | 1661 | 05:15:55 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:15:56 INFO - PROCESS | 1661 | RSS: Main: 293298176 05:15:56 INFO - PROCESS | 1661 | 05:15:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 05:15:59 INFO - PROCESS | 1661 | RSS: Main: 331214848 05:15:59 INFO - PROCESS | 1661 | 05:16:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 05:16:03 INFO - PROCESS | 1661 | RSS: Main: 331558912 05:16:03 INFO - PROCESS | 1661 | 05:16:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 05:16:06 INFO - PROCESS | 1661 | RSS: Main: 331112448 05:16:06 INFO - PROCESS | 1661 | 05:16:08 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:16:08 INFO - PROCESS | 1661 | RSS: Main: 311578624 05:16:08 INFO - PROCESS | 1661 | 05:16:10 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:16:11 INFO - PROCESS | 1661 | RSS: Main: 317845504 05:16:11 INFO - PROCESS | 1661 | 05:16:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:16:14 INFO - PROCESS | 1661 | RSS: Main: 316243968 05:16:14 INFO - PROCESS | 1661 | 05:16:17 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:16:17 INFO - PROCESS | 1661 | RSS: Main: 324886528 05:16:17 INFO - PROCESS | 1661 | 05:16:20 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:16:21 INFO - PROCESS | 1661 | RSS: Main: 326254592 05:16:21 INFO - PROCESS | 1661 | 05:16:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 05:16:23 INFO - PROCESS | 1661 | RSS: Main: 370335744 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | __start_tp_report 05:16:23 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:16:23 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:16:23 INFO - PROCESS | 1661 | |i|pagename|runs| 05:16:23 INFO - PROCESS | 1661 | |0;ai-astar;100;111;112;111;112;112;113;112;112;111 05:16:23 INFO - PROCESS | 1661 | |1;audio-beat-detection;142;107;106;156;101;107;129;110;145;131 05:16:23 INFO - PROCESS | 1661 | |2;audio-dft;184;181;185;181;183;181;178;180;185;183 05:16:23 INFO - PROCESS | 1661 | |3;audio-fft;79;73;58;106;57;57;60;60;96;57 05:16:23 INFO - PROCESS | 1661 | |4;audio-oscillator;110;110;125;101;71;67;119;99;97;98 05:16:23 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;88;85;88;86;89;86;89;86;89;84 05:16:23 INFO - PROCESS | 1661 | |6;imaging-darkroom;94;94;94;94;94;102;94;93;93;91 05:16:23 INFO - PROCESS | 1661 | |7;imaging-desaturate;84;84;86;84;85;85;87;84;84;88 05:16:23 INFO - PROCESS | 1661 | |8;json-parse-financial;55;55;53;55;54;53;54;55;53;53 05:16:23 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;67;71;78;77;74;74;71;70;70;70 05:16:23 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;82;81;82;82;85;85;81;85;82;85 05:16:23 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;137;143;142;127;154;151;152;144;184;136 05:16:23 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;161;163;162;182;162;163;169;162;161;160 05:16:23 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;66;66;67;66;66;66;65;65;63;64 05:16:23 INFO - PROCESS | 1661 | __end_tp_report 05:16:23 INFO - PROCESS | 1661 | __start_cc_report 05:16:23 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1998 05:16:23 INFO - PROCESS | 1661 | __end_cc_report 05:16:23 INFO - PROCESS | 1661 | __startTimestamp1468498583219__endTimestamp 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:16:23 INFO - PROCESS | 1661 | Number of tests: 14 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:110.60 Median:112.00 stddev:3.78 (3.4%) stddev-sans-first:0.67 05:16:23 INFO - PROCESS | 1661 | Values: 100.0 111.0 112.0 111.0 112.0 112.0 113.0 112.0 112.0 111.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:123.40 Median:130.00 stddev:19.68 (15.1%) stddev-sans-first:19.69 05:16:23 INFO - PROCESS | 1661 | Values: 142.0 107.0 106.0 156.0 101.0 107.0 129.0 110.0 145.0 131.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.10 Median:183.00 stddev:2.28 (1.2%) stddev-sans-first:2.32 05:16:23 INFO - PROCESS | 1661 | Values: 184.0 181.0 185.0 181.0 183.0 181.0 178.0 180.0 185.0 183.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:70.30 Median:60.00 stddev:17.99 (30.0%) stddev-sans-first:18.80 05:16:23 INFO - PROCESS | 1661 | Values: 79.0 73.0 58.0 106.0 57.0 57.0 60.0 60.0 96.0 57.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:99.70 Median:69.00 stddev:18.65 (27.0%) stddev-sans-first:19.40 05:16:23 INFO - PROCESS | 1661 | Values: 110.0 110.0 125.0 101.0 71.0 67.0 119.0 99.0 97.0 98.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.83 (2.1%) stddev-sans-first:1.90 05:16:23 INFO - PROCESS | 1661 | Values: 88.0 85.0 88.0 86.0 89.0 86.0 89.0 86.0 89.0 84.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.00 stddev:2.87 (3.1%) stddev-sans-first:3.04 05:16:23 INFO - PROCESS | 1661 | Values: 94.0 94.0 94.0 94.0 94.0 102.0 94.0 93.0 93.0 91.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.00 stddev:1.45 (1.7%) stddev-sans-first:1.48 05:16:23 INFO - PROCESS | 1661 | Values: 84.0 84.0 86.0 84.0 85.0 85.0 87.0 84.0 84.0 88.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.00 Median:54.50 stddev:0.94 (1.7%) stddev-sans-first:0.93 05:16:23 INFO - PROCESS | 1661 | Values: 55.0 55.0 53.0 55.0 54.0 53.0 54.0 55.0 53.0 53.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:72.50 stddev:3.46 (4.8%) stddev-sans-first:3.11 05:16:23 INFO - PROCESS | 1661 | Values: 67.0 71.0 78.0 77.0 74.0 74.0 71.0 70.0 70.0 70.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:1.76 (2.1%) stddev-sans-first:1.83 05:16:23 INFO - PROCESS | 1661 | Values: 82.0 81.0 82.0 82.0 85.0 85.0 81.0 85.0 82.0 85.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:147.50 stddev:15.38 (10.4%) stddev-sans-first:15.89 05:16:23 INFO - PROCESS | 1661 | Values: 137.0 143.0 142.0 127.0 154.0 151.0 152.0 144.0 184.0 136.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.50 Median:162.50 stddev:6.62 (4.1%) stddev-sans-first:6.90 05:16:23 INFO - PROCESS | 1661 | Values: 161.0 163.0 162.0 182.0 162.0 163.0 169.0 162.0 161.0 160.0 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:1.17 (1.8%) stddev-sans-first:1.22 05:16:23 INFO - PROCESS | 1661 | Values: 66.0 66.0 67.0 66.0 66.0 66.0 65.0 65.0 63.0 64.0 05:16:23 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:16:23 INFO - PROCESS | 1661 | 05:16:23 INFO - TEST-INFO | 1661: exit 0 05:16:24 INFO - TEST-OK | kraken | took 59134ms 05:16:24 INFO - SUITE-END | took 1175s 05:16:24 INFO - Completed test suite (00:19:36) 05:16:24 INFO - javascript benchmark 05:16:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.1689577605594, 3331.7667763256572, 3332.9333413331733, 3343.882964096257, 3398.235397035667, 5597.0, 5653.858653533659, 5718.857028574288, 5745.052066409043, 5781.9999999999945, 5718.6568805871675, 5743.885122297552, 5746.0806270996645, 5747.770089196427, 5758.683272420015, 5184.429712731606, 5225.242339860718, 5258.579313654917, 5261.526462618363, 5324.1215199492035, 5285.867853303679, 5303.124984377592, 5307.495787900162, 5309.469053094698, 5317.734113294339, 5206.114960456713, 5216.165413533835, 5231.372235331765, 5261.105612045962, 5310.867228319304, 5296.232046353276, 5341.278927344798, 5345.278387417688, 5359.624826262163, 5370.865728356784, 5141.794328226866, 5158.9484105158845, 5184.988927159206, 5249.7112658804135, 5288.524032837025, 5696.23100881384, 5696.658200507983, 5724.627899186581, 5747.540196784247, 5753.539716822645, 5562.3047119110115, 6275.780347687854, 6286.937130628681, 6289.622622642656, 6294.181756371689, 5269.947300526984, 6206.782762603332, 6253.96873015632, 6300.779472718478, 6326.49388048955, 5854.824355269348, 5876.324222714443, 5892.057270836688, 5897.941020589739, 5901.380355062743, 6261.217347831521, 6267.71795269214, 6280.717367718464, 6284.308726040132, 6294.685265736694, 5966.880662386815, 6039.54703397247, 6105.277797093398, 6107.992181290127, 6158.353372895871, 6239.064140378889, 6248.4688801452385, 6264.2796078451565, 6268.49852011838, 6268.717907694165, 6170.352113028157, 6200.627962322275, 6204.81385558434, 6212.0371342441995, 6233.96883015582, 6077.483413909778, 6129.938700612936, 6131.662758548234, 6139.9828168395225, 6229.968850155811, 4899.755012249373, 4937.23472861707, 4942.5304596063315, 4946.505349465025, 4949.604031677457, 4442.933355999663, 4470.128324976598, 4499.752513611783, 4513.458384993822, 4577.473590537064, 5785.88428231433, 5799.217105690678, 5806.44838740319, 5806.82579522615, 5821.708914554255, 6125.601835880653, 6204.348543402968, 6213.596116252429, 6217.595856269328, 6232.470240029557, 4652.813887444464, 5014.7994080236385, 5490.945090549123, 5617.8033768817895, 5676.7794924090695, 5365.6244062916, 5377.435369286247, 5381.569474442035, 5386.973065134727, 5390.272313237662, 6200.224971878515, 6228.128062071313, 6253.96873015632, 6338.778142764981, 6343.524235682342, 6311.084892690626, 6319.46284565817, 6321.33625969285, 6321.747130114744, 6334.239891213084, 6252.374762523711, 6345.587536810092, 6345.746170153327, 6350.714217860115, 6368.0, 5145.125328694056, 5218.425973142876, 6133.824358647991, 6218.378162183745, 6236.407541283571, 5461.590380721383, 5502.889942201054, 6253.812385628438, 6274.686265686789, 6284.591501552567, 5966.463018328372, 6028.854517641809, 6126.448619624255, 6134.877302454015, 6150.7847225346895, 6050.878982420415, 6103.5195042504665, 6107.053406721965, 6141.017437210025, 6156.815295541141, 5936.554758393224, 5969.462748352669, 5975.312839023568, 5988.790392336247, 6026.608270462406, 5939.940600593939, 5969.76120955157, 6096.498867793138, 6103.51950425029, 6109.633312616532, 5951.761929522944, 6010.729517171825, 6014.879702405839, 6027.306859711014, 6053.364396738455], "value": 5685.636835921503, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [524.9081410753118, 532.100749732951, 537.4249552978313, 542.5903442900609, 560.2101037537074, 30448.086557403287, 31062.37875242494, 42206.78896605513, 43528.0, 43637.7818110909, 44757.77621111898, 45114.0, 45125.54874451255, 45153.77423112888, 45223.77388113063, 9624.518774061287, 9786.657466988658, 9793.510324483746, 9800.705978820646, 9806.509674516281, 8800.911990880073, 9016.188543031127, 11858.407079646026, 12042.277463352184, 12044.337561434118, 12145.878541214563, 12227.449764760608, 12230.266184028942, 12238.020958323357, 12282.14025018249, 8830.69092581763, 11790.823137652942, 12097.637070887888, 12172.026237900947, 12172.2696638202, 11725.882741172565, 12098.81851772224, 12103.0, 12114.21257618252, 12152.392380381034, 42337.364939525934, 42680.0, 42683.57316426858, 42754.78622606898, 42816.57183428157, 3528.9823550882084, 3595.964040359589, 4021.416894550274, 4041.676665866753, 4215.578442155791, 32394.514082288784, 33668.98993030213, 33857.83071084654, 33933.15167120817, 34052.48921266183, 3226.2579606690356, 3400.7109395701395, 3404.6254911959663, 3411.624721280657, 3437.003269051972, 500110.99999999633, 502418.0, 503541.0, 505421.4728926332, 505655.0, 64435.355646442935, 64627.35372646307, 64965.675171623836, 64979.02531462032, 64979.35020649733, 67887.0, 67901.0, 67991.0, 68054.0, 68063.31936680668, 16354.591135221715, 16576.502704918872, 16586.834131658772, 16594.917025415038, 16614.501564953072, 11605.825912611317, 11853.288802671696, 12630.431630576646, 12770.233785972685, 12857.807132893015, 16880.071596062335, 17812.0, 17842.929424234586, 18113.637727245645, 18179.818201818078, 7358.96320518394, 7386.335229829341, 7485.625718714043, 7542.924570754222, 7564.524917641117, 781.6638845296463, 977.0815433492494, 1010.3483253301579, 1119.1103073056938, 1190.9583164589199, 28783.56824647632, 31373.52939705906, 31408.528872066938, 31988.840055799574, 32247.51628725571, 1098.368438148068, 1100.801855665988, 1113.154002957758, 1128.424503503203, 1129.6611016694958, 16032.638549042651, 16302.184890755414, 16342.019478831307, 16365.018098914103, 16696.74954875678, 660826.0, 733147.0, 743160.0, 743836.0, 976860.0, 62703.68648156821, 63290.0, 63761.36238637554, 63835.0, 63979.68010160013, 1297.5653156192593, 1331.121459836542, 1421.985780142206, 1437.4537675683173, 1444.978325325121, 31357.0, 31853.522197166596, 32319.01036495707, 33515.66484335125, 33939.830300849324, 48653.83750056235, 49540.50459495359, 59155.70422147862, 60057.0, 60104.69947650234, 626808.0, 634393.0, 634770.0, 635809.0, 637801.0, 22352.77647223507, 24407.0, 24821.255362339158, 26877.865610672605, 27235.455290894468, 35767.64232357643, 41628.79185604052, 41959.58040419557, 41996.0, 42110.0, 40660.59339406568, 41441.0, 41746.791266043474, 43002.35496467618, 43257.13485730331, 40996.79501602592, 41584.0, 42004.54991213757, 42405.787971059945, 42975.785121074194, 39595.80202098971, 39624.80187599044, 40534.986625334604, 40620.0, 40670.59329406668, 35051.12372190614, 35488.7578934736, 35551.82224088863, 36117.097072572345, 36150.819245904655, 26006.219813405627, 26082.86958565195, 26617.669116543708, 26625.068122615616, 26992.0], "value": 21211.329357815666, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.4128722459045, 355.53780085888343, 387.2042951734187, 398.6491887139317, 404.69889305881605, 19951.501212469695, 20069.397918062445, 20135.697964530525, 20596.176152953863, 20616.866072366032, 20255.49361265969, 20635.968201589934, 20902.268420605284, 21484.57030859382, 21615.783842161574, 5786.537077033849, 5919.141724449953, 6111.816645500642, 6837.0, 6850.554713943603, 6503.0, 6729.394354508093, 6738.258791532928, 6817.216020157671, 6822.590644561342, 6674.399304062634, 6712.328767123297, 6724.83187920301, 6727.260001399842, 6735.225449073346, 6430.999999999976, 6665.233498147678, 6668.233153187397, 6699.196096468406, 6726.461883049345, 5766.163906233573, 5786.971065144648, 5793.710314484259, 5800.680962547058, 5804.62269952456, 26245.868770656216, 26430.86784566065, 26790.196294111207, 27028.97282703805, 27096.45807083847, 2254.4701995031155, 2391.5814732421754, 2591.5335239656856, 2716.755492005729, 2890.8410037447934, 14967.550973470814, 15870.20648967547, 16141.757873631905, 16208.270627821776, 16491.257893394704, 1891.215145714527, 1916.8658193926292, 1943.893085880276, 1956.2761778142037, 2016.41523958052, 95173.5723964141, 110029.44985275023, 112588.0, 115412.42293788561, 118312.81687183103, 9034.819303614022, 10552.683419497427, 11167.776644467065, 11171.329720216812, 11330.94334528322, 13730.794038089436, 13810.240436775874, 13834.65413364674, 13839.654008649864, 13840.653983650489, 8046.994125734283, 8213.178682131858, 8222.506649601042, 8304.70933517324, 8305.667773289122, 6767.796966091025, 8032.799180020546, 8034.437589368805, 8164.7550573482895, 8210.261076503144, 6677.232118306457, 6789.762358317435, 6803.5032292895485, 6818.318168183241, 6819.897701534481, 1838.8712790104564, 1865.272543707955, 1876.035863335926, 1877.7652793400825, 1881.5390229393886, 906.9501177435172, 937.7561833923229, 992.717075633441, 1037.9429131397694, 1050.0497050169608, 22547.661785073236, 22860.7713922862, 22879.656805147937, 22934.853257337065, 23248.883755581453, 939.7509659940122, 943.42451104826, 988.3921388346115, 1059.2320567588438, 1062.9787404251872, 11406.942965285121, 11904.702382440335, 12094.758104837854, 12158.878411215952, 12172.391380430943, 550320.0, 556352.0, 561336.0, 561947.0, 567773.0, 110097.0, 111780.88219117705, 117803.82196177928, 118281.40859295649, 118858.8114118865, 937.4141161773891, 938.544805769215, 953.9761505962407, 980.9656662016795, 1003.5684655598017, 19236.71144932855, 19238.61522769565, 19318.8068119317, 19482.805171948097, 19487.415377538695, 42619.786901065294, 49643.0, 55900.16149757838, 56725.149122764014, 57218.42781572131, 66765.66617166882, 68083.63832723416, 72571.63714181395, 79037.0, 84717.0, 5756.712164391847, 8014.078380986261, 8097.8380432392205, 8145.551994640352, 8301.58492075406, 8566.571671416279, 8844.955775221082, 8928.419652722552, 9352.148621502469, 9384.577694003923, 7664.923350766421, 8115.675372985014, 8174.591270436574, 8204.425690201628, 8376.03675577292, 7740.6903723852665, 7924.286814186751, 7976.042874855055, 8024.759257222293, 8030.237127473114, 8068.0, 8095.676172953016, 8142.837143256983, 8342.29090527312, 8358.83282334362, 11577.594784182513, 12208.53497580296, 12297.385130743249, 12520.499180032697, 12639.431225595054, 10422.791544169226, 10891.128709703364, 11318.773624527628, 11621.767564648491, 11637.476313565741], "value": 9683.397274221306, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.4128995177048, 1416.8016477693122, 1567.1694002178845, 1708.8205738397469, 1874.512626717054, 7854.1360450350485, 8368.456050356723, 8398.20217079378, 8672.956635216815, 8948.105189481048, 8729.127087291268, 8820.60307286172, 8898.243649289801, 8900.1544853239, 8905.465672059665, 2349.659299401586, 2379.357573455166, 2385.451346190377, 2386.7493913139133, 2389.199618127929, 2122.8089471947524, 2336.8714720690355, 2408.9036438542526, 2413.722421921484, 2417.4319035026756, 2202.119152339061, 2225.9220927267547, 2354.799842013422, 2386.451116243265, 2391.8445301055376, 2092.089940875724, 2377.3937645900273, 2388.092524840567, 2388.4625959159225, 2389.319044072431, 2148.6669566217106, 2170.1536400803698, 2174.5759576882515, 2175.0212404418075, 2301.9884900575557, 8688.869666955, 8772.342074344386, 8896.644134234622, 8916.643334266622, 8917.0637083106, 5197.48025197481, 5355.437679043684, 5356.758945847408, 5357.223202635635, 5362.35651721792, 2943.7792165587452, 3571.339302229096, 3687.7049836013052, 3710.3321402147603, 3723.385641369198, 1103.0899507905929, 1110.1451882050776, 1123.9831402528969, 1125.4147843121614, 1786.490850107713, 11346.262492937933, 11724.941375293069, 11751.236169648946, 11763.117766167572, 11770.646880593597, 4716.957711232861, 4731.574158325767, 4820.638452116105, 4821.445533763592, 4904.558589726942, 10143.898561014295, 10164.288499804943, 10168.847467287997, 10176.898231017594, 10182.592696292213, 5373.462653734595, 5375.414252795435, 5379.946200538023, 5392.7057506198225, 5394.838154855361, 4882.5605695487575, 4908.558229759339, 4922.483139270396, 4928.802847886116, 4937.604991600663, 3243.9026829195163, 3244.4322243607276, 3898.5126859142606, 3983.203359328146, 4007.3988901664984, 1202.903767698582, 1206.4872429217548, 1217.792975194219, 1223.0521345956777, 1229.2931564350533, 932.1098351074708, 933.6638810028385, 936.8688383626297, 941.5621735892706, 985.3939827006483, 5921.437463440966, 5988.9101663475085, 6069.848253793691, 6147.108669242934, 6171.352008039181, 435.45568039950064, 436.9868903932887, 443.82690750607287, 447.8522087711113, 465.2393336894202, 3965.031385276013, 4054.412110243998, 4104.938425923614, 4121.855735049259, 4128.2156390285745, 10315.896841031643, 10828.566857325619, 10831.241813073168, 10849.837252441219, 10896.509657065293, 10394.532246048946, 10929.453527323602, 10930.01629853317, 10931.672049838358, 10936.835947460795, 8163.918360816316, 8210.261076503144, 8407.117252688502, 8416.326693864476, 8443.83112337762, 6048.304444988707, 6070.969645151746, 6171.228596425447, 6199.132121502903, 6207.379262073757, 9905.900940990497, 10192.643257485952, 10224.335418197794, 10240.334378265388, 10263.948680256552, 9321.86017209756, 9835.60657573689, 10227.488625568543, 10256.846147307648, 10426.530806113895, 7081.716731330689, 8038.39712021589, 8312.002559692875, 8317.085120636604, 8330.291925186428, 8206.507609543447, 8374.87437688423, 8406.873896891673, 8491.74524764258, 8514.829703406022, 7373.29953654402, 7506.737264195727, 7563.054618172729, 7588.81027974305, 7612.80967975805, 7446.73936412223, 7449.366803821736, 7493.875918612252, 7499.362554182955, 7536.811579710332, 6900.137482814648, 7082.433405327664, 7089.7873063808165, 7096.254893236136, 7187.496875218684, 5145.4340022598235, 5149.995750828552, 5155.226715992631, 5166.612504062286, 5304.708241046779, 5214.0, 5225.216217567243, 5242.9475705243985, 5262.710550919583, 5441.102218133963], "value": 4554.827860306359, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.0356101572429, 302.9924251893703, 311.8970739655914, 315.3550988229071, 316.6516831485366, 4332.5954095477, 5025.3718285214345, 5262.342207224097, 5285.788568457262, 5297.576193904488, 4014.5583985761614, 4750.524947505248, 5007.649464537484, 5199.246109314147, 5284.867878303045, 4031.657309128728, 4065.6340929316357, 4067.4712287402594, 4093.2427500912354, 4121.52602450719, 3669.9633003669887, 3762.7930463824478, 4084.816183271745, 4093.6111069448502, 4118.629323360897, 3547.0245682437408, 3742.3637981543066, 3826.253880493305, 4006.736194050367, 4035.9596404036024, 3290.3090351026194, 3858.498395208633, 3991.640752332275, 4006.6794656427414, 4024.1750441159493, 2732.8223665461683, 2972.5689774982725, 3078.6909817636774, 3535.3636345457808, 3538.929221415583, 4341.914521369657, 4447.2439685253585, 4552.635789136861, 4992.3114820178125, 5092.719900405476, 3784.5836957934607, 3816.053812555589, 3857.8842634721004, 3993.7603743775467, 4577.56513131252, 4507.661925355578, 5417.214503896952, 5478.972605136989, 5487.670739755627, 5494.395616482184, 3196.5524826524306, 3732.693557254966, 3872.244912242086, 3872.7925082702873, 3888.4556162137324, 4750.596199323062, 4798.712077275374, 4842.394700662418, 5785.47930686236, 5786.826395208151, 3291.6214635316765, 3868.9613103869165, 3899.7855117968784, 3904.7461914976, 3906.257811015898, 3726.720495962814, 3967.781772002568, 3984.541777695602, 4010.3382941814857, 4024.3561030235605, 4219.578042195817, 4533.297338912474, 4606.700564463299, 4613.077384523109, 4638.7216766993415, 5276.551493123127, 5472.097103977879, 5537.8061767837935, 5549.611527193058, 5556.833295001156, 1247.25788156047, 1247.8003519436847, 1309.7445998030482, 1333.3466601365262, 1334.5853395400907, 862.6635612111281, 892.3530440430636, 897.5736525150501, 898.4429653614801, 899.4288626721948, 4137.9861933825805, 5127.589792816566, 5153.433122356612, 5163.845084647466, 5222.477752224747, 5471.343438787291, 5627.887442251132, 5659.6321239119325, 5661.377248502661, 5679.0, 5015.022070696179, 5102.36220472441, 5122.846314610568, 5123.820666276737, 5142.948570514247, 2474.369035895871, 2645.894164233409, 2916.7895323440957, 2951.5094877086935, 2974.330775575478, 4545.409096817392, 5029.84910452687, 5201.0, 5749.741261643236, 5808.273965754281, 4986.950130498722, 5329.934013197376, 5752.913706294409, 5756.769729210785, 5759.149221135481, 4391.451068616423, 5442.972785136049, 5461.306994831615, 5747.885042299047, 5790.120619185156, 4437.445319335083, 5279.0, 5363.436839131835, 5559.527440167631, 5664.801731939362, 5216.895662086813, 5439.755211015435, 5510.366307874646, 5551.444855514578, 5567.5545956322585, 5272.657277277118, 5741.569382296261, 5761.366249712444, 5785.450382213683, 5799.594028417971, 3176.888808891678, 3538.5753709553987, 3841.117852252432, 3846.5576458707606, 3866.284737323604, 4670.9766451167525, 5090.41460232078, 5154.5103215194495, 5391.865203369947, 5659.603827732019, 4875.536824001714, 5278.524932755917, 5563.944360556343, 5598.188262701804, 5615.781766202245, 4857.0528746894015, 5268.9736551317, 5530.474604912527, 5535.391106978149, 5558.666480011212, 4481.91036179268, 5229.058769421541, 5279.812042290454, 5374.811881584125, 5513.5389121881935, 5628.268325117709, 5629.88740225185, 5641.943580564306, 5659.236003139524, 5659.320881494247, 5152.690838549699, 5464.26232458613, 5474.863128421821, 5501.477359650827, 5518.558515318685], "value": 3982.4047543336637, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [746.0525133080987, 748.775367389783, 802.6548584108834, 826.6528058215549, 891.6879092317689, 11497.770044599103, 13721.725565488698, 16054.277557509911, 16056.197190140505, 16075.0, 15322.233888305596, 15885.523434296989, 15895.205239738025, 16011.03933763975, 16011.839881601181, 715.0775499605529, 736.8489459660757, 871.6469829718958, 884.6594061286387, 898.5417437107089, 779.7543773711291, 902.8690839828221, 912.9726108216764, 916.5005072235648, 935.4621092871625, 962.2975228083503, 970.9077637624414, 971.0677749360623, 972.3776782858976, 973.1728031173517, 768.1051574915217, 933.225422898999, 946.8721722567434, 946.8863736351648, 951.5242378810594, 649.2014821769203, 659.3110199841177, 714.3320994869813, 714.7284032067781, 715.9713611455536, 15204.771928421083, 15237.771433428508, 15249.161296128708, 15262.23688815566, 15263.160526171056, 14318.785218221736, 14473.276336183255, 14521.491747788883, 14525.78211326831, 14535.0, 12942.805857912139, 13327.466901323935, 13404.530841420503, 13549.983751218699, 13595.456181752817, 4586.839460618862, 4672.2057250267535, 4765.2375619900995, 4856.198727210006, 4896.730679812609, 107244.0, 108226.0, 109220.4538977308, 109815.0, 109816.80366392707, 11040.171987101, 11208.943955280334, 11297.71755706114, 11452.427378631202, 11908.523659053713, 22539.084177845067, 23218.53562928732, 23446.17938372183, 23626.05495780184, 23657.763422365555, 7321.926780732231, 8296.936692306603, 8346.0, 8468.957655211685, 8493.490390576586, 4544.409226800495, 4547.0, 4597.218472859622, 4597.862064138081, 4743.810247590058, 3611.0, 3707.5921648618632, 3742.73800833939, 3747.3816820224356, 3750.2874453854224, 595.3064679648162, 665.7650059140225, 696.3837004251172, 703.1035429827011, 705.8059033765653, 636.4971672378769, 684.6576711644178, 701.8140192848899, 708.9436739258467, 715.8031541326072, 9659.227261819038, 9931.602735890629, 9942.304038717364, 9942.751431214278, 10004.599816007423, 677.8916471568922, 696.5263620737946, 701.8631366883409, 715.141829804237, 717.275551692794, 4053.777042262645, 4730.124926888537, 4777.593904518086, 4785.569298763058, 4866.367372241586, 143212.28393857964, 146949.0, 157958.2102089482, 160193.0, 163013.0, 136851.0, 136853.3157334227, 137973.3101334487, 140002.0, 141335.0, 73965.26034739584, 107917.0, 109801.90198097916, 113178.30232546518, 116885.24672129925, 8783.560821959005, 9233.353665243638, 9240.95379523098, 9397.85903211466, 9447.24422046221, 64745.0, 65520.01719974109, 66018.0, 66947.99578006237, 67092.66453667701, 91444.5427772857, 96009.0, 99265.0, 100686.49656751669, 101866.0, 12172.75654486923, 12374.81437778452, 12461.439235234617, 12488.812667809809, 13188.604341869759, 13334.466621335037, 13806.93096534511, 13965.650858728613, 14008.019438639198, 14257.857421425653, 9644.565994530123, 10879.891201087888, 13500.5274815381, 13869.79195312051, 14495.0, 11552.537898483966, 14057.507987220399, 14269.286535672967, 14445.422183112978, 14544.49094281695, 11463.942680286546, 13786.862131378557, 13998.720025599634, 14103.153810771386, 14551.708965820835, 11255.380954047607, 11595.853177943107, 11628.825567616323, 11641.941790290994, 11785.175037747618, 10716.948124881892, 11318.906002389254, 11523.884761152281, 11746.0, 12018.218815776947], "value": 8100.954184478282, "unit": "score"}], "name": "dromaeo_css", "value": 7454.692648507026, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 111.0, 112.0, 111.0, 112.0, 112.0, 113.0, 112.0, 112.0, 111.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 107.0, 106.0, 156.0, 101.0, 107.0, 129.0, 110.0, 145.0, 131.0], "value": 123.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 181.0, 185.0, 181.0, 183.0, 181.0, 178.0, 180.0, 185.0, 183.0], "value": 182.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 58.0, 106.0, 57.0, 57.0, 60.0, 60.0, 96.0, 57.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 125.0, 101.0, 71.0, 67.0, 119.0, 99.0, 97.0, 98.0], "value": 99.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 89.0, 86.0, 89.0, 86.0, 89.0, 84.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 94.0, 102.0, 94.0, 93.0, 93.0, 91.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 84.0, 85.0, 85.0, 87.0, 84.0, 84.0, 88.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 53.0, 55.0, 54.0, 53.0, 54.0, 55.0, 53.0, 53.0], "value": 54.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 78.0, 77.0, 74.0, 74.0, 71.0, 70.0, 70.0, 70.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 82.0, 85.0, 85.0, 81.0, 85.0, 82.0, 85.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 143.0, 142.0, 127.0, 154.0, 151.0, 152.0, 144.0, 184.0, 136.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 182.0, 162.0, 163.0, 169.0, 162.0, 161.0, 160.0], "value": 164.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0, 63.0, 64.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1438.6000000000001, "alertThreshold": 2.0}]} 05:16:24 INFO - Return code: 0 05:16:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:16:24 INFO - # TBPL SUCCESS # 05:16:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:16:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:16:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:16:24 INFO - 2016-07-14 05:16:24.550 screenresolution[1665:9727] starting screenresolution argv=screenresolution get 05:16:24 INFO - 2016-07-14 05:16:24.552 screenresolution[1665:9727] Display 0: 1600x1200x32@60 05:16:24 INFO - 2016-07-14 05:16:24.558 screenresolution[1666:9730] starting screenresolution argv=screenresolution list 05:16:24 INFO - Available Modes on Display 0 05:16:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:16:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:16:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:16:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:16:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:16:24 INFO - Intel Iris: 05:16:24 INFO - Chipset Model: Intel Iris 05:16:24 INFO - Type: GPU 05:16:24 INFO - Bus: Built-In 05:16:24 INFO - VRAM (Dynamic, Max): 1536 MB 05:16:24 INFO - Vendor: Intel (0x8086) 05:16:24 INFO - Device ID: 0x0a2e 05:16:24 INFO - Revision ID: 0x0009 05:16:24 INFO - Displays: 05:16:24 INFO - Display: 05:16:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:16:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:16:24 INFO - Display Serial Number: 1600x1200 60 05:16:24 INFO - Main Display: Yes 05:16:24 INFO - Mirror: Off 05:16:24 INFO - Online: Yes 05:16:24 INFO - Rotation: Supported 05:16:24 INFO - Return code: 0 05:16:24 INFO - Running post-action listener: _resource_record_post_action 05:16:24 INFO - ##### 05:16:24 INFO - ##### Finished run-tests step (success) 05:16:24 INFO - ##### 05:16:24 INFO - Running post-run listener: _resource_record_post_run 05:16:24 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 38151680; Write bytes: 704972288; Read time: 373; Write time: 2027 05:16:24 INFO - TinderboxPrint: CPU usage
53.4% 05:16:24 INFO - TinderboxPrint: I/O read bytes / time
38,151,680 / 373 05:16:24 INFO - TinderboxPrint: I/O write bytes / time
704,972,288 / 2,027 05:16:24 INFO - TinderboxPrint: CPU idle
2,260.0 (46.6%) 05:16:24 INFO - TinderboxPrint: CPU system
965.9 (19.9%) 05:16:24 INFO - TinderboxPrint: CPU user
1,619.8 (33.4%) 05:16:24 INFO - TinderboxPrint: Swap in / out
445,616,128 / 0 05:16:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 402164224; Write bytes: 391378944; Read time: 29525; Write time: 772 05:16:24 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 30989824; Write bytes: 293883392; Read time: 288; Write time: 1212 05:16:25 INFO - Running post-run listener: _upload_blobber_files 05:16:25 INFO - Blob upload gear active. 05:16:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:16:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:16:25 INFO - (blobuploader) - INFO - Open directory for files ... 05:16:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:16:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:16:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:26 INFO - (blobuploader) - INFO - Done attempting. 05:16:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:16:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:27 INFO - (blobuploader) - INFO - Done attempting. 05:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:16:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:16:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:16:30 INFO - (blobuploader) - INFO - Done attempting. 05:16:30 INFO - (blobuploader) - INFO - Iteration through files over. 05:16:30 INFO - Return code: 0 05:16:30 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:16:30 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:16:30 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a56a1201cb84c2826c2a53adca062d07e0ac767fd50d79f4cf527470fd7e009769376e8e23ab577fdef9b4a28c4b44dc64b0c0678ffa63b1f818170f927584", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45cc3a0f488bbb798f87432de9700e26ffe2414f1e9741df09d0210bedc901fe301ee4c6ece8ae081c03516ce0b59cec34dcec09fe433841ef5252d7815d3ccf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab6039021ae9c91fa8b8ca9f3a0331b77d9021ffa88969ff62fe1af068a7028a9755dd6cdfcd094565d8d8d301e24d23df07aa15f55b5e52b9cec2a48aaf3846"} 05:16:30 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:16:30 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:16:30 INFO - Contents: 05:16:30 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/73a56a1201cb84c2826c2a53adca062d07e0ac767fd50d79f4cf527470fd7e009769376e8e23ab577fdef9b4a28c4b44dc64b0c0678ffa63b1f818170f927584", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45cc3a0f488bbb798f87432de9700e26ffe2414f1e9741df09d0210bedc901fe301ee4c6ece8ae081c03516ce0b59cec34dcec09fe433841ef5252d7815d3ccf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab6039021ae9c91fa8b8ca9f3a0331b77d9021ffa88969ff62fe1af068a7028a9755dd6cdfcd094565d8d8d301e24d23df07aa15f55b5e52b9cec2a48aaf3846"} 05:16:30 INFO - Running post-run listener: copy_logs_to_upload_dir 05:16:30 INFO - Copying logs to upload dir... 05:16:30 INFO - mkdir: /builds/slave/test/build/upload/logs 05:16:30 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.145107 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-07-14 05:16:30.564554) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 05:16:30.568492) ========= 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.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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/73a56a1201cb84c2826c2a53adca062d07e0ac767fd50d79f4cf527470fd7e009769376e8e23ab577fdef9b4a28c4b44dc64b0c0678ffa63b1f818170f927584", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45cc3a0f488bbb798f87432de9700e26ffe2414f1e9741df09d0210bedc901fe301ee4c6ece8ae081c03516ce0b59cec34dcec09fe433841ef5252d7815d3ccf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab6039021ae9c91fa8b8ca9f3a0331b77d9021ffa88969ff62fe1af068a7028a9755dd6cdfcd094565d8d8d301e24d23df07aa15f55b5e52b9cec2a48aaf3846"} build_url:https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011068 build_url: 'https://queue.taskcluster.net/v1/task/FaxDy4CSQYOc20rCXj-jAg/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/73a56a1201cb84c2826c2a53adca062d07e0ac767fd50d79f4cf527470fd7e009769376e8e23ab577fdef9b4a28c4b44dc64b0c0678ffa63b1f818170f927584", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/45cc3a0f488bbb798f87432de9700e26ffe2414f1e9741df09d0210bedc901fe301ee4c6ece8ae081c03516ce0b59cec34dcec09fe433841ef5252d7815d3ccf", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ab6039021ae9c91fa8b8ca9f3a0331b77d9021ffa88969ff62fe1af068a7028a9755dd6cdfcd094565d8d8d301e24d23df07aa15f55b5e52b9cec2a48aaf3846"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-14 05:16:30.601501) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:16:30.601846) ========= 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.nsrZWYcFew/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.EzV8HcbIXH/Listeners TMPDIR=/var/folders/y_/gkptfz8n2s9frngl8ywr38k000000w/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.005337 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-14 05:16:30.653031) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 05:16:30.653589) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-14 05:16:30.654265) ========= ========= Total master_lag: 0.18 =========