builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0296 starttime: 1487988256.23 results: success (0) buildid: 20170224164722 builduid: 4bf71dc753c64f50ba1bd0f686ede5c0 revision: f36062d04d165f6f6e781cf0633ffcbbebe6c273 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:16.229768) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:16.230251) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:16.230537) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:16.593552) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:16.593908) ========= 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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 --2017-02-24 18:04:16-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.3M=0s 2017-02-24 18:04:17 (29.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.519725 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:17.177202) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:17.177522) ========= 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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.027592 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:17.262358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:17.262680) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f36062d04d165f6f6e781cf0633ffcbbebe6c273 --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 f36062d04d165f6f6e781cf0633ffcbbebe6c273 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 2017-02-24 18:04:17,359 truncating revision to first 12 chars 2017-02-24 18:04:17,359 Setting DEBUG logging. 2017-02-24 18:04:17,359 attempt 1/10 2017-02-24 18:04:17,359 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f36062d04d16?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-24 18:04:18,051 unpacking tar archive at: mozilla-central-f36062d04d16/testing/mozharness/ program finished with exit code 0 elapsedTime=0.973795 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:18.262091) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:18.262401) ========= script_repo_revision: f36062d04d165f6f6e781cf0633ffcbbebe6c273 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:18.262787) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:18.263044) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-24 18:04:18.286036) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-24 18:04:18.286517) ========= /tools/buildbot/bin/python -u 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', '-u', '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:04:18 INFO - MultiFileLogger online at 20170224 18:04:18 in /builds/slave/test 18:04:18 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 18:04:18 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:04:18 INFO - {'append_to_log': False, 18:04:18 INFO - 'base_work_dir': '/builds/slave/test', 18:04:18 INFO - 'blob_upload_branch': 'Firefox', 18:04:18 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:04:18 INFO - 'branch': 'Firefox', 18:04:18 INFO - 'buildbot_json_path': 'buildprops.json', 18:04:18 INFO - 'config_files': ('talos/mac_config.py',), 18:04:18 INFO - 'default_actions': ('clobber', 18:04:18 INFO - 'read-buildbot-config', 18:04:18 INFO - 'download-and-extract', 18:04:18 INFO - 'populate-webroot', 18:04:18 INFO - 'create-virtualenv', 18:04:18 INFO - 'install', 18:04:18 INFO - 'run-tests'), 18:04:18 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:04:18 INFO - 'download_minidump_stackwalk': True, 18:04:18 INFO - 'download_symbols': 'ondemand', 18:04:18 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:04:18 INFO - 'tooltool.py': '/tools/tooltool.py', 18:04:18 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:04:18 INFO - '/tools/misc-python/virtualenv.py')}, 18:04:18 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:04:18 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:04:18 INFO - 'gecko_profile': False, 18:04:18 INFO - 'gecko_profile_interval': 0, 18:04:18 INFO - 'installer_path': 'installer.exe', 18:04:18 INFO - 'log_level': 'info', 18:04:18 INFO - 'log_name': 'talos', 18:04:18 INFO - 'log_to_console': True, 18:04:18 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:04:18 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:04:18 INFO - 'opt_config_files': (), 18:04:18 INFO - 'pip_index': False, 18:04:18 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:18 INFO - 'cmd': ('bash', 18:04:18 INFO - '-c', 18:04:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:18 INFO - 'enabled': True, 18:04:18 INFO - 'halt_on_failure': False, 18:04:18 INFO - 'name': 'check_screen_resolution'},), 18:04:18 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:04:18 INFO - 'cmd': ('bash', 18:04:18 INFO - '-c', 18:04:18 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:04:18 INFO - 'enabled': True, 18:04:18 INFO - 'halt_on_failure': False, 18:04:18 INFO - 'name': 'check_screen_resolution'},), 18:04:18 INFO - 'run_cmd_checks_enabled': True, 18:04:18 INFO - 'suite': 'dromaeojs', 18:04:18 INFO - 'system_bits': '32', 18:04:18 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:04:18 INFO - 'title': 't-yosemite-r7-0296', 18:04:18 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:04:18 INFO - 'use_talos_json': True, 18:04:18 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:04:18 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:04:18 INFO - 'work_dir': 'build'} 18:04:18 INFO - [mozharness: 2017-02-25 02:04:18.488867Z] Running clobber step. 18:04:18 INFO - Running pre-action listener: _resource_record_pre_action 18:04:18 INFO - Running main action method: clobber 18:04:18 INFO - rmtree: /builds/slave/test/build 18:04:18 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:04:20 INFO - Running post-action listener: _resource_record_post_action 18:04:20 INFO - [mozharness: 2017-02-25 02:04:20.947853Z] Finished clobber step (success) 18:04:20 INFO - [mozharness: 2017-02-25 02:04:20.947985Z] Running read-buildbot-config step. 18:04:20 INFO - Running pre-action listener: _resource_record_pre_action 18:04:20 INFO - Running main action method: read_buildbot_config 18:04:20 INFO - Using buildbot properties: 18:04:20 INFO - { 18:04:20 INFO - "project": "", 18:04:20 INFO - "product": "firefox", 18:04:20 INFO - "script_repo_revision": "production", 18:04:20 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 18:04:20 INFO - "repository": "", 18:04:20 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 18:04:20 INFO - "buildid": "20170224164722", 18:04:20 INFO - "pgo_build": "False", 18:04:20 INFO - "basedir": "/builds/slave/test", 18:04:20 INFO - "buildnumber": 6, 18:04:20 INFO - "slavename": "t-yosemite-r7-0296", 18:04:20 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 18:04:20 INFO - "platform": "yosemite_r7", 18:04:20 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 18:04:20 INFO - "branch": "mozilla-central", 18:04:20 INFO - "repo_path": "mozilla-central", 18:04:20 INFO - "revision": "f36062d04d165f6f6e781cf0633ffcbbebe6c273", 18:04:20 INFO - "stage_platform": "macosx64", 18:04:20 INFO - "builduid": "4bf71dc753c64f50ba1bd0f686ede5c0", 18:04:20 INFO - "slavebuilddir": "test" 18:04:20 INFO - } 18:04:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 18:04:20 INFO - Running post-action listener: _resource_record_post_action 18:04:20 INFO - [mozharness: 2017-02-25 02:04:20.951306Z] Finished read-buildbot-config step (success) 18:04:20 INFO - [mozharness: 2017-02-25 02:04:20.951441Z] Running download-and-extract step. 18:04:20 INFO - Running pre-action listener: _resource_record_pre_action 18:04:20 INFO - Running main action method: download_and_extract 18:04:20 INFO - mkdir: /builds/slave/test/build/tests 18:04:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:20 INFO - trying https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 18:04:20 INFO - Downloading https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:04:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 18:04:22 INFO - Downloaded 1199 bytes. 18:04:22 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 18:04:22 INFO - Using the following test package requirements: 18:04:22 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:04:22 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 18:04:22 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 18:04:22 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'jsshell-mac.zip'], 18:04:22 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 18:04:22 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 18:04:22 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 18:04:22 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 18:04:22 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 18:04:22 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 18:04:22 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 18:04:22 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:04:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:04:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:22 INFO - Fetch https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:04:24 INFO - Expected file size: 51742825 18:04:24 INFO - Obtained file size: 51742825 18:04:39 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:04:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 18:04:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:04:39 INFO - Fetch https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 18:04:41 INFO - Expected file size: 51742825 18:04:41 INFO - Obtained file size: 51742825 18:04:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 18:04:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:04:56 INFO - Fetch https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 18:04:57 INFO - Expected file size: 14404421 18:04:57 INFO - Obtained file size: 14404421 18:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:04:57 INFO - trying https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:04:57 INFO - Downloading https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 18:04:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 18:04:59 INFO - Downloaded 74928689 bytes. 18:04:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:04:59 INFO - mkdir: /builds/slave/test/properties 18:04:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:04:59 INFO - Writing to file /builds/slave/test/properties/build_url 18:04:59 INFO - Contents: 18:04:59 INFO - build_url:https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 18:05:01 INFO - Running post-action listener: _resource_record_post_action 18:05:01 INFO - Running post-action listener: set_extra_try_arguments 18:05:01 INFO - [mozharness: 2017-02-25 02:05:01.055361Z] Finished download-and-extract step (success) 18:05:01 INFO - [mozharness: 2017-02-25 02:05:01.055490Z] Running populate-webroot step. 18:05:01 INFO - Running pre-action listener: _resource_record_pre_action 18:05:01 INFO - Running main action method: populate_webroot 18:05:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:05:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:05:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:05:01 INFO - '--tppagecycles', 18:05:01 INFO - '7'], 18:05:01 INFO - 'tests': ['tsvgm']}}, 18:05:01 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:05:01 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:05:01 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:05:01 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:05:01 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:01 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:05:01 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['damp', 'tps']}, 18:05:01 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'tests': ['damp', 'tps']}, 18:05:01 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['dromaeo_dom']}, 18:05:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:05:01 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['basic_compositor_video']}, 18:05:01 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:05:01 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'other-e10s': {'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['a11yr', 18:05:01 INFO - 'ts_paint', 18:05:01 INFO - 'tpaint', 18:05:01 INFO - 'sessionrestore', 18:05:01 INFO - 'sessionrestore_no_auto_restore', 18:05:01 INFO - 'tabpaint']}, 18:05:01 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['tsvgx', 18:05:01 INFO - 'tsvgr_opacity', 18:05:01 INFO - 'tart', 18:05:01 INFO - 'tscrollx', 18:05:01 INFO - 'cart', 18:05:01 INFO - 'tsvg_static']}, 18:05:01 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:05:01 INFO - 'tsvgr_opacity', 18:05:01 INFO - 'tart', 18:05:01 INFO - 'tscrollx', 18:05:01 INFO - 'cart', 18:05:01 INFO - 'tsvg_static']}, 18:05:01 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'talos_options': ['--disable-e10s'], 18:05:01 INFO - 'tests': ['tp5o']}, 18:05:01 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:05:01 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'talos_options': ['--disable-e10s', 18:05:01 INFO - '--xperf_path', 18:05:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:01 INFO - 'tests': ['tp5n']}, 18:05:01 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:05:01 INFO - 'talos_options': ['--xperf_path', 18:05:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:05:01 INFO - 'tests': ['tp5n']}}, 18:05:01 INFO - 'talos.zip': {'path': '', 18:05:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:05:01 INFO - Running post-action listener: _resource_record_post_action 18:05:01 INFO - [mozharness: 2017-02-25 02:05:01.073998Z] Finished populate-webroot step (success) 18:05:01 INFO - [mozharness: 2017-02-25 02:05:01.074132Z] Running create-virtualenv step. 18:05:01 INFO - Running pre-action listener: _resource_record_pre_action 18:05:01 INFO - Running main action method: create_virtualenv 18:05:01 INFO - Creating virtualenv /builds/slave/test/build/venv 18:05:01 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 18:05:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:05:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:05:01 INFO - Using real prefix '/tools/python27' 18:05:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:05:01 INFO - Installing distribute.............................................................................................................................................................................................done. 18:05:04 INFO - Installing pip.................done. 18:05:04 INFO - Return code: 0 18:05:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:05:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:05:05 INFO - Reading from file tmpfile_stdout 18:05:05 INFO - Output received: 18:05:05 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:05:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:05 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 18:05:05 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 18:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:05 INFO - 'HOME': '/Users/cltbld', 18:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:05 INFO - 'LOGNAME': 'cltbld', 18:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:05 INFO - 'MOZ_NO_REMOTE': '1', 18:05:05 INFO - 'NO_EM_RESTART': '1', 18:05:05 INFO - 'PAGER': '/bin/cat', 18:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:05 INFO - 'PWD': '/builds/slave/test', 18:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:05 INFO - 'SHELL': '/bin/bash', 18:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:05 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:05 INFO - 'USER': 'cltbld', 18:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:05 INFO - 'XPC_FLAGS': '0x0', 18:05:05 INFO - 'XPC_SERVICE_NAME': '0', 18:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 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 18:05:05 INFO - Cleaning up... 18:05:05 INFO - Return code: 0 18:05:05 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:05 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:05 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 18:05:05 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 18:05:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:05 INFO - 'HOME': '/Users/cltbld', 18:05:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:05 INFO - 'LOGNAME': 'cltbld', 18:05:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:05 INFO - 'MOZ_NO_REMOTE': '1', 18:05:05 INFO - 'NO_EM_RESTART': '1', 18:05:05 INFO - 'PAGER': '/bin/cat', 18:05:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:05 INFO - 'PWD': '/builds/slave/test', 18:05:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:05 INFO - 'SHELL': '/bin/bash', 18:05:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:05 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:05 INFO - 'USER': 'cltbld', 18:05:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:05 INFO - 'XPC_FLAGS': '0x0', 18:05:05 INFO - 'XPC_SERVICE_NAME': '0', 18:05:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:05 INFO - Downloading/unpacking psutil>=3.1.1 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:05 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 18:05:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:05:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:09 INFO - Installing collected packages: psutil 18:05:09 INFO - Running setup.py install for psutil 18:05:09 INFO - building 'psutil._psutil_osx' extension 18:05:09 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 18:05:10 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 18:05:10 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 18:05:10 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 18:05:10 INFO - building 'psutil._psutil_posix' extension 18:05:10 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 18:05:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:05:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:05:10 INFO - ^ 18:05:10 INFO - 1 warning generated. 18:05:10 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 18:05:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:05:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:05:10 INFO - Successfully installed psutil 18:05:10 INFO - Cleaning up... 18:05:10 INFO - Return code: 0 18:05:10 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:05:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:05:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:10 INFO - 'HOME': '/Users/cltbld', 18:05:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:10 INFO - 'LOGNAME': 'cltbld', 18:05:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:10 INFO - 'MOZ_NO_REMOTE': '1', 18:05:10 INFO - 'NO_EM_RESTART': '1', 18:05:10 INFO - 'PAGER': '/bin/cat', 18:05:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:10 INFO - 'PWD': '/builds/slave/test', 18:05:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:10 INFO - 'SHELL': '/bin/bash', 18:05:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:10 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:10 INFO - 'USER': 'cltbld', 18:05:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:10 INFO - 'XPC_FLAGS': '0x0', 18:05:10 INFO - 'XPC_SERVICE_NAME': '0', 18:05:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:05:11 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 18:05:11 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 18:05:11 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 18:05:11 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 18:05:11 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 18:05:11 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 18:05:13 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:05:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:05:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:05:13 INFO - Installing collected packages: mozsystemmonitor 18:05:13 INFO - Running setup.py install for mozsystemmonitor 18:05:13 INFO - Successfully installed mozsystemmonitor 18:05:13 INFO - Cleaning up... 18:05:13 INFO - Return code: 0 18:05:13 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:13 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:13 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 18:05:13 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 18:05:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:13 INFO - 'HOME': '/Users/cltbld', 18:05:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:13 INFO - 'LOGNAME': 'cltbld', 18:05:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:13 INFO - 'MOZ_NO_REMOTE': '1', 18:05:13 INFO - 'NO_EM_RESTART': '1', 18:05:13 INFO - 'PAGER': '/bin/cat', 18:05:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:13 INFO - 'PWD': '/builds/slave/test', 18:05:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:13 INFO - 'SHELL': '/bin/bash', 18:05:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:13 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:13 INFO - 'USER': 'cltbld', 18:05:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:13 INFO - 'XPC_FLAGS': '0x0', 18:05:13 INFO - 'XPC_SERVICE_NAME': '0', 18:05:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:14 INFO - Downloading/unpacking jsonschema==2.5.1 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:05:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:16 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 18:05:17 INFO - Downloading functools32-3.2.3-2.tar.gz 18:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:05:17 INFO - warning: no files found matching '*.txt' 18:05:17 INFO - no previously-included directories found matching 'build' 18:05:17 INFO - no previously-included directories found matching 'dist' 18:05:17 INFO - no previously-included directories found matching '.git*' 18:05:17 INFO - Installing collected packages: jsonschema, functools32 18:05:17 INFO - Running setup.py install for functools32 18:05:17 INFO - warning: no files found matching '*.txt' 18:05:17 INFO - no previously-included directories found matching 'build' 18:05:17 INFO - no previously-included directories found matching 'dist' 18:05:17 INFO - no previously-included directories found matching '.git*' 18:05:17 INFO - Successfully installed jsonschema functools32 18:05:17 INFO - Cleaning up... 18:05:17 INFO - Return code: 0 18:05:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:05:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:17 INFO - 'HOME': '/Users/cltbld', 18:05:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:17 INFO - 'LOGNAME': 'cltbld', 18:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:17 INFO - 'MOZ_NO_REMOTE': '1', 18:05:17 INFO - 'NO_EM_RESTART': '1', 18:05:17 INFO - 'PAGER': '/bin/cat', 18:05:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:17 INFO - 'PWD': '/builds/slave/test', 18:05:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:17 INFO - 'SHELL': '/bin/bash', 18:05:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:17 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:17 INFO - 'USER': 'cltbld', 18:05:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:17 INFO - 'XPC_FLAGS': '0x0', 18:05:17 INFO - 'XPC_SERVICE_NAME': '0', 18:05:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:05:17 INFO - Cleaning up... 18:05:17 INFO - Return code: 0 18:05:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:17 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:17 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 18:05:17 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 18:05:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:17 INFO - 'HOME': '/Users/cltbld', 18:05:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:17 INFO - 'LOGNAME': 'cltbld', 18:05:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:17 INFO - 'MOZ_NO_REMOTE': '1', 18:05:17 INFO - 'NO_EM_RESTART': '1', 18:05:17 INFO - 'PAGER': '/bin/cat', 18:05:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:17 INFO - 'PWD': '/builds/slave/test', 18:05:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:17 INFO - 'SHELL': '/bin/bash', 18:05:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:17 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:17 INFO - 'USER': 'cltbld', 18:05:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:17 INFO - 'XPC_FLAGS': '0x0', 18:05:17 INFO - 'XPC_SERVICE_NAME': '0', 18:05:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:18 INFO - Downloading/unpacking blobuploader==1.2.4 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:18 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 18:05:20 INFO - Downloading blobuploader-1.2.4.tar.gz 18:05:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:05:20 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:05:20 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 18:05:20 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 18:05:20 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 18:05:20 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 18:05:20 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 18:05:20 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 18:05:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:05:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:05:21 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 18:05:21 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 18:05:21 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 18:05:21 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 18:05:21 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 18:05:21 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 18:05:22 INFO - Downloading docopt-0.6.1.tar.gz 18:05:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:05:22 INFO - Installing collected packages: blobuploader, requests, docopt 18:05:22 INFO - Running setup.py install for blobuploader 18:05:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:05:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:05:22 INFO - Running setup.py install for requests 18:05:22 INFO - Running setup.py install for docopt 18:05:23 INFO - Successfully installed blobuploader requests docopt 18:05:23 INFO - Cleaning up... 18:05:23 INFO - Return code: 0 18:05:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:23 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:23 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 18:05:23 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 18:05:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:23 INFO - 'HOME': '/Users/cltbld', 18:05:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:23 INFO - 'LOGNAME': 'cltbld', 18:05:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:23 INFO - 'MOZ_NO_REMOTE': '1', 18:05:23 INFO - 'NO_EM_RESTART': '1', 18:05:23 INFO - 'PAGER': '/bin/cat', 18:05:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:23 INFO - 'PWD': '/builds/slave/test', 18:05:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:23 INFO - 'SHELL': '/bin/bash', 18:05:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:23 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:23 INFO - 'USER': 'cltbld', 18:05:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:23 INFO - 'XPC_FLAGS': '0x0', 18:05:23 INFO - 'XPC_SERVICE_NAME': '0', 18:05:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Pcvx2Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-r6lOTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-MndLvB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-KqRp6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-4fSQ2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:23 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wV1e6B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-L9z_mk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-d2o0Li-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-wRot3T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-tcaBTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-H4a58L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-s01GNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-OXE2rk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-Mc0cTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-JfSJXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:24 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-HrDSsH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:25 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-IYLY8T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:05:25 INFO - Running setup.py install for manifestparser 18:05:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:05:25 INFO - Running setup.py install for mozcrash 18:05:25 INFO - Running setup.py install for mozdebug 18:05:25 INFO - Running setup.py install for mozdevice 18:05:25 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:05:25 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:05:25 INFO - Running setup.py install for mozfile 18:05:25 INFO - Running setup.py install for mozhttpd 18:05:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozinfo 18:05:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozInstall 18:05:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozleak 18:05:26 INFO - Running setup.py install for mozlog 18:05:26 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for moznetwork 18:05:26 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:05:26 INFO - Running setup.py install for mozprocess 18:05:27 INFO - Running setup.py install for mozprofile 18:05:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for mozrunner 18:05:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Running setup.py install for mozscreenshot 18:05:27 INFO - Running setup.py install for moztest 18:05:27 INFO - Running setup.py install for mozversion 18:05:27 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:05:27 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:05:27 INFO - Cleaning up... 18:05:27 INFO - Return code: 0 18:05:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:27 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:27 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 18:05:27 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 18:05:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:27 INFO - 'HOME': '/Users/cltbld', 18:05:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:27 INFO - 'LOGNAME': 'cltbld', 18:05:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:27 INFO - 'MOZ_NO_REMOTE': '1', 18:05:27 INFO - 'NO_EM_RESTART': '1', 18:05:27 INFO - 'PAGER': '/bin/cat', 18:05:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:27 INFO - 'PWD': '/builds/slave/test', 18:05:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:27 INFO - 'SHELL': '/bin/bash', 18:05:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:27 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:27 INFO - 'USER': 'cltbld', 18:05:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:27 INFO - 'XPC_FLAGS': '0x0', 18:05:27 INFO - 'XPC_SERVICE_NAME': '0', 18:05:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-AWq8od-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-qfY4A1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-OqL4a0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-V4WwK7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:05:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-xC1jdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-yHzZ7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-s6b4x0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-WJm2yp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:05:28 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)) 18:05:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:05:28 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-P62ABu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-i5QFFX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-ncycZi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-vweJtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-OjA8tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-lV5c08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-HGxM7c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:05:29 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-SSLLU_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:05:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:05:29 INFO - Running setup.py (path:/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/pip-pRdfnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:05:29 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)) 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:05:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:05:29 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)) 18:05:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:05:29 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:05:29 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:05:32 INFO - Downloading blessings-1.6.tar.gz 18:05:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:05:32 INFO - Installing collected packages: blessings 18:05:32 INFO - Running setup.py install for blessings 18:05:32 INFO - Successfully installed blessings 18:05:32 INFO - Cleaning up... 18:05:32 INFO - Return code: 0 18:05:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:05:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:33 INFO - Reading from file tmpfile_stdout 18:05:33 INFO - Current package versions: 18:05:33 INFO - blessings == 1.6 18:05:33 INFO - blobuploader == 1.2.4 18:05:33 INFO - docopt == 0.6.1 18:05:33 INFO - functools32 == 3.2.3-2 18:05:33 INFO - jsonschema == 2.5.1 18:05:33 INFO - manifestparser == 1.1 18:05:33 INFO - mozInstall == 1.12 18:05:33 INFO - mozcrash == 1.0 18:05:33 INFO - mozdebug == 0.1 18:05:33 INFO - mozdevice == 0.49 18:05:33 INFO - mozfile == 1.2 18:05:33 INFO - mozhttpd == 0.7 18:05:33 INFO - mozinfo == 0.9 18:05:33 INFO - mozleak == 0.1 18:05:33 INFO - mozlog == 3.4 18:05:33 INFO - moznetwork == 0.27 18:05:33 INFO - mozprocess == 0.25 18:05:33 INFO - mozprofile == 0.28 18:05:33 INFO - mozrunner == 6.13 18:05:33 INFO - mozscreenshot == 0.1 18:05:33 INFO - mozsystemmonitor == 0.3 18:05:33 INFO - moztest == 0.8 18:05:33 INFO - mozversion == 1.4 18:05:33 INFO - psutil == 3.1.1 18:05:33 INFO - requests == 1.2.3 18:05:33 INFO - wsgiref == 0.1.2 18:05:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:33 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 18:05:33 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 18:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:33 INFO - 'HOME': '/Users/cltbld', 18:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:33 INFO - 'LOGNAME': 'cltbld', 18:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:33 INFO - 'MOZ_NO_REMOTE': '1', 18:05:33 INFO - 'NO_EM_RESTART': '1', 18:05:33 INFO - 'PAGER': '/bin/cat', 18:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:33 INFO - 'PWD': '/builds/slave/test', 18:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:33 INFO - 'SHELL': '/bin/bash', 18:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:33 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:33 INFO - 'USER': 'cltbld', 18:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:33 INFO - 'XPC_FLAGS': '0x0', 18:05:33 INFO - 'XPC_SERVICE_NAME': '0', 18:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 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)) 18:05:33 INFO - Cleaning up... 18:05:33 INFO - Return code: 0 18:05:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:05:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:05:33 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 0x106bf7b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106bfa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbac9d51400>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, '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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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 18:05:33 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 18:05:33 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 18:05:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:33 INFO - 'HOME': '/Users/cltbld', 18:05:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:33 INFO - 'LOGNAME': 'cltbld', 18:05:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:33 INFO - 'MOZ_NO_REMOTE': '1', 18:05:33 INFO - 'NO_EM_RESTART': '1', 18:05:33 INFO - 'PAGER': '/bin/cat', 18:05:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:33 INFO - 'PWD': '/builds/slave/test', 18:05:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:33 INFO - 'SHELL': '/bin/bash', 18:05:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:33 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:33 INFO - 'USER': 'cltbld', 18:05:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:33 INFO - 'XPC_FLAGS': '0x0', 18:05:33 INFO - 'XPC_SERVICE_NAME': '0', 18:05:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:05:33 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:05:33 INFO - Cleaning up... 18:05:33 INFO - Return code: 0 18:05:33 INFO - Running post-action listener: _resource_record_post_action 18:05:33 INFO - Running post-action listener: _start_resource_monitoring 18:05:33 INFO - Starting resource monitoring. 18:05:33 INFO - [mozharness: 2017-02-25 02:05:33.795691Z] Finished create-virtualenv step (success) 18:05:33 INFO - [mozharness: 2017-02-25 02:05:33.796672Z] Running install step. 18:05:33 INFO - Running pre-action listener: _resource_record_pre_action 18:05:33 INFO - Running main action method: install 18:05:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:05:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:05:34 INFO - Reading from file tmpfile_stdout 18:05:34 INFO - Detecting whether we're running mozinstall >=1.0... 18:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:05:34 INFO - Reading from file tmpfile_stdout 18:05:34 INFO - Output received: 18:05:34 INFO - Usage: mozinstall [options] installer 18:05:34 INFO - Options: 18:05:34 INFO - -h, --help show this help message and exit 18:05:34 INFO - -d DEST, --destination=DEST 18:05:34 INFO - Directory to install application into. [default: 18:05:34 INFO - "/builds/slave/test"] 18:05:34 INFO - --app=APP Application being installed. [default: firefox] 18:05:34 INFO - mkdir: /builds/slave/test/build/application 18:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:05:54 INFO - Reading from file tmpfile_stdout 18:05:54 INFO - Output received: 18:05:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:05:54 INFO - Running post-action listener: _resource_record_post_action 18:05:54 INFO - [mozharness: 2017-02-25 02:05:54.392045Z] Finished install step (success) 18:05:54 INFO - [mozharness: 2017-02-25 02:05:54.392191Z] Running run-tests step. 18:05:54 INFO - Running pre-action listener: _resource_record_pre_action 18:05:54 INFO - Running main action method: run_tests 18:05:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:05:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:05:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:05:54 INFO - 2017-02-24 18:05:54.404 screenresolution[1636:9366] starting screenresolution argv=screenresolution get 18:05:54 INFO - 2017-02-24 18:05:54.407 screenresolution[1636:9366] Display 0: 1600x1200x32@60 18:05:54 INFO - 2017-02-24 18:05:54.412 screenresolution[1637:9369] starting screenresolution argv=screenresolution list 18:05:54 INFO - Available Modes on Display 0 18:05:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:05:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:05:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:05:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:05:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:05:54 INFO - Intel Iris: 18:05:54 INFO - Chipset Model: Intel Iris 18:05:54 INFO - Type: GPU 18:05:54 INFO - Bus: Built-In 18:05:54 INFO - VRAM (Dynamic, Max): 1536 MB 18:05:54 INFO - Vendor: Intel (0x8086) 18:05:54 INFO - Device ID: 0x0a2e 18:05:54 INFO - Revision ID: 0x0009 18:05:54 INFO - Displays: 18:05:54 INFO - Display: 18:05:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:05:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:05:54 INFO - Display Serial Number: 1600x1200 60 18:05:54 INFO - Main Display: Yes 18:05:54 INFO - Mirror: Off 18:05:54 INFO - Online: Yes 18:05:54 INFO - Rotation: Supported 18:05:54 INFO - Return code: 0 18:05:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:05:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:05:54 INFO - Python 2.7.3 18:05:54 INFO - Return code: 0 18:05:54 INFO - grabbing minidump binary from tooltool 18:05: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:05:54 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 0x1069d5d30>, '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 0x1068fcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1069cabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:05:54 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 18:05:54 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 18:05:54 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 18:05:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:05:54 INFO - Return code: 0 18:05:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:05:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:05:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:05:54 INFO - ENV: RUST_BACKTRACE is now 1 18:05:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:05:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:05:54 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/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 18:05:54 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/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0296 --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 18:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ru9EhM8V1a/Render', 18:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:05:54 INFO - 'HOME': '/Users/cltbld', 18:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:05:54 INFO - 'LOGNAME': 'cltbld', 18:05:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:05:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:05:54 INFO - 'MOZ_NO_REMOTE': '1', 18:05:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:05:54 INFO - 'NO_EM_RESTART': '1', 18:05:54 INFO - 'PAGER': '/bin/cat', 18:05:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:05:54 INFO - 'PWD': '/builds/slave/test', 18:05:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:05:54 INFO - 'RUST_BACKTRACE': '1', 18:05:54 INFO - 'SHELL': '/bin/bash', 18:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tj4GzbTG1v/Listeners', 18:05:54 INFO - 'TMPDIR': '/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/', 18:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:05:54 INFO - 'USER': 'cltbld', 18:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:05:54 INFO - 'XPC_FLAGS': '0x0', 18:05:54 INFO - 'XPC_SERVICE_NAME': '0', 18:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:05:54 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/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0296', '--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 18:05:55 INFO - mozversion application_buildid: 20170224164722 18:05:55 INFO - mozversion application_changeset: f36062d04d165f6f6e781cf0633ffcbbebe6c273 18:05:55 INFO - mozversion application_display_name: Nightly 18:05:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:05:55 INFO - mozversion application_name: Firefox 18:05:55 INFO - mozversion application_remotingname: firefox 18:05:55 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:05:55 INFO - mozversion application_vendor: Mozilla 18:05:55 INFO - mozversion application_version: 54.0a1 18:05:55 INFO - mozversion platform_buildid: 20170224164722 18:05:55 INFO - mozversion platform_changeset: f36062d04d165f6f6e781cf0633ffcbbebe6c273 18:05:55 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:05:55 INFO - mozversion platform_version: 54.0a1 18:05:55 INFO - using testdate: 1487988355 18:05:55 INFO - actual date: 1487988355 18:05:55 INFO - starting webserver on 'localhost:49249' 18:05:55 INFO - SUITE-START | Running 2 tests 18:05:55 INFO - TEST-START | dromaeo_css 18:05:55 INFO - operating with platform_type : mac_ 18:05:55 INFO - Initialising browser for dromaeo_css test... 18:05:55 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpYAWrww/profile http://localhost:49249/getInfo.html) 18:05:55 INFO - PROCESS | 1647 | 1487988355562 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 18:05:55 INFO - PROCESS | 1647 | 1487988355563 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 18:05:55 INFO - PROCESS | 1647 | 1487988355604 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 18:05:57 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 18:05:57 INFO - PROCESS | 1647 | colorDepth:24 18:05:57 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 18:05:57 INFO - PROCESS | 1647 | __metrics 18:05:57 INFO - PROCESS | 1647 | 1487988357549 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 18:05:57 INFO - PROCESS | 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:05:57 INFO - PROCESS | 1647 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:05:58 INFO - TEST-INFO | 1647: exit 0 18:05:58 INFO - Browser initialized. 18:05:58 INFO - Running cycle 1/1 for dromaeo_css test... 18:05:58 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpYAWrww/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:05:58 INFO - PROCESS | 1650 | 1487988358787 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 18:05:58 INFO - PROCESS | 1650 | 1487988358788 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 18:06:10 INFO - PROCESS | 1650 | RSS: Main: 231202816 18:06:10 INFO - PROCESS | 1650 | 18:09:14 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 18:09:17 INFO - PROCESS | 1650 | RSS: Main: 419733504 18:09:17 INFO - PROCESS | 1650 | 18:11:00 INFO - PROCESS | 1650 | ************************* 18:11:00 INFO - PROCESS | 1650 | A coding exception was thrown and uncaught in a Task. 18:11:00 INFO - PROCESS | 1650 | 18:11:00 ERROR - PROCESS | 1650 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:11:00 INFO - PROCESS | 1650 | Full stack: 18:11:00 INFO - PROCESS | 1650 | ************************* 18:12:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 18:12:21 INFO - PROCESS | 1650 | RSS: Main: 366473216 18:12:21 INFO - PROCESS | 1650 | 18:15:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 18:15:24 INFO - PROCESS | 1650 | RSS: Main: 357060608 18:15:24 INFO - PROCESS | 1650 | 18:18:26 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 18:18:27 INFO - PROCESS | 1650 | RSS: Main: 331767808 18:18:27 INFO - PROCESS | 1650 | 18:21:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 18:21:32 INFO - PROCESS | 1650 | RSS: Main: 335298560 18:21:32 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 18:24:35 INFO - PROCESS | 1650 | RSS: Main: 526073856 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | __start_tp_report 18:24:35 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 18:24:35 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 18:24:35 INFO - PROCESS | 1650 | |i|pagename|runs| 18:24:35 INFO - PROCESS | 1650 | |0;dojo.html;2828.816126951748;3304.8512816923244;3316.071499980005;3332.7500437467197;3376.746743994201;4545.409096817409;5600.691961942091;5654.9151762723595;5719.656820590768;5722.828315150547;5619.044762390393;5641.8589535261635;5693.544516438686;5701.116326969326;5703.99999999999;4783.229935587542;5104.234364845284;5111.105556527611;5174.66364686296;5184.559312458446;5269.473052694719;5306.363236411636;5311.575073994071;5339.305890234265;5350.0904846176245;5052.494750524955;5109.029284435946;5125.436202017775;5167.819126330561;5248.343957005355;5164.173732202867;5174.793008279665;5176.534111929945;5185.896282074337;5203.765830537635;5108.182690769496;5131.820386286499;5151.227315902607;5163.3545806774155;5166.07010738067;5615.887682246373;5619.887602247933;5622.297212848394;5628.296462942132;5633.492985631272;6125.998614523584;6127.92262824669;6138.002676948283;6146.139540464339;6156.230471191101;6084.939150608481;6200.565960382775;6205;6215.627062376227;6217;5731.140328950649;5736.082226843685;5737.426257374229;5743.339515955635;5770.88458230833;5631.901779153108;5769.624974376652;6213.65824879636;6225.408586184305;6227.875442491216;5984.820455386345;6048.697565121726;6061.878762424727;6124.38756124384;6140;6166.845828854314;6201.782937597252;6207.379262073847;6268.310485846553;6268.341824108494;6217.813465595949;6231.906521402183;6242.1261023456755;6245.656488893063;6247.437730604267;6100.816975490654;6259.374062593704;6278.30938596754;6279.497640188774;6281.403266689748;4924.43369012561;4933.901321973541;4941.752912354368;4946.233333833262;4977.1538838397555;4446.044100518404;4456.487503937024;4458.286674132123;4482.887927801831;4502.257127573914;4879.634027447955;5692.8861422771315;5705.372409035002;5712.2288491054005;5718.485336319751;5261.657992230493;6112.816615501542;6143;6157.014877619559;6264.279607845065;5629.986602411524;5630.943690563042;5631.577631677641;5634.774609015675;5642.802501912413;5292.894142117136;5337.359516858002;5341.839744807662;5343.385510666245;5345.3852806926925;6267.5299352548745;6268.059791031382;6274.309825919054;6277.968610157011;6282.968585157137;6127.433875357673;6143.450468055886;6150.231221097363;6152.415520525543;6202.782902598387;5864.853378665567;6248.500119990299;6267.843303917439;6275.96862015687;6279.843003924939;6216.658083805434;6217.844553886189;6219.440250377488;6233.065040244;6234.563580549318;6286.748530058747;6296.937030629635;6298;6310.927142385897;6312.4634406076;4396.340548917688;5788.623739456922;5795;5845.1232315153065;5922.3485416605;5754.597178197486;5910.172575839472;5932.79235226765;5940.732667030081;6055.212822333068;5962.970185149046;5981.342052374148;5989.071693887455;6014.398560143951;6029.909551356645;6126.5098792097415;6127.693615319127;6132.908006379818;6139.9079013813935;6147.200863887845;5632.464915832989;5665.915011274752;5993.790217342372;6004.489618382312;6022.397760223943 18:24:35 INFO - PROCESS | 1650 | |1;ext.html;455.69923850258823;465.0977104417431;472.20904984151537;498.44672413620896;535.8928214357129;28185.71814281859;40271.79864100679;41619.37570936434;41632.75101746945;42111.57888421115;42701.7864910675;43006.56993430065;43017.784911075476;43117.56882431175;43295.3505697414;9353.766155846108;9502;9516.000819913914;9534.80930381392;9582.993785652476;7467.738629147981;7654.617269136521;9623.711288661352;11417.828732569018;11470.197086203969;11337.036351910097;11401.087912966985;11411.372374519398;11415;11458;11328.886711132865;11378.772424551462;11379;11390.373529455877;11396.259243149168;7438.851222975511;7596.620168991583;7617.923820761832;7687.500312479671;7693;39527.80236098801;39793.60206397928;39889.601103988876;41589.58410415887;41776.58223417757;3728.5525736911472;3741.981290093532;3742.1018955450495;3766.4162054881263;3807.7715337079862;29655.9517083471;29883;30240.546391804142;30592;31167.53248701271;3086.9845650771604;3114.392693424783;3192.696693814084;3232.2081090132833;3239;459309;459803;463073;464833;473439;49859.50421487361;51167.23249151266;62162.0675689865;62561.06158407628;62698.68650656717;53014;59765.70117149386;67759;67770.3222967764;68460.31539684639;15714.9214253928;15803.20983950798;15813.762793558106;15821.446249381217;15821.604459888365;12009.939950300193;12075.215111017756;12119.333436661069;12120.27278363301;12158;17032.892861963934;17075.743863842054;17099.658006839793;17103.315867365414;17135.65728685419;6677.799666010027;6718.731250750013;6769.492288078413;6807.523473356917;6811.148606424197;792.5126047480873;797.6849144587856;814.1898810683408;853.7950891785927;860.4966094834467;29244.268893277836;29390.971316003837;29592.112236632936;30077.69922300749;31238.53142202869;967.3470407474927;1010.5503051142318;1039.812833689943;1047.120418848171;1068.6900798768424;13391.330433478288;14722.705545889023;15260.542183734506;15406.537803865902;15511.032962678753;579114;583254.0837295871;583845;584075;584573;59776.40223597708;59957;60080;60359;60435;1363.740889231043;1398.7482253194523;1407.479232683893;1409.563035459011;1411.5976946570383;26057.306933062424;28173.859130705034;29256.853715731286;29832.254193644465;29883;49823.750881246815;50350.7412314695;51751;51781.44655660336;54883;541673;547151;547225;548156;550365;21107.356381596026;21658.133674652836;21659;22155.77844221537;24446.877765611058;32549.021564000494;33763.493547596314;39759.40360894531;41863.37204941867;43027.56972430235;31351.945812192072;31568.842155789072;31864.7254109833;33964.49053264154;36272.81863590665;30000;34714.82642586771;35623.64376356203;35626.8218658905;35977;34008.3198336027;34146.658533414346;35108.64891351054;35190;35349;30307.696923030486;30967.845160774054;30999.225019374695;31042.844785775927;31083.222919427197;22928.082876684744;23136.6529502061;23515.764842351356;23597.174098906456;23654.763452365944 18:24:35 INFO - PROCESS | 1650 | |2;jquery.html;333.60801058755965;347.6158844476851;384.26221654423506;390.7089218532193;409.65588905319544;13267.346530693863;13977.23125228112;20309.898450507746;20668;21455;21713.782862171374;21808.672869906964;22056.779432205672;22574.774252257474;22901.885490572524;5234.214867769846;6105.358937311585;6106;6116;6116.052011938156;3815.523059617548;3922.3920292354587;5609.270794796671;5990.251218597676;6758.087658166159;6700.597964122168;6767.052612634235;6772.525923185379;6783.864322713543;6789.287124851893;6512.446442052431;6625.403713665745;6683.732650693966;6692.364225398579;6714.429273511709;5606.719664016783;5787.884242315172;5868.2371291732225;5875.970620146914;5899.5870289079785;26787.598186027226;27172.184834454998;27290.77191526386;27416.862915685495;27550.5867411989;2462.5198086373166;2681.302861256068;2708.688500822411;2744.2041807875694;2753.2841461220028;15233.390664373412;15295.541133766004;15637.921810390875;15767.659748921355;15889.28498217583;1756.350150444331;1789.9821001789946;1808.526051268212;1829.231722676479;1833.9083045847653;92456;114187;116556.41721791337;116963.83036169615;118945.15822626589;11215.719607009727;11317.83023254652;11341.829872551918;11371.374574398487;11588.594399195988;12940.935295323652;13498.392572334073;13508.757898104384;14421.855781442262;14964.102153870803;7594.2408507789;8503.404761666749;8709.433886797338;8824.647014119491;8960.327975401871;8439.789005274917;8529.275011623957;8695.30449558935;8834.249088827522;8844.380893337533;6115.4190351916495;7226.891596626055;7341.155767086747;7347.5224110432655;7393.408527317802;1877.8075921789762;1947.5879987009305;1951.1219951021812;1954.5309125809988;1956.9315073972343;956.9616965592271;989.8713167288206;1043.9164866810636;1047.350642601592;1119.2668801934747;17896.026467072432;22906.65640015401;22977.425564361023;23050;23364.883175584015;1059.8304271316551;1060.9151267898549;1102.5314241447352;1105.7677887643686;1110.3948348150277;11576.36330001841;11919.225250358872;11956.222845515014;12137;12256.203346782431;658526;660041;670766;689749;692105.5394723095;151073.24463377614;153406;154546;155040;155239;1000.364768372089;1031.9690409287734;1050.1021626509225;1054.918708323974;1056.1972900595604;18657.53356166107;20306;20357.69463458079;20539.178432862518;20552.897235513727;57219.42780572308;57660.42339576551;59110.5222369427;59155;59451.108233377396;83681;84522;85533.57233213795;87326;88673;7352.852942941218;7390.9260907390235;7854.685812567662;8212.753617391489;8608.440451370641;12476.126671133297;13057.738845222852;13311.267880266678;13423.261720605069;13446;9259.444433333752;11249.775004500028;11405.03057240144;11424.314541127227;11785.233959792586;9061.637534498546;10507.054365107177;11332.830007550057;11608.419579020847;11640.94179529097;11454.370009649549;11774.587889423829;11820.763584728085;11874.703132421759;11953.760924781629;7456;7539.181681756355;7705.1524332324525;7738.7678369649;7999.320057795153;8383;10121.190304775748;11383.430828458379;11805.468753906267;11806.46870890829 18:24:35 INFO - PROCESS | 1650 | |3;mootools.html;1429.5139652518142;1448.145594099481;1463.6926245488446;1467.0537503310363;1468.1411374346014;8032.634452143135;8465.44974576652;8472.872906906394;8480.406371553994;8482.279006284465;7559.1684914659345;8053.718119865805;8331.750047498585;8356.373272004594;8476.957615211915;2170.1427935965326;2267.489814791675;2267.818574514043;2275.1581914691587;2276.35123989663;2249.100359856054;2275.544891021794;2279.4415368234804;2280.851744636593;2283.1209984156053;2248.2580748353084;2257.1761307122865;2268.092762894839;2273.329367836492;2275.5107651854846;1853.3883818339916;2145.5279838435517;2176.6082105221053;2183.5370301897765;2215.601191785478;2189.6606026065983;2256.4922892349255;2256.672782446536;2260.7513173550897;2265.1279257485985;8405.873911891327;8443.957780211122;8462.15378462155;8464.02663693677;8477;4990.87522811929;5148.974255128738;5163.122269214243;5163.974180129076;5165.612579056586;2983.731464168235;3277.4919887417486;3430.6054803697393;3485.843137058813;3488.982555087234;1193.337697577844;1194.593838095043;1209.099221080292;1230.4831970572382;1260.0549587809144;9666.661666841626;9992.65025724096;9997.600095996151;10751.3011654243;10761.677149685522;4037.7981100944835;4138.730982486129;4139.172165566899;4139.606737359946;4140.275451795924;9291.953540232256;9495.810083798424;9531.809363812685;9537.523123843781;9640.614375425044;4926.729029903389;5136.0241554104605;5168.61235407346;5171.793128274902;5175.922361164586;4511.887202819956;4697.812087516461;4712.198926182488;4741.5495527924795;4760.976195119002;3657.085728567858;3693.279810436939;3792.241551689618;3828.559715632682;3830.540335159799;1208.7763763703567;1231.9137660363688;1237.3503910447087;1256.5727652598162;1257.7987521996436;706.159669992707;711.2638419236066;729.3399473476512;742.962851857405;771.9421043421765;4915.26271059344;5114.590832733446;5317.601179911522;5401.810936617199;5549.19536667181;547.824696097253;554.6339415985511;561.3123923194055;562.1820251534008;585.1398444286891;3435.450327947566;3525.347810655037;3542.1852973816167;3669.9082522936606;3731.9067023324633;8197.508149511048;9308;9773.951130244302;9883.45640989738;9899.851502227473;9658.613655453844;9844.261680374;9861.359011664219;9863.80272394548;9864.704058878246;7479.588622625808;7607.733729319558;7622.352100071556;7740.458167928304;7752.961235193788;5197.4282828889545;5224.895502089861;5330.413654498079;5388.8579165338715;5454;9069.501177435304;9197.310201734763;9214.308926830381;9236.72289831306;9239.907600924174;8409.95795021021;8721.869171962298;8857;9159.221466175182;9295.90842060531;6543.967280163569;6905.274946130584;7071.080759501232;7088.610126443095;7133.928660713534;7653.464257501922;7804.219578042151;7868.055833300041;7902.841943161219;7908.051033875971;5926.059170651469;6959.61722105289;7007.439404847498;7110.004599356198;7133.357997780225;6820.658967051727;6826.112605361272;6959.756408525677;7031.156261248683;7036.120484939383;6240.750369985331;6263.937360626335;6285.96857015712;6359.173307470184;6371.171747672773;4762.380890484214;4807.423109226916;4816.073570571659;4819.373481447389;4985.750712464434;4655.11552804966;4721.244600863845;4766.499517550607;4789.44921334051;4853.441854186673 18:24:35 INFO - PROCESS | 1650 | |4;prototype.html;330.8180500724601;344.54003904787106;360.44311538672747;360.79254428703496;365.25123496831486;5247.580193584518;5290.444503327149;5370.409254981953;5406.216098665691;5421.9457805421935;5047.47001564836;5058.949410505894;5280.046382838345;5317.813876514323;5378.757955891984;3401.795892246473;3510.947335789965;4031.9596804031876;4041.2923341361357;4083.5099788025404;4057.9594204057876;4061.939070913939;4079;4085.5301640311445;4089.20260549193;3491.5460990071256;3895.2599006188875;3991.940120898189;4039.596040395966;4059.8985025374304;3377.4596064629786;3635.4001589737795;3750.5055094286863;3993.0616305168264;4005.2590270799706;3475.2371864367888;3475.9652403475893;3482.512448257246;3486.9476957845654;3491.30173965208;4871.951280487221;5236.188390799432;5246.7901283948595;5290.984139040748;5292.629515933886;4509.842155524574;4578.253048171104;4601.608863246628;4607.746573938432;4639.028412924527;4994.725290109042;5197.272381866542;5233.685978841282;5596.804111856066;5620.61124022293;3521.8239088045752;3835.0412396900774;3843.6348546888003;3848.9230215395537;3892.469894568553;5413;5480.561555075585;5589.972050139764;5630.746616402272;5826.902315166537;3405.7786243894066;3636.1818590816856;3674.4488326751202;3680.2150956785936;3686.612905644922;3723.3298006358573;3821.872547598465;3873.4189871518934;3902.863399780994;3922.2351641429645;4259.446271984684;4535.455745310518;4538.546145385435;4542;4574.268117101248;4461.620762235181;4742.051589682077;4825.793551612097;5364.061289274361;5600.551955843523;1132.4790596325784;1245.7819881520697;1251.4493622805937;1257.7421628566121;1263.6335462715892;740.8147963009247;826.5454000299811;850.2985037344216;851.29342645604;864.7708357285198;4490.955090449119;4722.716637001791;4875.561199491992;4948.38145231846;4996.525330093636;5119.820806271762;5445.83662490126;5491.2312276281355;5502.147167189092;5513.669179849142;4197.916041679149;4740.644451666139;4906.803727850917;4936.482393979097;4940.431850337185;2658.5480468320434;2703.635009273762;2797;2833.5324671429394;2850.7149285071328;4725.983913458623;4989.875253118628;5076.568491678248;5583.426606971541;5633.577481688808;5046.974765126224;5178.637495375363;5210.879660872931;5350.121162157405;5554.91667624986;4945.975270123627;5022.7990880365105;5627.437256274422;5631.1271752878365;5654.575906806924;4594.655400845017;4718;4730.42192005351;5446.346438427256;5714.571407144397;4954.801807927643;5344.4121146674615;5361.383440904346;5370.409254981871;5377.3816011159215;5093.363329583802;5521.420250873601;5523.54202379937;5636.915446268227;5644.717764111861;3018.5321275202377;3660.670539651444;3782.3759079751535;3791.8483260669263;3806.2958352704836;5698.546870547983;5733.827985160452;5743.684097374685;5753.798617048383;5771.940871338197;5052.8231511898375;5341.465853414627;5388.784448622011;5506.64206826555;5516.9448305518035;4538.795754191004;5479.808206712746;5517.503424691797;5528.756029893242;5528.834134975958;4857.421338065073;5071.199724097892;5447.700376479173;5454.181872719124;5467.289252397163;4666.696664716783;5501.862453438696;5532.146730845051;5560.332760068818;5562;4682.946337074131;5010.423801262756;5373.623846330719;5451.291332126798;5487.807926722546 18:24:35 INFO - PROCESS | 1650 | |5;yui.html;694.7776711452334;717.0642311783121;722.9457790665701;758.7647829172956;813.5240884082812;16931.915340423297;17566.385176518823;17586.384476543324;17602.20790064447;17612.471625851227;16094.75857862133;16131.758023629654;16143.112128832909;16177.433789817358;16254.349826006946;876.1413814461831;897.6274845938927;899.8695189197563;901.1889299630319;904.6878826804742;878.227160099115;897.5736525150567;901.4591245252828;901.5807649443008;917.2524392620027;955.5461155950937;956.1155930764015;956.2780101023717;957.6504575829841;959.7360725800426;950.3680052764903;950.4582388038821;951.3435729346763;952.8570714392828;953.9332246742731;711.0791524975118;711.4735096028901;713.3437237741291;714.0681410758957;714.4570126703691;15178.924105379512;15280.847191528164;15337.846621533865;15391.538253852403;15408.075515468996;13602.795958060637;13770.173789572658;13908.791368129487;13984.93007534966;14187.219702916336;11120.944395278053;11338.740283096928;11343.943280283629;11587.30476171424;12007.759844803142;3594.137407022322;3597.3524765542193;3604.7837129772297;3627.7097832173627;3764.4729737836724;98649;100643;101553;102183.97816021893;103950.96049039564;10298.17614590846;10355.844662330072;10398.064174224357;10402.47987600617;10704.464776761131;21529.56940861174;21799.45501362447;22087.66868496974;22240.332790016324;22259.332220033426;7362.042934418599;7467.066616672916;7478.663460144307;7676.270754278335;7732.072151341875;3968.8015599219925;4010.542564861091;4151.750894946313;4220.324748040361;4230.048239146229;3548.982255088708;3600.5859326177824;3656.1408069104273;3664.5419322584676;3680.190358121209;662.9701663425158;664.6007911439699;668.906353110565;670.76858483823;681.2846511163259;642.1844257792577;647.6179054357951;650.9121268628769;659.1365311442026;664.1664710788027;7843.9215607844335;9713.36863103896;9759.512024398751;9767.609295628237;9779.217662586976;627.1909237084202;645.9967700161469;659.2780904909117;661.576590981772;670.715579664946;4156.605122513356;4742.340180936644;4746.931334416254;4797.880052998633;4800.087928338463;95141.5728764069;97956.04087918202;98097;99455;101077.9353238808;82446.58776706079;83280;83756.74364884531;83986.16013839905;86064.56967715122;70949;71313.64343178355;72383.6380818103;72406;72740;9195.494247816434;9207.677731279373;9234.492102934402;9285.257179425495;9287.814243715224;30213.848930756085;40863.59136408598;42003.57996419997;44975.65073047814;48394;48496;54542;56065;57126.143107854245;57255.141172883275;12401.62795116148;12483;12576.056795740538;12869.35653217354;13238.933805330911;13865.514706985206;13925.234112123526;14130.222837744022;14179.787303190255;14236.359363828446;13112.672183195496;13214.075014748876;13231.08425112311;13383.263920484467;13468.326583670581;12932.870671293167;13164.407601657756;13207.207567545978;13355.732885342433;13542.187468751907;12649;12798.87201128014;12861.807072893727;13107.868921310665;13142.540011099565;10528.263021588722;10683;10685.626003089854;10949.372428545019;11494.54021839117;8999.595018224065;10155.55622075222;10361.015703508154;10386;10422.94943050139 18:24:35 INFO - PROCESS | 1650 | __end_tp_report 18:24:35 INFO - PROCESS | 1650 | __start_cc_report 18:24:35 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,2118 18:24:35 INFO - PROCESS | 1650 | __end_cc_report 18:24:35 INFO - PROCESS | 1650 | __startTimestamp1487989475525__endTimestamp 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | ------- Summary: start ------- 18:24:35 INFO - PROCESS | 1650 | Number of tests: 6 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5672.59 Median:5788.62 stddev:658.22 (11.4%) stddev-sans-first:621.28 18:24:35 INFO - PROCESS | 1650 | Values: 2828.8 3304.9 3316.1 3332.8 3376.7 4545.4 5600.7 5654.9 5719.7 5722.8 5619.0 5641.9 5693.5 5701.1 5704.0 4783.2 5104.2 5111.1 5174.7 5184.6 5269.5 5306.4 5311.6 5339.3 5350.1 5052.5 5109.0 5125.4 5167.8 5248.3 5164.2 5174.8 5176.5 5185.9 5203.8 5108.2 5131.8 5151.2 5163.4 5166.1 5615.9 5619.9 5622.3 5628.3 5633.5 6126.0 6127.9 6138.0 6146.1 6156.2 6084.9 6200.6 6205.0 6215.6 6217.0 5731.1 5736.1 5737.4 5743.3 5770.9 5631.9 5769.6 6213.7 6225.4 6227.9 5984.8 6048.7 6061.9 6124.4 6140.0 6166.8 6201.8 6207.4 6268.3 6268.3 6217.8 6231.9 6242.1 6245.7 6247.4 6100.8 6259.4 6278.3 6279.5 6281.4 4924.4 4933.9 4941.8 4946.2 4977.2 4446.0 4456.5 4458.3 4482.9 4502.3 4879.6 5692.9 5705.4 5712.2 5718.5 5261.7 6112.8 6143.0 6157.0 6264.3 5630.0 5630.9 5631.6 5634.8 5642.8 5292.9 5337.4 5341.8 5343.4 5345.4 6267.5 6268.1 6274.3 6278.0 6283.0 6127.4 6143.5 6150.2 6152.4 6202.8 5864.9 6248.5 6267.8 6276.0 6279.8 6216.7 6217.8 6219.4 6233.1 6234.6 6286.7 6296.9 6298.0 6310.9 6312.5 4396.3 5788.6 5795.0 5845.1 5922.3 5754.6 5910.2 5932.8 5940.7 6055.2 5963.0 5981.3 5989.1 6014.4 6029.9 6126.5 6127.7 6132.9 6139.9 6147.2 5632.5 5665.9 5993.8 6004.5 6022.4 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:66152.64 Median:35625.23 stddev:142470.19 (399.9%) stddev-sans-first:142784.47 18:24:35 INFO - PROCESS | 1650 | Values: 455.7 465.1 472.2 498.4 535.9 28185.7 40271.8 41619.4 41632.8 42111.6 42701.8 43006.6 43017.8 43117.6 43295.4 9353.8 9502.0 9516.0 9534.8 9583.0 7467.7 7654.6 9623.7 11417.8 11470.2 11337.0 11401.1 11411.4 11415.0 11458.0 11328.9 11378.8 11379.0 11390.4 11396.3 7438.9 7596.6 7617.9 7687.5 7693.0 39527.8 39793.6 39889.6 41589.6 41776.6 3728.6 3742.0 3742.1 3766.4 3807.8 29656.0 29883.0 30240.5 30592.0 31167.5 3087.0 3114.4 3192.7 3232.2 3239.0 459309.0 459803.0 463073.0 464833.0 473439.0 49859.5 51167.2 62162.1 62561.1 62698.7 53014.0 59765.7 67759.0 67770.3 68460.3 15714.9 15803.2 15813.8 15821.4 15821.6 12009.9 12075.2 12119.3 12120.3 12158.0 17032.9 17075.7 17099.7 17103.3 17135.7 6677.8 6718.7 6769.5 6807.5 6811.1 792.5 797.7 814.2 853.8 860.5 29244.3 29391.0 29592.1 30077.7 31238.5 967.3 1010.6 1039.8 1047.1 1068.7 13391.3 14722.7 15260.5 15406.5 15511.0 579114.0 583254.1 583845.0 584075.0 584573.0 59776.4 59957.0 60080.0 60359.0 60435.0 1363.7 1398.7 1407.5 1409.6 1411.6 26057.3 28173.9 29256.9 29832.3 29883.0 49823.8 50350.7 51751.0 51781.4 54883.0 541673.0 547151.0 547225.0 548156.0 550365.0 21107.4 21658.1 21659.0 22155.8 24446.9 32549.0 33763.5 39759.4 41863.4 43027.6 31351.9 31568.8 31864.7 33964.5 36272.8 30000.0 34714.8 35623.6 35626.8 35977.0 34008.3 34146.7 35108.6 35190.0 35349.0 30307.7 30967.8 30999.2 31042.8 31083.2 22928.1 23136.7 23515.8 23597.2 23654.8 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:38452.67 Median:22942.04 stddev:112491.68 (490.3%) stddev-sans-first:112770.84 18:24:35 INFO - PROCESS | 1650 | Values: 333.6 347.6 384.3 390.7 409.7 13267.3 13977.2 20309.9 20668.0 21455.0 21713.8 21808.7 22056.8 22574.8 22901.9 5234.2 6105.4 6106.0 6116.0 6116.1 3815.5 3922.4 5609.3 5990.3 6758.1 6700.6 6767.1 6772.5 6783.9 6789.3 6512.4 6625.4 6683.7 6692.4 6714.4 5606.7 5787.9 5868.2 5876.0 5899.6 26787.6 27172.2 27290.8 27416.9 27550.6 2462.5 2681.3 2708.7 2744.2 2753.3 15233.4 15295.5 15637.9 15767.7 15889.3 1756.4 1790.0 1808.5 1829.2 1833.9 92456.0 114187.0 116556.4 116963.8 118945.2 11215.7 11317.8 11341.8 11371.4 11588.6 12940.9 13498.4 13508.8 14421.9 14964.1 7594.2 8503.4 8709.4 8824.6 8960.3 8439.8 8529.3 8695.3 8834.2 8844.4 6115.4 7226.9 7341.2 7347.5 7393.4 1877.8 1947.6 1951.1 1954.5 1956.9 957.0 989.9 1043.9 1047.4 1119.3 17896.0 22906.7 22977.4 23050.0 23364.9 1059.8 1060.9 1102.5 1105.8 1110.4 11576.4 11919.2 11956.2 12137.0 12256.2 658526.0 660041.0 670766.0 689749.0 692105.5 151073.2 153406.0 154546.0 155040.0 155239.0 1000.4 1032.0 1050.1 1054.9 1056.2 18657.5 20306.0 20357.7 20539.2 20552.9 57219.4 57660.4 59110.5 59155.0 59451.1 83681.0 84522.0 85533.6 87326.0 88673.0 7352.9 7390.9 7854.7 8212.8 8608.4 12476.1 13057.7 13311.3 13423.3 13446.0 9259.4 11249.8 11405.0 11424.3 11785.2 9061.6 10507.1 11332.8 11608.4 11640.9 11454.4 11774.6 11820.8 11874.7 11953.8 7456.0 7539.2 7705.2 7738.8 7999.3 8383.0 10121.2 11383.4 11805.5 11806.5 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5299.22 Median:5271.25 stddev:2927.44 (55.5%) stddev-sans-first:2921.21 18:24:35 INFO - PROCESS | 1650 | Values: 1429.5 1448.1 1463.7 1467.1 1468.1 8032.6 8465.4 8472.9 8480.4 8482.3 7559.2 8053.7 8331.8 8356.4 8477.0 2170.1 2267.5 2267.8 2275.2 2276.4 2249.1 2275.5 2279.4 2280.9 2283.1 2248.3 2257.2 2268.1 2273.3 2275.5 1853.4 2145.5 2176.6 2183.5 2215.6 2189.7 2256.5 2256.7 2260.8 2265.1 8405.9 8444.0 8462.2 8464.0 8477.0 4990.9 5149.0 5163.1 5164.0 5165.6 2983.7 3277.5 3430.6 3485.8 3489.0 1193.3 1194.6 1209.1 1230.5 1260.1 9666.7 9992.7 9997.6 10751.3 10761.7 4037.8 4138.7 4139.2 4139.6 4140.3 9292.0 9495.8 9531.8 9537.5 9640.6 4926.7 5136.0 5168.6 5171.8 5175.9 4511.9 4697.8 4712.2 4741.5 4761.0 3657.1 3693.3 3792.2 3828.6 3830.5 1208.8 1231.9 1237.4 1256.6 1257.8 706.2 711.3 729.3 743.0 771.9 4915.3 5114.6 5317.6 5401.8 5549.2 547.8 554.6 561.3 562.2 585.1 3435.5 3525.3 3542.2 3669.9 3731.9 8197.5 9308.0 9774.0 9883.5 9899.9 9658.6 9844.3 9861.4 9863.8 9864.7 7479.6 7607.7 7622.4 7740.5 7753.0 5197.4 5224.9 5330.4 5388.9 5454.0 9069.5 9197.3 9214.3 9236.7 9239.9 8410.0 8721.9 8857.0 9159.2 9295.9 6544.0 6905.3 7071.1 7088.6 7133.9 7653.5 7804.2 7868.1 7902.8 7908.1 5926.1 6959.6 7007.4 7110.0 7133.4 6820.7 6826.1 6959.8 7031.2 7036.1 6240.8 6263.9 6286.0 6359.2 6371.2 4762.4 4807.4 4816.1 4819.4 4985.8 4655.1 4721.2 4766.5 4789.4 4853.4 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4407.55 Median:4947.18 stddev:1339.84 (27.1%) stddev-sans-first:1308.19 18:24:35 INFO - PROCESS | 1650 | Values: 330.8 344.5 360.4 360.8 365.3 5247.6 5290.4 5370.4 5406.2 5421.9 5047.5 5058.9 5280.0 5317.8 5378.8 3401.8 3510.9 4032.0 4041.3 4083.5 4058.0 4061.9 4079.0 4085.5 4089.2 3491.5 3895.3 3991.9 4039.6 4059.9 3377.5 3635.4 3750.5 3993.1 4005.3 3475.2 3476.0 3482.5 3486.9 3491.3 4872.0 5236.2 5246.8 5291.0 5292.6 4509.8 4578.3 4601.6 4607.7 4639.0 4994.7 5197.3 5233.7 5596.8 5620.6 3521.8 3835.0 3843.6 3848.9 3892.5 5413.0 5480.6 5590.0 5630.7 5826.9 3405.8 3636.2 3674.4 3680.2 3686.6 3723.3 3821.9 3873.4 3902.9 3922.2 4259.4 4535.5 4538.5 4542.0 4574.3 4461.6 4742.1 4825.8 5364.1 5600.6 1132.5 1245.8 1251.4 1257.7 1263.6 740.8 826.5 850.3 851.3 864.8 4491.0 4722.7 4875.6 4948.4 4996.5 5119.8 5445.8 5491.2 5502.1 5513.7 4197.9 4740.6 4906.8 4936.5 4940.4 2658.5 2703.6 2797.0 2833.5 2850.7 4726.0 4989.9 5076.6 5583.4 5633.6 5047.0 5178.6 5210.9 5350.1 5554.9 4946.0 5022.8 5627.4 5631.1 5654.6 4594.7 4718.0 4730.4 5446.3 5714.6 4954.8 5344.4 5361.4 5370.4 5377.4 5093.4 5521.4 5523.5 5636.9 5644.7 3018.5 3660.7 3782.4 3791.8 3806.3 5698.5 5733.8 5743.7 5753.8 5771.9 5052.8 5341.5 5388.8 5506.6 5516.9 4538.8 5479.8 5517.5 5528.8 5528.8 4857.4 5071.2 5447.7 5454.2 5467.3 4666.7 5501.9 5532.1 5560.3 5562.0 4682.9 5010.4 5373.6 5451.3 5487.8 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:19299.79 Median:23111.95 stddev:27275.20 (118.0%) stddev-sans-first:27315.94 18:24:35 INFO - PROCESS | 1650 | Values: 694.8 717.1 722.9 758.8 813.5 16931.9 17566.4 17586.4 17602.2 17612.5 16094.8 16131.8 16143.1 16177.4 16254.3 876.1 897.6 899.9 901.2 904.7 878.2 897.6 901.5 901.6 917.3 955.5 956.1 956.3 957.7 959.7 950.4 950.5 951.3 952.9 953.9 711.1 711.5 713.3 714.1 714.5 15178.9 15280.8 15337.8 15391.5 15408.1 13602.8 13770.2 13908.8 13984.9 14187.2 11120.9 11338.7 11343.9 11587.3 12007.8 3594.1 3597.4 3604.8 3627.7 3764.5 98649.0 100643.0 101553.0 102184.0 103951.0 10298.2 10355.8 10398.1 10402.5 10704.5 21529.6 21799.5 22087.7 22240.3 22259.3 7362.0 7467.1 7478.7 7676.3 7732.1 3968.8 4010.5 4151.8 4220.3 4230.0 3549.0 3600.6 3656.1 3664.5 3680.2 663.0 664.6 668.9 670.8 681.3 642.2 647.6 650.9 659.1 664.2 7843.9 9713.4 9759.5 9767.6 9779.2 627.2 646.0 659.3 661.6 670.7 4156.6 4742.3 4746.9 4797.9 4800.1 95141.6 97956.0 98097.0 99455.0 101077.9 82446.6 83280.0 83756.7 83986.2 86064.6 70949.0 71313.6 72383.6 72406.0 72740.0 9195.5 9207.7 9234.5 9285.3 9287.8 30213.8 40863.6 42003.6 44975.7 48394.0 48496.0 54542.0 56065.0 57126.1 57255.1 12401.6 12483.0 12576.1 12869.4 13238.9 13865.5 13925.2 14130.2 14179.8 14236.4 13112.7 13214.1 13231.1 13383.3 13468.3 12932.9 13164.4 13207.2 13355.7 13542.2 12649.0 12798.9 12861.8 13107.9 13142.5 10528.3 10683.0 10685.6 10949.4 11494.5 8999.6 10155.6 10361.0 10386.0 10422.9 18:24:35 INFO - PROCESS | 1650 | -------- Summary: end -------- 18:24:35 INFO - PROCESS | 1650 | 18:24:35 INFO - PROCESS | 1650 | 1487989475550 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 18:24:36 INFO - TEST-INFO | 1650: exit 0 18:24:36 INFO - TEST-OK | dromaeo_css | took 1121229ms 18:24:36 INFO - TEST-START | kraken 18:24:36 INFO - operating with platform_type : mac_ 18:24:36 INFO - Initialising browser for kraken test... 18:24:36 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpaRbMwf/profile http://localhost:49249/getInfo.html) 18:24:36 INFO - PROCESS | 1655 | 1487989476743 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 18:24:36 INFO - PROCESS | 1655 | 1487989476744 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 18:24:36 INFO - PROCESS | 1655 | 1487989476785 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 18:24:38 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 18:24:38 INFO - PROCESS | 1655 | colorDepth:24 18:24:38 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 18:24:38 INFO - PROCESS | 1655 | __metrics 18:24:38 INFO - PROCESS | 1655 | 1487989478871 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 18:24:38 INFO - PROCESS | 1655 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:24:38 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:24:39 INFO - TEST-INFO | 1655: exit 0 18:24:39 INFO - Browser initialized. 18:24:39 INFO - Running cycle 1/1 for kraken test... 18:24:39 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/T/tmpaRbMwf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:24:40 INFO - PROCESS | 1656 | 1487989480058 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 18:24:40 INFO - PROCESS | 1656 | 1487989480059 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 18:24:51 INFO - PROCESS | 1656 | RSS: Main: 232701952 18:24:51 INFO - PROCESS | 1656 | 18:24:54 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 18:24:55 INFO - PROCESS | 1656 | RSS: Main: 284028928 18:24:55 INFO - PROCESS | 1656 | 18:24:58 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 18:24:58 INFO - PROCESS | 1656 | RSS: Main: 285556736 18:24:58 INFO - PROCESS | 1656 | 18:25:02 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 18:25:02 INFO - PROCESS | 1656 | RSS: Main: 377769984 18:25:02 INFO - PROCESS | 1656 | 18:25:04 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 18:25:05 INFO - PROCESS | 1656 | RSS: Main: 281268224 18:25:05 INFO - PROCESS | 1656 | 18:25:07 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:25:08 INFO - PROCESS | 1656 | RSS: Main: 284295168 18:25:08 INFO - PROCESS | 1656 | 18:25:11 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 18:25:11 INFO - PROCESS | 1656 | RSS: Main: 326397952 18:25:11 INFO - PROCESS | 1656 | 18:25:14 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 18:25:15 INFO - PROCESS | 1656 | RSS: Main: 327184384 18:25:15 INFO - PROCESS | 1656 | 18:25:18 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 18:25:18 INFO - PROCESS | 1656 | RSS: Main: 325255168 18:25:18 INFO - PROCESS | 1656 | 18:25:20 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:25:21 INFO - PROCESS | 1656 | RSS: Main: 301129728 18:25:21 INFO - PROCESS | 1656 | 18:25:23 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:25:23 INFO - PROCESS | 1656 | RSS: Main: 305610752 18:25:23 INFO - PROCESS | 1656 | 18:25:25 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:25:26 INFO - PROCESS | 1656 | RSS: Main: 306790400 18:25:26 INFO - PROCESS | 1656 | 18:25:29 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:25:30 INFO - PROCESS | 1656 | RSS: Main: 323297280 18:25:30 INFO - PROCESS | 1656 | 18:25:33 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:25:33 INFO - PROCESS | 1656 | RSS: Main: 317333504 18:25:33 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 18:25:35 INFO - PROCESS | 1656 | RSS: Main: 362336256 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | __start_tp_report 18:25:35 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 18:25:35 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 18:25:35 INFO - PROCESS | 1656 | |i|pagename|runs| 18:25:35 INFO - PROCESS | 1656 | |0;ai-astar;114;113;113;113;112;112;113;110;113;122 18:25:35 INFO - PROCESS | 1656 | |1;audio-beat-detection;171;110;113;149;118;115;113;111;133;112 18:25:35 INFO - PROCESS | 1656 | |2;audio-dft;187;184;184;183;185;185;183;183;185;183 18:25:35 INFO - PROCESS | 1656 | |3;audio-fft;84;77;82;84;80;84;84;62;77;58 18:25:35 INFO - PROCESS | 1656 | |4;audio-oscillator;92;71;87;92;100;99;120;69;68;85 18:25:35 INFO - PROCESS | 1656 | |5;imaging-gaussian-blur;89;89;89;89;90;89;88;85;89;90 18:25:35 INFO - PROCESS | 1656 | |6;imaging-darkroom;95;95;95;95;96;95;95;96;94;95 18:25:35 INFO - PROCESS | 1656 | |7;imaging-desaturate;87;85;85;85;86;85;85;86;85;84 18:25:35 INFO - PROCESS | 1656 | |8;json-parse-financial;60;60;59;60;61;61;60;61;60;62 18:25:35 INFO - PROCESS | 1656 | |9;json-stringify-tinderbox;67;73;69;69;76;71;71;69;67;71 18:25:35 INFO - PROCESS | 1656 | |10;stanford-crypto-aes;89;88;90;88;88;88;91;86;85;90 18:25:35 INFO - PROCESS | 1656 | |11;stanford-crypto-ccm;143;146;140;152;134;142;141;143;184;153 18:25:35 INFO - PROCESS | 1656 | |12;stanford-crypto-pbkdf2;175;175;177;171;174;174;176;172;172;170 18:25:35 INFO - PROCESS | 1656 | |13;stanford-crypto-sha256-iterative;68;68;67;68;68;66;66;65;65;67 18:25:35 INFO - PROCESS | 1656 | __end_tp_report 18:25:35 INFO - PROCESS | 1656 | __start_cc_report 18:25:35 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,2187 18:25:35 INFO - PROCESS | 1656 | __end_cc_report 18:25:35 INFO - PROCESS | 1656 | __startTimestamp1487989535771__endTimestamp 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | ------- Summary: start ------- 18:25:35 INFO - PROCESS | 1656 | Number of tests: 14 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#0] ai-astar Cycles:10 Average:113.50 Median:113.00 stddev:3.17 (2.8%) stddev-sans-first:3.36 18:25:35 INFO - PROCESS | 1656 | Values: 114.0 113.0 113.0 113.0 112.0 112.0 113.0 110.0 113.0 122.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#1] audio-beat-detection Cycles:10 Average:124.50 Median:116.50 stddev:20.50 (17.6%) stddev-sans-first:13.12 18:25:35 INFO - PROCESS | 1656 | Values: 171.0 110.0 113.0 149.0 118.0 115.0 113.0 111.0 133.0 112.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#2] audio-dft Cycles:10 Average:184.20 Median:184.50 stddev:1.32 (0.7%) stddev-sans-first:0.93 18:25:35 INFO - PROCESS | 1656 | Values: 187.0 184.0 184.0 183.0 185.0 185.0 183.0 183.0 185.0 183.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#3] audio-fft Cycles:10 Average:77.20 Median:83.00 stddev:9.52 (11.5%) stddev-sans-first:9.77 18:25:35 INFO - PROCESS | 1656 | Values: 84.0 77.0 82.0 84.0 80.0 84.0 84.0 62.0 77.0 58.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#4] audio-oscillator Cycles:10 Average:88.30 Median:86.00 stddev:16.26 (18.9%) stddev-sans-first:17.19 18:25:35 INFO - PROCESS | 1656 | Values: 92.0 71.0 87.0 92.0 100.0 99.0 120.0 69.0 68.0 85.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.00 stddev:1.42 (1.6%) stddev-sans-first:1.50 18:25:35 INFO - PROCESS | 1656 | Values: 89.0 89.0 89.0 89.0 90.0 89.0 88.0 85.0 89.0 90.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#6] imaging-darkroom Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 18:25:35 INFO - PROCESS | 1656 | Values: 95.0 95.0 95.0 95.0 96.0 95.0 95.0 96.0 94.0 95.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:0.82 (1.0%) stddev-sans-first:0.60 18:25:35 INFO - PROCESS | 1656 | Values: 87.0 85.0 85.0 85.0 86.0 85.0 85.0 86.0 85.0 84.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.50 stddev:0.84 (1.4%) stddev-sans-first:0.88 18:25:35 INFO - PROCESS | 1656 | Values: 60.0 60.0 59.0 60.0 61.0 61.0 60.0 61.0 60.0 62.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.00 stddev:2.75 (3.9%) stddev-sans-first:2.65 18:25:35 INFO - PROCESS | 1656 | Values: 67.0 73.0 69.0 69.0 76.0 71.0 71.0 69.0 67.0 71.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:88.50 stddev:1.83 (2.1%) stddev-sans-first:1.92 18:25:35 INFO - PROCESS | 1656 | Values: 89.0 88.0 90.0 88.0 88.0 88.0 91.0 86.0 85.0 90.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:147.80 Median:144.50 stddev:13.89 (9.6%) stddev-sans-first:14.62 18:25:35 INFO - PROCESS | 1656 | Values: 143.0 146.0 140.0 152.0 134.0 142.0 141.0 143.0 184.0 153.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.60 Median:174.50 stddev:2.27 (1.3%) stddev-sans-first:2.35 18:25:35 INFO - PROCESS | 1656 | Values: 175.0 175.0 177.0 171.0 174.0 174.0 176.0 172.0 172.0 170.0 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.50 stddev:1.23 (1.8%) stddev-sans-first:1.22 18:25:35 INFO - PROCESS | 1656 | Values: 68.0 68.0 67.0 68.0 68.0 66.0 66.0 65.0 65.0 67.0 18:25:35 INFO - PROCESS | 1656 | -------- Summary: end -------- 18:25:35 INFO - PROCESS | 1656 | 18:25:35 INFO - PROCESS | 1656 | 1487989535793 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 18:25:36 INFO - TEST-INFO | 1656: exit 0 18:25:36 INFO - TEST-OK | kraken | took 60479ms 18:25:36 INFO - SUITE-END | took 1181s 18:25:36 INFO - Completed test suite (00:19:41) 18:25:36 INFO - javascript benchmark 18:25:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7175.726442975477, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2828.816126951748, 3304.8512816923244, 3316.071499980005, 3332.7500437467197, 3376.746743994201, 4545.409096817409, 5600.691961942091, 5654.9151762723595, 5719.656820590768, 5722.828315150547, 5619.044762390393, 5641.8589535261635, 5693.544516438686, 5701.116326969326, 5703.99999999999, 4783.229935587542, 5104.234364845284, 5111.105556527611, 5174.66364686296, 5184.559312458446, 5269.473052694719, 5306.363236411636, 5311.575073994071, 5339.305890234265, 5350.0904846176245, 5052.494750524955, 5109.029284435946, 5125.436202017775, 5167.819126330561, 5248.343957005355, 5164.173732202867, 5174.793008279665, 5176.534111929945, 5185.896282074337, 5203.765830537635, 5108.182690769496, 5131.820386286499, 5151.227315902607, 5163.3545806774155, 5166.07010738067, 5615.887682246373, 5619.887602247933, 5622.297212848394, 5628.296462942132, 5633.492985631272, 6125.998614523584, 6127.92262824669, 6138.002676948283, 6146.139540464339, 6156.230471191101, 6084.939150608481, 6200.565960382775, 6205.0, 6215.627062376227, 6217.0, 5731.140328950649, 5736.082226843685, 5737.426257374229, 5743.339515955635, 5770.88458230833, 5631.901779153108, 5769.624974376652, 6213.65824879636, 6225.408586184305, 6227.875442491216, 5984.820455386345, 6048.697565121726, 6061.878762424727, 6124.38756124384, 6140.0, 6166.845828854314, 6201.782937597252, 6207.379262073847, 6268.310485846553, 6268.341824108494, 6217.813465595949, 6231.906521402183, 6242.1261023456755, 6245.656488893063, 6247.437730604267, 6100.816975490654, 6259.374062593704, 6278.30938596754, 6279.497640188774, 6281.403266689748, 4924.43369012561, 4933.901321973541, 4941.752912354368, 4946.233333833262, 4977.1538838397555, 4446.044100518404, 4456.487503937024, 4458.286674132123, 4482.887927801831, 4502.257127573914, 4879.634027447955, 5692.8861422771315, 5705.372409035002, 5712.2288491054005, 5718.485336319751, 5261.657992230493, 6112.816615501542, 6143.0, 6157.014877619559, 6264.279607845065, 5629.986602411524, 5630.943690563042, 5631.577631677641, 5634.774609015675, 5642.802501912413, 5292.894142117136, 5337.359516858002, 5341.839744807662, 5343.385510666245, 5345.3852806926925, 6267.5299352548745, 6268.059791031382, 6274.309825919054, 6277.968610157011, 6282.968585157137, 6127.433875357673, 6143.450468055886, 6150.231221097363, 6152.415520525543, 6202.782902598387, 5864.853378665567, 6248.500119990299, 6267.843303917439, 6275.96862015687, 6279.843003924939, 6216.658083805434, 6217.844553886189, 6219.440250377488, 6233.065040244, 6234.563580549318, 6286.748530058747, 6296.937030629635, 6298.0, 6310.927142385897, 6312.4634406076, 4396.340548917688, 5788.623739456922, 5795.0, 5845.1232315153065, 5922.3485416605, 5754.597178197486, 5910.172575839472, 5932.79235226765, 5940.732667030081, 6055.212822333068, 5962.970185149046, 5981.342052374148, 5989.071693887455, 6014.398560143951, 6029.909551356645, 6126.5098792097415, 6127.693615319127, 6132.908006379818, 6139.9079013813935, 6147.200863887845, 5632.464915832989, 5665.915011274752, 5993.790217342372, 6004.489618382312, 6022.397760223943], "value": 5630.185491726549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.69923850258823, 465.0977104417431, 472.20904984151537, 498.44672413620896, 535.8928214357129, 28185.71814281859, 40271.79864100679, 41619.37570936434, 41632.75101746945, 42111.57888421115, 42701.7864910675, 43006.56993430065, 43017.784911075476, 43117.56882431175, 43295.3505697414, 9353.766155846108, 9502.0, 9516.000819913914, 9534.80930381392, 9582.993785652476, 7467.738629147981, 7654.617269136521, 9623.711288661352, 11417.828732569018, 11470.197086203969, 11337.036351910097, 11401.087912966985, 11411.372374519398, 11415.0, 11458.0, 11328.886711132865, 11378.772424551462, 11379.0, 11390.373529455877, 11396.259243149168, 7438.851222975511, 7596.620168991583, 7617.923820761832, 7687.500312479671, 7693.0, 39527.80236098801, 39793.60206397928, 39889.601103988876, 41589.58410415887, 41776.58223417757, 3728.5525736911472, 3741.981290093532, 3742.1018955450495, 3766.4162054881263, 3807.7715337079862, 29655.9517083471, 29883.0, 30240.546391804142, 30592.0, 31167.53248701271, 3086.9845650771604, 3114.392693424783, 3192.696693814084, 3232.2081090132833, 3239.0, 459309.0, 459803.0, 463073.0, 464833.0, 473439.0, 49859.50421487361, 51167.23249151266, 62162.0675689865, 62561.06158407628, 62698.68650656717, 53014.0, 59765.70117149386, 67759.0, 67770.3222967764, 68460.31539684639, 15714.9214253928, 15803.20983950798, 15813.762793558106, 15821.446249381217, 15821.604459888365, 12009.939950300193, 12075.215111017756, 12119.333436661069, 12120.27278363301, 12158.0, 17032.892861963934, 17075.743863842054, 17099.658006839793, 17103.315867365414, 17135.65728685419, 6677.799666010027, 6718.731250750013, 6769.492288078413, 6807.523473356917, 6811.148606424197, 792.5126047480873, 797.6849144587856, 814.1898810683408, 853.7950891785927, 860.4966094834467, 29244.268893277836, 29390.971316003837, 29592.112236632936, 30077.69922300749, 31238.53142202869, 967.3470407474927, 1010.5503051142318, 1039.812833689943, 1047.120418848171, 1068.6900798768424, 13391.330433478288, 14722.705545889023, 15260.542183734506, 15406.537803865902, 15511.032962678753, 579114.0, 583254.0837295871, 583845.0, 584075.0, 584573.0, 59776.40223597708, 59957.0, 60080.0, 60359.0, 60435.0, 1363.740889231043, 1398.7482253194523, 1407.479232683893, 1409.563035459011, 1411.5976946570383, 26057.306933062424, 28173.859130705034, 29256.853715731286, 29832.254193644465, 29883.0, 49823.750881246815, 50350.7412314695, 51751.0, 51781.44655660336, 54883.0, 541673.0, 547151.0, 547225.0, 548156.0, 550365.0, 21107.356381596026, 21658.133674652836, 21659.0, 22155.77844221537, 24446.877765611058, 32549.021564000494, 33763.493547596314, 39759.40360894531, 41863.37204941867, 43027.56972430235, 31351.945812192072, 31568.842155789072, 31864.7254109833, 33964.49053264154, 36272.81863590665, 30000.0, 34714.82642586771, 35623.64376356203, 35626.8218658905, 35977.0, 34008.3198336027, 34146.658533414346, 35108.64891351054, 35190.0, 35349.0, 30307.696923030486, 30967.845160774054, 30999.225019374695, 31042.844785775927, 31083.222919427197, 22928.082876684744, 23136.6529502061, 23515.764842351356, 23597.174098906456, 23654.763452365944], "value": 19148.189367577845, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.60801058755965, 347.6158844476851, 384.26221654423506, 390.7089218532193, 409.65588905319544, 13267.346530693863, 13977.23125228112, 20309.898450507746, 20668.0, 21455.0, 21713.782862171374, 21808.672869906964, 22056.779432205672, 22574.774252257474, 22901.885490572524, 5234.214867769846, 6105.358937311585, 6106.0, 6116.0, 6116.052011938156, 3815.523059617548, 3922.3920292354587, 5609.270794796671, 5990.251218597676, 6758.087658166159, 6700.597964122168, 6767.052612634235, 6772.525923185379, 6783.864322713543, 6789.287124851893, 6512.446442052431, 6625.403713665745, 6683.732650693966, 6692.364225398579, 6714.429273511709, 5606.719664016783, 5787.884242315172, 5868.2371291732225, 5875.970620146914, 5899.5870289079785, 26787.598186027226, 27172.184834454998, 27290.77191526386, 27416.862915685495, 27550.5867411989, 2462.5198086373166, 2681.302861256068, 2708.688500822411, 2744.2041807875694, 2753.2841461220028, 15233.390664373412, 15295.541133766004, 15637.921810390875, 15767.659748921355, 15889.28498217583, 1756.350150444331, 1789.9821001789946, 1808.526051268212, 1829.231722676479, 1833.9083045847653, 92456.0, 114187.0, 116556.41721791337, 116963.83036169615, 118945.15822626589, 11215.719607009727, 11317.83023254652, 11341.829872551918, 11371.374574398487, 11588.594399195988, 12940.935295323652, 13498.392572334073, 13508.757898104384, 14421.855781442262, 14964.102153870803, 7594.2408507789, 8503.404761666749, 8709.433886797338, 8824.647014119491, 8960.327975401871, 8439.789005274917, 8529.275011623957, 8695.30449558935, 8834.249088827522, 8844.380893337533, 6115.4190351916495, 7226.891596626055, 7341.155767086747, 7347.5224110432655, 7393.408527317802, 1877.8075921789762, 1947.5879987009305, 1951.1219951021812, 1954.5309125809988, 1956.9315073972343, 956.9616965592271, 989.8713167288206, 1043.9164866810636, 1047.350642601592, 1119.2668801934747, 17896.026467072432, 22906.65640015401, 22977.425564361023, 23050.0, 23364.883175584015, 1059.8304271316551, 1060.9151267898549, 1102.5314241447352, 1105.7677887643686, 1110.3948348150277, 11576.36330001841, 11919.225250358872, 11956.222845515014, 12137.0, 12256.203346782431, 658526.0, 660041.0, 670766.0, 689749.0, 692105.5394723095, 151073.24463377614, 153406.0, 154546.0, 155040.0, 155239.0, 1000.364768372089, 1031.9690409287734, 1050.1021626509225, 1054.918708323974, 1056.1972900595604, 18657.53356166107, 20306.0, 20357.69463458079, 20539.178432862518, 20552.897235513727, 57219.42780572308, 57660.42339576551, 59110.5222369427, 59155.0, 59451.108233377396, 83681.0, 84522.0, 85533.57233213795, 87326.0, 88673.0, 7352.852942941218, 7390.9260907390235, 7854.685812567662, 8212.753617391489, 8608.440451370641, 12476.126671133297, 13057.738845222852, 13311.267880266678, 13423.261720605069, 13446.0, 9259.444433333752, 11249.775004500028, 11405.03057240144, 11424.314541127227, 11785.233959792586, 9061.637534498546, 10507.054365107177, 11332.830007550057, 11608.419579020847, 11640.94179529097, 11454.370009649549, 11774.587889423829, 11820.763584728085, 11874.703132421759, 11953.760924781629, 7456.0, 7539.181681756355, 7705.1524332324525, 7738.7678369649, 7999.320057795153, 8383.0, 10121.190304775748, 11383.430828458379, 11805.468753906267, 11806.46870890829], "value": 10149.016823483682, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.5139652518142, 1448.145594099481, 1463.6926245488446, 1467.0537503310363, 1468.1411374346014, 8032.634452143135, 8465.44974576652, 8472.872906906394, 8480.406371553994, 8482.279006284465, 7559.1684914659345, 8053.718119865805, 8331.750047498585, 8356.373272004594, 8476.957615211915, 2170.1427935965326, 2267.489814791675, 2267.818574514043, 2275.1581914691587, 2276.35123989663, 2249.100359856054, 2275.544891021794, 2279.4415368234804, 2280.851744636593, 2283.1209984156053, 2248.2580748353084, 2257.1761307122865, 2268.092762894839, 2273.329367836492, 2275.5107651854846, 1853.3883818339916, 2145.5279838435517, 2176.6082105221053, 2183.5370301897765, 2215.601191785478, 2189.6606026065983, 2256.4922892349255, 2256.672782446536, 2260.7513173550897, 2265.1279257485985, 8405.873911891327, 8443.957780211122, 8462.15378462155, 8464.02663693677, 8477.0, 4990.87522811929, 5148.974255128738, 5163.122269214243, 5163.974180129076, 5165.612579056586, 2983.731464168235, 3277.4919887417486, 3430.6054803697393, 3485.843137058813, 3488.982555087234, 1193.337697577844, 1194.593838095043, 1209.099221080292, 1230.4831970572382, 1260.0549587809144, 9666.661666841626, 9992.65025724096, 9997.600095996151, 10751.3011654243, 10761.677149685522, 4037.7981100944835, 4138.730982486129, 4139.172165566899, 4139.606737359946, 4140.275451795924, 9291.953540232256, 9495.810083798424, 9531.809363812685, 9537.523123843781, 9640.614375425044, 4926.729029903389, 5136.0241554104605, 5168.61235407346, 5171.793128274902, 5175.922361164586, 4511.887202819956, 4697.812087516461, 4712.198926182488, 4741.5495527924795, 4760.976195119002, 3657.085728567858, 3693.279810436939, 3792.241551689618, 3828.559715632682, 3830.540335159799, 1208.7763763703567, 1231.9137660363688, 1237.3503910447087, 1256.5727652598162, 1257.7987521996436, 706.159669992707, 711.2638419236066, 729.3399473476512, 742.962851857405, 771.9421043421765, 4915.26271059344, 5114.590832733446, 5317.601179911522, 5401.810936617199, 5549.19536667181, 547.824696097253, 554.6339415985511, 561.3123923194055, 562.1820251534008, 585.1398444286891, 3435.450327947566, 3525.347810655037, 3542.1852973816167, 3669.9082522936606, 3731.9067023324633, 8197.508149511048, 9308.0, 9773.951130244302, 9883.45640989738, 9899.851502227473, 9658.613655453844, 9844.261680374, 9861.359011664219, 9863.80272394548, 9864.704058878246, 7479.588622625808, 7607.733729319558, 7622.352100071556, 7740.458167928304, 7752.961235193788, 5197.4282828889545, 5224.895502089861, 5330.413654498079, 5388.8579165338715, 5454.0, 9069.501177435304, 9197.310201734763, 9214.308926830381, 9236.72289831306, 9239.907600924174, 8409.95795021021, 8721.869171962298, 8857.0, 9159.221466175182, 9295.90842060531, 6543.967280163569, 6905.274946130584, 7071.080759501232, 7088.610126443095, 7133.928660713534, 7653.464257501922, 7804.219578042151, 7868.055833300041, 7902.841943161219, 7908.051033875971, 5926.059170651469, 6959.61722105289, 7007.439404847498, 7110.004599356198, 7133.357997780225, 6820.658967051727, 6826.112605361272, 6959.756408525677, 7031.156261248683, 7036.120484939383, 6240.750369985331, 6263.937360626335, 6285.96857015712, 6359.173307470184, 6371.171747672773, 4762.380890484214, 4807.423109226916, 4816.073570571659, 4819.373481447389, 4985.750712464434, 4655.11552804966, 4721.244600863845, 4766.499517550607, 4789.44921334051, 4853.441854186673], "value": 4239.860045354107, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.8180500724601, 344.54003904787106, 360.44311538672747, 360.79254428703496, 365.25123496831486, 5247.580193584518, 5290.444503327149, 5370.409254981953, 5406.216098665691, 5421.9457805421935, 5047.47001564836, 5058.949410505894, 5280.046382838345, 5317.813876514323, 5378.757955891984, 3401.795892246473, 3510.947335789965, 4031.9596804031876, 4041.2923341361357, 4083.5099788025404, 4057.9594204057876, 4061.939070913939, 4079.0, 4085.5301640311445, 4089.20260549193, 3491.5460990071256, 3895.2599006188875, 3991.940120898189, 4039.596040395966, 4059.8985025374304, 3377.4596064629786, 3635.4001589737795, 3750.5055094286863, 3993.0616305168264, 4005.2590270799706, 3475.2371864367888, 3475.9652403475893, 3482.512448257246, 3486.9476957845654, 3491.30173965208, 4871.951280487221, 5236.188390799432, 5246.7901283948595, 5290.984139040748, 5292.629515933886, 4509.842155524574, 4578.253048171104, 4601.608863246628, 4607.746573938432, 4639.028412924527, 4994.725290109042, 5197.272381866542, 5233.685978841282, 5596.804111856066, 5620.61124022293, 3521.8239088045752, 3835.0412396900774, 3843.6348546888003, 3848.9230215395537, 3892.469894568553, 5413.0, 5480.561555075585, 5589.972050139764, 5630.746616402272, 5826.902315166537, 3405.7786243894066, 3636.1818590816856, 3674.4488326751202, 3680.2150956785936, 3686.612905644922, 3723.3298006358573, 3821.872547598465, 3873.4189871518934, 3902.863399780994, 3922.2351641429645, 4259.446271984684, 4535.455745310518, 4538.546145385435, 4542.0, 4574.268117101248, 4461.620762235181, 4742.051589682077, 4825.793551612097, 5364.061289274361, 5600.551955843523, 1132.4790596325784, 1245.7819881520697, 1251.4493622805937, 1257.7421628566121, 1263.6335462715892, 740.8147963009247, 826.5454000299811, 850.2985037344216, 851.29342645604, 864.7708357285198, 4490.955090449119, 4722.716637001791, 4875.561199491992, 4948.38145231846, 4996.525330093636, 5119.820806271762, 5445.83662490126, 5491.2312276281355, 5502.147167189092, 5513.669179849142, 4197.916041679149, 4740.644451666139, 4906.803727850917, 4936.482393979097, 4940.431850337185, 2658.5480468320434, 2703.635009273762, 2797.0, 2833.5324671429394, 2850.7149285071328, 4725.983913458623, 4989.875253118628, 5076.568491678248, 5583.426606971541, 5633.577481688808, 5046.974765126224, 5178.637495375363, 5210.879660872931, 5350.121162157405, 5554.91667624986, 4945.975270123627, 5022.7990880365105, 5627.437256274422, 5631.1271752878365, 5654.575906806924, 4594.655400845017, 4718.0, 4730.42192005351, 5446.346438427256, 5714.571407144397, 4954.801807927643, 5344.4121146674615, 5361.383440904346, 5370.409254981871, 5377.3816011159215, 5093.363329583802, 5521.420250873601, 5523.54202379937, 5636.915446268227, 5644.717764111861, 3018.5321275202377, 3660.670539651444, 3782.3759079751535, 3791.8483260669263, 3806.2958352704836, 5698.546870547983, 5733.827985160452, 5743.684097374685, 5753.798617048383, 5771.940871338197, 5052.8231511898375, 5341.465853414627, 5388.784448622011, 5506.64206826555, 5516.9448305518035, 4538.795754191004, 5479.808206712746, 5517.503424691797, 5528.756029893242, 5528.834134975958, 4857.421338065073, 5071.199724097892, 5447.700376479173, 5454.181872719124, 5467.289252397163, 4666.696664716783, 5501.862453438696, 5532.146730845051, 5560.332760068818, 5562.0, 4682.946337074131, 5010.423801262756, 5373.623846330719, 5451.291332126798, 5487.807926722546], "value": 3988.1945646876966, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.7776711452334, 717.0642311783121, 722.9457790665701, 758.7647829172956, 813.5240884082812, 16931.915340423297, 17566.385176518823, 17586.384476543324, 17602.20790064447, 17612.471625851227, 16094.75857862133, 16131.758023629654, 16143.112128832909, 16177.433789817358, 16254.349826006946, 876.1413814461831, 897.6274845938927, 899.8695189197563, 901.1889299630319, 904.6878826804742, 878.227160099115, 897.5736525150567, 901.4591245252828, 901.5807649443008, 917.2524392620027, 955.5461155950937, 956.1155930764015, 956.2780101023717, 957.6504575829841, 959.7360725800426, 950.3680052764903, 950.4582388038821, 951.3435729346763, 952.8570714392828, 953.9332246742731, 711.0791524975118, 711.4735096028901, 713.3437237741291, 714.0681410758957, 714.4570126703691, 15178.924105379512, 15280.847191528164, 15337.846621533865, 15391.538253852403, 15408.075515468996, 13602.795958060637, 13770.173789572658, 13908.791368129487, 13984.93007534966, 14187.219702916336, 11120.944395278053, 11338.740283096928, 11343.943280283629, 11587.30476171424, 12007.759844803142, 3594.137407022322, 3597.3524765542193, 3604.7837129772297, 3627.7097832173627, 3764.4729737836724, 98649.0, 100643.0, 101553.0, 102183.97816021893, 103950.96049039564, 10298.17614590846, 10355.844662330072, 10398.064174224357, 10402.47987600617, 10704.464776761131, 21529.56940861174, 21799.45501362447, 22087.66868496974, 22240.332790016324, 22259.332220033426, 7362.042934418599, 7467.066616672916, 7478.663460144307, 7676.270754278335, 7732.072151341875, 3968.8015599219925, 4010.542564861091, 4151.750894946313, 4220.324748040361, 4230.048239146229, 3548.982255088708, 3600.5859326177824, 3656.1408069104273, 3664.5419322584676, 3680.190358121209, 662.9701663425158, 664.6007911439699, 668.906353110565, 670.76858483823, 681.2846511163259, 642.1844257792577, 647.6179054357951, 650.9121268628769, 659.1365311442026, 664.1664710788027, 7843.9215607844335, 9713.36863103896, 9759.512024398751, 9767.609295628237, 9779.217662586976, 627.1909237084202, 645.9967700161469, 659.2780904909117, 661.576590981772, 670.715579664946, 4156.605122513356, 4742.340180936644, 4746.931334416254, 4797.880052998633, 4800.087928338463, 95141.5728764069, 97956.04087918202, 98097.0, 99455.0, 101077.9353238808, 82446.58776706079, 83280.0, 83756.74364884531, 83986.16013839905, 86064.56967715122, 70949.0, 71313.64343178355, 72383.6380818103, 72406.0, 72740.0, 9195.494247816434, 9207.677731279373, 9234.492102934402, 9285.257179425495, 9287.814243715224, 30213.848930756085, 40863.59136408598, 42003.57996419997, 44975.65073047814, 48394.0, 48496.0, 54542.0, 56065.0, 57126.143107854245, 57255.141172883275, 12401.62795116148, 12483.0, 12576.056795740538, 12869.35653217354, 13238.933805330911, 13865.514706985206, 13925.234112123526, 14130.222837744022, 14179.787303190255, 14236.359363828446, 13112.672183195496, 13214.075014748876, 13231.08425112311, 13383.263920484467, 13468.326583670581, 12932.870671293167, 13164.407601657756, 13207.207567545978, 13355.732885342433, 13542.187468751907, 12649.0, 12798.87201128014, 12861.807072893727, 13107.868921310665, 13142.540011099565, 10528.263021588722, 10683.0, 10685.626003089854, 10949.372428545019, 11494.54021839117, 8999.595018224065, 10155.55622075222, 10361.015703508154, 10386.0, 10422.94943050139], "value": 7378.079789465537, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1463.9999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 112.0, 112.0, 113.0, 110.0, 113.0, 122.0], "value": 113.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 110.0, 113.0, 149.0, 118.0, 115.0, 113.0, 111.0, 133.0, 112.0], "value": 124.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 184.0, 183.0, 185.0, 185.0, 183.0, 183.0, 185.0, 183.0], "value": 184.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 77.0, 82.0, 84.0, 80.0, 84.0, 84.0, 62.0, 77.0, 58.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 87.0, 92.0, 100.0, 99.0, 120.0, 69.0, 68.0, 85.0], "value": 88.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 89.0, 89.0, 90.0, 89.0, 88.0, 85.0, 89.0, 90.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0, 96.0, 94.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 85.0, 86.0, 85.0, 85.0, 86.0, 85.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 61.0, 61.0, 60.0, 61.0, 60.0, 62.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 69.0, 69.0, 76.0, 71.0, 71.0, 69.0, 67.0, 71.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 88.0, 88.0, 88.0, 91.0, 86.0, 85.0, 90.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 146.0, 140.0, 152.0, 134.0, 142.0, 141.0, 143.0, 184.0, 153.0], "value": 147.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 177.0, 171.0, 174.0, 174.0, 176.0, 172.0, 172.0, 170.0], "value": 173.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 68.0, 66.0, 66.0, 65.0, 65.0, 67.0], "value": 66.8, "unit": "score"}]}]} 18:25:36 INFO - Return code: 0 18:25:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:25:37 ERROR - # TBPL SUCCESS # 18:25:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:25:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:25:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:25:37 INFO - 2017-02-24 18:25:37.077 screenresolution[1660:12046] starting screenresolution argv=screenresolution get 18:25:37 INFO - 2017-02-24 18:25:37.079 screenresolution[1660:12046] Display 0: 1600x1200x32@60 18:25:37 INFO - 2017-02-24 18:25:37.086 screenresolution[1661:12049] starting screenresolution argv=screenresolution list 18:25:37 INFO - Available Modes on Display 0 18:25:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:25:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:25:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:25:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:25:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:25:37 INFO - Intel Iris: 18:25:37 INFO - Chipset Model: Intel Iris 18:25:37 INFO - Type: GPU 18:25:37 INFO - Bus: Built-In 18:25:37 INFO - VRAM (Dynamic, Max): 1536 MB 18:25:37 INFO - Vendor: Intel (0x8086) 18:25:37 INFO - Device ID: 0x0a2e 18:25:37 INFO - Revision ID: 0x0009 18:25:37 INFO - Displays: 18:25:37 INFO - Display: 18:25:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:25:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:25:37 INFO - Display Serial Number: 1600x1200 60 18:25:37 INFO - Main Display: Yes 18:25:37 INFO - Mirror: Off 18:25:37 INFO - Online: Yes 18:25:37 INFO - Rotation: Supported 18:25:37 INFO - Return code: 0 18:25:37 INFO - Running post-action listener: _resource_record_post_action 18:25:37 INFO - [mozharness: 2017-02-25 02:25:37.324997Z] Finished run-tests step (success) 18:25:37 INFO - Running post-run listener: _resource_record_post_run 18:25:37 INFO - Total resource usage - Wall time: 1203s; CPU: 54.0%; Read bytes: 32418816; Write bytes: 516308480; Read time: 322; Write time: 1611 18:25:37 INFO - TinderboxPrint: CPU usage
53.9% 18:25:37 INFO - TinderboxPrint: I/O read bytes / time
32,418,816 / 322 18:25:37 INFO - TinderboxPrint: I/O write bytes / time
516,308,480 / 1,611 18:25:37 INFO - TinderboxPrint: CPU idle
2,219.5 (46.1%) 18:25:37 INFO - TinderboxPrint: CPU system
958.7 (19.9%) 18:25:37 INFO - TinderboxPrint: CPU user
1,635.6 (34.0%) 18:25:37 INFO - TinderboxPrint: Swap in / out
238,481,408 / 0 18:25:37 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 204085760; Write bytes: 201703424; Read time: 15539; Write time: 436 18:25:37 INFO - run-tests - Wall time: 1183s; CPU: 54.0%; Read bytes: 30632960; Write bytes: 303971840; Read time: 308; Write time: 1138 18:25:37 INFO - Running post-run listener: _upload_blobber_files 18:25:37 INFO - Blob upload gear active. 18:25:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:25:37 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 18:25:37 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'] 18:25:37 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 18:25:38 INFO - (blobuploader) - INFO - Open directory for files ... 18:25:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:25:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:25:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:39 INFO - (blobuploader) - INFO - Done attempting. 18:25:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:25:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:25:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:40 INFO - (blobuploader) - INFO - Done attempting. 18:25:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 18:25:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:41 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 18:25:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:41 INFO - (blobuploader) - INFO - Done attempting. 18:25:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:25:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:25:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:25:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:25:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:25:43 INFO - (blobuploader) - INFO - Done attempting. 18:25:43 INFO - (blobuploader) - INFO - Iteration through files over. 18:25:43 INFO - Return code: 0 18:25:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:25:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:25:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c6c8eb6fc23fe207a507c48f4949de6f21812d57de44a5fae04f4ef11cab04db01838683e9369b84f0560ef5a98441fdff7e0cd3363ee9174ed0de06d3a9dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad7ad3fea09526bcc80adb79953e9034e5442c39b937e94fe3ead68cd4bbdcc8c5d09cf28697584879a7ed1719ee1a66f0bfe73e1600c3e9255a90bf1a35dd53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88ece9263e5c1c31385b1593503649163ec132f5073aad5898e9d5ab94af3b367d16e9da1f436ee4ff8401ae9733d8bef441a499ebacfa7e4324682a3c7fa37a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56575e3e89f6c3c8f1cd58e942bfd2553f939fea578e4284d6d76612e6b2af411ebc685beff274a1988f3306c4cb2c5c98e62dc15fc5740984fb176bf5b53ade"} 18:25:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:25:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:25:43 INFO - Contents: 18:25:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c6c8eb6fc23fe207a507c48f4949de6f21812d57de44a5fae04f4ef11cab04db01838683e9369b84f0560ef5a98441fdff7e0cd3363ee9174ed0de06d3a9dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad7ad3fea09526bcc80adb79953e9034e5442c39b937e94fe3ead68cd4bbdcc8c5d09cf28697584879a7ed1719ee1a66f0bfe73e1600c3e9255a90bf1a35dd53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88ece9263e5c1c31385b1593503649163ec132f5073aad5898e9d5ab94af3b367d16e9da1f436ee4ff8401ae9733d8bef441a499ebacfa7e4324682a3c7fa37a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56575e3e89f6c3c8f1cd58e942bfd2553f939fea578e4284d6d76612e6b2af411ebc685beff274a1988f3306c4cb2c5c98e62dc15fc5740984fb176bf5b53ade"} 18:25:43 INFO - Running post-run listener: copy_logs_to_upload_dir 18:25:43 INFO - Copying logs to upload dir... 18:25:43 INFO - mkdir: /builds/slave/test/build/upload/logs 18:25:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1285.282664 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 25 secs) (at 2017-02-24 18:25:43.611517) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 18:25:43.614868) ========= 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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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/89c6c8eb6fc23fe207a507c48f4949de6f21812d57de44a5fae04f4ef11cab04db01838683e9369b84f0560ef5a98441fdff7e0cd3363ee9174ed0de06d3a9dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad7ad3fea09526bcc80adb79953e9034e5442c39b937e94fe3ead68cd4bbdcc8c5d09cf28697584879a7ed1719ee1a66f0bfe73e1600c3e9255a90bf1a35dd53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88ece9263e5c1c31385b1593503649163ec132f5073aad5898e9d5ab94af3b367d16e9da1f436ee4ff8401ae9733d8bef441a499ebacfa7e4324682a3c7fa37a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56575e3e89f6c3c8f1cd58e942bfd2553f939fea578e4284d6d76612e6b2af411ebc685beff274a1988f3306c4cb2c5c98e62dc15fc5740984fb176bf5b53ade"} build_url:https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011621 build_url: 'https://queue.taskcluster.net/v1/task/IJBdIvkbRomCz6u18PUFpw/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/89c6c8eb6fc23fe207a507c48f4949de6f21812d57de44a5fae04f4ef11cab04db01838683e9369b84f0560ef5a98441fdff7e0cd3363ee9174ed0de06d3a9dd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad7ad3fea09526bcc80adb79953e9034e5442c39b937e94fe3ead68cd4bbdcc8c5d09cf28697584879a7ed1719ee1a66f0bfe73e1600c3e9255a90bf1a35dd53", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/88ece9263e5c1c31385b1593503649163ec132f5073aad5898e9d5ab94af3b367d16e9da1f436ee4ff8401ae9733d8bef441a499ebacfa7e4324682a3c7fa37a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/56575e3e89f6c3c8f1cd58e942bfd2553f939fea578e4284d6d76612e6b2af411ebc685beff274a1988f3306c4cb2c5c98e62dc15fc5740984fb176bf5b53ade"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-24 18:25:43.649556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:25:43.649865) ========= 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.ru9EhM8V1a/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.tj4GzbTG1v/Listeners TMPDIR=/var/folders/8l/37jgynkn5xx3w6kvn53v07ym00000w/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.005254 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-24 18:25:43.698304) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 18:25:43.698649) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-24 18:25:43.698960) ========= ========= Total master_lag: 0.26 =========