builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0200 starttime: 1480593418.38 results: success (0) buildid: 20161201004019 builduid: d0a18ee4297b4ea883fece53f621d9ca revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.377018) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.377441) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.377720) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.404594) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.404991) ========= 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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-01 03:56:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.27M=0.001s 2016-12-01 03:56:58 (9.27 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086369 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.516196) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.516588) ========= 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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.026110 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.565118) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:58.565507) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa39ccc915c12f39eb51bd55871266f23fb5e999 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev fa39ccc915c12f39eb51bd55871266f23fb5e999 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-01 03:56:58,667 truncating revision to first 12 chars 2016-12-01 03:56:58,667 Setting DEBUG logging. 2016-12-01 03:56:58,667 attempt 1/10 2016-12-01 03:56:58,667 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/fa39ccc915c1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-01 03:56:58,968 unpacking tar archive at: mozilla-aurora-fa39ccc915c1/testing/mozharness/ program finished with exit code 0 elapsedTime=0.587346 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:59.177648) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:59.178204) ========= script_repo_revision: fa39ccc915c12f39eb51bd55871266f23fb5e999 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:59.178831) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:59.179237) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-01 03:56:59.204181) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-01 03:56:59.204574) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:56:59 INFO - MultiFileLogger online at 20161201 03:56:59 in /builds/slave/test 03:56:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:56:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:56:59 INFO - {'append_to_log': False, 03:56:59 INFO - 'base_work_dir': '/builds/slave/test', 03:56:59 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:56:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:56:59 INFO - 'branch': 'Mozilla-Aurora', 03:56:59 INFO - 'buildbot_json_path': 'buildprops.json', 03:56:59 INFO - 'config_files': ('talos/mac_config.py',), 03:56:59 INFO - 'default_actions': ('clobber', 03:56:59 INFO - 'read-buildbot-config', 03:56:59 INFO - 'download-and-extract', 03:56:59 INFO - 'populate-webroot', 03:56:59 INFO - 'create-virtualenv', 03:56:59 INFO - 'install', 03:56:59 INFO - 'run-tests'), 03:56:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:56:59 INFO - 'download_minidump_stackwalk': True, 03:56:59 INFO - 'download_symbols': 'ondemand', 03:56:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:56:59 INFO - 'tooltool.py': '/tools/tooltool.py', 03:56:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:56:59 INFO - '/tools/misc-python/virtualenv.py')}, 03:56:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:56:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:56:59 INFO - 'installer_path': 'installer.exe', 03:56:59 INFO - 'log_level': 'info', 03:56:59 INFO - 'log_name': 'talos', 03:56:59 INFO - 'log_to_console': True, 03:56:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:56:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:56:59 INFO - 'opt_config_files': (), 03:56:59 INFO - 'pip_index': False, 03:56:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:56:59 INFO - 'cmd': ('bash', 03:56:59 INFO - '-c', 03:56:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:56:59 INFO - 'enabled': True, 03:56:59 INFO - 'halt_on_failure': False, 03:56:59 INFO - 'name': 'check_screen_resolution'},), 03:56:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:56:59 INFO - 'cmd': ('bash', 03:56:59 INFO - '-c', 03:56:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:56:59 INFO - 'enabled': True, 03:56:59 INFO - 'halt_on_failure': False, 03:56:59 INFO - 'name': 'check_screen_resolution'},), 03:56:59 INFO - 'run_cmd_checks_enabled': True, 03:56:59 INFO - 'sps_profile': False, 03:56:59 INFO - 'sps_profile_interval': 0, 03:56:59 INFO - 'suite': 'dromaeojs', 03:56:59 INFO - 'system_bits': '32', 03:56:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:56:59 INFO - 'title': 't-yosemite-r7-0200', 03:56:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:56:59 INFO - 'use_talos_json': True, 03:56:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:56:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:56:59 INFO - 'work_dir': 'build'} 03:56:59 INFO - [mozharness: 2016-12-01 11:56:59.415093Z] Running clobber step. 03:56:59 INFO - Running pre-action listener: _resource_record_pre_action 03:56:59 INFO - Running main action method: clobber 03:56:59 INFO - rmtree: /builds/slave/test/build 03:56:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:57:01 INFO - Running post-action listener: _resource_record_post_action 03:57:01 INFO - [mozharness: 2016-12-01 11:57:01.920924Z] Finished clobber step (success) 03:57:01 INFO - [mozharness: 2016-12-01 11:57:01.921106Z] Running read-buildbot-config step. 03:57:01 INFO - Running pre-action listener: _resource_record_pre_action 03:57:01 INFO - Running main action method: read_buildbot_config 03:57:01 INFO - Using buildbot properties: 03:57:01 INFO - { 03:57:01 INFO - "project": "", 03:57:01 INFO - "product": "firefox", 03:57:01 INFO - "script_repo_revision": "production", 03:57:01 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:57:01 INFO - "repository": "", 03:57:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:57:01 INFO - "buildid": "20161201004019", 03:57:01 INFO - "stage_platform": "macosx64", 03:57:01 INFO - "basedir": "/builds/slave/test", 03:57:01 INFO - "nightly_build": "True", 03:57:01 INFO - "buildnumber": 13, 03:57:01 INFO - "slavename": "t-yosemite-r7-0200", 03:57:01 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 03:57:01 INFO - "platform": "yosemite_r7", 03:57:01 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:57:01 INFO - "branch": "mozilla-aurora", 03:57:01 INFO - "repo_path": "releases/mozilla-aurora", 03:57:01 INFO - "revision": "fa39ccc915c12f39eb51bd55871266f23fb5e999", 03:57:01 INFO - "pgo_build": "False", 03:57:01 INFO - "builduid": "d0a18ee4297b4ea883fece53f621d9ca", 03:57:01 INFO - "slavebuilddir": "test" 03:57:01 INFO - } 03:57:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 03:57:01 INFO - Running post-action listener: _resource_record_post_action 03:57:01 INFO - [mozharness: 2016-12-01 11:57:01.924894Z] Finished read-buildbot-config step (success) 03:57:01 INFO - [mozharness: 2016-12-01 11:57:01.925083Z] Running download-and-extract step. 03:57:01 INFO - Running pre-action listener: _resource_record_pre_action 03:57:01 INFO - Running main action method: download_and_extract 03:57:01 INFO - mkdir: /builds/slave/test/build/tests 03:57:01 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')]} 03:57:01 INFO - trying https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 03:57:01 INFO - Downloading https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:57:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 03:57:03 INFO - Downloaded 1199 bytes. 03:57:03 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 03:57:03 INFO - Using the following test package requirements: 03:57:03 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:57:03 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 03:57:03 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 03:57:03 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'jsshell-mac.zip'], 03:57:03 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 03:57:03 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 03:57:03 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 03:57:03 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 03:57:03 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 03:57:03 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 03:57:03 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 03:57:03 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:57:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:57:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:57:03 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:57:03 INFO - Http code: 200 03:57:03 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 03:57:03 INFO - x-amz-cf-id: eP97IcFasmWuJHMjHC6MbGcVch-8uY5ubKjqGWvZoUvAPUGhC9Mssg== 03:57:03 INFO - x-amz-version-id: xdguqhcwyCQTII8YxclVDN1qtES43Mme 03:57:04 INFO - Expected file size: 16285400 03:57:04 INFO - Obtained file size: 16285400 03:57:10 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:57:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 03:57:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:57:10 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 03:57:11 INFO - Http code: 200 03:57:11 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 03:57:11 INFO - x-amz-cf-id: r47ko2UTMigJCGhSxyzrWg2-qt_4u6z3utD9H3hgeaOEhIZeF_JtQw== 03:57:11 INFO - x-amz-version-id: xdguqhcwyCQTII8YxclVDN1qtES43Mme 03:57:11 INFO - Expected file size: 16285400 03:57:11 INFO - Obtained file size: 16285400 03:57:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 03:57:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:57:18 INFO - Fetch https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 03:57:18 INFO - Http code: 200 03:57:18 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 03:57:18 INFO - x-amz-cf-id: 5xNq4A7w934FIN6BJ6MFjIU4oeEjZohjep7spVFUTX7PON0f710YLA== 03:57:18 INFO - x-amz-version-id: e0FjGJzkluJxeWrtIc5Sm.sp1A6QYfnA 03:57:18 INFO - Expected file size: 14409038 03:57:18 INFO - Obtained file size: 14409038 03:57:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:19 INFO - trying https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:57:19 INFO - Downloading https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 03:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 03:57:22 INFO - Downloaded 109854203 bytes. 03:57:22 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:57:22 INFO - mkdir: /builds/slave/test/properties 03:57:22 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:57:22 INFO - Writing to file /builds/slave/test/properties/build_url 03:57:22 INFO - Contents: 03:57:22 INFO - build_url:https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 03:57:23 INFO - Running post-action listener: _resource_record_post_action 03:57:23 INFO - Running post-action listener: set_extra_try_arguments 03:57:23 INFO - [mozharness: 2016-12-01 11:57:23.182267Z] Finished download-and-extract step (success) 03:57:23 INFO - [mozharness: 2016-12-01 11:57:23.182388Z] Running populate-webroot step. 03:57:23 INFO - Running pre-action listener: _resource_record_pre_action 03:57:23 INFO - Running main action method: populate_webroot 03:57:23 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:57:23 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:57:23 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:57:23 INFO - '--tppagecycles', 03:57:23 INFO - '7'], 03:57:23 INFO - 'tests': ['tsvgm']}}, 03:57:23 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:57:23 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:57:23 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:57:23 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:57:23 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:23 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:57:23 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['damp', 'tps']}, 03:57:23 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'tests': ['damp', 'tps']}, 03:57:23 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['dromaeo_dom']}, 03:57:23 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:57:23 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['basic_compositor_video']}, 03:57:23 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:57:23 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'other-e10s': {'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['a11yr', 03:57:23 INFO - 'ts_paint', 03:57:23 INFO - 'tpaint', 03:57:23 INFO - 'sessionrestore', 03:57:23 INFO - 'sessionrestore_no_auto_restore', 03:57:23 INFO - 'tabpaint']}, 03:57:23 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['tsvgx', 03:57:23 INFO - 'tsvgr_opacity', 03:57:23 INFO - 'tart', 03:57:23 INFO - 'tscrollx', 03:57:23 INFO - 'cart']}, 03:57:23 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:57:23 INFO - 'tsvgr_opacity', 03:57:23 INFO - 'tart', 03:57:23 INFO - 'tscrollx', 03:57:23 INFO - 'cart']}, 03:57:23 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'talos_options': ['--disable-e10s'], 03:57:23 INFO - 'tests': ['tp5o']}, 03:57:23 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'tests': ['tp5o']}, 03:57:23 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'talos_options': ['--disable-e10s', 03:57:23 INFO - '--xperf_path', 03:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:23 INFO - 'tests': ['tp5n']}, 03:57:23 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:57:23 INFO - 'talos_options': ['--xperf_path', 03:57:23 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:57:23 INFO - 'tests': ['tp5n']}}, 03:57:23 INFO - 'talos.zip': {'path': '', 03:57:23 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:57:23 INFO - Running post-action listener: _resource_record_post_action 03:57:23 INFO - [mozharness: 2016-12-01 11:57:23.201414Z] Finished populate-webroot step (success) 03:57:23 INFO - [mozharness: 2016-12-01 11:57:23.201544Z] Running create-virtualenv step. 03:57:23 INFO - Running pre-action listener: _resource_record_pre_action 03:57:23 INFO - Running main action method: create_virtualenv 03:57:23 INFO - Creating virtualenv /builds/slave/test/build/venv 03:57:23 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 03:57:23 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:57:23 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:57:23 INFO - Using real prefix '/tools/python27' 03:57:23 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:57:24 INFO - Installing distribute.............................................................................................................................................................................................done. 03:57:26 INFO - Installing pip.................done. 03:57:26 INFO - Return code: 0 03:57:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 03:57:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 03:57:27 INFO - Reading from file tmpfile_stdout 03:57:27 INFO - Output received: 03:57:27 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) 03:57:27 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:27 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:27 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 03:57:27 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 03:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:27 INFO - 'HOME': '/Users/cltbld', 03:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:27 INFO - 'LOGNAME': 'cltbld', 03:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:27 INFO - 'MOZ_NO_REMOTE': '1', 03:57:27 INFO - 'NO_EM_RESTART': '1', 03:57:27 INFO - 'PAGER': '/bin/cat', 03:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:27 INFO - 'PWD': '/builds/slave/test', 03:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:27 INFO - 'SHELL': '/bin/bash', 03:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:27 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:27 INFO - 'USER': 'cltbld', 03:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:27 INFO - 'XPC_FLAGS': '0x0', 03:57:27 INFO - 'XPC_SERVICE_NAME': '0', 03:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:27 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 03:57:27 INFO - Cleaning up... 03:57:27 INFO - Return code: 0 03:57:27 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:27 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:27 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 03:57:27 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 03:57:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:27 INFO - 'HOME': '/Users/cltbld', 03:57:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:27 INFO - 'LOGNAME': 'cltbld', 03:57:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:27 INFO - 'MOZ_NO_REMOTE': '1', 03:57:27 INFO - 'NO_EM_RESTART': '1', 03:57:27 INFO - 'PAGER': '/bin/cat', 03:57:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:27 INFO - 'PWD': '/builds/slave/test', 03:57:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:27 INFO - 'SHELL': '/bin/bash', 03:57:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:27 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:27 INFO - 'USER': 'cltbld', 03:57:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:27 INFO - 'XPC_FLAGS': '0x0', 03:57:27 INFO - 'XPC_SERVICE_NAME': '0', 03:57:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:27 INFO - Downloading/unpacking psutil>=3.1.1 03:57:27 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 03:57:27 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 03:57:27 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 03:57:27 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 03:57:27 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 03:57:27 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 03:57:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:57:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:31 INFO - Installing collected packages: psutil 03:57:31 INFO - Running setup.py install for psutil 03:57:31 INFO - building 'psutil._psutil_osx' extension 03:57:31 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 03:57:31 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 03:57:31 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 03:57:32 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 03:57:32 INFO - building 'psutil._psutil_posix' extension 03:57:32 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 03:57:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:57:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:57:32 INFO - ^ 03:57:32 INFO - 1 warning generated. 03:57:32 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 03:57:32 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:57:32 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:57:32 INFO - Successfully installed psutil 03:57:32 INFO - Cleaning up... 03:57:32 INFO - Return code: 0 03:57:32 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:32 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:32 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 03:57:32 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 03:57:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:32 INFO - 'HOME': '/Users/cltbld', 03:57:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:32 INFO - 'LOGNAME': 'cltbld', 03:57:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:32 INFO - 'MOZ_NO_REMOTE': '1', 03:57:32 INFO - 'NO_EM_RESTART': '1', 03:57:32 INFO - 'PAGER': '/bin/cat', 03:57:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:32 INFO - 'PWD': '/builds/slave/test', 03:57:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:32 INFO - 'SHELL': '/bin/bash', 03:57:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:32 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:32 INFO - 'USER': 'cltbld', 03:57:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:32 INFO - 'XPC_FLAGS': '0x0', 03:57:32 INFO - 'XPC_SERVICE_NAME': '0', 03:57:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:32 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:57:32 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 03:57:32 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 03:57:32 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 03:57:32 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 03:57:32 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 03:57:32 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 03:57:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:57:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:57:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:57:35 INFO - Installing collected packages: mozsystemmonitor 03:57:35 INFO - Running setup.py install for mozsystemmonitor 03:57:35 INFO - Successfully installed mozsystemmonitor 03:57:35 INFO - Cleaning up... 03:57:35 INFO - Return code: 0 03:57:35 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:35 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:35 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 03:57:35 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 03:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:35 INFO - 'HOME': '/Users/cltbld', 03:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:35 INFO - 'LOGNAME': 'cltbld', 03:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:35 INFO - 'MOZ_NO_REMOTE': '1', 03:57:35 INFO - 'NO_EM_RESTART': '1', 03:57:35 INFO - 'PAGER': '/bin/cat', 03:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:35 INFO - 'PWD': '/builds/slave/test', 03:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:35 INFO - 'SHELL': '/bin/bash', 03:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:35 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:35 INFO - 'USER': 'cltbld', 03:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:35 INFO - 'XPC_FLAGS': '0x0', 03:57:35 INFO - 'XPC_SERVICE_NAME': '0', 03:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:35 INFO - Downloading/unpacking jsonschema==2.5.1 03:57:35 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 03:57:35 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 03:57:35 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 03:57:35 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 03:57:35 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 03:57:35 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 03:57:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:57:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:57:38 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 03:57:38 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 03:57:38 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 03:57:38 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 03:57:38 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 03:57:38 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 03:57:38 INFO - Downloading functools32-3.2.3-2.tar.gz 03:57:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:57:38 INFO - warning: no files found matching '*.txt' 03:57:38 INFO - no previously-included directories found matching 'build' 03:57:38 INFO - no previously-included directories found matching 'dist' 03:57:38 INFO - no previously-included directories found matching '.git*' 03:57:39 INFO - Installing collected packages: jsonschema, functools32 03:57:39 INFO - Running setup.py install for functools32 03:57:39 INFO - warning: no files found matching '*.txt' 03:57:39 INFO - no previously-included directories found matching 'build' 03:57:39 INFO - no previously-included directories found matching 'dist' 03:57:39 INFO - no previously-included directories found matching '.git*' 03:57:39 INFO - Successfully installed jsonschema functools32 03:57:39 INFO - Cleaning up... 03:57:39 INFO - Return code: 0 03:57:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:57:39 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')]} 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 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')]} 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:39 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 03:57:39 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 03:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:39 INFO - 'HOME': '/Users/cltbld', 03:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:39 INFO - 'LOGNAME': 'cltbld', 03:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:39 INFO - 'MOZ_NO_REMOTE': '1', 03:57:39 INFO - 'NO_EM_RESTART': '1', 03:57:39 INFO - 'PAGER': '/bin/cat', 03:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:39 INFO - 'PWD': '/builds/slave/test', 03:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:39 INFO - 'SHELL': '/bin/bash', 03:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:39 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:39 INFO - 'USER': 'cltbld', 03:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:39 INFO - 'XPC_FLAGS': '0x0', 03:57:39 INFO - 'XPC_SERVICE_NAME': '0', 03:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:57:39 INFO - Cleaning up... 03:57:39 INFO - Return code: 0 03:57:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:57:39 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')]} 03:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 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')]} 03:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:39 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:39 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 03:57:39 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 03:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:39 INFO - 'HOME': '/Users/cltbld', 03:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:39 INFO - 'LOGNAME': 'cltbld', 03:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:39 INFO - 'MOZ_NO_REMOTE': '1', 03:57:39 INFO - 'NO_EM_RESTART': '1', 03:57:39 INFO - 'PAGER': '/bin/cat', 03:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:39 INFO - 'PWD': '/builds/slave/test', 03:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:39 INFO - 'SHELL': '/bin/bash', 03:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:39 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:39 INFO - 'USER': 'cltbld', 03:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:39 INFO - 'XPC_FLAGS': '0x0', 03:57:39 INFO - 'XPC_SERVICE_NAME': '0', 03:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:39 INFO - Downloading/unpacking blobuploader==1.2.4 03:57:39 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 03:57:39 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 03:57:39 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 03:57:39 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 03:57:39 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 03:57:39 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 03:57:42 INFO - Downloading blobuploader-1.2.4.tar.gz 03:57:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:57:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:57:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:57:43 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 03:57:43 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 03:57:43 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 03:57:43 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 03:57:43 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 03:57:43 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 03:57:43 INFO - Downloading docopt-0.6.1.tar.gz 03:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:57:43 INFO - Installing collected packages: blobuploader, requests, docopt 03:57:43 INFO - Running setup.py install for blobuploader 03:57:43 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:57:43 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:57:43 INFO - Running setup.py install for requests 03:57:44 INFO - Running setup.py install for docopt 03:57:44 INFO - Successfully installed blobuploader requests docopt 03:57:44 INFO - Cleaning up... 03:57:44 INFO - Return code: 0 03:57:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:44 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:44 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 03:57:44 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 03:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:44 INFO - 'HOME': '/Users/cltbld', 03:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:44 INFO - 'LOGNAME': 'cltbld', 03:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:44 INFO - 'MOZ_NO_REMOTE': '1', 03:57:44 INFO - 'NO_EM_RESTART': '1', 03:57:44 INFO - 'PAGER': '/bin/cat', 03:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:44 INFO - 'PWD': '/builds/slave/test', 03:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:44 INFO - 'SHELL': '/bin/bash', 03:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:44 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:44 INFO - 'USER': 'cltbld', 03:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:44 INFO - 'XPC_FLAGS': '0x0', 03:57:44 INFO - 'XPC_SERVICE_NAME': '0', 03:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:44 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-rpjxgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-7tXtxJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-XytNY8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-DKg_5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-WZgG8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-7vPAOz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-tMKFnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-bu4HqI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-k5qBD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-9WCRe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:45 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-LW0144-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-FL8dmp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-WF_bEo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-I92crc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-wzTWmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-1r4jKf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:46 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-kTVPhw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:46 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:57:46 INFO - Running setup.py install for manifestparser 03:57:46 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:57:46 INFO - Running setup.py install for mozcrash 03:57:46 INFO - Running setup.py install for mozdebug 03:57:47 INFO - Running setup.py install for mozdevice 03:57:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Running setup.py install for mozfile 03:57:47 INFO - Running setup.py install for mozhttpd 03:57:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Running setup.py install for mozinfo 03:57:47 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Running setup.py install for mozInstall 03:57:47 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:57:47 INFO - Running setup.py install for mozleak 03:57:48 INFO - Running setup.py install for mozlog 03:57:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Running setup.py install for moznetwork 03:57:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Running setup.py install for mozprocess 03:57:48 INFO - Running setup.py install for mozprofile 03:57:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Running setup.py install for mozrunner 03:57:48 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:57:48 INFO - Running setup.py install for mozscreenshot 03:57:48 INFO - Running setup.py install for moztest 03:57:49 INFO - Running setup.py install for mozversion 03:57:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:57:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:57:49 INFO - Cleaning up... 03:57:49 INFO - Return code: 0 03:57:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:49 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:49 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 03:57:49 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 03:57:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:49 INFO - 'HOME': '/Users/cltbld', 03:57:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:49 INFO - 'LOGNAME': 'cltbld', 03:57:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:49 INFO - 'MOZ_NO_REMOTE': '1', 03:57:49 INFO - 'NO_EM_RESTART': '1', 03:57:49 INFO - 'PAGER': '/bin/cat', 03:57:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:49 INFO - 'PWD': '/builds/slave/test', 03:57:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:49 INFO - 'SHELL': '/bin/bash', 03:57:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:49 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:49 INFO - 'USER': 'cltbld', 03:57:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:49 INFO - 'XPC_FLAGS': '0x0', 03:57:49 INFO - 'XPC_SERVICE_NAME': '0', 03:57:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:57:49 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-0qN21f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:57:49 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)) 03:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:57:49 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-dTxEp0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:57:49 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)) 03:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:57:49 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-vWvT3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:57:49 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)) 03:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:57:49 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-tpWF0P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:57:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:57:49 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-eeyvHD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-cWx_aS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-NZv1by-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-9E7dW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-xC_V2y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-UQ60JL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-MjiuCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-nIBu5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:57:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ykLR0z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:57:50 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)) 03:57:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:57:50 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-ypGcRx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:57:51 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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:51 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-KbsDsk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:57:51 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)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:57:51 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-QEEgVS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:57:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:57:51 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-wGtxcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:57:51 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)) 03:57:51 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)) 03:57:51 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)) 03:57:51 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)) 03:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:57:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:57:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:57:53 INFO - Downloading blessings-1.6.tar.gz 03:57:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:57:54 INFO - Installing collected packages: blessings 03:57:54 INFO - Running setup.py install for blessings 03:57:54 INFO - Successfully installed blessings 03:57:54 INFO - Cleaning up... 03:57:54 INFO - Return code: 0 03:57:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:57:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:54 INFO - Reading from file tmpfile_stdout 03:57:54 INFO - Current package versions: 03:57:54 INFO - blessings == 1.6 03:57:54 INFO - blobuploader == 1.2.4 03:57:54 INFO - docopt == 0.6.1 03:57:54 INFO - functools32 == 3.2.3-2 03:57:54 INFO - jsonschema == 2.5.1 03:57:54 INFO - manifestparser == 1.1 03:57:54 INFO - mozInstall == 1.12 03:57:54 INFO - mozcrash == 1.0 03:57:54 INFO - mozdebug == 0.1 03:57:54 INFO - mozdevice == 0.48 03:57:54 INFO - mozfile == 1.2 03:57:54 INFO - mozhttpd == 0.7 03:57:54 INFO - mozinfo == 0.9 03:57:54 INFO - mozleak == 0.1 03:57:54 INFO - mozlog == 3.4 03:57:54 INFO - moznetwork == 0.27 03:57:54 INFO - mozprocess == 0.23 03:57:54 INFO - mozprofile == 0.28 03:57:54 INFO - mozrunner == 6.13 03:57:54 INFO - mozscreenshot == 0.1 03:57:54 INFO - mozsystemmonitor == 0.3 03:57:54 INFO - moztest == 0.7 03:57:54 INFO - mozversion == 1.4 03:57:54 INFO - psutil == 3.1.1 03:57:54 INFO - requests == 1.2.3 03:57:54 INFO - wsgiref == 0.1.2 03:57:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:57: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')]} 03:57:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57: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')]} 03:57:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:55 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 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:55 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 03:57:55 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 03:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:55 INFO - 'HOME': '/Users/cltbld', 03:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:55 INFO - 'LOGNAME': 'cltbld', 03:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:55 INFO - 'MOZ_NO_REMOTE': '1', 03:57:55 INFO - 'NO_EM_RESTART': '1', 03:57:55 INFO - 'PAGER': '/bin/cat', 03:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:55 INFO - 'PWD': '/builds/slave/test', 03:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:55 INFO - 'SHELL': '/bin/bash', 03:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:55 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:55 INFO - 'USER': 'cltbld', 03:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:55 INFO - 'XPC_FLAGS': '0x0', 03:57:55 INFO - 'XPC_SERVICE_NAME': '0', 03:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 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)) 03:57:55 INFO - Cleaning up... 03:57:55 INFO - Return code: 0 03:57:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:57:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10845f490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1083d5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd773d5ef50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, '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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 03:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:57:55 INFO - 'HOME': '/Users/cltbld', 03:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:57:55 INFO - 'LOGNAME': 'cltbld', 03:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:57:55 INFO - 'MOZ_NO_REMOTE': '1', 03:57:55 INFO - 'NO_EM_RESTART': '1', 03:57:55 INFO - 'PAGER': '/bin/cat', 03:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:57:55 INFO - 'PWD': '/builds/slave/test', 03:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:57:55 INFO - 'SHELL': '/bin/bash', 03:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:57:55 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:57:55 INFO - 'USER': 'cltbld', 03:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:57:55 INFO - 'XPC_FLAGS': '0x0', 03:57:55 INFO - 'XPC_SERVICE_NAME': '0', 03:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:57:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:57:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:57:56 INFO - Cleaning up... 03:57:56 INFO - Return code: 0 03:57:56 INFO - Running post-action listener: _resource_record_post_action 03:57:56 INFO - Running post-action listener: _start_resource_monitoring 03:57:56 INFO - Starting resource monitoring. 03:57:56 INFO - [mozharness: 2016-12-01 11:57:56.257670Z] Finished create-virtualenv step (success) 03:57:56 INFO - [mozharness: 2016-12-01 11:57:56.258658Z] Running install step. 03:57:56 INFO - Running pre-action listener: _resource_record_pre_action 03:57:56 INFO - Running main action method: install 03:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:57:56 INFO - Reading from file tmpfile_stdout 03:57:56 INFO - Detecting whether we're running mozinstall >=1.0... 03:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:57:56 INFO - Reading from file tmpfile_stdout 03:57:56 INFO - Output received: 03:57:56 INFO - Usage: mozinstall [options] installer 03:57:56 INFO - Options: 03:57:56 INFO - -h, --help show this help message and exit 03:57:56 INFO - -d DEST, --destination=DEST 03:57:56 INFO - Directory to install application into. [default: 03:57:56 INFO - "/builds/slave/test"] 03:57:56 INFO - --app=APP Application being installed. [default: firefox] 03:57:56 INFO - mkdir: /builds/slave/test/build/application 03:57:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:57:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:58:28 INFO - Reading from file tmpfile_stdout 03:58:28 INFO - Output received: 03:58:28 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:58:28 INFO - Running post-action listener: _resource_record_post_action 03:58:28 INFO - [mozharness: 2016-12-01 11:58:28.224770Z] Finished install step (success) 03:58:28 INFO - [mozharness: 2016-12-01 11:58:28.224870Z] Running run-tests step. 03:58:28 INFO - Running pre-action listener: _resource_record_pre_action 03:58:28 INFO - Running main action method: run_tests 03:58:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:58:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:58:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:58:28 INFO - 2016-12-01 03:58:28.238 screenresolution[1623:7509] starting screenresolution argv=screenresolution get 03:58:28 INFO - 2016-12-01 03:58:28.240 screenresolution[1623:7509] Display 0: 1600x1200x32@60 03:58:28 INFO - 2016-12-01 03:58:28.246 screenresolution[1624:7511] starting screenresolution argv=screenresolution list 03:58:28 INFO - Available Modes on Display 0 03:58:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:58:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:58:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:58:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:58:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:58:28 INFO - Intel Iris: 03:58:28 INFO - Chipset Model: Intel Iris 03:58:28 INFO - Type: GPU 03:58:28 INFO - Bus: Built-In 03:58:28 INFO - VRAM (Dynamic, Max): 1536 MB 03:58:28 INFO - Vendor: Intel (0x8086) 03:58:28 INFO - Device ID: 0x0a2e 03:58:28 INFO - Revision ID: 0x0009 03:58:28 INFO - Displays: 03:58:28 INFO - Display: 03:58:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:58:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:58:28 INFO - Display Serial Number: 1600x1200 60 03:58:28 INFO - Main Display: Yes 03:58:28 INFO - Mirror: Off 03:58:28 INFO - Online: Yes 03:58:28 INFO - Rotation: Supported 03:58:28 INFO - Return code: 0 03:58:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:58:28 INFO - Python 2.7.3 03:58:28 INFO - Return code: 0 03:58:28 INFO - grabbing minidump binary from tooltool 03:58:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:58:28 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 0x108273d30>, '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 0x1080d9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10826bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:58:28 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 03:58:28 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 03:58:28 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 03:58:28 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:58:28 INFO - Return code: 0 03:58:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:58:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:58:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:58:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:58:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:58:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 03:58:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 03:58:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.n8m7LiXVEK/Render', 03:58:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:58:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:58:28 INFO - 'HOME': '/Users/cltbld', 03:58:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:58:28 INFO - 'LOGNAME': 'cltbld', 03:58:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:58:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:58:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:58:28 INFO - 'MOZ_NO_REMOTE': '1', 03:58:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:58:28 INFO - 'NO_EM_RESTART': '1', 03:58:28 INFO - 'PAGER': '/bin/cat', 03:58:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:58:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:58:28 INFO - 'PWD': '/builds/slave/test', 03:58:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:58:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:58:28 INFO - 'SHELL': '/bin/bash', 03:58:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bf9Jz2i4KE/Listeners', 03:58:28 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 03:58:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:58:28 INFO - 'USER': 'cltbld', 03:58:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:58:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:58:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:58:28 INFO - 'XPC_FLAGS': '0x0', 03:58:28 INFO - 'XPC_SERVICE_NAME': '0', 03:58:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:58:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 03:58:28 INFO - mozversion application_buildid: 20161201004019 03:58:28 INFO - mozversion application_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 03:58:28 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:58:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:58:28 INFO - mozversion application_name: Firefox 03:58:28 INFO - mozversion application_remotingname: firefox-dev 03:58:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:58:28 INFO - mozversion application_vendor: Mozilla 03:58:28 INFO - mozversion application_version: 52.0a2 03:58:28 INFO - mozversion platform_buildid: 20161201004019 03:58:28 INFO - mozversion platform_changeset: fa39ccc915c12f39eb51bd55871266f23fb5e999 03:58:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:58:28 INFO - mozversion platform_version: 52.0a2 03:58:28 INFO - using testdate: 1480593508 03:58:28 INFO - actual date: 1480593508 03:58:28 INFO - starting webserver on 'localhost:49242' 03:58:28 INFO - SUITE-START | Running 2 tests 03:58:28 INFO - TEST-START | dromaeo_css 03:58:28 INFO - operating with platform_type : mac_ 03:58:28 INFO - Initialising browser for dromaeo_css test... 03:58:28 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpwSneXI/profile http://localhost:49242/getInfo.html) 03:58:31 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 03:58:31 INFO - PROCESS | 1634 | colorDepth:24 03:58:31 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 03:58:31 INFO - PROCESS | 1634 | __metrics 03:58:31 INFO - PROCESS | 1634 | 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" 03:58:31 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:58:32 INFO - TEST-INFO | 1634: exit 0 03:58:32 INFO - Browser initialized. 03:58:32 INFO - Running cycle 1/1 for dromaeo_css test... 03:58:32 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpwSneXI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:58:44 INFO - PROCESS | 1637 | RSS: Main: 222642176 03:58:44 INFO - PROCESS | 1637 | 03:59:36 INFO - PROCESS | 1637 | 1480593576134 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:01:49 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 04:01:51 INFO - PROCESS | 1637 | RSS: Main: 371871744 04:01:51 INFO - PROCESS | 1637 | 04:04:54 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 04:04:55 INFO - PROCESS | 1637 | RSS: Main: 348372992 04:04:55 INFO - PROCESS | 1637 | 04:07:57 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 04:07:58 INFO - PROCESS | 1637 | RSS: Main: 315854848 04:07:58 INFO - PROCESS | 1637 | 04:11:00 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 04:11:01 INFO - PROCESS | 1637 | RSS: Main: 312356864 04:11:01 INFO - PROCESS | 1637 | 04:14:04 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 04:14:05 INFO - PROCESS | 1637 | RSS: Main: 311394304 04:14:05 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 04:17:08 INFO - PROCESS | 1637 | RSS: Main: 394244096 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | __start_tp_report 04:17:08 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 04:17:08 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 04:17:08 INFO - PROCESS | 1637 | |i|pagename|runs| 04:17:08 INFO - PROCESS | 1637 | |0;dojo.html;3103.456895043367;3341.3651406232816;3359.1434184283003;3361.848716807744;3409.8124603146834;5651.982643124236;5679.8580035499135;5708.37207907131;5773.278340207474;5774.3648198698165;5704.429557044294;5765.3369862465825;5782.335031471372;5785.566082543805;5790.36306006339;5255.185446255837;5266.736663166846;5278.788848446067;5300.708461034642;5344.706041167754;5195.636305458619;5196.402413722432;5200.947990520084;5216.504432078947;5353.170258609922;5241.2400201970695;5318.654287471321;5342.27879236302;5347.866303342428;5363.731813409334;5318.468153184689;5320.095583750753;5322.866928326784;5323.22813192085;5366.6779993200535;5055.51972562606;5171.3535808024;5241.633085684004;5257.369115706139;5270.894582108336;5635.774569017234;5646.435356464362;5716.114002329646;5732.885342293131;5790.536757059425;5176.094183517869;6134.2332208473945;6208.472279856218;6251.968740156316;6259.718312675895;6185.351582906686;6241.063840423973;6318.4629306509005;6319.778807741707;6320.93679063208;5584.497395234367;5726.3700992890745;5757.88484230313;5797.275340582427;5904.0258357370985;6167.136600875881;6284.183056202664;6287.937120628735;6296.937030629727;6310.810675679737;6134.78528251509;6165.136880836686;6170.444659980623;6178.104174894615;6186.566940314225;6140.078988151813;6163.044728067157;6175.96912015437;6216.036514340284;6245.843853903689;6129.988551888889;6167.383261673886;6182.165407669997;6234.625922444668;6242.719077641517;6094.6647934363145;6239.06414037898;6242.500599951993;6245.156903818017;6246.375362463808;4293.892652683646;4837.7097374157665;4892.315075889378;4954.801807927643;4972.229304457815;4374.671899607479;4385.890352741207;4390.121975604892;4423.380726698265;4486.461624605068;5824.097264923944;5879.235699359056;5885.440883116183;5887.11693246008;5898.262717160355;5381.92361527696;6176.382361763787;6309.053641953652;6320.494360451153;6325.399087086719;5628;5726.713664316768;5727.312722473246;5801.622894511843;5852.356240813507;5390.838274851761;5407.729613519387;5415.48552887475;5453.018456677757;5464.590155738336;6266.498680105672;6297.716602752978;6300.71646775887;6300.936990630035;6305.495560355253;4680.180968330596;5378.8973260480725;5860.09168578871;6298.653574053288;6310.936890631035;6207.131001659862;6228.128062071404;6285.43431091204;6286.497080233479;6300.810975670737;6189.071639254148;6206.317305096345;6224.751009959732;6242.875142497034;6246.562740608114;6116.335771508327;6214.844628884314;6280.057991301342;6291.937080629135;6295.370462953668;5118.846434606968;6037.0642550404755;6072;6119.4798442132915;6156.29202641702;5633;5989.880202396015;6162.722677479434;6164.229471316085;6169.876602467836;5995.760169593342;6006.969965150146;6009.098635204754;6010.459058684739;6019;5924.7037648118285;6042.0332746760305;6045.093236014633;6109.602875813058;6117.155020040472;5059.190529515407;5977.22296101504;6023.367546407564;6034.788782392595;6074.210352654127 04:17:08 INFO - PROCESS | 1637 | |1;ext.html;437.6498800959232;468.97655117244153;472.40476998981285;472.7966974201093;532.8782911970305;29723;31449;31474.68525314749;31476.99999999997;31545.84227078867;31693.366132677333;32428;32432.027039188804;40559.39160912589;41106;9229.538523073854;9363.95318023409;9368.250539956787;9375.906240937606;9383.718488445358;9195.172434480895;9300.999999999965;9304.81390372192;9315.720528384125;9352.345335826494;9290.628374864997;9345;9355.812883742321;9361.4383137012;9371.765705857373;8817;9254.259659227278;9281.443113413217;9291.674791382336;9352.67265645706;8602.354823388208;9267.953660231655;9282.582283797245;9284.675036373694;9319.394239374418;38513.61486385128;38723;38808.80595597004;38816.22367552661;38977;3146.1819926819194;3296.862582409089;3299.4555898276753;3310.93378132436;3666.3767159582935;28005;28027.159185224475;28123.57814632782;29199.56200656992;29846;3118.2672072063046;3171.3657268546385;3337.0656216259245;3338.3657105149946;3340.448825943716;407804;466941;467060;467487;468409;58429.70785146047;58838;58958.462492250605;58997;59233;61688.38311616916;61724.074138887954;61726.38273617206;61763.38236617666;61828;15160.620984475476;15294.617634559225;15312.846871531365;15330.156841373831;15373.461928832661;12342.691432714075;12408.62774116778;12418.379081045912;12423.813642795365;12443.253404795742;13075.411606477732;15535.844641553665;15545.533633990999;15657.042869641295;15752.92123539375;6168.506519478431;6220.937790622126;6310.9368906311265;6320.652364120017;6333.334999825044;877.9824403511894;938.8544775559799;1034.8447732840134;1035.8549803027581;1042.4110377636762;28572;29131.148513676515;29168.41663166725;29378.412431751673;29518;898.573177740568;1070.6680928911906;1086.5056399338282;1137.886211378872;1144.959926402572;14607.415703371746;14667.633309167142;14942.775858362133;15061.698766024618;15067.09597424158;631124;634362;634367;636011;637358;56620.43379566151;56897;56932.715336423884;56968.430315697144;56990;1228.9016878649866;1275.043717212091;1360.6190266725332;1392.9442822287197;1422.665673566711;28322.43355132927;29947.70052299449;30000.549991749704;30176.396472070875;30739.53890691597;48493.51506485031;48984.26523602077;49013;49072.50927490679;49090.772730682016;546508.2674586602;550028;550468;551225.9264444147;551879;25381.61927571051;26158.86920565385;26235.737642623328;26635.20094397171;26746.19761407161;44672.78749838369;44996.55003449923;45098.323525146494;45283.54716452793;45839.54160458353;31862.840685796422;32399.51400729038;36866.893993180245;37389.43915841319;37839.810800945816;37192.256154877294;37287;37301.81349093237;37583.06042348963;37607;36668.26663466769;36958.44562331621;37079;37568.62431375651;37747;31725.682743172274;32751.672483275815;33852.66147338495;34025;34239.315213696085;24649.137280195107;25206.873965630053;25214.74785252124;25481.249037998088;25866.482670346864 04:17:08 INFO - PROCESS | 1637 | |2;jquery.html;335.9025882494077;344.438565138824;372.4357598238667;375.9718021148413;396.7004911291974;14642.34109465074;14823.925880370596;15577;19791.911444870526;20202.19191232349;15702.586767190951;19403.708944365844;20301.390958271277;20464.897675511602;20518.281860134863;5651.5761317901215;6037.305709843358;6041.818745437644;6044.093385992114;6053.969730151365;5012.874678133057;5525.419830917756;5867.207926929853;5894.528437724972;5901.586888917777;5877.324107727602;5895;5895.174675545427;5899;5904.439078287556;5589.860253493654;5769.6826674533095;5809.3609702932645;5816.680082595455;5825.271841019872;5515.862103447405;5599.440055994409;5637.915431268534;5643.68959707216;5655.37790843011;13131.803022954664;13160.27618480983;13262.137961032598;13364.73270534584;13426.597202083953;2177.0421014753456;2188.6498160294436;2190.145843121184;2190.386691726303;2380.5595964746576;9337.066293370677;9892.356996795184;10134.087932086148;10315.535800888978;10427.06156445916;1803.9188236529255;1839.678056340135;1928.6142771445766;1935.47742109631;1994.6609076457037;110828.33757493643;114901;115332;130258;130793.34603326922;11129.72175695598;11448.427578621202;11586.246893951866;11600.825987610193;11776.646700598996;14632.561023169323;14660.633484162981;14667.97324187318;14681.632959176106;14682.265886705622;7149.213586505481;8352.540610266493;8470.957645211734;8523.82952340962;8645.049044605088;8322.6670933162;8327.292180164633;8333.916660833434;8491.320694344438;8499.150084991452;4483.125790470827;4512.120136573337;4514.774261286922;4518.932216016762;4608.5621865922685;1872.9625407491774;1919.7984211657854;1923.519120219945;1930.8841469511874;1934.032983508246;808.7775861638097;878.7012415778538;905.2305540290832;916.702071826659;931.7763736703278;18500.982445965332;20981.580368392548;20994.685079723815;21116.260930867655;21193.547585315293;925.9259259259243;933.6125507914139;947.0671388682142;963.6001059560344;1012.4684540616236;10409.323393979366;10834.349939003527;10968.957948994832;10979.615713449992;11042.227044106996;577093;586689;620101;625664;647402;112716;124739;129419;131219;136641.6335836629;927.638221093774;975.2100798353215;995.1491474789096;1031.1802117316602;1052.3370276725614;18090.638187236444;18181.818181818013;18208.36270730518;18512.629747405244;18517.40756294147;39667.809965701075;51461;52109.47890521046;53269.66825829385;53311.93376132378;77242.84135737857;78742.60628696819;79574.80637790322;80144.19855801601;80828;5942.465178133816;6549.279579246184;7008.544444611084;7451.739189128354;7834.0599128105;7740.612969351398;12510.937445312715;12546.811797823222;12647.304398258184;13051.608451746462;10784.838227426439;10884.401357925073;10894.509747061244;11225.719357015812;11364.147688923198;7683.385329173764;9557.330986830855;10591.788164236827;10843.295185812896;10884.673459796219;10122.949385253027;10716.784265126364;10737.785244295226;10919.672409827717;11505.884941150482;6990.335918087773;7387.482876198615;7427.405807535276;7767.417443691858;8236.423450358658;4510.345999830072;5404.864878378072;10671.35971841692;11173.832392514281;11213.439328033728 04:17:08 INFO - PROCESS | 1637 | |3;mootools.html;1295.1257900916878;1442.7835824626302;1473.1823837770037;1602.5192442267316;1786.928522859085;7460.6642701078445;7851.979242698449;7994.640241189146;8323.835802691314;8325.167483251671;8049;8280.054187265987;8300.377471689617;8304.792380190498;8312.54281014544;2262.9094836206573;2305.458217318937;2306.8615883047073;2307.284741730061;2310.4916918278036;2321.2339927823778;2366.9881650591724;2370.3600027992434;2371.5019845832394;2371.5256948610263;2312.5143719818775;2315.5600435917204;2344;2346.4133966508375;2347.2371479269304;2289.461976435536;2296.1848543766973;2321.025169428844;2321.0367697405554;2321.8258630602772;2329.312852708455;2348.894299756515;2351.165336305621;2352.517733864571;2359.7994170495526;8135.633896474673;8325.750227493185;8332.16678332168;8334.499930004158;8337.83324333516;4180.623743863067;5164.25118357836;5167.276581278624;5199.610029247784;5204.9479505204845;3429.658627937326;3534.3461459630053;3570.5179800727087;3605.9278814423565;3610.801405922673;1057.962971296001;1065.5524679634573;1071.7534966957564;1080.6487891435315;1112.9220954533105;10676.35941843484;10791.352518848895;10905.34567925927;10910.672679819618;10967.396793176373;4357.801604558772;4476.820927162877;4572.634189264851;4672.345871577982;4692.694974826625;8678.175573320525;9398.671046513477;9415.529223538795;9416.717498475056;9430.245580353556;5123.538881500683;5132.717700526507;5136.512031357015;5142.357205349332;5148.510891465305;4050.18996200761;4266.530681625019;4401;4420.270655341833;4674.976625116853;3749.775013499199;3808.6000969898314;3900.278448487039;3902.434147048719;3905.4922860028582;1077.3912739302268;1179.6461061681462;1236.0605939485877;1252.1297698099734;1265.7215412609212;750.6471958179646;871.9433236839712;907.8011369837122;911.4166933162782;911.7720569857536;5639.295088113986;5685.943140568541;5697.116946873241;5739.598228123991;6071.755290165505;912.6121398405651;924.0990034716098;924.9583768730289;927.1238679447857;968.8837339519303;3100.488419410772;3681.2637472505608;3812.84748610058;3855.7493762905883;3928.6624458447177;9349.205317548085;10077.992200779863;10132.24008199388;10138.239632027628;10151.1371533419;9686.128248457673;9991.550380232908;10078.748031299276;10085.29402941787;10091.949540252252;7688;7880.605969701606;7887.842243155104;7919.20807919215;7928.960355198187;4764.166270902509;5435.23906653061;5453.945460545344;5611.74747136388;5611.971940140274;9295.907040929504;9313.720588382359;9371.625134994523;9375.109364610624;9484.146426821619;9102.635894564144;9328.486933218737;9519.857202142111;9644.807103858024;9676.080772326617;7069.681864316221;7156.85686286261;7242.927570724436;7246.058012458347;7258.782236532912;7682.308592226726;7688.115866675404;7780.92219077802;7797.532148071134;7807.414443916616;6984.685689144102;7065.752698655522;7111.43108551325;7139.82150446243;7251.637418129178;6175.59858609189;6972.233054363915;7037.331453511908;7039.612821294878;7054.6472676367;6586.308437613981;6612.272650008399;6612.371824676648;6657.933420665731;6704;4676.181668208118;4725.8818529535665;4829.492903245108;4849.120342663263;4950.975245123751;4731.574158325767;4820;4859.271109333628;4963.156263435297;5000.999800040079 04:17:08 INFO - PROCESS | 1637 | |4;prototype.html;298.59689419283967;306.0527666871035;306.2115053736628;309.1251757526201;310.5171458382218;4290.1470522102845;5130.563902068324;5138.922916156261;5150.330457040584;5183.766730497127;4814.459373000641;5055;5097.260897169908;5116.488351164881;5127.461616530257;3198.5681932939106;3543.3976224041976;3603.351396748584;3746.1945681678367;3998.1403998140395;3209.871605135792;3699.3341198584244;3887.319719049169;3998.840046398155;4040.353543433051;3356.664333566636;3723.4973278607313;3767.6420740029653;3965.9575318710886;3979.7214195006363;3254.5769050023464;3744.4383342498572;3866.5940076292145;3879.282332768447;3984.342583473723;3063.8161710297454;3475.079104037432;3477.2697733476;3500.7222363837272;3514.9630858896685;4711.45818230901;4973.950260497385;4974.781178611263;4995.975020124876;5013.573846223076;3557.3991703733423;4334.612923864364;4480.171168333836;4502.829264391249;4507.639388848885;4498.617617502517;5113.874947511542;5223.973880130575;5299.49654782795;5349.7592608332325;3263.4452143135723;3796.8481260749536;3806.448065030555;3844.442555829389;3848.8845334640005;5353.9196912046355;5682.715864206773;5699.893558010853;5706.80026199081;5741.368449470597;3285.5235990781202;3837.654611085016;3881.6312450317173;3884.2814079395403;3933.977165936877;3062.9234269143003;3957.2876882161486;3968.6229808168177;3972.0735443309973;3976.0855003349393;3754.8873533793485;4203.747775133441;4360.869173924787;4541.75020373876;4551.95448045522;5257.211418287287;5267.209918512254;5414.756335964891;5443.237946687467;5486.149646804752;1259.4521383198155;1272.4910035985679;1277.6167149855007;1281.35932033983;1282.2819221236139;840.277361469136;874.5146443723794;882.9051975550271;889.355217467331;894.9752533349279;4510.571495707903;4729.935764453039;4793.675067592215;4987.426445958761;4993.925091123636;4548.976480291974;5058.215976523645;5415.458454154553;5585.162225666101;5601.551875849922;5066.392032956069;5067.315912351785;5079.187330027178;5089.160288552422;5091.592672586185;2225.151805688721;2409.734929157791;2649.7747691446057;2757.3382388226682;2764.6682398112357;5449.83650490478;5471.9206701453195;5486.6982315973;5495.587830912697;5534.944650553443;4552.362669226311;5045.562014825757;5394.195018590311;5395.3525576931015;5444.83665490028;4659.6505262105475;4715.834945776881;5051.646384753032;5462.672239665632;5524.889502209933;5056.898862022813;5250.343707036621;5282.094847833753;5297.54970827484;5319.175527793198;4576.107659006462;4793.976030119827;4870.586000190024;5057.786131328381;5199.506046925536;5455.0726376515295;5471.2887324647545;5478.506934375925;5481.643693159932;5510.889782204414;3162.5730526378643;3441.328940856509;3678.705703543764;3700.5929347771184;3760.7367484275837;4097.713160078766;5263.289455923401;5269.6838189707205;5284.784499565045;5330.573554115584;4347.826086956613;5129.948700512947;5172.482751724798;5191.714455704972;5217.8173763918085;4445.488768791631;4637.8491250331435;5135;5173.533732399377;5177.731455793355;4208.221479026268;4690.249560070372;5025.447200807981;5042.924356134734;5080.596000679628;5077.949220507895;5105.749091472613;5137.537621614073;5167.922481162861;5227.947720522746;5180.663256888441;5184.900115453247;5187.766550505161;5320.920186197132;5327.653702509325 04:17:08 INFO - PROCESS | 1637 | |5;yui.html;667.7729571945544;694.6353164588592;700.9684564194611;736.7274108579826;799.9720009799659;14325.355359008843;14631.853681463183;14665.999999999987;14693.55919322422;14695.191764452951;12875.420606072727;12884.549040783575;12910.225386476795;12975.026872984517;12985.09104362695;640.0974625777654;665.2316574356626;665.4809248785955;667.0760996020522;803.8191406933437;656.0127008851672;658.9851628492116;792.8969233999572;822.235321151329;827.4125370986607;873.4322690251356;883.7613844262046;888.4935586715575;888.6623083228365;891.2068259249273;875.2691502595341;875.2822685397945;875.9562021898944;876.5923845411851;878.5343767803074;501.9974900125476;514.9382074151089;515.6983164848568;657.7993711917863;659.264919614631;11943.94028029863;11963.521459141624;11972.70068248292;12973.41619627119;13507.932460337734;11972.461239244254;12741.808872866914;13225.933870330682;13495.122817016863;13635.250061246628;11131.554737810478;11137.832932506019;11268.549258029669;11300.717482063013;11314;3510.8946731598094;3620.6922411594783;3700.148965737895;3767.924641507182;3802.8668996585266;74679.25320746777;76097.23902760957;79239.6038019812;79461;81806;10142.340747851365;10375.63685271027;10435.634752783768;10486.213533984981;10597.84103238452;20544.486387840425;20688.48278793042;20850.374488765363;20874.895625521774;21144.365669029954;7674.462787604814;7690.15408305086;7706.38348932084;7726.961365193138;7899.407544434191;3770.4155855842387;4170.016027001263;4290.313549832012;4348.260795664744;4417.779111044435;3338.148772063157;3457.893474088268;3463.5670541182353;3466.722662187019;3470.3753324401346;561.1750726432139;600.7116584039599;619.0744836469416;635.8664680417164;644.24301445801;623.9282482514569;632.8133200705711;650.6096342194645;666.2737615998487;684.2541629623734;7572.460875119783;8135.79660508492;8763.693270735494;8883.689070882489;9093.909060909438;617.6386813713938;653.8496145886472;656.3483526552769;658.9670516474157;660.9306022867626;3914.256291304643;4238.894027649333;4281.657467402601;4421.911561768747;4505.617022553054;77256;80514;108648;112059.43970280097;120015.19977200349;89229;89841.55079224562;90895.54552227196;93229;96216;83431.16568834234;87790.56104719563;88147.99999999872;93483;93770.12459750767;7712.730054448068;8910;8927;9147.817043658957;9415.477415037847;39360.6063939357;51147.232791508904;51480.71298217575;52471;54901;72612.63693681498;78154;78534.607326963;79042.81435778353;80217;11391.658250252505;11506.482208300815;11545.076393888301;11600.941995290306;11729.35488548138;13322.666933326744;13532.188068715908;13556.93221533886;14170.645733856736;14808.407663693331;12864.356782161041;13110.147840390344;13345;13360.198388096745;13572.389242483914;11650.76698466043;12821.038422118565;13189.802152967904;13204.867951320364;13661.18032918028;12141.878581214074;12206.328651923874;12385.93807030959;12397.070219733376;12918.160319579198;10320.638777642747;10683.679489615324;11089.5564177432;11174.385408802269;11381.487833047699;10738.516766745359;11150.330980141218;11238.93230143135;11330.37682927447;11400 04:17:08 INFO - PROCESS | 1637 | __end_tp_report 04:17:08 INFO - PROCESS | 1637 | __start_cc_report 04:17:08 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,1940 04:17:08 INFO - PROCESS | 1637 | __end_cc_report 04:17:08 INFO - PROCESS | 1637 | __startTimestamp1480594628598__endTimestamp 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | ------- Summary: start ------- 04:17:08 INFO - PROCESS | 1637 | Number of tests: 6 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5722.26 Median:5904.03 stddev:649.83 (11.0%) stddev-sans-first:618.50 04:17:08 INFO - PROCESS | 1637 | Values: 3103.5 3341.4 3359.1 3361.8 3409.8 5652.0 5679.9 5708.4 5773.3 5774.4 5704.4 5765.3 5782.3 5785.6 5790.4 5255.2 5266.7 5278.8 5300.7 5344.7 5195.6 5196.4 5200.9 5216.5 5353.2 5241.2 5318.7 5342.3 5347.9 5363.7 5318.5 5320.1 5322.9 5323.2 5366.7 5055.5 5171.4 5241.6 5257.4 5270.9 5635.8 5646.4 5716.1 5732.9 5790.5 5176.1 6134.2 6208.5 6252.0 6259.7 6185.4 6241.1 6318.5 6319.8 6320.9 5584.5 5726.4 5757.9 5797.3 5904.0 6167.1 6284.2 6287.9 6296.9 6310.8 6134.8 6165.1 6170.4 6178.1 6186.6 6140.1 6163.0 6176.0 6216.0 6245.8 6130.0 6167.4 6182.2 6234.6 6242.7 6094.7 6239.1 6242.5 6245.2 6246.4 4293.9 4837.7 4892.3 4954.8 4972.2 4374.7 4385.9 4390.1 4423.4 4486.5 5824.1 5879.2 5885.4 5887.1 5898.3 5381.9 6176.4 6309.1 6320.5 6325.4 5628.0 5726.7 5727.3 5801.6 5852.4 5390.8 5407.7 5415.5 5453.0 5464.6 6266.5 6297.7 6300.7 6300.9 6305.5 4680.2 5378.9 5860.1 6298.7 6310.9 6207.1 6228.1 6285.4 6286.5 6300.8 6189.1 6206.3 6224.8 6242.9 6246.6 6116.3 6214.8 6280.1 6291.9 6295.4 5118.8 6037.1 6072.0 6119.5 6156.3 5633.0 5989.9 6162.7 6164.2 6169.9 5995.8 6007.0 6009.1 6010.5 6019.0 5924.7 6042.0 6045.1 6109.6 6117.2 5059.2 5977.2 6023.4 6034.8 6074.2 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:67235.45 Median:37575.84 stddev:147581.14 (392.8%) stddev-sans-first:147909.93 04:17:08 INFO - PROCESS | 1637 | Values: 437.6 469.0 472.4 472.8 532.9 29723.0 31449.0 31474.7 31477.0 31545.8 31693.4 32428.0 32432.0 40559.4 41106.0 9229.5 9364.0 9368.3 9375.9 9383.7 9195.2 9301.0 9304.8 9315.7 9352.3 9290.6 9345.0 9355.8 9361.4 9371.8 8817.0 9254.3 9281.4 9291.7 9352.7 8602.4 9268.0 9282.6 9284.7 9319.4 38513.6 38723.0 38808.8 38816.2 38977.0 3146.2 3296.9 3299.5 3310.9 3666.4 28005.0 28027.2 28123.6 29199.6 29846.0 3118.3 3171.4 3337.1 3338.4 3340.4 407804.0 466941.0 467060.0 467487.0 468409.0 58429.7 58838.0 58958.5 58997.0 59233.0 61688.4 61724.1 61726.4 61763.4 61828.0 15160.6 15294.6 15312.8 15330.2 15373.5 12342.7 12408.6 12418.4 12423.8 12443.3 13075.4 15535.8 15545.5 15657.0 15752.9 6168.5 6220.9 6310.9 6320.7 6333.3 878.0 938.9 1034.8 1035.9 1042.4 28572.0 29131.1 29168.4 29378.4 29518.0 898.6 1070.7 1086.5 1137.9 1145.0 14607.4 14667.6 14942.8 15061.7 15067.1 631124.0 634362.0 634367.0 636011.0 637358.0 56620.4 56897.0 56932.7 56968.4 56990.0 1228.9 1275.0 1360.6 1392.9 1422.7 28322.4 29947.7 30000.5 30176.4 30739.5 48493.5 48984.3 49013.0 49072.5 49090.8 546508.3 550028.0 550468.0 551225.9 551879.0 25381.6 26158.9 26235.7 26635.2 26746.2 44672.8 44996.6 45098.3 45283.5 45839.5 31862.8 32399.5 36866.9 37389.4 37839.8 37192.3 37287.0 37301.8 37583.1 37607.0 36668.3 36958.4 37079.0 37568.6 37747.0 31725.7 32751.7 33852.7 34025.0 34239.3 24649.1 25206.9 25214.7 25481.2 25866.5 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:34703.17 Median:1358.23 stddev:102191.39 (7523.9%) stddev-sans-first:102445.48 04:17:08 INFO - PROCESS | 1637 | Values: 335.9 344.4 372.4 376.0 396.7 14642.3 14823.9 15577.0 19791.9 20202.2 15702.6 19403.7 20301.4 20464.9 20518.3 5651.6 6037.3 6041.8 6044.1 6054.0 5012.9 5525.4 5867.2 5894.5 5901.6 5877.3 5895.0 5895.2 5899.0 5904.4 5589.9 5769.7 5809.4 5816.7 5825.3 5515.9 5599.4 5637.9 5643.7 5655.4 13131.8 13160.3 13262.1 13364.7 13426.6 2177.0 2188.6 2190.1 2190.4 2380.6 9337.1 9892.4 10134.1 10315.5 10427.1 1803.9 1839.7 1928.6 1935.5 1994.7 110828.3 114901.0 115332.0 130258.0 130793.3 11129.7 11448.4 11586.2 11600.8 11776.6 14632.6 14660.6 14668.0 14681.6 14682.3 7149.2 8352.5 8471.0 8523.8 8645.0 8322.7 8327.3 8333.9 8491.3 8499.2 4483.1 4512.1 4514.8 4518.9 4608.6 1873.0 1919.8 1923.5 1930.9 1934.0 808.8 878.7 905.2 916.7 931.8 18501.0 20981.6 20994.7 21116.3 21193.5 925.9 933.6 947.1 963.6 1012.5 10409.3 10834.3 10969.0 10979.6 11042.2 577093.0 586689.0 620101.0 625664.0 647402.0 112716.0 124739.0 129419.0 131219.0 136641.6 927.6 975.2 995.1 1031.2 1052.3 18090.6 18181.8 18208.4 18512.6 18517.4 39667.8 51461.0 52109.5 53269.7 53311.9 77242.8 78742.6 79574.8 80144.2 80828.0 5942.5 6549.3 7008.5 7451.7 7834.1 7740.6 12510.9 12546.8 12647.3 13051.6 10784.8 10884.4 10894.5 11225.7 11364.1 7683.4 9557.3 10591.8 10843.3 10884.7 10122.9 10716.8 10737.8 10919.7 11505.9 6990.3 7387.5 7427.4 7767.4 8236.4 4510.3 5404.9 10671.4 11173.8 11213.4 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5417.55 Median:5134.61 stddev:2965.89 (57.8%) stddev-sans-first:2958.03 04:17:08 INFO - PROCESS | 1637 | Values: 1295.1 1442.8 1473.2 1602.5 1786.9 7460.7 7852.0 7994.6 8323.8 8325.2 8049.0 8280.1 8300.4 8304.8 8312.5 2262.9 2305.5 2306.9 2307.3 2310.5 2321.2 2367.0 2370.4 2371.5 2371.5 2312.5 2315.6 2344.0 2346.4 2347.2 2289.5 2296.2 2321.0 2321.0 2321.8 2329.3 2348.9 2351.2 2352.5 2359.8 8135.6 8325.8 8332.2 8334.5 8337.8 4180.6 5164.3 5167.3 5199.6 5204.9 3429.7 3534.3 3570.5 3605.9 3610.8 1058.0 1065.6 1071.8 1080.6 1112.9 10676.4 10791.4 10905.3 10910.7 10967.4 4357.8 4476.8 4572.6 4672.3 4692.7 8678.2 9398.7 9415.5 9416.7 9430.2 5123.5 5132.7 5136.5 5142.4 5148.5 4050.2 4266.5 4401.0 4420.3 4675.0 3749.8 3808.6 3900.3 3902.4 3905.5 1077.4 1179.6 1236.1 1252.1 1265.7 750.6 871.9 907.8 911.4 911.8 5639.3 5685.9 5697.1 5739.6 6071.8 912.6 924.1 925.0 927.1 968.9 3100.5 3681.3 3812.8 3855.7 3928.7 9349.2 10078.0 10132.2 10138.2 10151.1 9686.1 9991.6 10078.7 10085.3 10091.9 7688.0 7880.6 7887.8 7919.2 7929.0 4764.2 5435.2 5453.9 5611.7 5612.0 9295.9 9313.7 9371.6 9375.1 9484.1 9102.6 9328.5 9519.9 9644.8 9676.1 7069.7 7156.9 7242.9 7246.1 7258.8 7682.3 7688.1 7780.9 7797.5 7807.4 6984.7 7065.8 7111.4 7139.8 7251.6 6175.6 6972.2 7037.3 7039.6 7054.6 6586.3 6612.3 6612.4 6657.9 6704.0 4676.2 4725.9 4829.5 4849.1 4951.0 4731.6 4820.0 4859.3 4963.2 5001.0 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4288.85 Median:4974.37 stddev:1306.13 (26.3%) stddev-sans-first:1275.00 04:17:08 INFO - PROCESS | 1637 | Values: 298.6 306.1 306.2 309.1 310.5 4290.1 5130.6 5138.9 5150.3 5183.8 4814.5 5055.0 5097.3 5116.5 5127.5 3198.6 3543.4 3603.4 3746.2 3998.1 3209.9 3699.3 3887.3 3998.8 4040.4 3356.7 3723.5 3767.6 3966.0 3979.7 3254.6 3744.4 3866.6 3879.3 3984.3 3063.8 3475.1 3477.3 3500.7 3515.0 4711.5 4974.0 4974.8 4996.0 5013.6 3557.4 4334.6 4480.2 4502.8 4507.6 4498.6 5113.9 5224.0 5299.5 5349.8 3263.4 3796.8 3806.4 3844.4 3848.9 5353.9 5682.7 5699.9 5706.8 5741.4 3285.5 3837.7 3881.6 3884.3 3934.0 3062.9 3957.3 3968.6 3972.1 3976.1 3754.9 4203.7 4360.9 4541.8 4552.0 5257.2 5267.2 5414.8 5443.2 5486.1 1259.5 1272.5 1277.6 1281.4 1282.3 840.3 874.5 882.9 889.4 895.0 4510.6 4729.9 4793.7 4987.4 4993.9 4549.0 5058.2 5415.5 5585.2 5601.6 5066.4 5067.3 5079.2 5089.2 5091.6 2225.2 2409.7 2649.8 2757.3 2764.7 5449.8 5471.9 5486.7 5495.6 5534.9 4552.4 5045.6 5394.2 5395.4 5444.8 4659.7 4715.8 5051.6 5462.7 5524.9 5056.9 5250.3 5282.1 5297.5 5319.2 4576.1 4794.0 4870.6 5057.8 5199.5 5455.1 5471.3 5478.5 5481.6 5510.9 3162.6 3441.3 3678.7 3700.6 3760.7 4097.7 5263.3 5269.7 5284.8 5330.6 4347.8 5129.9 5172.5 5191.7 5217.8 4445.5 4637.8 5135.0 5173.5 5177.7 4208.2 4690.2 5025.4 5042.9 5080.6 5077.9 5105.7 5137.5 5167.9 5227.9 5180.7 5184.9 5187.8 5320.9 5327.7 04:17:08 INFO - PROCESS | 1637 | 04:17:08 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19871.34 Median:4383.02 stddev:28891.22 (659.2%) stddev-sans-first:28936.29 04:17:08 INFO - PROCESS | 1637 | Values: 667.8 694.6 701.0 736.7 800.0 14325.4 14631.9 14666.0 14693.6 14695.2 12875.4 12884.5 12910.2 12975.0 12985.1 640.1 665.2 665.5 667.1 803.8 656.0 659.0 792.9 822.2 827.4 873.4 883.8 888.5 888.7 891.2 875.3 875.3 876.0 876.6 878.5 502.0 514.9 515.7 657.8 659.3 11943.9 11963.5 11972.7 12973.4 13507.9 11972.5 12741.8 13225.9 13495.1 13635.3 11131.6 11137.8 11268.5 11300.7 11314.0 3510.9 3620.7 3700.1 3767.9 3802.9 74679.3 76097.2 79239.6 79461.0 81806.0 10142.3 10375.6 10435.6 10486.2 10597.8 20544.5 20688.5 20850.4 20874.9 21144.4 7674.5 7690.2 7706.4 7727.0 7899.4 3770.4 4170.0 4290.3 4348.3 4417.8 3338.1 3457.9 3463.6 3466.7 3470.4 561.2 600.7 619.1 635.9 644.2 623.9 632.8 650.6 666.3 684.3 7572.5 8135.8 8763.7 8883.7 9093.9 617.6 653.8 656.3 659.0 660.9 3914.3 4238.9 4281.7 4421.9 4505.6 77256.0 80514.0 108648.0 112059.4 120015.2 89229.0 89841.6 90895.5 93229.0 96216.0 83431.2 87790.6 88148.0 93483.0 93770.1 7712.7 8910.0 8927.0 9147.8 9415.5 39360.6 51147.2 51480.7 52471.0 54901.0 72612.6 78154.0 78534.6 79042.8 80217.0 11391.7 11506.5 11545.1 11600.9 11729.4 13322.7 13532.2 13556.9 14170.6 14808.4 12864.4 13110.1 13345.0 13360.2 13572.4 11650.8 12821.0 13189.8 13204.9 13661.2 12141.9 12206.3 12385.9 12397.1 12918.2 10320.6 10683.7 11089.6 11174.4 11381.5 10738.5 11150.3 11238.9 11330.4 11400.0 04:17:08 INFO - PROCESS | 1637 | -------- Summary: end -------- 04:17:08 INFO - PROCESS | 1637 | 04:17:09 INFO - TEST-INFO | 1637: exit 0 04:17:09 INFO - TEST-OK | dromaeo_css | took 1120409ms 04:17:09 INFO - TEST-START | kraken 04:17:09 INFO - operating with platform_type : mac_ 04:17:09 INFO - Initialising browser for kraken test... 04:17:09 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmprLesUV/profile http://localhost:49242/getInfo.html) 04:17:11 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 04:17:11 INFO - PROCESS | 1647 | colorDepth:24 04:17:11 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 04:17:11 INFO - PROCESS | 1647 | __metrics 04:17:11 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" 04:17:11 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:17:12 INFO - TEST-INFO | 1647: exit 0 04:17:12 INFO - Browser initialized. 04:17:12 INFO - Running cycle 1/1 for kraken test... 04:17:12 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmprLesUV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:17:25 INFO - PROCESS | 1649 | RSS: Main: 222810112 04:17:25 INFO - PROCESS | 1649 | 04:17:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 04:17:28 INFO - PROCESS | 1649 | RSS: Main: 270770176 04:17:28 INFO - PROCESS | 1649 | 04:17:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 04:17:32 INFO - PROCESS | 1649 | RSS: Main: 285347840 04:17:32 INFO - PROCESS | 1649 | 04:17:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 04:17:35 INFO - PROCESS | 1649 | RSS: Main: 382382080 04:17:35 INFO - PROCESS | 1649 | 04:17:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 04:17:38 INFO - PROCESS | 1649 | RSS: Main: 285130752 04:17:38 INFO - PROCESS | 1649 | 04:17:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:17:41 INFO - PROCESS | 1649 | RSS: Main: 286031872 04:17:41 INFO - PROCESS | 1649 | 04:17:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 04:17:44 INFO - PROCESS | 1649 | RSS: Main: 326606848 04:17:44 INFO - PROCESS | 1649 | 04:17:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 04:17:47 INFO - PROCESS | 1649 | RSS: Main: 328032256 04:17:47 INFO - PROCESS | 1649 | 04:17:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 04:17:51 INFO - PROCESS | 1649 | RSS: Main: 327749632 04:17:51 INFO - PROCESS | 1649 | 04:17:52 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:17:53 INFO - PROCESS | 1649 | RSS: Main: 299786240 04:17:53 INFO - PROCESS | 1649 | 04:17:55 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:17:56 INFO - PROCESS | 1649 | RSS: Main: 300568576 04:17:56 INFO - PROCESS | 1649 | 04:17:58 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:17:58 INFO - PROCESS | 1649 | RSS: Main: 302919680 04:17:58 INFO - PROCESS | 1649 | 04:18:01 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:18:02 INFO - PROCESS | 1649 | RSS: Main: 320856064 04:18:02 INFO - PROCESS | 1649 | 04:18:05 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:18:05 INFO - PROCESS | 1649 | RSS: Main: 313454592 04:18:05 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 04:18:07 INFO - PROCESS | 1649 | RSS: Main: 357756928 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | __start_tp_report 04:18:07 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 04:18:07 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 04:18:07 INFO - PROCESS | 1649 | |i|pagename|runs| 04:18:07 INFO - PROCESS | 1649 | |0;ai-astar;104;114;117;149;112;113;114;114;113;113 04:18:07 INFO - PROCESS | 1649 | |1;audio-beat-detection;149;112;113;147;114;124;140;116;143;114 04:18:07 INFO - PROCESS | 1649 | |2;audio-dft;185;186;182;185;185;184;184;184;187;184 04:18:07 INFO - PROCESS | 1649 | |3;audio-fft;80;61;58;88;63;61;58;59;81;58 04:18:07 INFO - PROCESS | 1649 | |4;audio-oscillator;83;67;94;69;68;70;89;68;70;92 04:18:07 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;89;85;90;87;91;125;126;86;90;87 04:18:07 INFO - PROCESS | 1649 | |6;imaging-darkroom;95;96;95;96;96;95;94;96;94;95 04:18:07 INFO - PROCESS | 1649 | |7;imaging-desaturate;85;82;80;81;85;84;87;83;83;84 04:18:07 INFO - PROCESS | 1649 | |8;json-parse-financial;55;54;53;54;56;59;58;57;56;57 04:18:07 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;65;73;64;74;73;70;70;71;71;71 04:18:07 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;82;85;85;83;79;78;76;84;97;98 04:18:07 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;147;145;145;146;149;166;146;134;228;164 04:18:07 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;166;167;168;167;166;176;163;165;166;166 04:18:07 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;70;70;66;69;69;68;69;68;67;68 04:18:07 INFO - PROCESS | 1649 | __end_tp_report 04:18:07 INFO - PROCESS | 1649 | __start_cc_report 04:18:07 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2013 04:18:07 INFO - PROCESS | 1649 | __end_cc_report 04:18:07 INFO - PROCESS | 1649 | __startTimestamp1480594687973__endTimestamp 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | ------- Summary: start ------- 04:18:07 INFO - PROCESS | 1649 | Number of tests: 14 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:116.30 Median:114.00 stddev:11.96 (10.5%) stddev-sans-first:11.83 04:18:07 INFO - PROCESS | 1649 | Values: 104.0 114.0 117.0 149.0 112.0 113.0 114.0 114.0 113.0 113.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:127.20 Median:132.00 stddev:15.63 (11.8%) stddev-sans-first:14.45 04:18:07 INFO - PROCESS | 1649 | Values: 149.0 112.0 113.0 147.0 114.0 124.0 140.0 116.0 143.0 114.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.00 stddev:1.35 (0.7%) stddev-sans-first:1.42 04:18:07 INFO - PROCESS | 1649 | Values: 185.0 186.0 182.0 185.0 185.0 184.0 184.0 184.0 187.0 184.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:66.70 Median:62.00 stddev:11.55 (18.6%) stddev-sans-first:11.20 04:18:07 INFO - PROCESS | 1649 | Values: 80.0 61.0 58.0 88.0 63.0 61.0 58.0 59.0 81.0 58.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.00 Median:76.50 stddev:11.15 (14.6%) stddev-sans-first:11.61 04:18:07 INFO - PROCESS | 1649 | Values: 83.0 67.0 94.0 69.0 68.0 70.0 89.0 68.0 70.0 92.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:88.00 stddev:15.88 (18.0%) stddev-sans-first:16.66 04:18:07 INFO - PROCESS | 1649 | Values: 89.0 85.0 90.0 87.0 91.0 125.0 126.0 86.0 90.0 87.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:95.20 Median:95.50 stddev:0.79 (0.8%) stddev-sans-first:0.83 04:18:07 INFO - PROCESS | 1649 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 94.0 96.0 94.0 95.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:83.40 Median:84.00 stddev:2.07 (2.5%) stddev-sans-first:2.11 04:18:07 INFO - PROCESS | 1649 | Values: 85.0 82.0 80.0 81.0 85.0 84.0 87.0 83.0 83.0 84.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.50 stddev:1.91 (3.4%) stddev-sans-first:2.00 04:18:07 INFO - PROCESS | 1649 | Values: 55.0 54.0 53.0 54.0 56.0 59.0 58.0 57.0 56.0 57.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:71.00 stddev:3.29 (4.6%) stddev-sans-first:2.91 04:18:07 INFO - PROCESS | 1649 | Values: 65.0 73.0 64.0 74.0 73.0 70.0 70.0 71.0 71.0 71.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:84.70 Median:84.50 stddev:7.39 (8.8%) stddev-sans-first:7.78 04:18:07 INFO - PROCESS | 1649 | Values: 82.0 85.0 85.0 83.0 79.0 78.0 76.0 84.0 97.0 98.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:148.00 stddev:26.65 (18.0%) stddev-sans-first:28.02 04:18:07 INFO - PROCESS | 1649 | Values: 147.0 145.0 145.0 146.0 149.0 166.0 146.0 134.0 228.0 164.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.00 Median:166.50 stddev:3.43 (2.1%) stddev-sans-first:3.62 04:18:07 INFO - PROCESS | 1649 | Values: 166.0 167.0 168.0 167.0 166.0 176.0 163.0 165.0 166.0 166.0 04:18:07 INFO - PROCESS | 1649 | 04:18:07 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:69.00 stddev:1.26 (1.8%) stddev-sans-first:1.20 04:18:07 INFO - PROCESS | 1649 | Values: 70.0 70.0 66.0 69.0 69.0 68.0 69.0 68.0 67.0 68.0 04:18:07 INFO - PROCESS | 1649 | -------- Summary: end -------- 04:18:07 INFO - PROCESS | 1649 | 04:18:08 INFO - TEST-INFO | 1649: exit 0 04:18:09 INFO - TEST-OK | kraken | took 59802ms 04:18:09 INFO - SUITE-END | took 1180s 04:18:09 INFO - Completed test suite (00:19:40) 04:18:09 INFO - javascript benchmark 04:18:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3103.456895043367, 3341.3651406232816, 3359.1434184283003, 3361.848716807744, 3409.8124603146834, 5651.982643124236, 5679.8580035499135, 5708.37207907131, 5773.278340207474, 5774.3648198698165, 5704.429557044294, 5765.3369862465825, 5782.335031471372, 5785.566082543805, 5790.36306006339, 5255.185446255837, 5266.736663166846, 5278.788848446067, 5300.708461034642, 5344.706041167754, 5195.636305458619, 5196.402413722432, 5200.947990520084, 5216.504432078947, 5353.170258609922, 5241.2400201970695, 5318.654287471321, 5342.27879236302, 5347.866303342428, 5363.731813409334, 5318.468153184689, 5320.095583750753, 5322.866928326784, 5323.22813192085, 5366.6779993200535, 5055.51972562606, 5171.3535808024, 5241.633085684004, 5257.369115706139, 5270.894582108336, 5635.774569017234, 5646.435356464362, 5716.114002329646, 5732.885342293131, 5790.536757059425, 5176.094183517869, 6134.2332208473945, 6208.472279856218, 6251.968740156316, 6259.718312675895, 6185.351582906686, 6241.063840423973, 6318.4629306509005, 6319.778807741707, 6320.93679063208, 5584.497395234367, 5726.3700992890745, 5757.88484230313, 5797.275340582427, 5904.0258357370985, 6167.136600875881, 6284.183056202664, 6287.937120628735, 6296.937030629727, 6310.810675679737, 6134.78528251509, 6165.136880836686, 6170.444659980623, 6178.104174894615, 6186.566940314225, 6140.078988151813, 6163.044728067157, 6175.96912015437, 6216.036514340284, 6245.843853903689, 6129.988551888889, 6167.383261673886, 6182.165407669997, 6234.625922444668, 6242.719077641517, 6094.6647934363145, 6239.06414037898, 6242.500599951993, 6245.156903818017, 6246.375362463808, 4293.892652683646, 4837.7097374157665, 4892.315075889378, 4954.801807927643, 4972.229304457815, 4374.671899607479, 4385.890352741207, 4390.121975604892, 4423.380726698265, 4486.461624605068, 5824.097264923944, 5879.235699359056, 5885.440883116183, 5887.11693246008, 5898.262717160355, 5381.92361527696, 6176.382361763787, 6309.053641953652, 6320.494360451153, 6325.399087086719, 5628.0, 5726.713664316768, 5727.312722473246, 5801.622894511843, 5852.356240813507, 5390.838274851761, 5407.729613519387, 5415.48552887475, 5453.018456677757, 5464.590155738336, 6266.498680105672, 6297.716602752978, 6300.71646775887, 6300.936990630035, 6305.495560355253, 4680.180968330596, 5378.8973260480725, 5860.09168578871, 6298.653574053288, 6310.936890631035, 6207.131001659862, 6228.128062071404, 6285.43431091204, 6286.497080233479, 6300.810975670737, 6189.071639254148, 6206.317305096345, 6224.751009959732, 6242.875142497034, 6246.562740608114, 6116.335771508327, 6214.844628884314, 6280.057991301342, 6291.937080629135, 6295.370462953668, 5118.846434606968, 6037.0642550404755, 6072.0, 6119.4798442132915, 6156.29202641702, 5633.0, 5989.880202396015, 6162.722677479434, 6164.229471316085, 6169.876602467836, 5995.760169593342, 6006.969965150146, 6009.098635204754, 6010.459058684739, 6019.0, 5924.7037648118285, 6042.0332746760305, 6045.093236014633, 6109.602875813058, 6117.155020040472, 5059.190529515407, 5977.22296101504, 6023.367546407564, 6034.788782392595, 6074.210352654127], "value": 5681.635694156995, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [437.6498800959232, 468.97655117244153, 472.40476998981285, 472.7966974201093, 532.8782911970305, 29723.0, 31449.0, 31474.68525314749, 31476.99999999997, 31545.84227078867, 31693.366132677333, 32428.0, 32432.027039188804, 40559.39160912589, 41106.0, 9229.538523073854, 9363.95318023409, 9368.250539956787, 9375.906240937606, 9383.718488445358, 9195.172434480895, 9300.999999999965, 9304.81390372192, 9315.720528384125, 9352.345335826494, 9290.628374864997, 9345.0, 9355.812883742321, 9361.4383137012, 9371.765705857373, 8817.0, 9254.259659227278, 9281.443113413217, 9291.674791382336, 9352.67265645706, 8602.354823388208, 9267.953660231655, 9282.582283797245, 9284.675036373694, 9319.394239374418, 38513.61486385128, 38723.0, 38808.80595597004, 38816.22367552661, 38977.0, 3146.1819926819194, 3296.862582409089, 3299.4555898276753, 3310.93378132436, 3666.3767159582935, 28005.0, 28027.159185224475, 28123.57814632782, 29199.56200656992, 29846.0, 3118.2672072063046, 3171.3657268546385, 3337.0656216259245, 3338.3657105149946, 3340.448825943716, 407804.0, 466941.0, 467060.0, 467487.0, 468409.0, 58429.70785146047, 58838.0, 58958.462492250605, 58997.0, 59233.0, 61688.38311616916, 61724.074138887954, 61726.38273617206, 61763.38236617666, 61828.0, 15160.620984475476, 15294.617634559225, 15312.846871531365, 15330.156841373831, 15373.461928832661, 12342.691432714075, 12408.62774116778, 12418.379081045912, 12423.813642795365, 12443.253404795742, 13075.411606477732, 15535.844641553665, 15545.533633990999, 15657.042869641295, 15752.92123539375, 6168.506519478431, 6220.937790622126, 6310.9368906311265, 6320.652364120017, 6333.334999825044, 877.9824403511894, 938.8544775559799, 1034.8447732840134, 1035.8549803027581, 1042.4110377636762, 28572.0, 29131.148513676515, 29168.41663166725, 29378.412431751673, 29518.0, 898.573177740568, 1070.6680928911906, 1086.5056399338282, 1137.886211378872, 1144.959926402572, 14607.415703371746, 14667.633309167142, 14942.775858362133, 15061.698766024618, 15067.09597424158, 631124.0, 634362.0, 634367.0, 636011.0, 637358.0, 56620.43379566151, 56897.0, 56932.715336423884, 56968.430315697144, 56990.0, 1228.9016878649866, 1275.043717212091, 1360.6190266725332, 1392.9442822287197, 1422.665673566711, 28322.43355132927, 29947.70052299449, 30000.549991749704, 30176.396472070875, 30739.53890691597, 48493.51506485031, 48984.26523602077, 49013.0, 49072.50927490679, 49090.772730682016, 546508.2674586602, 550028.0, 550468.0, 551225.9264444147, 551879.0, 25381.61927571051, 26158.86920565385, 26235.737642623328, 26635.20094397171, 26746.19761407161, 44672.78749838369, 44996.55003449923, 45098.323525146494, 45283.54716452793, 45839.54160458353, 31862.840685796422, 32399.51400729038, 36866.893993180245, 37389.43915841319, 37839.810800945816, 37192.256154877294, 37287.0, 37301.81349093237, 37583.06042348963, 37607.0, 36668.26663466769, 36958.44562331621, 37079.0, 37568.62431375651, 37747.0, 31725.682743172274, 32751.672483275815, 33852.66147338495, 34025.0, 34239.315213696085, 24649.137280195107, 25206.873965630053, 25214.74785252124, 25481.249037998088, 25866.482670346864], "value": 19024.18684087371, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.9025882494077, 344.438565138824, 372.4357598238667, 375.9718021148413, 396.7004911291974, 14642.34109465074, 14823.925880370596, 15577.0, 19791.911444870526, 20202.19191232349, 15702.586767190951, 19403.708944365844, 20301.390958271277, 20464.897675511602, 20518.281860134863, 5651.5761317901215, 6037.305709843358, 6041.818745437644, 6044.093385992114, 6053.969730151365, 5012.874678133057, 5525.419830917756, 5867.207926929853, 5894.528437724972, 5901.586888917777, 5877.324107727602, 5895.0, 5895.174675545427, 5899.0, 5904.439078287556, 5589.860253493654, 5769.6826674533095, 5809.3609702932645, 5816.680082595455, 5825.271841019872, 5515.862103447405, 5599.440055994409, 5637.915431268534, 5643.68959707216, 5655.37790843011, 13131.803022954664, 13160.27618480983, 13262.137961032598, 13364.73270534584, 13426.597202083953, 2177.0421014753456, 2188.6498160294436, 2190.145843121184, 2190.386691726303, 2380.5595964746576, 9337.066293370677, 9892.356996795184, 10134.087932086148, 10315.535800888978, 10427.06156445916, 1803.9188236529255, 1839.678056340135, 1928.6142771445766, 1935.47742109631, 1994.6609076457037, 110828.33757493643, 114901.0, 115332.0, 130258.0, 130793.34603326922, 11129.72175695598, 11448.427578621202, 11586.246893951866, 11600.825987610193, 11776.646700598996, 14632.561023169323, 14660.633484162981, 14667.97324187318, 14681.632959176106, 14682.265886705622, 7149.213586505481, 8352.540610266493, 8470.957645211734, 8523.82952340962, 8645.049044605088, 8322.6670933162, 8327.292180164633, 8333.916660833434, 8491.320694344438, 8499.150084991452, 4483.125790470827, 4512.120136573337, 4514.774261286922, 4518.932216016762, 4608.5621865922685, 1872.9625407491774, 1919.7984211657854, 1923.519120219945, 1930.8841469511874, 1934.032983508246, 808.7775861638097, 878.7012415778538, 905.2305540290832, 916.702071826659, 931.7763736703278, 18500.982445965332, 20981.580368392548, 20994.685079723815, 21116.260930867655, 21193.547585315293, 925.9259259259243, 933.6125507914139, 947.0671388682142, 963.6001059560344, 1012.4684540616236, 10409.323393979366, 10834.349939003527, 10968.957948994832, 10979.615713449992, 11042.227044106996, 577093.0, 586689.0, 620101.0, 625664.0, 647402.0, 112716.0, 124739.0, 129419.0, 131219.0, 136641.6335836629, 927.638221093774, 975.2100798353215, 995.1491474789096, 1031.1802117316602, 1052.3370276725614, 18090.638187236444, 18181.818181818013, 18208.36270730518, 18512.629747405244, 18517.40756294147, 39667.809965701075, 51461.0, 52109.47890521046, 53269.66825829385, 53311.93376132378, 77242.84135737857, 78742.60628696819, 79574.80637790322, 80144.19855801601, 80828.0, 5942.465178133816, 6549.279579246184, 7008.544444611084, 7451.739189128354, 7834.0599128105, 7740.612969351398, 12510.937445312715, 12546.811797823222, 12647.304398258184, 13051.608451746462, 10784.838227426439, 10884.401357925073, 10894.509747061244, 11225.719357015812, 11364.147688923198, 7683.385329173764, 9557.330986830855, 10591.788164236827, 10843.295185812896, 10884.673459796219, 10122.949385253027, 10716.784265126364, 10737.785244295226, 10919.672409827717, 11505.884941150482, 6990.335918087773, 7387.482876198615, 7427.405807535276, 7767.417443691858, 8236.423450358658, 4510.345999830072, 5404.864878378072, 10671.35971841692, 11173.832392514281, 11213.439328033728], "value": 9111.61517477368, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1295.1257900916878, 1442.7835824626302, 1473.1823837770037, 1602.5192442267316, 1786.928522859085, 7460.6642701078445, 7851.979242698449, 7994.640241189146, 8323.835802691314, 8325.167483251671, 8049.0, 8280.054187265987, 8300.377471689617, 8304.792380190498, 8312.54281014544, 2262.9094836206573, 2305.458217318937, 2306.8615883047073, 2307.284741730061, 2310.4916918278036, 2321.2339927823778, 2366.9881650591724, 2370.3600027992434, 2371.5019845832394, 2371.5256948610263, 2312.5143719818775, 2315.5600435917204, 2344.0, 2346.4133966508375, 2347.2371479269304, 2289.461976435536, 2296.1848543766973, 2321.025169428844, 2321.0367697405554, 2321.8258630602772, 2329.312852708455, 2348.894299756515, 2351.165336305621, 2352.517733864571, 2359.7994170495526, 8135.633896474673, 8325.750227493185, 8332.16678332168, 8334.499930004158, 8337.83324333516, 4180.623743863067, 5164.25118357836, 5167.276581278624, 5199.610029247784, 5204.9479505204845, 3429.658627937326, 3534.3461459630053, 3570.5179800727087, 3605.9278814423565, 3610.801405922673, 1057.962971296001, 1065.5524679634573, 1071.7534966957564, 1080.6487891435315, 1112.9220954533105, 10676.35941843484, 10791.352518848895, 10905.34567925927, 10910.672679819618, 10967.396793176373, 4357.801604558772, 4476.820927162877, 4572.634189264851, 4672.345871577982, 4692.694974826625, 8678.175573320525, 9398.671046513477, 9415.529223538795, 9416.717498475056, 9430.245580353556, 5123.538881500683, 5132.717700526507, 5136.512031357015, 5142.357205349332, 5148.510891465305, 4050.18996200761, 4266.530681625019, 4401.0, 4420.270655341833, 4674.976625116853, 3749.775013499199, 3808.6000969898314, 3900.278448487039, 3902.434147048719, 3905.4922860028582, 1077.3912739302268, 1179.6461061681462, 1236.0605939485877, 1252.1297698099734, 1265.7215412609212, 750.6471958179646, 871.9433236839712, 907.8011369837122, 911.4166933162782, 911.7720569857536, 5639.295088113986, 5685.943140568541, 5697.116946873241, 5739.598228123991, 6071.755290165505, 912.6121398405651, 924.0990034716098, 924.9583768730289, 927.1238679447857, 968.8837339519303, 3100.488419410772, 3681.2637472505608, 3812.84748610058, 3855.7493762905883, 3928.6624458447177, 9349.205317548085, 10077.992200779863, 10132.24008199388, 10138.239632027628, 10151.1371533419, 9686.128248457673, 9991.550380232908, 10078.748031299276, 10085.29402941787, 10091.949540252252, 7688.0, 7880.605969701606, 7887.842243155104, 7919.20807919215, 7928.960355198187, 4764.166270902509, 5435.23906653061, 5453.945460545344, 5611.74747136388, 5611.971940140274, 9295.907040929504, 9313.720588382359, 9371.625134994523, 9375.109364610624, 9484.146426821619, 9102.635894564144, 9328.486933218737, 9519.857202142111, 9644.807103858024, 9676.080772326617, 7069.681864316221, 7156.85686286261, 7242.927570724436, 7246.058012458347, 7258.782236532912, 7682.308592226726, 7688.115866675404, 7780.92219077802, 7797.532148071134, 7807.414443916616, 6984.685689144102, 7065.752698655522, 7111.43108551325, 7139.82150446243, 7251.637418129178, 6175.59858609189, 6972.233054363915, 7037.331453511908, 7039.612821294878, 7054.6472676367, 6586.308437613981, 6612.272650008399, 6612.371824676648, 6657.933420665731, 6704.0, 4676.181668208118, 4725.8818529535665, 4829.492903245108, 4849.120342663263, 4950.975245123751, 4731.574158325767, 4820.0, 4859.271109333628, 4963.156263435297, 5000.999800040079], "value": 4395.303527203617, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.59689419283967, 306.0527666871035, 306.2115053736628, 309.1251757526201, 310.5171458382218, 4290.1470522102845, 5130.563902068324, 5138.922916156261, 5150.330457040584, 5183.766730497127, 4814.459373000641, 5055.0, 5097.260897169908, 5116.488351164881, 5127.461616530257, 3198.5681932939106, 3543.3976224041976, 3603.351396748584, 3746.1945681678367, 3998.1403998140395, 3209.871605135792, 3699.3341198584244, 3887.319719049169, 3998.840046398155, 4040.353543433051, 3356.664333566636, 3723.4973278607313, 3767.6420740029653, 3965.9575318710886, 3979.7214195006363, 3254.5769050023464, 3744.4383342498572, 3866.5940076292145, 3879.282332768447, 3984.342583473723, 3063.8161710297454, 3475.079104037432, 3477.2697733476, 3500.7222363837272, 3514.9630858896685, 4711.45818230901, 4973.950260497385, 4974.781178611263, 4995.975020124876, 5013.573846223076, 3557.3991703733423, 4334.612923864364, 4480.171168333836, 4502.829264391249, 4507.639388848885, 4498.617617502517, 5113.874947511542, 5223.973880130575, 5299.49654782795, 5349.7592608332325, 3263.4452143135723, 3796.8481260749536, 3806.448065030555, 3844.442555829389, 3848.8845334640005, 5353.9196912046355, 5682.715864206773, 5699.893558010853, 5706.80026199081, 5741.368449470597, 3285.5235990781202, 3837.654611085016, 3881.6312450317173, 3884.2814079395403, 3933.977165936877, 3062.9234269143003, 3957.2876882161486, 3968.6229808168177, 3972.0735443309973, 3976.0855003349393, 3754.8873533793485, 4203.747775133441, 4360.869173924787, 4541.75020373876, 4551.95448045522, 5257.211418287287, 5267.209918512254, 5414.756335964891, 5443.237946687467, 5486.149646804752, 1259.4521383198155, 1272.4910035985679, 1277.6167149855007, 1281.35932033983, 1282.2819221236139, 840.277361469136, 874.5146443723794, 882.9051975550271, 889.355217467331, 894.9752533349279, 4510.571495707903, 4729.935764453039, 4793.675067592215, 4987.426445958761, 4993.925091123636, 4548.976480291974, 5058.215976523645, 5415.458454154553, 5585.162225666101, 5601.551875849922, 5066.392032956069, 5067.315912351785, 5079.187330027178, 5089.160288552422, 5091.592672586185, 2225.151805688721, 2409.734929157791, 2649.7747691446057, 2757.3382388226682, 2764.6682398112357, 5449.83650490478, 5471.9206701453195, 5486.6982315973, 5495.587830912697, 5534.944650553443, 4552.362669226311, 5045.562014825757, 5394.195018590311, 5395.3525576931015, 5444.83665490028, 4659.6505262105475, 4715.834945776881, 5051.646384753032, 5462.672239665632, 5524.889502209933, 5056.898862022813, 5250.343707036621, 5282.094847833753, 5297.54970827484, 5319.175527793198, 4576.107659006462, 4793.976030119827, 4870.586000190024, 5057.786131328381, 5199.506046925536, 5455.0726376515295, 5471.2887324647545, 5478.506934375925, 5481.643693159932, 5510.889782204414, 3162.5730526378643, 3441.328940856509, 3678.705703543764, 3700.5929347771184, 3760.7367484275837, 4097.713160078766, 5263.289455923401, 5269.6838189707205, 5284.784499565045, 5330.573554115584, 4347.826086956613, 5129.948700512947, 5172.482751724798, 5191.714455704972, 5217.8173763918085, 4445.488768791631, 4637.8491250331435, 5135.0, 5173.533732399377, 5177.731455793355, 4208.221479026268, 4690.249560070372, 5025.447200807981, 5042.924356134734, 5080.596000679628, 5077.949220507895, 5105.749091472613, 5137.537621614073, 5167.922481162861, 5227.947720522746, 5180.663256888441, 5184.900115453247, 5187.766550505161, 5320.920186197132, 5327.653702509325], "value": 3880.1426865416634, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.7729571945544, 694.6353164588592, 700.9684564194611, 736.7274108579826, 799.9720009799659, 14325.355359008843, 14631.853681463183, 14665.999999999987, 14693.55919322422, 14695.191764452951, 12875.420606072727, 12884.549040783575, 12910.225386476795, 12975.026872984517, 12985.09104362695, 640.0974625777654, 665.2316574356626, 665.4809248785955, 667.0760996020522, 803.8191406933437, 656.0127008851672, 658.9851628492116, 792.8969233999572, 822.235321151329, 827.4125370986607, 873.4322690251356, 883.7613844262046, 888.4935586715575, 888.6623083228365, 891.2068259249273, 875.2691502595341, 875.2822685397945, 875.9562021898944, 876.5923845411851, 878.5343767803074, 501.9974900125476, 514.9382074151089, 515.6983164848568, 657.7993711917863, 659.264919614631, 11943.94028029863, 11963.521459141624, 11972.70068248292, 12973.41619627119, 13507.932460337734, 11972.461239244254, 12741.808872866914, 13225.933870330682, 13495.122817016863, 13635.250061246628, 11131.554737810478, 11137.832932506019, 11268.549258029669, 11300.717482063013, 11314.0, 3510.8946731598094, 3620.6922411594783, 3700.148965737895, 3767.924641507182, 3802.8668996585266, 74679.25320746777, 76097.23902760957, 79239.6038019812, 79461.0, 81806.0, 10142.340747851365, 10375.63685271027, 10435.634752783768, 10486.213533984981, 10597.84103238452, 20544.486387840425, 20688.48278793042, 20850.374488765363, 20874.895625521774, 21144.365669029954, 7674.462787604814, 7690.15408305086, 7706.38348932084, 7726.961365193138, 7899.407544434191, 3770.4155855842387, 4170.016027001263, 4290.313549832012, 4348.260795664744, 4417.779111044435, 3338.148772063157, 3457.893474088268, 3463.5670541182353, 3466.722662187019, 3470.3753324401346, 561.1750726432139, 600.7116584039599, 619.0744836469416, 635.8664680417164, 644.24301445801, 623.9282482514569, 632.8133200705711, 650.6096342194645, 666.2737615998487, 684.2541629623734, 7572.460875119783, 8135.79660508492, 8763.693270735494, 8883.689070882489, 9093.909060909438, 617.6386813713938, 653.8496145886472, 656.3483526552769, 658.9670516474157, 660.9306022867626, 3914.256291304643, 4238.894027649333, 4281.657467402601, 4421.911561768747, 4505.617022553054, 77256.0, 80514.0, 108648.0, 112059.43970280097, 120015.19977200349, 89229.0, 89841.55079224562, 90895.54552227196, 93229.0, 96216.0, 83431.16568834234, 87790.56104719563, 88147.99999999872, 93483.0, 93770.12459750767, 7712.730054448068, 8910.0, 8927.0, 9147.817043658957, 9415.477415037847, 39360.6063939357, 51147.232791508904, 51480.71298217575, 52471.0, 54901.0, 72612.63693681498, 78154.0, 78534.607326963, 79042.81435778353, 80217.0, 11391.658250252505, 11506.482208300815, 11545.076393888301, 11600.941995290306, 11729.35488548138, 13322.666933326744, 13532.188068715908, 13556.93221533886, 14170.645733856736, 14808.407663693331, 12864.356782161041, 13110.147840390344, 13345.0, 13360.198388096745, 13572.389242483914, 11650.76698466043, 12821.038422118565, 13189.802152967904, 13204.867951320364, 13661.18032918028, 12141.878581214074, 12206.328651923874, 12385.93807030959, 12397.070219733376, 12918.160319579198, 10320.638777642747, 10683.679489615324, 11089.5564177432, 11174.385408802269, 11381.487833047699, 10738.516766745359, 11150.330980141218, 11238.93230143135, 11330.37682927447, 11400.0], "value": 7097.53653255526, "unit": "score"}], "name": "dromaeo_css", "value": 7015.539579727672, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 117.0, 149.0, 112.0, 113.0, 114.0, 114.0, 113.0, 113.0], "value": 116.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 112.0, 113.0, 147.0, 114.0, 124.0, 140.0, 116.0, 143.0, 114.0], "value": 127.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 186.0, 182.0, 185.0, 185.0, 184.0, 184.0, 184.0, 187.0, 184.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 61.0, 58.0, 88.0, 63.0, 61.0, 58.0, 59.0, 81.0, 58.0], "value": 66.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 67.0, 94.0, 69.0, 68.0, 70.0, 89.0, 68.0, 70.0, 92.0], "value": 77.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 90.0, 87.0, 91.0, 125.0, 126.0, 86.0, 90.0, 87.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 94.0, 96.0, 94.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 80.0, 81.0, 85.0, 84.0, 87.0, 83.0, 83.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 53.0, 54.0, 56.0, 59.0, 58.0, 57.0, 56.0, 57.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 64.0, 74.0, 73.0, 70.0, 70.0, 71.0, 71.0, 71.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 85.0, 83.0, 79.0, 78.0, 76.0, 84.0, 97.0, 98.0], "value": 84.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 145.0, 146.0, 149.0, 166.0, 146.0, 134.0, 228.0, 164.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 168.0, 167.0, 166.0, 176.0, 163.0, 165.0, 166.0, 166.0], "value": 167.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 66.0, 69.0, 69.0, 68.0, 69.0, 68.0, 67.0, 68.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1449.2, "alertThreshold": 2.0}]} 04:18:09 INFO - Return code: 0 04:18:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:18:09 INFO - # TBPL SUCCESS # 04:18:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:18:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:18:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:18:09 INFO - 2016-12-01 04:18:09.294 screenresolution[1653:10038] starting screenresolution argv=screenresolution get 04:18:09 INFO - 2016-12-01 04:18:09.297 screenresolution[1653:10038] Display 0: 1600x1200x32@60 04:18:09 INFO - 2016-12-01 04:18:09.303 screenresolution[1654:10041] starting screenresolution argv=screenresolution list 04:18:09 INFO - Available Modes on Display 0 04:18:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:18:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:18:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:18:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:18:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:18:09 INFO - Intel Iris: 04:18:09 INFO - Chipset Model: Intel Iris 04:18:09 INFO - Type: GPU 04:18:09 INFO - Bus: Built-In 04:18:09 INFO - VRAM (Dynamic, Max): 1536 MB 04:18:09 INFO - Vendor: Intel (0x8086) 04:18:09 INFO - Device ID: 0x0a2e 04:18:09 INFO - Revision ID: 0x0009 04:18:09 INFO - Displays: 04:18:09 INFO - Display: 04:18:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:18:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:18:09 INFO - Display Serial Number: 1600x1200 60 04:18:09 INFO - Main Display: Yes 04:18:09 INFO - Mirror: Off 04:18:09 INFO - Online: Yes 04:18:09 INFO - Rotation: Supported 04:18:09 INFO - Return code: 0 04:18:09 INFO - Running post-action listener: _resource_record_post_action 04:18:09 INFO - [mozharness: 2016-12-01 12:18:09.548312Z] Finished run-tests step (success) 04:18:09 INFO - Running post-run listener: _resource_record_post_run 04:18:09 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37745664; Write bytes: 651868160; Read time: 394; Write time: 1986 04:18:09 INFO - TinderboxPrint: CPU usage
53.7% 04:18:09 INFO - TinderboxPrint: I/O read bytes / time
37,745,664 / 394 04:18:09 INFO - TinderboxPrint: I/O write bytes / time
651,868,160 / 1,986 04:18:09 INFO - TinderboxPrint: CPU idle
2,244.6 (46.3%) 04:18:09 INFO - TinderboxPrint: CPU system
975.3 (20.1%) 04:18:09 INFO - TinderboxPrint: CPU user
1,629.8 (33.6%) 04:18:09 INFO - TinderboxPrint: Swap in / out
386,281,472 / 0 04:18:09 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 334055936; Write bytes: 332666368; Read time: 26577; Write time: 571 04:18:09 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36815872; Write bytes: 301466112; Read time: 380; Write time: 1359 04:18:10 INFO - Running post-run listener: _upload_blobber_files 04:18:10 INFO - Blob upload gear active. 04:18:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:18:10 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 04:18:10 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:18:10 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:18:10 INFO - (blobuploader) - INFO - Open directory for files ... 04:18:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:18:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:18:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:11 INFO - (blobuploader) - INFO - Done attempting. 04:18:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:18:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:18:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:12 INFO - (blobuploader) - INFO - Done attempting. 04:18:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:18:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:18:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:18:14 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:18:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:18:14 INFO - (blobuploader) - INFO - Done attempting. 04:18:14 INFO - (blobuploader) - INFO - Iteration through files over. 04:18:14 INFO - Return code: 0 04:18:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:18:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:18:14 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e463658a60a8e16fa9bee6faf6c66327c8880f6ee119131af5e00940a72d151f2af737b2ee7a2ef2791291036bea713a7518800b8b58c43a82b73b0b1bc1a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9e02833def1af48778952625d6040d18fb2965abfce751696e1d54b15cddafe5966744b93d75ea3b2ec8c3cfc1becc6e521fdf4aed04256bebe7c6187460d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9af98ea033b3a8440967472ff76f7ce570ef1e262b2cebe9f85cc9cff8f69fb2c39c5e5121d370208b60447d31cd8c2f1f743cd98aca50586d259e0e8636293"} 04:18:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:18:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:18:14 INFO - Contents: 04:18:14 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e463658a60a8e16fa9bee6faf6c66327c8880f6ee119131af5e00940a72d151f2af737b2ee7a2ef2791291036bea713a7518800b8b58c43a82b73b0b1bc1a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9e02833def1af48778952625d6040d18fb2965abfce751696e1d54b15cddafe5966744b93d75ea3b2ec8c3cfc1becc6e521fdf4aed04256bebe7c6187460d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9af98ea033b3a8440967472ff76f7ce570ef1e262b2cebe9f85cc9cff8f69fb2c39c5e5121d370208b60447d31cd8c2f1f743cd98aca50586d259e0e8636293"} 04:18:14 INFO - Running post-run listener: copy_logs_to_upload_dir 04:18:14 INFO - Copying logs to upload dir... 04:18:14 INFO - mkdir: /builds/slave/test/build/upload/logs 04:18:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.483041 ========= master_lag: 2.03 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-12-01 04:18:16.721522) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 04:18:16.722753) ========= 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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/Mozilla-Aurora/sha512/3e463658a60a8e16fa9bee6faf6c66327c8880f6ee119131af5e00940a72d151f2af737b2ee7a2ef2791291036bea713a7518800b8b58c43a82b73b0b1bc1a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9e02833def1af48778952625d6040d18fb2965abfce751696e1d54b15cddafe5966744b93d75ea3b2ec8c3cfc1becc6e521fdf4aed04256bebe7c6187460d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9af98ea033b3a8440967472ff76f7ce570ef1e262b2cebe9f85cc9cff8f69fb2c39c5e5121d370208b60447d31cd8c2f1f743cd98aca50586d259e0e8636293"} build_url:https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011333 build_url: 'https://queue.taskcluster.net/v1/task/SINLyq4eSISiiUko73ai1Q/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3e463658a60a8e16fa9bee6faf6c66327c8880f6ee119131af5e00940a72d151f2af737b2ee7a2ef2791291036bea713a7518800b8b58c43a82b73b0b1bc1a52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9e02833def1af48778952625d6040d18fb2965abfce751696e1d54b15cddafe5966744b93d75ea3b2ec8c3cfc1becc6e521fdf4aed04256bebe7c6187460d25", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b9af98ea033b3a8440967472ff76f7ce570ef1e262b2cebe9f85cc9cff8f69fb2c39c5e5121d370208b60447d31cd8c2f1f743cd98aca50586d259e0e8636293"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-01 04:18:16.770865) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-12-01 04:18:16.771300) ========= 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.n8m7LiXVEK/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.bf9Jz2i4KE/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005059 ========= master_lag: 2.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-12-01 04:18:18.828846) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 04:18:18.829252) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-01 04:18:18.838099) ========= ========= Total master_lag: 4.20 =========