builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0233 starttime: 1483818232.33 results: success (0) buildid: 20170107094256 builduid: d824de7591cc41b99a8f629feb6f525c revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.329528) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.330042) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.330326) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.357140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.357576) ========= 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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-07 11:43:52-- 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% 18.5M=0.001s 2017-01-07 11:43:52 (18.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125254 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.506672) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.507013) ========= 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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.024947 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:52.555858) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 11:43:52.556237) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4195687bda73679988aaff7b27a8c3b31e38a0fc --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 4195687bda73679988aaff7b27a8c3b31e38a0fc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-07 11:43:52,652 truncating revision to first 12 chars 2017-01-07 11:43:52,652 Setting DEBUG logging. 2017-01-07 11:43:52,652 attempt 1/10 2017-01-07 11:43:52,652 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4195687bda73?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-07 11:43:54,315 unpacking tar archive at: mozilla-aurora-4195687bda73/testing/mozharness/ program finished with exit code 0 elapsedTime=1.940460 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-07 11:43:54.520213) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:54.520544) ========= script_repo_revision: 4195687bda73679988aaff7b27a8c3b31e38a0fc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:54.520974) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:54.521249) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-07 11:43:54.541373) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-07 11:43:54.541917) ========= /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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:43:54 INFO - MultiFileLogger online at 20170107 11:43:54 in /builds/slave/test 11:43:54 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 11:43:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:43:54 INFO - {'append_to_log': False, 11:43:54 INFO - 'base_work_dir': '/builds/slave/test', 11:43:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 11:43:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:43:54 INFO - 'branch': 'Mozilla-Aurora', 11:43:54 INFO - 'buildbot_json_path': 'buildprops.json', 11:43:54 INFO - 'config_files': ('talos/mac_config.py',), 11:43:54 INFO - 'default_actions': ('clobber', 11:43:54 INFO - 'read-buildbot-config', 11:43:54 INFO - 'download-and-extract', 11:43:54 INFO - 'populate-webroot', 11:43:54 INFO - 'create-virtualenv', 11:43:54 INFO - 'install', 11:43:54 INFO - 'run-tests'), 11:43:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:43:54 INFO - 'download_minidump_stackwalk': True, 11:43:54 INFO - 'download_symbols': 'ondemand', 11:43:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:43:54 INFO - 'tooltool.py': '/tools/tooltool.py', 11:43:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:43:54 INFO - '/tools/misc-python/virtualenv.py')}, 11:43:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:43:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:43:54 INFO - 'installer_path': 'installer.exe', 11:43:54 INFO - 'log_level': 'info', 11:43:54 INFO - 'log_name': 'talos', 11:43:54 INFO - 'log_to_console': True, 11:43:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:43:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:43:54 INFO - 'opt_config_files': (), 11:43:54 INFO - 'pip_index': False, 11:43:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:43:54 INFO - 'cmd': ('bash', 11:43:54 INFO - '-c', 11:43:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:43:54 INFO - 'enabled': True, 11:43:54 INFO - 'halt_on_failure': False, 11:43:54 INFO - 'name': 'check_screen_resolution'},), 11:43:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:43:54 INFO - 'cmd': ('bash', 11:43:54 INFO - '-c', 11:43:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:43:54 INFO - 'enabled': True, 11:43:54 INFO - 'halt_on_failure': False, 11:43:54 INFO - 'name': 'check_screen_resolution'},), 11:43:54 INFO - 'run_cmd_checks_enabled': True, 11:43:54 INFO - 'sps_profile': False, 11:43:54 INFO - 'sps_profile_interval': 0, 11:43:54 INFO - 'suite': 'dromaeojs', 11:43:54 INFO - 'system_bits': '32', 11:43:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:43:54 INFO - 'title': 't-yosemite-r7-0233', 11:43:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:43:54 INFO - 'use_talos_json': True, 11:43:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:43:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:43:54 INFO - 'work_dir': 'build'} 11:43:54 INFO - [mozharness: 2017-01-07 19:43:54.744767Z] Running clobber step. 11:43:54 INFO - Running pre-action listener: _resource_record_pre_action 11:43:54 INFO - Running main action method: clobber 11:43:54 INFO - rmtree: /builds/slave/test/build 11:43:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:43:57 INFO - Running post-action listener: _resource_record_post_action 11:43:57 INFO - [mozharness: 2017-01-07 19:43:57.028452Z] Finished clobber step (success) 11:43:57 INFO - [mozharness: 2017-01-07 19:43:57.028573Z] Running read-buildbot-config step. 11:43:57 INFO - Running pre-action listener: _resource_record_pre_action 11:43:57 INFO - Running main action method: read_buildbot_config 11:43:57 INFO - Using buildbot properties: 11:43:57 INFO - { 11:43:57 INFO - "project": "", 11:43:57 INFO - "product": "firefox", 11:43:57 INFO - "script_repo_revision": "production", 11:43:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 11:43:57 INFO - "repository": "", 11:43:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 11:43:57 INFO - "buildid": "20170107094256", 11:43:57 INFO - "pgo_build": "False", 11:43:57 INFO - "basedir": "/builds/slave/test", 11:43:57 INFO - "buildnumber": 38, 11:43:57 INFO - "slavename": "t-yosemite-r7-0233", 11:43:57 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 11:43:57 INFO - "platform": "yosemite_r7", 11:43:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 11:43:57 INFO - "branch": "mozilla-aurora", 11:43:57 INFO - "repo_path": "releases/mozilla-aurora", 11:43:57 INFO - "revision": "4195687bda73679988aaff7b27a8c3b31e38a0fc", 11:43:57 INFO - "stage_platform": "macosx64", 11:43:57 INFO - "builduid": "d824de7591cc41b99a8f629feb6f525c", 11:43:57 INFO - "slavebuilddir": "test" 11:43:57 INFO - } 11:43:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 11:43:57 INFO - Running post-action listener: _resource_record_post_action 11:43:57 INFO - [mozharness: 2017-01-07 19:43:57.031858Z] Finished read-buildbot-config step (success) 11:43:57 INFO - [mozharness: 2017-01-07 19:43:57.031965Z] Running download-and-extract step. 11:43:57 INFO - Running pre-action listener: _resource_record_pre_action 11:43:57 INFO - Running main action method: download_and_extract 11:43:57 INFO - mkdir: /builds/slave/test/build/tests 11:43:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:43:57 INFO - trying https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 11:43:57 INFO - Downloading https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/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 11:43:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/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 11:43:58 INFO - Downloaded 1199 bytes. 11:43:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 11:43:58 INFO - Using the following test package requirements: 11:43:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:43:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 11:43:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 11:43:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'jsshell-mac.zip'], 11:43:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 11:43:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 11:43:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 11:43:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 11:43:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 11:43:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 11:43:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 11:43:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 11:43:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:43:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:43:58 INFO - Fetch https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:43:58 INFO - Http code: 200 11:43:58 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 11:43:58 INFO - x-amz-cf-id: 9XDVekxIBGCGxUTX-fcxYIWaSEWq090MicpXLU1VizWoDKy0wOAGbQ== 11:43:58 INFO - x-amz-version-id: cqh_zbhjs4ORwpc59p2fcuE9wG4ihK_E 11:43:59 INFO - Expected file size: 16270879 11:43:59 INFO - Obtained file size: 16270879 11:44:06 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 11:44:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 11:44:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 11:44:06 INFO - Fetch https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 11:44:06 INFO - Http code: 200 11:44:06 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 11:44:06 INFO - x-amz-cf-id: zUSpnLDbE1h9BA70DK3nnn5AMoZEncE07fstwQjvO6Jql8yUCXkERQ== 11:44:06 INFO - x-amz-version-id: cqh_zbhjs4ORwpc59p2fcuE9wG4ihK_E 11:44:07 INFO - Expected file size: 16270879 11:44:07 INFO - Obtained file size: 16270879 11:44:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 11:44:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 11:44:13 INFO - Fetch https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 11:44:14 INFO - Http code: 200 11:44:14 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 11:44:14 INFO - x-amz-cf-id: H_AI4mwPSobjnUANv26PLiiHsZ-2XUBp60OjHW-GjQbHGlYSNJM1UQ== 11:44:14 INFO - x-amz-version-id: HEu3vf7vLtu6sUFjdPj3WvP2IFIkwTeZ 11:44:14 INFO - Expected file size: 14409038 11:44:14 INFO - Obtained file size: 14409038 11:44:14 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')]} 11:44:14 INFO - trying https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:14 INFO - Downloading https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 11:44:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/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 11:44:17 INFO - Downloaded 109874209 bytes. 11:44:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:17 INFO - mkdir: /builds/slave/test/properties 11:44:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:44:17 INFO - Writing to file /builds/slave/test/properties/build_url 11:44:17 INFO - Contents: 11:44:17 INFO - build_url:https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 11:44:18 INFO - Running post-action listener: _resource_record_post_action 11:44:18 INFO - Running post-action listener: set_extra_try_arguments 11:44:18 INFO - [mozharness: 2017-01-07 19:44:18.116814Z] Finished download-and-extract step (success) 11:44:18 INFO - [mozharness: 2017-01-07 19:44:18.116943Z] Running populate-webroot step. 11:44:18 INFO - Running pre-action listener: _resource_record_pre_action 11:44:18 INFO - Running main action method: populate_webroot 11:44:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:44:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:44:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:44:18 INFO - '--tppagecycles', 11:44:18 INFO - '7'], 11:44:18 INFO - 'tests': ['tsvgm']}}, 11:44:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:44:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:44:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:44:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:44:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:44:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:44:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['damp', 'tps']}, 11:44:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'tests': ['damp', 'tps']}, 11:44:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['dromaeo_dom']}, 11:44:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:44:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['basic_compositor_video']}, 11:44:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:44:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'other-e10s': {'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['a11yr', 11:44:18 INFO - 'ts_paint', 11:44:18 INFO - 'tpaint', 11:44:18 INFO - 'sessionrestore', 11:44:18 INFO - 'sessionrestore_no_auto_restore', 11:44:18 INFO - 'tabpaint']}, 11:44:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['tsvgx', 11:44:18 INFO - 'tsvgr_opacity', 11:44:18 INFO - 'tart', 11:44:18 INFO - 'tscrollx', 11:44:18 INFO - 'cart']}, 11:44:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:44:18 INFO - 'tsvgr_opacity', 11:44:18 INFO - 'tart', 11:44:18 INFO - 'tscrollx', 11:44:18 INFO - 'cart']}, 11:44:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'talos_options': ['--disable-e10s'], 11:44:18 INFO - 'tests': ['tp5o']}, 11:44:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'tests': ['tp5o']}, 11:44:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'talos_options': ['--disable-e10s', 11:44:18 INFO - '--xperf_path', 11:44:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:44:18 INFO - 'tests': ['tp5n']}, 11:44:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:44:18 INFO - 'talos_options': ['--xperf_path', 11:44:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:44:18 INFO - 'tests': ['tp5n']}}, 11:44:18 INFO - 'talos.zip': {'path': '', 11:44:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:44:18 INFO - Running post-action listener: _resource_record_post_action 11:44:18 INFO - [mozharness: 2017-01-07 19:44:18.136059Z] Finished populate-webroot step (success) 11:44:18 INFO - [mozharness: 2017-01-07 19:44:18.136176Z] Running create-virtualenv step. 11:44:18 INFO - Running pre-action listener: _resource_record_pre_action 11:44:18 INFO - Running main action method: create_virtualenv 11:44:18 INFO - Creating virtualenv /builds/slave/test/build/venv 11:44:18 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 11:44:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:44:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:44:18 INFO - Using real prefix '/tools/python27' 11:44:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:44:19 INFO - Installing distribute.............................................................................................................................................................................................done. 11:44:21 INFO - Installing pip.................done. 11:44:21 INFO - Return code: 0 11:44:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:44:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:44:22 INFO - Reading from file tmpfile_stdout 11:44:22 INFO - Output received: 11:44:22 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) 11:44:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:44:22 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')]} 11:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:22 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')]} 11:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:22 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:22 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 11:44:22 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 11:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:22 INFO - 'HOME': '/Users/cltbld', 11:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:22 INFO - 'LOGNAME': 'cltbld', 11:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:22 INFO - 'MOZ_NO_REMOTE': '1', 11:44:22 INFO - 'NO_EM_RESTART': '1', 11:44:22 INFO - 'PAGER': '/bin/cat', 11:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:22 INFO - 'PWD': '/builds/slave/test', 11:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:22 INFO - 'SHELL': '/bin/bash', 11:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:22 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:22 INFO - 'USER': 'cltbld', 11:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:22 INFO - 'XPC_FLAGS': '0x0', 11:44:22 INFO - 'XPC_SERVICE_NAME': '0', 11:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:22 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 11:44:22 INFO - Cleaning up... 11:44:22 INFO - Return code: 0 11:44:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:44:22 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')]} 11:44:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:22 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')]} 11:44:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:22 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:22 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 11:44:22 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 11:44:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:22 INFO - 'HOME': '/Users/cltbld', 11:44:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:22 INFO - 'LOGNAME': 'cltbld', 11:44:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:22 INFO - 'MOZ_NO_REMOTE': '1', 11:44:22 INFO - 'NO_EM_RESTART': '1', 11:44:22 INFO - 'PAGER': '/bin/cat', 11:44:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:22 INFO - 'PWD': '/builds/slave/test', 11:44:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:22 INFO - 'SHELL': '/bin/bash', 11:44:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:22 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:22 INFO - 'USER': 'cltbld', 11:44:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:22 INFO - 'XPC_FLAGS': '0x0', 11:44:22 INFO - 'XPC_SERVICE_NAME': '0', 11:44:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:22 INFO - Downloading/unpacking psutil>=3.1.1 11:44:22 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 11:44:22 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 11:44:22 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 11:44:22 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 11:44:22 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 11:44:22 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 11:44:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:44:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:44:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:44:26 INFO - Installing collected packages: psutil 11:44:26 INFO - Running setup.py install for psutil 11:44:26 INFO - building 'psutil._psutil_osx' extension 11:44:26 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 11:44:26 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 11:44:26 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 11:44:26 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 11:44:26 INFO - building 'psutil._psutil_posix' extension 11:44:26 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 11:44:27 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:44:27 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:44:27 INFO - ^ 11:44:27 INFO - 1 warning generated. 11:44:27 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 11:44:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:44:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:44:27 INFO - Successfully installed psutil 11:44:27 INFO - Cleaning up... 11:44:27 INFO - Return code: 0 11:44:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:44: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 mozsystemmonitor==0.3 11:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:27 INFO - 'HOME': '/Users/cltbld', 11:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:27 INFO - 'LOGNAME': 'cltbld', 11:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:27 INFO - 'MOZ_NO_REMOTE': '1', 11:44:27 INFO - 'NO_EM_RESTART': '1', 11:44:27 INFO - 'PAGER': '/bin/cat', 11:44:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:27 INFO - 'PWD': '/builds/slave/test', 11:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:27 INFO - 'SHELL': '/bin/bash', 11:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:27 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:27 INFO - 'USER': 'cltbld', 11:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:27 INFO - 'XPC_FLAGS': '0x0', 11:44:27 INFO - 'XPC_SERVICE_NAME': '0', 11:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:44: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 11:44: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 11:44: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 11:44: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 11:44: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 11:44: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 11:44:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:44:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:44:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:44:30 INFO - Installing collected packages: mozsystemmonitor 11:44:30 INFO - Running setup.py install for mozsystemmonitor 11:44:30 INFO - Successfully installed mozsystemmonitor 11:44:30 INFO - Cleaning up... 11:44:30 INFO - Return code: 0 11:44:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:44:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:30 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:30 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 11:44:30 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 11:44:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:30 INFO - 'HOME': '/Users/cltbld', 11:44:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:30 INFO - 'LOGNAME': 'cltbld', 11:44:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:30 INFO - 'MOZ_NO_REMOTE': '1', 11:44:30 INFO - 'NO_EM_RESTART': '1', 11:44:30 INFO - 'PAGER': '/bin/cat', 11:44:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:30 INFO - 'PWD': '/builds/slave/test', 11:44:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:30 INFO - 'SHELL': '/bin/bash', 11:44:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:30 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:30 INFO - 'USER': 'cltbld', 11:44:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:30 INFO - 'XPC_FLAGS': '0x0', 11:44:30 INFO - 'XPC_SERVICE_NAME': '0', 11:44:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:30 INFO - Downloading/unpacking jsonschema==2.5.1 11:44:30 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 11:44:30 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 11:44:30 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 11:44:30 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 11:44:30 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 11:44:30 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 11:44:33 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:44:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:44:33 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 11:44:33 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 11:44:33 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 11:44:33 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 11:44:33 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 11:44:33 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 11:44:33 INFO - Downloading functools32-3.2.3-2.tar.gz 11:44:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:44:33 INFO - warning: no files found matching '*.txt' 11:44:33 INFO - no previously-included directories found matching 'build' 11:44:33 INFO - no previously-included directories found matching 'dist' 11:44:33 INFO - no previously-included directories found matching '.git*' 11:44:33 INFO - Installing collected packages: jsonschema, functools32 11:44:33 INFO - Running setup.py install for functools32 11:44:34 INFO - warning: no files found matching '*.txt' 11:44:34 INFO - no previously-included directories found matching 'build' 11:44:34 INFO - no previously-included directories found matching 'dist' 11:44:34 INFO - no previously-included directories found matching '.git*' 11:44:34 INFO - Successfully installed jsonschema functools32 11:44:34 INFO - Cleaning up... 11:44:34 INFO - Return code: 0 11:44:34 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:44:34 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')]} 11:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:34 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')]} 11:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:34 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:34 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 11:44:34 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 11:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:34 INFO - 'HOME': '/Users/cltbld', 11:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:34 INFO - 'LOGNAME': 'cltbld', 11:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:34 INFO - 'MOZ_NO_REMOTE': '1', 11:44:34 INFO - 'NO_EM_RESTART': '1', 11:44:34 INFO - 'PAGER': '/bin/cat', 11:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:34 INFO - 'PWD': '/builds/slave/test', 11:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:34 INFO - 'SHELL': '/bin/bash', 11:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:34 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:34 INFO - 'USER': 'cltbld', 11:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:34 INFO - 'XPC_FLAGS': '0x0', 11:44:34 INFO - 'XPC_SERVICE_NAME': '0', 11:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:44:34 INFO - Cleaning up... 11:44:34 INFO - Return code: 0 11:44:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:44:34 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')]} 11:44:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:34 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')]} 11:44:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:34 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:34 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 11:44:34 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 11:44:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:34 INFO - 'HOME': '/Users/cltbld', 11:44:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:34 INFO - 'LOGNAME': 'cltbld', 11:44:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:34 INFO - 'MOZ_NO_REMOTE': '1', 11:44:34 INFO - 'NO_EM_RESTART': '1', 11:44:34 INFO - 'PAGER': '/bin/cat', 11:44:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:34 INFO - 'PWD': '/builds/slave/test', 11:44:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:34 INFO - 'SHELL': '/bin/bash', 11:44:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:34 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:34 INFO - 'USER': 'cltbld', 11:44:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:34 INFO - 'XPC_FLAGS': '0x0', 11:44:34 INFO - 'XPC_SERVICE_NAME': '0', 11:44:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:34 INFO - Downloading/unpacking blobuploader==1.2.4 11:44:34 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 11:44:34 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 11:44:34 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 11:44:34 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 11:44:34 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 11:44:34 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 11:44:37 INFO - Downloading blobuploader-1.2.4.tar.gz 11:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:44:37 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:44:37 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 11:44:37 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 11:44:37 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 11:44:37 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 11:44:37 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 11:44:37 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 11:44:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:44:38 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:44: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 11:44: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 11:44: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 11:44: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 11:44: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 11:44: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 11:44:38 INFO - Downloading docopt-0.6.1.tar.gz 11:44:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:44:38 INFO - Installing collected packages: blobuploader, requests, docopt 11:44:38 INFO - Running setup.py install for blobuploader 11:44:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:44:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:44:38 INFO - Running setup.py install for requests 11:44:39 INFO - Running setup.py install for docopt 11:44:39 INFO - Successfully installed blobuploader requests docopt 11:44:39 INFO - Cleaning up... 11:44:39 INFO - Return code: 0 11:44:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:39 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:39 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 11:44:39 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 11:44:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:39 INFO - 'HOME': '/Users/cltbld', 11:44:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:39 INFO - 'LOGNAME': 'cltbld', 11:44:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:39 INFO - 'MOZ_NO_REMOTE': '1', 11:44:39 INFO - 'NO_EM_RESTART': '1', 11:44:39 INFO - 'PAGER': '/bin/cat', 11:44:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:39 INFO - 'PWD': '/builds/slave/test', 11:44:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:39 INFO - 'SHELL': '/bin/bash', 11:44:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:39 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:39 INFO - 'USER': 'cltbld', 11:44:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:39 INFO - 'XPC_FLAGS': '0x0', 11:44:39 INFO - 'XPC_SERVICE_NAME': '0', 11:44:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:44:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_4Ski5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:44:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:44:39 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-K64B2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-ef_y1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-G7SDys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Orr2um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-frLCAu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-xAb6P8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-viyWVD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-QRPwMn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-QcPcyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-O8ZNX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:44:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:44:40 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bKZ0zd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:44:41 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-BYbq38-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:44:41 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-FL022B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:41 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-A_m9wx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:44:41 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-kb1ZQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:44:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:44:41 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-dT3yMQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:44:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:44:41 INFO - Running setup.py install for manifestparser 11:44:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:44:41 INFO - Running setup.py install for mozcrash 11:44:41 INFO - Running setup.py install for mozdebug 11:44:42 INFO - Running setup.py install for mozdevice 11:44:42 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Running setup.py install for mozfile 11:44:42 INFO - Running setup.py install for mozhttpd 11:44:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Running setup.py install for mozinfo 11:44:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Running setup.py install for mozInstall 11:44:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:44:42 INFO - Running setup.py install for mozleak 11:44:42 INFO - Running setup.py install for mozlog 11:44:43 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Running setup.py install for moznetwork 11:44:43 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Running setup.py install for mozprocess 11:44:43 INFO - Running setup.py install for mozprofile 11:44:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Running setup.py install for mozrunner 11:44:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:44:43 INFO - Running setup.py install for mozscreenshot 11:44:43 INFO - Running setup.py install for moztest 11:44:44 INFO - Running setup.py install for mozversion 11:44:44 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:44:44 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:44:44 INFO - Cleaning up... 11:44:44 INFO - Return code: 0 11:44:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:44 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:44 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 11:44:44 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 11:44:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:44 INFO - 'HOME': '/Users/cltbld', 11:44:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:44 INFO - 'LOGNAME': 'cltbld', 11:44:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:44 INFO - 'MOZ_NO_REMOTE': '1', 11:44:44 INFO - 'NO_EM_RESTART': '1', 11:44:44 INFO - 'PAGER': '/bin/cat', 11:44:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:44 INFO - 'PWD': '/builds/slave/test', 11:44:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:44 INFO - 'SHELL': '/bin/bash', 11:44:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:44 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:44 INFO - 'USER': 'cltbld', 11:44:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:44 INFO - 'XPC_FLAGS': '0x0', 11:44:44 INFO - 'XPC_SERVICE_NAME': '0', 11:44:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:44:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-q7FPrG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:44:44 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)) 11:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:44:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-VSs5sN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:44:44 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)) 11:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:44:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-_K4ibJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:44:44 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)) 11:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:44:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-yMhFkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:44:44 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)) 11:44:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:44:44 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-NpMjag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bO0Dgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-iX1yrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-bZlsv0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-8s8XgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-cBYD9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-LoJiWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-Zh1PSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-wh15fg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-CD716B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:44:45 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)) 11:44:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:45 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-qe2TDp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:44:46 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)) 11:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:44:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-jDb_RM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:44:46 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:44:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:44:46 INFO - Running setup.py (path:/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/pip-mYC3hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:44:46 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)) 11:44:46 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)) 11:44:46 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)) 11:44:46 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)) 11:44:46 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)) 11:44:46 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)) 11:44:46 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:44:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:44:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:44:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:44:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:44:48 INFO - Downloading blessings-1.6.tar.gz 11:44:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:44:48 INFO - Installing collected packages: blessings 11:44:48 INFO - Running setup.py install for blessings 11:44:49 INFO - Successfully installed blessings 11:44:49 INFO - Cleaning up... 11:44:49 INFO - Return code: 0 11:44:49 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:44:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:44:49 INFO - Reading from file tmpfile_stdout 11:44:49 INFO - Current package versions: 11:44:49 INFO - blessings == 1.6 11:44:49 INFO - blobuploader == 1.2.4 11:44:49 INFO - docopt == 0.6.1 11:44:49 INFO - functools32 == 3.2.3-2 11:44:49 INFO - jsonschema == 2.5.1 11:44:49 INFO - manifestparser == 1.1 11:44:49 INFO - mozInstall == 1.12 11:44:49 INFO - mozcrash == 1.0 11:44:49 INFO - mozdebug == 0.1 11:44:49 INFO - mozdevice == 0.48 11:44:49 INFO - mozfile == 1.2 11:44:49 INFO - mozhttpd == 0.7 11:44:49 INFO - mozinfo == 0.9 11:44:49 INFO - mozleak == 0.1 11:44:49 INFO - mozlog == 3.4 11:44:49 INFO - moznetwork == 0.27 11:44:49 INFO - mozprocess == 0.23 11:44:49 INFO - mozprofile == 0.28 11:44:49 INFO - mozrunner == 6.13 11:44:49 INFO - mozscreenshot == 0.1 11:44:49 INFO - mozsystemmonitor == 0.3 11:44:49 INFO - moztest == 0.8 11:44:49 INFO - mozversion == 1.4 11:44:49 INFO - psutil == 3.1.1 11:44:49 INFO - requests == 1.2.3 11:44:49 INFO - wsgiref == 0.1.2 11:44:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:44: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')]} 11:44:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44: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')]} 11:44:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:50 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:50 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 11:44:50 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 11:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:50 INFO - 'HOME': '/Users/cltbld', 11:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:50 INFO - 'LOGNAME': 'cltbld', 11:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:50 INFO - 'MOZ_NO_REMOTE': '1', 11:44:50 INFO - 'NO_EM_RESTART': '1', 11:44:50 INFO - 'PAGER': '/bin/cat', 11:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:50 INFO - 'PWD': '/builds/slave/test', 11:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:50 INFO - 'SHELL': '/bin/bash', 11:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:50 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:50 INFO - 'USER': 'cltbld', 11:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:50 INFO - 'XPC_FLAGS': '0x0', 11:44:50 INFO - 'XPC_SERVICE_NAME': '0', 11:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 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)) 11:44:50 INFO - Cleaning up... 11:44:50 INFO - Return code: 0 11:44:50 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:44:50 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')]} 11:44:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:44:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:50 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')]} 11:44:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:44:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:44:50 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 0x10b4b8490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b42e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f894a4aee60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, '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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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 11:44:50 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 11:44:50 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 11:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:44:50 INFO - 'HOME': '/Users/cltbld', 11:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:44:50 INFO - 'LOGNAME': 'cltbld', 11:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:44:50 INFO - 'MOZ_NO_REMOTE': '1', 11:44:50 INFO - 'NO_EM_RESTART': '1', 11:44:50 INFO - 'PAGER': '/bin/cat', 11:44:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:44:50 INFO - 'PWD': '/builds/slave/test', 11:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:44:50 INFO - 'SHELL': '/bin/bash', 11:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:44:50 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:44:50 INFO - 'USER': 'cltbld', 11:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:44:50 INFO - 'XPC_FLAGS': '0x0', 11:44:50 INFO - 'XPC_SERVICE_NAME': '0', 11:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:44:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:44:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:44:51 INFO - Cleaning up... 11:44:51 INFO - Return code: 0 11:44:51 INFO - Running post-action listener: _resource_record_post_action 11:44:51 INFO - Running post-action listener: _start_resource_monitoring 11:44:51 INFO - Starting resource monitoring. 11:44:51 INFO - [mozharness: 2017-01-07 19:44:51.237041Z] Finished create-virtualenv step (success) 11:44:51 INFO - [mozharness: 2017-01-07 19:44:51.238009Z] Running install step. 11:44:51 INFO - Running pre-action listener: _resource_record_pre_action 11:44:51 INFO - Running main action method: install 11:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:44:51 INFO - Reading from file tmpfile_stdout 11:44:51 INFO - Detecting whether we're running mozinstall >=1.0... 11:44:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:44:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:44:51 INFO - Reading from file tmpfile_stdout 11:44:51 INFO - Output received: 11:44:51 INFO - Usage: mozinstall [options] installer 11:44:51 INFO - Options: 11:44:51 INFO - -h, --help show this help message and exit 11:44:51 INFO - -d DEST, --destination=DEST 11:44:51 INFO - Directory to install application into. [default: 11:44:51 INFO - "/builds/slave/test"] 11:44:51 INFO - --app=APP Application being installed. [default: firefox] 11:44:51 INFO - mkdir: /builds/slave/test/build/application 11:44:51 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'] 11:44:51 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 11:45:22 INFO - Reading from file tmpfile_stdout 11:45:22 INFO - Output received: 11:45:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 11:45:22 INFO - Running post-action listener: _resource_record_post_action 11:45:22 INFO - [mozharness: 2017-01-07 19:45:22.934627Z] Finished install step (success) 11:45:22 INFO - [mozharness: 2017-01-07 19:45:22.934795Z] Running run-tests step. 11:45:22 INFO - Running pre-action listener: _resource_record_pre_action 11:45:22 INFO - Running main action method: run_tests 11:45:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:45:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:45:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:45:22 INFO - 2017-01-07 11:45:22.949 screenresolution[1623:7318] starting screenresolution argv=screenresolution get 11:45:22 INFO - 2017-01-07 11:45:22.951 screenresolution[1623:7318] Display 0: 1600x1200x32@60 11:45:22 INFO - 2017-01-07 11:45:22.957 screenresolution[1624:7320] starting screenresolution argv=screenresolution list 11:45:22 INFO - Available Modes on Display 0 11:45:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:45:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:45:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:45:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:45:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:45:23 INFO - Intel Iris: 11:45:23 INFO - Chipset Model: Intel Iris 11:45:23 INFO - Type: GPU 11:45:23 INFO - Bus: Built-In 11:45:23 INFO - VRAM (Dynamic, Max): 1536 MB 11:45:23 INFO - Vendor: Intel (0x8086) 11:45:23 INFO - Device ID: 0x0a2e 11:45:23 INFO - Revision ID: 0x0009 11:45:23 INFO - Displays: 11:45:23 INFO - Display: 11:45:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:45:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:45:23 INFO - Display Serial Number: 1600x1200 60 11:45:23 INFO - Main Display: Yes 11:45:23 INFO - Mirror: Off 11:45:23 INFO - Online: Yes 11:45:23 INFO - Rotation: Supported 11:45:23 INFO - Return code: 0 11:45:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:45:23 INFO - Python 2.7.3 11:45:23 INFO - Return code: 0 11:45:23 INFO - grabbing minidump binary from tooltool 11:45:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:45:23 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 0x10b2ccd30>, '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 0x10b132db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b2c4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:45:23 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 11:45:23 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 11:45:23 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 11:45:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:45:23 INFO - Return code: 0 11:45:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:45:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:45:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:45:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:45:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:45:23 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/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 11:45:23 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/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0233 --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 11:45:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.zuIAdQffU2/Render', 11:45:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:45:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:45:23 INFO - 'HOME': '/Users/cltbld', 11:45:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:45:23 INFO - 'LOGNAME': 'cltbld', 11:45:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:45:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:45:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:45:23 INFO - 'MOZ_NO_REMOTE': '1', 11:45:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:45:23 INFO - 'NO_EM_RESTART': '1', 11:45:23 INFO - 'PAGER': '/bin/cat', 11:45:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:45:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:45:23 INFO - 'PWD': '/builds/slave/test', 11:45:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:45:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:45:23 INFO - 'SHELL': '/bin/bash', 11:45:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7cnXSwm1Xb/Listeners', 11:45:23 INFO - 'TMPDIR': '/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/', 11:45:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:45:23 INFO - 'USER': 'cltbld', 11:45:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:45:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:45:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:45:23 INFO - 'XPC_FLAGS': '0x0', 11:45:23 INFO - 'XPC_SERVICE_NAME': '0', 11:45:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:45:23 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/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0233', '--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 11:45:23 INFO - mozversion application_buildid: 20170107094256 11:45:23 INFO - mozversion application_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 11:45:23 INFO - mozversion application_display_name: FirefoxDeveloperEdition 11:45:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:45:23 INFO - mozversion application_name: Firefox 11:45:23 INFO - mozversion application_remotingname: firefox-dev 11:45:23 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:45:23 INFO - mozversion application_vendor: Mozilla 11:45:23 INFO - mozversion application_version: 52.0a2 11:45:23 INFO - mozversion platform_buildid: 20170107094256 11:45:23 INFO - mozversion platform_changeset: 4195687bda73679988aaff7b27a8c3b31e38a0fc 11:45:23 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 11:45:23 INFO - mozversion platform_version: 52.0a2 11:45:23 INFO - using testdate: 1483818323 11:45:23 INFO - actual date: 1483818323 11:45:23 INFO - starting webserver on 'localhost:49240' 11:45:23 INFO - SUITE-START | Running 2 tests 11:45:23 INFO - TEST-START | dromaeo_css 11:45:23 INFO - operating with platform_type : mac_ 11:45:23 INFO - Initialising browser for dromaeo_css test... 11:45:23 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpuplrC7/profile http://localhost:49240/getInfo.html) 11:45:26 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 11:45:26 INFO - PROCESS | 1634 | colorDepth:24 11:45:26 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 11:45:26 INFO - PROCESS | 1634 | __metrics 11:45:26 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" 11:45:26 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:45:27 INFO - TEST-INFO | 1634: exit 0 11:45:27 INFO - Browser initialized. 11:45:27 INFO - Running cycle 1/1 for dromaeo_css test... 11:45:27 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpuplrC7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:39 INFO - PROCESS | 1636 | RSS: Main: 222445568 11:45:39 INFO - PROCESS | 1636 | 11:48:44 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 11:48:46 INFO - PROCESS | 1636 | RSS: Main: 385085440 11:48:46 INFO - PROCESS | 1636 | 11:51:49 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 11:51:49 INFO - PROCESS | 1636 | RSS: Main: 347422720 11:51:49 INFO - PROCESS | 1636 | 11:54:52 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 11:54:52 INFO - PROCESS | 1636 | RSS: Main: 323002368 11:54:52 INFO - PROCESS | 1636 | 11:57:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 11:57:55 INFO - PROCESS | 1636 | RSS: Main: 320495616 11:57:55 INFO - PROCESS | 1636 | 12:00:59 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 12:00:59 INFO - PROCESS | 1636 | RSS: Main: 310816768 12:00:59 INFO - PROCESS | 1636 | 12:04:02 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 12:04:03 INFO - PROCESS | 1636 | RSS: Main: 400560128 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | __start_tp_report 12:04:03 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 12:04:03 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 12:04:03 INFO - PROCESS | 1636 | |i|pagename|runs| 12:04:03 INFO - PROCESS | 1636 | |0;dojo.html;2626.697929738081;2797.7062408447114;3207.855646495906;3280.7211387032094;3333.649966753491;5496.697681627509;5505.788726480178;5517.69652669104;5562.916556251655;5578.302712160975;5447.45525447455;5471.097268950628;5500.477454641803;5504.311961004874;5524.530414914737;4923.310736496894;5009.4239162496415;5085.1355269604255;5087.389513258414;5093.694378337312;5033.471485494026;5056.873578160538;5076.492350764931;5086.109930762121;5128.179491281396;5129.717865517395;5139.126348520742;5144.4083930348115;5153.510416510426;5160.845174644766;5124.154514505102;5141.871453213662;5143.3570803649545;5148.588112950993;5158.045761534109;4996.850094497171;5095.668781529226;5097.2608971699265;5099.15863882459;5112.514311140436;5639.351474580394;5645.463680950304;5649.971750141264;5664.773409063632;5664.801731939362;5803.941960580382;5804.477597016289;5806.1000544915605;5823.82528524145;5832.387599302097;5957.463828255478;5968.67172305523;5972.850678733023;6110.602810817282;6128.724207410677;5590.804321848756;5608.411116832755;5620.4098569650005;5644.294463192101;5651.943480565224;6082.878342433127;6113.419225173601;6131.957567213584;6152.3847615238565;6158.507319414436;5867.885101830639;5893.882122357529;5944.791932282349;5951.5238780897425;5956.195913551702;6047.909281360783;6101.908471372933;6113.663748493876;6121.938780612137;6138.386161383826;6065.423784740443;6084.5132389408745;6085.2393450818645;6089.512838972872;6097.5731698780655;5953.196318497034;6011.368806275365;6025.758969641253;6140.162398500937;6147.477464415575;4717.080169366941;4774.331593576902;4784.473707892129;4785.784639691222;4829.44461386945;4312.504062032906;4326.199653064194;4332.67504937131;4340.414044104069;4356.411884395629;5461.371942226615;5573.716493070279;5589.776408943678;5595.5523558116065;5641.379448260688;5995.970020149958;6074.362191969868;6075.878482430415;6083.117947897618;6095;5491.835244942658;5532.474414930575;5548.805791797348;5552.52803511706;5553.611247212737;5205.609579281569;5217.243499692523;5222.190560463135;5222.843314700565;5232.947670523246;6119.593952096689;6214.223222097238;6220.720067596879;6223.626582405071;6232.75068997235;6067.514598832171;6086.573939824169;6090.512758979359;6092.086187071975;6106.847328816637;6141.63943600049;6218.968905155445;6219.222597175353;6221.813345599639;6227.034809604518;6013.579049466495;6025;6034.2155519782145;6060;6065.848353791014;5915.615484993462;6121.418465245794;6124.295705993868;6142.416470435302;6146.262448506208;5050.595952323732;5742.253507044057;5804.82585522435;5810.041343178328;5893.911591326219;5935.109733539831;5937.881242375215;5939.524838013035;5940.732667029907;5952.107183922446;5773.16289138081;5791.0734282514595;5797.565182611237;5802.564807639528;5811.622244554091;5641.407652196624;5818.883622327445;5839.620424672383;5894.557908156819;5926.259217597801;5696.458836410535;5699.088145896802;5766.279215098113;5816.5055970243;5826.038703613856 12:04:03 INFO - PROCESS | 1636 | |1;ext.html;512.2981515324005;516.0916786455837;530.1993989076493;532.6910391972654;543.2910052381642;30421.847890760542;30871.228219294502;31739.682603173933;31764.68235317646;31775;32424.18939526513;33150.33699326012;41699;42698.573014269845;42976.35535466971;9304.534773261326;9467.573959171854;9478.099580539872;9479.905200947971;9482.525873706321;9327.86008209874;9449.81100377992;9451.19664828487;9451.621935122586;9454.4800035998;8438.662453501853;8630.568471576427;9426.905730942672;11156.497957591888;11391.715207119805;11262.54949802007;11325.886741132566;11339.603113891057;11351.43242837859;11370.204085714004;10855.565777368896;11003.504842282116;11008.064314533274;11018.402331661944;11030.393328366936;31514.842425787723;31937.201569960704;31954.680453195404;32924;39239.41140882889;2902;3316.535685004101;3325.783824051429;3343.7157841583494;3350.1959529712935;28552;28755;29526;30453.543196852064;31117.377652447052;3183.029176101288;3183.586133802614;3186.2193762528327;3276.967755157118;3285.0965984354493;457150;490776;491895;492058;500668.9933100659;34276;35073.29853402918;61326.69336653288;61630.0755488668;61708.38291617116;28385.43229135447;34964;35052.29895402078;35071.4739278911;35169.47245791315;14110.647233819236;14257.714845703027;14285.78571321431;14285.85714142866;14287.928560357132;11991.64025079249;12037.097217708706;12071.939640301742;12079.456424460921;12100.878991210151;7364.0426744522865;7830.569318687527;7864.21357864209;7900.367970562341;8011.959940200261;3065.555494453292;3094.133642580081;3105.068479456154;3111.4088323218516;3179.2210908327606;885.1458342699198;958.755517343073;976.8192884316425;978.5449765858767;982.3958265666564;29579;29866.70133298639;30451.847740761154;30600.54099188514;30958.690413096032;978.2320878110648;997.4464172384252;998.8102260570469;1033.7543260371235;1040.921930855189;14722.852771472148;15203.239838008056;15319.23403829804;15498.690026199638;15514.224288785515;687672;691155;691241;692229;692350;58551;58648;58673;58774;58899.46603203822;1177.1524502358288;1199.1785626845638;1337.9197248165142;1351.716139610673;1377.882879955195;29185.708142918298;30251.697483024887;30528.542071868495;30731;31146.68853311438;50329.74835125948;50991.980160397325;51041.74479127581;51117.48882511127;51134.48865511446;519277;551894;556171;556376;556443;25354.746452535976;28114.734836931977;28155.71844281531;28691.569626456036;28763.849446021926;46470;47079.293810592186;47184.7640761794;47429;47759;39229.80385098056;39250;39508.802455987534;39701.404478933415;40983.18033639251;38555.42166867555;38964.805175975074;39380.606193937696;40008.599914000486;40656.186876261716;37734.24531509409;38053.809730951165;38248.66129685448;38565.6143438562;38571.22857542778;33427.498587521695;34456;35191.76828810979;35349.82325088358;35530.644693552735;25485.745142548338;25513.23460296194;25675.749630595295;25758.09846655358;26057.348566285993 12:04:03 INFO - PROCESS | 1636 | |2;jquery.html;345.6077352205246;367.8528588564574;369.7060836634877;383.4612369620683;394.4202023026152;14476.420943162275;14697.97114202005;15574.532764017084;19655.410337689893;20040.899795501038;18377.356792512266;19003.429897103073;19294;19427.222911083576;19675;5516.3380394352735;5800.361960184377;5800.854978625526;5813.854653633672;5820.738066786984;4617.746023968681;5465.262189604392;5735.483806457418;5796.304443466769;5810.651360918338;5644.943550564503;5649.350324712669;5713.085906255;5751.137329400582;5762.049261871786;5472.288602481692;5677.829665110054;5709.286339207599;5723.969685456616;5736.541076713853;4746.264329029006;5007.799688012475;5021.246812978046;5024.949750502521;5026.421961474404;24142.275731728078;24227.87886060576;24331;24578.754212458003;24723.876380618163;2201.636729939558;2319.7796209360085;2428.1258746851117;2436.2691192642137;2463.19946017545;14064.718705625932;14386.496472623512;14822.777658335133;15000.924995374953;15096.698066038727;1791.7312403139504;1832.129738374275;1926.0273561851175;1929.9324523641606;2009.7085922541296;105348.47325763399;105593.08051896139;108155.37766933502;124376;128284.7171528282;8875.778105547284;9210.493422861673;11472.713182170513;11603.825942610867;11609.825852612217;14452.132872027501;14460.20468874222;14483;14483.49307774244;14493.492727754478;8192.54940978252;8271;8334.749957501284;8347.874781878276;8405.663773449116;7344.669489872862;7592.202818704067;8239.546824924566;8355.623996920152;8360.289375403037;3762.814713365281;3916.432117343026;4261.31818908973;4348.934765978513;4376.168527979674;1537.0623919409152;1668.9749653755202;1700.9879121922436;1872.4008317338594;1965.9311924082588;820.6676296100089;862.1593945902695;915.3638221436171;916.3814425262921;1004.9447280399648;19668.213271469267;19903.60192796136;19914.402567922985;20089.19643214255;20228.898855505922;911.4459845962618;929.5166513413122;948.814981078683;958.0467434902317;974.4640447753707;9735.756606084904;10612.628558000588;10627.36235825853;10629.202809789296;10726.785464290671;591965;594370;605681;612577;613024;89510.72195103456;104368.47815760873;112852;127342;128830;914.0813482450095;948.7106432538004;949.3307218411142;1015.6902144845893;1020.5917632946881;13223.735525289248;17635;18216.815906966003;18300.542486437946;18313.725294120864;37181.81409092937;49043.50956490389;56600.15099773589;56898.7155064222;57891.42108578861;79405.60297198477;79675;81060.378792425;81068.59465702633;82891;6379.6810159490915;7949.8410031800195;8035.477693949874;8137.918620813716;8216.548089855116;10381.740456488647;12219.938900305442;12287.078469114673;12718.427670754652;12778.808317875053;10768.246222764645;10773.569057237622;10898.727531811768;11147.777044459228;11223;10560.471976401303;10610;10706.625268115578;10777.838332424863;10866.782664346827;9229.861552076858;10877;10896.836547451636;10957.342559446459;11295.926886945726;7940.444168908352;7941.960290198512;8036.075851276944;8136.430449868689;8162.18378162179;9077.727668169966;9898;10571.629992950517;10792.190585706261;10902.345859248471 12:04:03 INFO - PROCESS | 1636 | |3;mootools.html;1466.0324186037215;1486;1638.2955329208444;1809.4300295406954;1836.4857839804852;8024.037115546135;8338.24955753982;8426.368022398314;8429.78925526862;8448.493090414579;7497.137829149649;7720.073591169067;7735.729249476255;7963.761087167394;8212.548309842956;2273.783990520902;2330.755270696578;2337.9415514612188;2339.0643742502966;2348.0490401387465;2308.6537019447132;2338.298510446859;2354.682117914085;2358.3278765551822;2367.1241640593003;1927.7493925789627;2293.3693234360603;2298.793108620224;2324.9535009299802;2328.6972693549815;2309.3302942146756;2318.1654604342425;2318.6174281243575;2319.304208737372;2319.431739223901;2176.706144670465;2269.2851751698163;2270.4437412833963;2270.829687773407;2277.202978957368;7951.165127661628;8022.1175670676175;8029.678812847479;8038.236367545074;8040.477368971056;5057.367829021372;5222.582193424517;5237.659552129099;5247.1866860636665;5248.816291429782;3201.183698156978;3349.681780230874;3362.680545348188;3588.2105936693883;3594.982025089884;1061.3154515337642;1075.2526993739355;1083.3608171179042;1097.3964319624254;1106.8173751331021;10662.466876656135;10769.784604307948;10907;10920.399378034126;10920.945395273053;4456.732596044248;4566.88582785433;4658.208104622222;4666.580007799314;4682.297655351656;9474.668386606574;9489.288303377274;9492.098250666175;9496.050394960448;9537.904620953701;5195.688258704414;5197.298364720715;5197.792088316501;5215.55667768244;5219.034478621467;3535.6641119093647;3588.3540962626976;4196.727212731163;4759.190937540627;4762.59517940972;3813.142043040349;3842.7886466244067;3920.2355540669296;3940.389239667856;3956.090099277182;1257.861635220125;1283.8395200599925;1287.7746394381134;1297.8247936528448;1300.830891984036;813.2721214512977;875.1205038935828;877.7191298784328;907.8365894138987;915.9358844880927;5770.394108618619;5823.767049318065;5851.063829787298;5911.280810826982;6045.274567051982;876.9210771030638;885.287343688337;896.8834051573253;903.503073309677;970.7184916374173;3712.2389910068373;3738.457923601117;3773.660370566607;3804.7907365094634;3820.465134881119;9597.184239339735;10012.349197302297;10066.043725846033;10085.848712269322;10141.492925353703;10055.798884022279;10057.497125143713;10073.949630251802;10081.294309398418;10107.241956853266;7390.667419966115;7669.616519174019;7729.613519324123;7804.843903121906;7888.171741967011;5471.9726401369335;5580.246666700106;5814;5825.563082768894;5842.853928651818;7736;9118.316126290687;9135.771605709644;9456.243500520079;9473.431594104375;9233.907660923305;9257.305702072506;9576.473293968898;9606.855897161407;9681.273904457054;6598;6810.182778066664;6812.080369150101;7015.228324884157;7244;7626.3898888089925;7923.445358825057;7925.326347260548;8014.559199244098;8033.55815430157;7056.717731290896;7139.678714457758;7158.892616610651;7201.963990180225;7294.416446684359;6852.794416167523;6982.197047339418;7038.824029399101;7047.260037695968;7155.606441645551;6616.073749674953;6619.569727967667;6712.127423434922;6720.966395168189;6730.394264516217;4678.806904239397;4812.422509298906;4817.373741413734;4860.17377045896;4905.435874874435;4287.485501739811;4749;4765.76171191446;4940.777665005012;5023.623228257822 12:04:03 INFO - PROCESS | 1636 | |4;prototype.html;292.1542135517676;300.5506767382763;306.191654033352;310.6567243196268;315.23869854301864;4531.977340113295;4905.681130726501;5135.202679062331;5178.275041494194;5187.974060129694;4093.8362465501345;4237.834595486235;4866.951330486694;4978;5023.874403139914;3771.057235691077;3883.669888059518;3921.450996860442;3922.3920292354587;3974.8608798692053;3241.0438920518504;3306.6197387300526;3916.020994751298;3945.579591347113;4011.338129208677;3425.7944523328556;3706.703463722909;3876.379779235323;3924.54867690215;3933.8033098345113;3914.393269043303;3918.88243352697;3940.251352243079;3945.224648908003;3957.6042395760483;3114.5795317632283;3386.5258863759095;3391.4743214801747;3400.931981360384;3421.5209870618132;4348.630366418826;5007.2489126630935;5036.84889453317;5044.571211447032;5051.318072060299;3786.163710600357;4234.343676730112;4435.556444355571;4484.372187893698;4492.438445194351;4488.304312831517;5073.46728593496;5158.92261616076;5430.402655707869;5487.42382049883;3732.533433320835;3738.9904725724173;3756.19702542781;3764.209516001643;3765.868194613203;4726.929096063562;5596.972015139939;5609.551235901118;5619.213310136543;5657.264555607827;3152.511360739089;3764.1906989997283;3767.547894252707;3778.8110594470168;3782.71629627779;3422;3665.963340366562;3670.064133645903;3849.9615003850163;3875.6511913927884;4064.7357921734992;4092.7339722917914;4118.299889018874;4287.378330142112;4496.910061798746;4776.259679749644;5435.157550579666;5439.700816455053;5450.781968721207;5471.868739101953;1195.8863907928733;1196.5154112584416;1203.9405323315602;1210.4189988805422;1214.8967337776387;764.7705688293489;818.5620692929255;828.0849661124371;847.2671139464319;853.7822855172016;4145.139853501075;4523.218220794188;4761.523847615211;4829.9275510867365;4860.4896485869185;4316.417283666728;4434.55654434554;4791.921817591014;4863.902721945541;5372.91940620891;4170.812313445902;4561.771911404417;4793.304970779287;4824.662273640812;4841.540053694894;2419.5040016796515;2623.790096792252;2775.500409926233;2803.8738256778493;2807.8066821600737;4754.9286760698615;4898.926516102261;5567.3040869891265;5606.748086110047;5606.803761868315;4791.353167322437;4943.505649435027;5304.363476382781;5723.998300297515;5784.4504772046585;5262.158054711304;5305.973470132624;5587.513556467333;5792.102224155263;5820.272465941757;4922.606191504743;4986.576141027908;5205.3071370810085;5246.239295302236;5416.539594134385;5496.945030549803;5517;5517.420670829505;5546.557894947262;5555.972220138873;5251.711155886463;5780.537556995346;5787.247657804459;5846.970765146147;5856.812379148929;3271.3620843935205;3608.62109478501;3712.294664013829;3738.691457989682;3739.8691045813266;4835.685680430761;4909.797099710594;5566.610337276352;5605.579581531483;5626.634268772516;5017.749112544285;5037.078494473123;5444.972775136099;5466.945330546802;5525.309336332958;4817;5296.682199067915;5367.893561425591;5478.329519222051;5526.502614764529;5115.283860259492;5142.6914385137015;5285.788568457218;5344.305240318734;5369.436209197978;4629.740638612418;5125.769340379767;5333.3066701330135;5373.892522149613;5516.779328826802;5355.973220133875;5371.570274377962;5376;5378.247045413567;5407 12:04:03 INFO - PROCESS | 1636 | |5;yui.html;687.2920891481775;745.6197339356926;747.3124725252769;769.9451751100991;780.8789637606166;11734.295942243461;14882.330295136719;14893.925530372333;14907;14952.476663316786;13157.934210328958;13266.005049621268;13275.668108297297;13284.867151328484;13286.468541258339;655.4003087175253;674.2448457727352;803.3492870774662;814.4950130918838;836.7908022994252;823.7734622978669;832.76682528892;833.5082301442147;840.0843081041663;840.6889450903175;868.9695860644879;899.3210126354593;899.7525680437866;903.0382642485731;904.3217586809892;895.6104094718786;896.2516298890434;898.083954366549;898.6630013744846;898.7797989492583;508.5169089365081;526.2816255810791;624.7126321891935;649.1496140056541;652.7160685101995;13894.722105557934;14164.85835141656;14385;14387;14459.277036148256;12961.935190324082;13251.006174536948;13315.267660278681;13339.866601334057;13388.665283367896;11700.122490813223;12022.879771202264;12037.097217708619;12144.453499592539;12321.260724356478;3791.639794219545;4394.4946331172005;4499.505054444008;4595.4025976622825;4617.2381557043045;91893;94414.52792736061;95145.52427237888;97223;97998;9097.493699521754;10243.334183278062;10552.47237638115;10642.840357394645;10764.569417223223;20444;20632.58734825325;20659;20701.58596828055;20702.585948281252;7371.299726525972;7508.174100849012;7522.811929701692;7721;7726.690932362642;4142.689298302579;4199.517055538591;4211;4371.650267978554;4389.868303950887;3286.4084464796565;3343.7826541275226;3376.544166537535;3421.9828900855337;3424;586.601111244358;702.8524009957864;709.7835160276165;712.4122598855964;720.4524561333425;560.9551235901118;585.6925114314934;609.8048624440137;641.5252712992445;650.6012074040756;7992.841038049451;8454.196851299117;8927.375083744075;9039.514133613178;9190.218831399272;631.4674690328544;641.9807405777834;647.4464332995221;650.5283669339785;659.6635715784888;4277;4353.586409291112;4427.048184640318;4512.2554778461845;4552.256986739991;78022;104141.47929260305;117092;119750;125200;82358.17641823625;94136.11727765406;98819;101093.98906010993;105736.47131764446;84431;85130.57434712787;85228.57385713156;92712.6093108604;92990;9257.953710231406;9652.972875696183;9687.612495500101;9711;9741.756456088371;39003.21993560056;42958.57041429671;51790.741046294526;54642.72678636581;57298.713506433865;57685.42314576801;68519.65740171267;70198.64900675668;76030;78667;10460.424676642553;12195.329256890956;12659.240445573296;12665.746685066431;12767;13415.128016678884;14178.574642760734;14336;14356.641083972985;14390.712185756436;12762.553310634083;12799.680007999874;12848.229106253655;13427.865721343052;13598.86401135976;12933.288669123289;12944.352782361033;13118.672033199246;13648.86351136476;13690.86309136896;10649.918622879663;12797.808032879328;13088.607341779762;13166.539171128965;13393.598192054254;10761.677149685522;10983.560657573607;11071.391073490713;11434.656960291204;11456.656300311004;9089.19235891639;9605.847298324246;11366;11495.137864660017;11559.19085664029 12:04:03 INFO - PROCESS | 1636 | __end_tp_report 12:04:03 INFO - PROCESS | 1636 | __start_cc_report 12:04:03 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1817 12:04:03 INFO - PROCESS | 1636 | __end_cc_report 12:04:03 INFO - PROCESS | 1636 | __startTimestamp1483819443248__endTimestamp 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | ------- Summary: start ------- 12:04:03 INFO - PROCESS | 1636 | Number of tests: 6 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5578.26 Median:5791.07 stddev:644.01 (11.1%) stddev-sans-first:602.93 12:04:03 INFO - PROCESS | 1636 | Values: 2626.7 2797.7 3207.9 3280.7 3333.6 5496.7 5505.8 5517.7 5562.9 5578.3 5447.5 5471.1 5500.5 5504.3 5524.5 4923.3 5009.4 5085.1 5087.4 5093.7 5033.5 5056.9 5076.5 5086.1 5128.2 5129.7 5139.1 5144.4 5153.5 5160.8 5124.2 5141.9 5143.4 5148.6 5158.0 4996.9 5095.7 5097.3 5099.2 5112.5 5639.4 5645.5 5650.0 5664.8 5664.8 5803.9 5804.5 5806.1 5823.8 5832.4 5957.5 5968.7 5972.9 6110.6 6128.7 5590.8 5608.4 5620.4 5644.3 5651.9 6082.9 6113.4 6132.0 6152.4 6158.5 5867.9 5893.9 5944.8 5951.5 5956.2 6047.9 6101.9 6113.7 6121.9 6138.4 6065.4 6084.5 6085.2 6089.5 6097.6 5953.2 6011.4 6025.8 6140.2 6147.5 4717.1 4774.3 4784.5 4785.8 4829.4 4312.5 4326.2 4332.7 4340.4 4356.4 5461.4 5573.7 5589.8 5595.6 5641.4 5996.0 6074.4 6075.9 6083.1 6095.0 5491.8 5532.5 5548.8 5552.5 5553.6 5205.6 5217.2 5222.2 5222.8 5232.9 6119.6 6214.2 6220.7 6223.6 6232.8 6067.5 6086.6 6090.5 6092.1 6106.8 6141.6 6219.0 6219.2 6221.8 6227.0 6013.6 6025.0 6034.2 6060.0 6065.8 5915.6 6121.4 6124.3 6142.4 6146.3 5050.6 5742.3 5804.8 5810.0 5893.9 5935.1 5937.9 5939.5 5940.7 5952.1 5773.2 5791.1 5797.6 5802.6 5811.6 5641.4 5818.9 5839.6 5894.6 5926.3 5696.5 5699.1 5766.3 5816.5 5826.0 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:68890.80 Median:35061.89 stddev:155976.34 (444.9%) stddev-sans-first:156329.41 12:04:03 INFO - PROCESS | 1636 | Values: 512.3 516.1 530.2 532.7 543.3 30421.8 30871.2 31739.7 31764.7 31775.0 32424.2 33150.3 41699.0 42698.6 42976.4 9304.5 9467.6 9478.1 9479.9 9482.5 9327.9 9449.8 9451.2 9451.6 9454.5 8438.7 8630.6 9426.9 11156.5 11391.7 11262.5 11325.9 11339.6 11351.4 11370.2 10855.6 11003.5 11008.1 11018.4 11030.4 31514.8 31937.2 31954.7 32924.0 39239.4 2902.0 3316.5 3325.8 3343.7 3350.2 28552.0 28755.0 29526.0 30453.5 31117.4 3183.0 3183.6 3186.2 3277.0 3285.1 457150.0 490776.0 491895.0 492058.0 500669.0 34276.0 35073.3 61326.7 61630.1 61708.4 28385.4 34964.0 35052.3 35071.5 35169.5 14110.6 14257.7 14285.8 14285.9 14287.9 11991.6 12037.1 12071.9 12079.5 12100.9 7364.0 7830.6 7864.2 7900.4 8012.0 3065.6 3094.1 3105.1 3111.4 3179.2 885.1 958.8 976.8 978.5 982.4 29579.0 29866.7 30451.8 30600.5 30958.7 978.2 997.4 998.8 1033.8 1040.9 14722.9 15203.2 15319.2 15498.7 15514.2 687672.0 691155.0 691241.0 692229.0 692350.0 58551.0 58648.0 58673.0 58774.0 58899.5 1177.2 1199.2 1337.9 1351.7 1377.9 29185.7 30251.7 30528.5 30731.0 31146.7 50329.7 50992.0 51041.7 51117.5 51134.5 519277.0 551894.0 556171.0 556376.0 556443.0 25354.7 28114.7 28155.7 28691.6 28763.8 46470.0 47079.3 47184.8 47429.0 47759.0 39229.8 39250.0 39508.8 39701.4 40983.2 38555.4 38964.8 39380.6 40008.6 40656.2 37734.2 38053.8 38248.7 38565.6 38571.2 33427.5 34456.0 35191.8 35349.8 35530.6 25485.7 25513.2 25675.7 25758.1 26057.3 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:34392.82 Median:12534.74 stddev:100375.30 (800.8%) stddev-sans-first:100624.32 12:04:03 INFO - PROCESS | 1636 | Values: 345.6 367.9 369.7 383.5 394.4 14476.4 14698.0 15574.5 19655.4 20040.9 18377.4 19003.4 19294.0 19427.2 19675.0 5516.3 5800.4 5800.9 5813.9 5820.7 4617.7 5465.3 5735.5 5796.3 5810.7 5644.9 5649.4 5713.1 5751.1 5762.0 5472.3 5677.8 5709.3 5724.0 5736.5 4746.3 5007.8 5021.2 5024.9 5026.4 24142.3 24227.9 24331.0 24578.8 24723.9 2201.6 2319.8 2428.1 2436.3 2463.2 14064.7 14386.5 14822.8 15000.9 15096.7 1791.7 1832.1 1926.0 1929.9 2009.7 105348.5 105593.1 108155.4 124376.0 128284.7 8875.8 9210.5 11472.7 11603.8 11609.8 14452.1 14460.2 14483.0 14483.5 14493.5 8192.5 8271.0 8334.7 8347.9 8405.7 7344.7 7592.2 8239.5 8355.6 8360.3 3762.8 3916.4 4261.3 4348.9 4376.2 1537.1 1669.0 1701.0 1872.4 1965.9 820.7 862.2 915.4 916.4 1004.9 19668.2 19903.6 19914.4 20089.2 20228.9 911.4 929.5 948.8 958.0 974.5 9735.8 10612.6 10627.4 10629.2 10726.8 591965.0 594370.0 605681.0 612577.0 613024.0 89510.7 104368.5 112852.0 127342.0 128830.0 914.1 948.7 949.3 1015.7 1020.6 13223.7 17635.0 18216.8 18300.5 18313.7 37181.8 49043.5 56600.2 56898.7 57891.4 79405.6 79675.0 81060.4 81068.6 82891.0 6379.7 7949.8 8035.5 8137.9 8216.5 10381.7 12219.9 12287.1 12718.4 12778.8 10768.2 10773.6 10898.7 11147.8 11223.0 10560.5 10610.0 10706.6 10777.8 10866.8 9229.9 10877.0 10896.8 10957.3 11295.9 7940.4 7942.0 8036.1 8136.4 8162.2 9077.7 9898.0 10571.6 10792.2 10902.3 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5418.98 Median:5196.49 stddev:2956.44 (56.9%) stddev-sans-first:2949.81 12:04:03 INFO - PROCESS | 1636 | Values: 1466.0 1486.0 1638.3 1809.4 1836.5 8024.0 8338.2 8426.4 8429.8 8448.5 7497.1 7720.1 7735.7 7963.8 8212.5 2273.8 2330.8 2337.9 2339.1 2348.0 2308.7 2338.3 2354.7 2358.3 2367.1 1927.7 2293.4 2298.8 2325.0 2328.7 2309.3 2318.2 2318.6 2319.3 2319.4 2176.7 2269.3 2270.4 2270.8 2277.2 7951.2 8022.1 8029.7 8038.2 8040.5 5057.4 5222.6 5237.7 5247.2 5248.8 3201.2 3349.7 3362.7 3588.2 3595.0 1061.3 1075.3 1083.4 1097.4 1106.8 10662.5 10769.8 10907.0 10920.4 10920.9 4456.7 4566.9 4658.2 4666.6 4682.3 9474.7 9489.3 9492.1 9496.1 9537.9 5195.7 5197.3 5197.8 5215.6 5219.0 3535.7 3588.4 4196.7 4759.2 4762.6 3813.1 3842.8 3920.2 3940.4 3956.1 1257.9 1283.8 1287.8 1297.8 1300.8 813.3 875.1 877.7 907.8 915.9 5770.4 5823.8 5851.1 5911.3 6045.3 876.9 885.3 896.9 903.5 970.7 3712.2 3738.5 3773.7 3804.8 3820.5 9597.2 10012.3 10066.0 10085.8 10141.5 10055.8 10057.5 10073.9 10081.3 10107.2 7390.7 7669.6 7729.6 7804.8 7888.2 5472.0 5580.2 5814.0 5825.6 5842.9 7736.0 9118.3 9135.8 9456.2 9473.4 9233.9 9257.3 9576.5 9606.9 9681.3 6598.0 6810.2 6812.1 7015.2 7244.0 7626.4 7923.4 7925.3 8014.6 8033.6 7056.7 7139.7 7158.9 7202.0 7294.4 6852.8 6982.2 7038.8 7047.3 7155.6 6616.1 6619.6 6712.1 6721.0 6730.4 4678.8 4812.4 4817.4 4860.2 4905.4 4287.5 4749.0 4765.8 4940.8 5023.6 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4320.94 Median:4827.29 stddev:1335.00 (27.7%) stddev-sans-first:1304.05 12:04:03 INFO - PROCESS | 1636 | Values: 292.2 300.6 306.2 310.7 315.2 4532.0 4905.7 5135.2 5178.3 5188.0 4093.8 4237.8 4867.0 4978.0 5023.9 3771.1 3883.7 3921.5 3922.4 3974.9 3241.0 3306.6 3916.0 3945.6 4011.3 3425.8 3706.7 3876.4 3924.5 3933.8 3914.4 3918.9 3940.3 3945.2 3957.6 3114.6 3386.5 3391.5 3400.9 3421.5 4348.6 5007.2 5036.8 5044.6 5051.3 3786.2 4234.3 4435.6 4484.4 4492.4 4488.3 5073.5 5158.9 5430.4 5487.4 3732.5 3739.0 3756.2 3764.2 3765.9 4726.9 5597.0 5609.6 5619.2 5657.3 3152.5 3764.2 3767.5 3778.8 3782.7 3422.0 3666.0 3670.1 3850.0 3875.7 4064.7 4092.7 4118.3 4287.4 4496.9 4776.3 5435.2 5439.7 5450.8 5471.9 1195.9 1196.5 1203.9 1210.4 1214.9 764.8 818.6 828.1 847.3 853.8 4145.1 4523.2 4761.5 4829.9 4860.5 4316.4 4434.6 4791.9 4863.9 5372.9 4170.8 4561.8 4793.3 4824.7 4841.5 2419.5 2623.8 2775.5 2803.9 2807.8 4754.9 4898.9 5567.3 5606.7 5606.8 4791.4 4943.5 5304.4 5724.0 5784.5 5262.2 5306.0 5587.5 5792.1 5820.3 4922.6 4986.6 5205.3 5246.2 5416.5 5496.9 5517.0 5517.4 5546.6 5556.0 5251.7 5780.5 5787.2 5847.0 5856.8 3271.4 3608.6 3712.3 3738.7 3739.9 4835.7 4909.8 5566.6 5605.6 5626.6 5017.7 5037.1 5445.0 5466.9 5525.3 4817.0 5296.7 5367.9 5478.3 5526.5 5115.3 5142.7 5285.8 5344.3 5369.4 4629.7 5125.8 5333.3 5373.9 5516.8 5356.0 5371.6 5376.0 5378.2 5407.0 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:20612.77 Median:4505.88 stddev:30519.31 (677.3%) stddev-sans-first:30568.25 12:04:03 INFO - PROCESS | 1636 | Values: 687.3 745.6 747.3 769.9 780.9 11734.3 14882.3 14893.9 14907.0 14952.5 13157.9 13266.0 13275.7 13284.9 13286.5 655.4 674.2 803.3 814.5 836.8 823.8 832.8 833.5 840.1 840.7 869.0 899.3 899.8 903.0 904.3 895.6 896.3 898.1 898.7 898.8 508.5 526.3 624.7 649.1 652.7 13894.7 14164.9 14385.0 14387.0 14459.3 12961.9 13251.0 13315.3 13339.9 13388.7 11700.1 12022.9 12037.1 12144.5 12321.3 3791.6 4394.5 4499.5 4595.4 4617.2 91893.0 94414.5 95145.5 97223.0 97998.0 9097.5 10243.3 10552.5 10642.8 10764.6 20444.0 20632.6 20659.0 20701.6 20702.6 7371.3 7508.2 7522.8 7721.0 7726.7 4142.7 4199.5 4211.0 4371.7 4389.9 3286.4 3343.8 3376.5 3422.0 3424.0 586.6 702.9 709.8 712.4 720.5 561.0 585.7 609.8 641.5 650.6 7992.8 8454.2 8927.4 9039.5 9190.2 631.5 642.0 647.4 650.5 659.7 4277.0 4353.6 4427.0 4512.3 4552.3 78022.0 104141.5 117092.0 119750.0 125200.0 82358.2 94136.1 98819.0 101094.0 105736.5 84431.0 85130.6 85228.6 92712.6 92990.0 9258.0 9653.0 9687.6 9711.0 9741.8 39003.2 42958.6 51790.7 54642.7 57298.7 57685.4 68519.7 70198.6 76030.0 78667.0 10460.4 12195.3 12659.2 12665.7 12767.0 13415.1 14178.6 14336.0 14356.6 14390.7 12762.6 12799.7 12848.2 13427.9 13598.9 12933.3 12944.4 13118.7 13648.9 13690.9 10649.9 12797.8 13088.6 13166.5 13393.6 10761.7 10983.6 11071.4 11434.7 11456.7 9089.2 9605.8 11366.0 11495.1 11559.2 12:04:03 INFO - PROCESS | 1636 | -------- Summary: end -------- 12:04:03 INFO - PROCESS | 1636 | 12:04:03 INFO - TEST-INFO | 1636: exit 0 12:04:03 INFO - TEST-OK | dromaeo_css | took 1120375ms 12:04:03 INFO - TEST-START | kraken 12:04:03 INFO - operating with platform_type : mac_ 12:04:03 INFO - Initialising browser for kraken test... 12:04:03 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpY4gIG2/profile http://localhost:49240/getInfo.html) 12:04:06 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 12:04:06 INFO - PROCESS | 1647 | colorDepth:24 12:04:06 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 12:04:06 INFO - PROCESS | 1647 | __metrics 12:04:06 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" 12:04:06 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:04:07 INFO - TEST-INFO | 1647: exit 0 12:04:07 INFO - Browser initialized. 12:04:07 INFO - Running cycle 1/1 for kraken test... 12:04:07 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/T/tmpY4gIG2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:04:19 INFO - PROCESS | 1648 | RSS: Main: 223006720 12:04:19 INFO - PROCESS | 1648 | 12:04:22 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 12:04:23 INFO - PROCESS | 1648 | RSS: Main: 272334848 12:04:23 INFO - PROCESS | 1648 | 12:04:26 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 12:04:26 INFO - PROCESS | 1648 | RSS: Main: 283234304 12:04:26 INFO - PROCESS | 1648 | 12:04:29 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 12:04:30 INFO - PROCESS | 1648 | RSS: Main: 380223488 12:04:30 INFO - PROCESS | 1648 | 12:04:32 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 12:04:33 INFO - PROCESS | 1648 | RSS: Main: 282447872 12:04:33 INFO - PROCESS | 1648 | 12:04:35 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:04:35 INFO - PROCESS | 1648 | RSS: Main: 283369472 12:04:35 INFO - PROCESS | 1648 | 12:04:38 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 12:04:39 INFO - PROCESS | 1648 | RSS: Main: 324530176 12:04:39 INFO - PROCESS | 1648 | 12:04:42 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 12:04:43 INFO - PROCESS | 1648 | RSS: Main: 326750208 12:04:43 INFO - PROCESS | 1648 | 12:04:45 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 12:04:46 INFO - PROCESS | 1648 | RSS: Main: 324202496 12:04:46 INFO - PROCESS | 1648 | 12:04:48 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:04:48 INFO - PROCESS | 1648 | RSS: Main: 299376640 12:04:48 INFO - PROCESS | 1648 | 12:04:50 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:04:51 INFO - PROCESS | 1648 | RSS: Main: 301047808 12:04:51 INFO - PROCESS | 1648 | 12:04:53 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:04:54 INFO - PROCESS | 1648 | RSS: Main: 303325184 12:04:54 INFO - PROCESS | 1648 | 12:04:56 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:04:57 INFO - PROCESS | 1648 | RSS: Main: 323756032 12:04:57 INFO - PROCESS | 1648 | 12:05:00 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:05:01 INFO - PROCESS | 1648 | RSS: Main: 317034496 12:05:01 INFO - PROCESS | 1648 | 12:05:02 INFO - PROCESS | 1648 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 12:05:03 INFO - PROCESS | 1648 | RSS: Main: 361181184 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | __start_tp_report 12:05:03 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 12:05:03 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 12:05:03 INFO - PROCESS | 1648 | |i|pagename|runs| 12:05:03 INFO - PROCESS | 1648 | |0;ai-astar;116;114;114;114;115;113;114;112;112;114 12:05:03 INFO - PROCESS | 1648 | |1;audio-beat-detection;169;144;146;144;113;111;110;96;148;142 12:05:03 INFO - PROCESS | 1648 | |2;audio-dft;183;182;179;183;183;183;182;187;222;183 12:05:03 INFO - PROCESS | 1648 | |3;audio-fft;81;58;60;101;89;50;74;83;111;83 12:05:03 INFO - PROCESS | 1648 | |4;audio-oscillator;92;71;94;70;68;69;89;68;68;91 12:05:03 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;117;131;89;86;89;84;88;87;90;86 12:05:03 INFO - PROCESS | 1648 | |6;imaging-darkroom;94;95;95;94;94;105;151;119;94;109 12:05:03 INFO - PROCESS | 1648 | |7;imaging-desaturate;97;123;79;78;80;81;83;93;122;123 12:05:03 INFO - PROCESS | 1648 | |8;json-parse-financial;56;56;56;57;59;58;57;57;56;54 12:05:03 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;64;72;72;74;71;69;69;70;68;71 12:05:03 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;84;81;83;84;84;84;83;85;84;84 12:05:03 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;139;139;141;141;139;147;152;152;187;140 12:05:03 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;165;166;165;165;166;164;168;167;165;164 12:05:03 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;68;67;67;77;84;85;85;82;65 12:05:03 INFO - PROCESS | 1648 | __end_tp_report 12:05:03 INFO - PROCESS | 1648 | __start_cc_report 12:05:03 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,2059 12:05:03 INFO - PROCESS | 1648 | __end_cc_report 12:05:03 INFO - PROCESS | 1648 | __startTimestamp1483819503253__endTimestamp 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | ------- Summary: start ------- 12:05:03 INFO - PROCESS | 1648 | Number of tests: 14 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:113.80 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 12:05:03 INFO - PROCESS | 1648 | Values: 116.0 114.0 114.0 114.0 115.0 113.0 114.0 112.0 112.0 114.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:145.00 stddev:23.07 (15.9%) stddev-sans-first:20.29 12:05:03 INFO - PROCESS | 1648 | Values: 169.0 144.0 146.0 144.0 113.0 111.0 110.0 96.0 148.0 142.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:186.70 Median:183.00 stddev:12.55 (6.9%) stddev-sans-first:13.24 12:05:03 INFO - PROCESS | 1648 | Values: 183.0 182.0 179.0 183.0 183.0 183.0 182.0 187.0 222.0 183.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:79.00 Median:77.50 stddev:19.18 (24.8%) stddev-sans-first:20.33 12:05:03 INFO - PROCESS | 1648 | Values: 81.0 58.0 60.0 101.0 89.0 50.0 74.0 83.0 111.0 83.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:80.00 stddev:11.72 (14.6%) stddev-sans-first:11.28 12:05:03 INFO - PROCESS | 1648 | Values: 92.0 71.0 94.0 70.0 68.0 69.0 89.0 68.0 68.0 91.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:94.70 Median:87.50 stddev:15.89 (18.2%) stddev-sans-first:14.66 12:05:03 INFO - PROCESS | 1648 | Values: 117.0 131.0 89.0 86.0 89.0 84.0 88.0 87.0 90.0 86.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:105.00 Median:94.00 stddev:18.29 (19.5%) stddev-sans-first:18.97 12:05:03 INFO - PROCESS | 1648 | Values: 94.0 95.0 95.0 94.0 94.0 105.0 151.0 119.0 94.0 109.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:95.90 Median:80.50 stddev:19.46 (24.2%) stddev-sans-first:20.63 12:05:03 INFO - PROCESS | 1648 | Values: 97.0 123.0 79.0 78.0 80.0 81.0 83.0 93.0 122.0 123.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.35 (2.4%) stddev-sans-first:1.41 12:05:03 INFO - PROCESS | 1648 | Values: 56.0 56.0 56.0 57.0 59.0 58.0 57.0 57.0 56.0 54.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:70.00 Median:71.00 stddev:2.75 (3.9%) stddev-sans-first:1.87 12:05:03 INFO - PROCESS | 1648 | Values: 64.0 72.0 72.0 74.0 71.0 69.0 69.0 70.0 68.0 71.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.07 (1.3%) stddev-sans-first:1.13 12:05:03 INFO - PROCESS | 1648 | Values: 84.0 81.0 83.0 84.0 84.0 84.0 83.0 85.0 84.0 84.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:147.70 Median:144.00 stddev:14.75 (10.2%) stddev-sans-first:15.31 12:05:03 INFO - PROCESS | 1648 | Values: 139.0 139.0 141.0 141.0 139.0 147.0 152.0 152.0 187.0 140.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:165.50 stddev:1.27 (0.8%) stddev-sans-first:1.33 12:05:03 INFO - PROCESS | 1648 | Values: 165.0 166.0 165.0 165.0 166.0 164.0 168.0 167.0 165.0 164.0 12:05:03 INFO - PROCESS | 1648 | 12:05:03 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:79.50 stddev:8.65 (10.9%) stddev-sans-first:8.72 12:05:03 INFO - PROCESS | 1648 | Values: 67.0 68.0 67.0 67.0 77.0 84.0 85.0 85.0 82.0 65.0 12:05:03 INFO - PROCESS | 1648 | -------- Summary: end -------- 12:05:03 INFO - PROCESS | 1648 | 12:05:04 INFO - TEST-INFO | 1648: exit 0 12:05:04 INFO - TEST-OK | kraken | took 60407ms 12:05:04 INFO - SUITE-END | took 1180s 12:05:04 INFO - Completed test suite (00:19:40) 12:05:04 INFO - javascript benchmark 12:05:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2626.697929738081, 2797.7062408447114, 3207.855646495906, 3280.7211387032094, 3333.649966753491, 5496.697681627509, 5505.788726480178, 5517.69652669104, 5562.916556251655, 5578.302712160975, 5447.45525447455, 5471.097268950628, 5500.477454641803, 5504.311961004874, 5524.530414914737, 4923.310736496894, 5009.4239162496415, 5085.1355269604255, 5087.389513258414, 5093.694378337312, 5033.471485494026, 5056.873578160538, 5076.492350764931, 5086.109930762121, 5128.179491281396, 5129.717865517395, 5139.126348520742, 5144.4083930348115, 5153.510416510426, 5160.845174644766, 5124.154514505102, 5141.871453213662, 5143.3570803649545, 5148.588112950993, 5158.045761534109, 4996.850094497171, 5095.668781529226, 5097.2608971699265, 5099.15863882459, 5112.514311140436, 5639.351474580394, 5645.463680950304, 5649.971750141264, 5664.773409063632, 5664.801731939362, 5803.941960580382, 5804.477597016289, 5806.1000544915605, 5823.82528524145, 5832.387599302097, 5957.463828255478, 5968.67172305523, 5972.850678733023, 6110.602810817282, 6128.724207410677, 5590.804321848756, 5608.411116832755, 5620.4098569650005, 5644.294463192101, 5651.943480565224, 6082.878342433127, 6113.419225173601, 6131.957567213584, 6152.3847615238565, 6158.507319414436, 5867.885101830639, 5893.882122357529, 5944.791932282349, 5951.5238780897425, 5956.195913551702, 6047.909281360783, 6101.908471372933, 6113.663748493876, 6121.938780612137, 6138.386161383826, 6065.423784740443, 6084.5132389408745, 6085.2393450818645, 6089.512838972872, 6097.5731698780655, 5953.196318497034, 6011.368806275365, 6025.758969641253, 6140.162398500937, 6147.477464415575, 4717.080169366941, 4774.331593576902, 4784.473707892129, 4785.784639691222, 4829.44461386945, 4312.504062032906, 4326.199653064194, 4332.67504937131, 4340.414044104069, 4356.411884395629, 5461.371942226615, 5573.716493070279, 5589.776408943678, 5595.5523558116065, 5641.379448260688, 5995.970020149958, 6074.362191969868, 6075.878482430415, 6083.117947897618, 6095.0, 5491.835244942658, 5532.474414930575, 5548.805791797348, 5552.52803511706, 5553.611247212737, 5205.609579281569, 5217.243499692523, 5222.190560463135, 5222.843314700565, 5232.947670523246, 6119.593952096689, 6214.223222097238, 6220.720067596879, 6223.626582405071, 6232.75068997235, 6067.514598832171, 6086.573939824169, 6090.512758979359, 6092.086187071975, 6106.847328816637, 6141.63943600049, 6218.968905155445, 6219.222597175353, 6221.813345599639, 6227.034809604518, 6013.579049466495, 6025.0, 6034.2155519782145, 6060.0, 6065.848353791014, 5915.615484993462, 6121.418465245794, 6124.295705993868, 6142.416470435302, 6146.262448506208, 5050.595952323732, 5742.253507044057, 5804.82585522435, 5810.041343178328, 5893.911591326219, 5935.109733539831, 5937.881242375215, 5939.524838013035, 5940.732667029907, 5952.107183922446, 5773.16289138081, 5791.0734282514595, 5797.565182611237, 5802.564807639528, 5811.622244554091, 5641.407652196624, 5818.883622327445, 5839.620424672383, 5894.557908156819, 5926.259217597801, 5696.458836410535, 5699.088145896802, 5766.279215098113, 5816.5055970243, 5826.038703613856], "value": 5533.420049840318, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.2981515324005, 516.0916786455837, 530.1993989076493, 532.6910391972654, 543.2910052381642, 30421.847890760542, 30871.228219294502, 31739.682603173933, 31764.68235317646, 31775.0, 32424.18939526513, 33150.33699326012, 41699.0, 42698.573014269845, 42976.35535466971, 9304.534773261326, 9467.573959171854, 9478.099580539872, 9479.905200947971, 9482.525873706321, 9327.86008209874, 9449.81100377992, 9451.19664828487, 9451.621935122586, 9454.4800035998, 8438.662453501853, 8630.568471576427, 9426.905730942672, 11156.497957591888, 11391.715207119805, 11262.54949802007, 11325.886741132566, 11339.603113891057, 11351.43242837859, 11370.204085714004, 10855.565777368896, 11003.504842282116, 11008.064314533274, 11018.402331661944, 11030.393328366936, 31514.842425787723, 31937.201569960704, 31954.680453195404, 32924.0, 39239.41140882889, 2902.0, 3316.535685004101, 3325.783824051429, 3343.7157841583494, 3350.1959529712935, 28552.0, 28755.0, 29526.0, 30453.543196852064, 31117.377652447052, 3183.029176101288, 3183.586133802614, 3186.2193762528327, 3276.967755157118, 3285.0965984354493, 457150.0, 490776.0, 491895.0, 492058.0, 500668.9933100659, 34276.0, 35073.29853402918, 61326.69336653288, 61630.0755488668, 61708.38291617116, 28385.43229135447, 34964.0, 35052.29895402078, 35071.4739278911, 35169.47245791315, 14110.647233819236, 14257.714845703027, 14285.78571321431, 14285.85714142866, 14287.928560357132, 11991.64025079249, 12037.097217708706, 12071.939640301742, 12079.456424460921, 12100.878991210151, 7364.0426744522865, 7830.569318687527, 7864.21357864209, 7900.367970562341, 8011.959940200261, 3065.555494453292, 3094.133642580081, 3105.068479456154, 3111.4088323218516, 3179.2210908327606, 885.1458342699198, 958.755517343073, 976.8192884316425, 978.5449765858767, 982.3958265666564, 29579.0, 29866.70133298639, 30451.847740761154, 30600.54099188514, 30958.690413096032, 978.2320878110648, 997.4464172384252, 998.8102260570469, 1033.7543260371235, 1040.921930855189, 14722.852771472148, 15203.239838008056, 15319.23403829804, 15498.690026199638, 15514.224288785515, 687672.0, 691155.0, 691241.0, 692229.0, 692350.0, 58551.0, 58648.0, 58673.0, 58774.0, 58899.46603203822, 1177.1524502358288, 1199.1785626845638, 1337.9197248165142, 1351.716139610673, 1377.882879955195, 29185.708142918298, 30251.697483024887, 30528.542071868495, 30731.0, 31146.68853311438, 50329.74835125948, 50991.980160397325, 51041.74479127581, 51117.48882511127, 51134.48865511446, 519277.0, 551894.0, 556171.0, 556376.0, 556443.0, 25354.746452535976, 28114.734836931977, 28155.71844281531, 28691.569626456036, 28763.849446021926, 46470.0, 47079.293810592186, 47184.7640761794, 47429.0, 47759.0, 39229.80385098056, 39250.0, 39508.802455987534, 39701.404478933415, 40983.18033639251, 38555.42166867555, 38964.805175975074, 39380.606193937696, 40008.599914000486, 40656.186876261716, 37734.24531509409, 38053.809730951165, 38248.66129685448, 38565.6143438562, 38571.22857542778, 33427.498587521695, 34456.0, 35191.76828810979, 35349.82325088358, 35530.644693552735, 25485.745142548338, 25513.23460296194, 25675.749630595295, 25758.09846655358, 26057.348566285993], "value": 18412.474519944615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.6077352205246, 367.8528588564574, 369.7060836634877, 383.4612369620683, 394.4202023026152, 14476.420943162275, 14697.97114202005, 15574.532764017084, 19655.410337689893, 20040.899795501038, 18377.356792512266, 19003.429897103073, 19294.0, 19427.222911083576, 19675.0, 5516.3380394352735, 5800.361960184377, 5800.854978625526, 5813.854653633672, 5820.738066786984, 4617.746023968681, 5465.262189604392, 5735.483806457418, 5796.304443466769, 5810.651360918338, 5644.943550564503, 5649.350324712669, 5713.085906255, 5751.137329400582, 5762.049261871786, 5472.288602481692, 5677.829665110054, 5709.286339207599, 5723.969685456616, 5736.541076713853, 4746.264329029006, 5007.799688012475, 5021.246812978046, 5024.949750502521, 5026.421961474404, 24142.275731728078, 24227.87886060576, 24331.0, 24578.754212458003, 24723.876380618163, 2201.636729939558, 2319.7796209360085, 2428.1258746851117, 2436.2691192642137, 2463.19946017545, 14064.718705625932, 14386.496472623512, 14822.777658335133, 15000.924995374953, 15096.698066038727, 1791.7312403139504, 1832.129738374275, 1926.0273561851175, 1929.9324523641606, 2009.7085922541296, 105348.47325763399, 105593.08051896139, 108155.37766933502, 124376.0, 128284.7171528282, 8875.778105547284, 9210.493422861673, 11472.713182170513, 11603.825942610867, 11609.825852612217, 14452.132872027501, 14460.20468874222, 14483.0, 14483.49307774244, 14493.492727754478, 8192.54940978252, 8271.0, 8334.749957501284, 8347.874781878276, 8405.663773449116, 7344.669489872862, 7592.202818704067, 8239.546824924566, 8355.623996920152, 8360.289375403037, 3762.814713365281, 3916.432117343026, 4261.31818908973, 4348.934765978513, 4376.168527979674, 1537.0623919409152, 1668.9749653755202, 1700.9879121922436, 1872.4008317338594, 1965.9311924082588, 820.6676296100089, 862.1593945902695, 915.3638221436171, 916.3814425262921, 1004.9447280399648, 19668.213271469267, 19903.60192796136, 19914.402567922985, 20089.19643214255, 20228.898855505922, 911.4459845962618, 929.5166513413122, 948.814981078683, 958.0467434902317, 974.4640447753707, 9735.756606084904, 10612.628558000588, 10627.36235825853, 10629.202809789296, 10726.785464290671, 591965.0, 594370.0, 605681.0, 612577.0, 613024.0, 89510.72195103456, 104368.47815760873, 112852.0, 127342.0, 128830.0, 914.0813482450095, 948.7106432538004, 949.3307218411142, 1015.6902144845893, 1020.5917632946881, 13223.735525289248, 17635.0, 18216.815906966003, 18300.542486437946, 18313.725294120864, 37181.81409092937, 49043.50956490389, 56600.15099773589, 56898.7155064222, 57891.42108578861, 79405.60297198477, 79675.0, 81060.378792425, 81068.59465702633, 82891.0, 6379.6810159490915, 7949.8410031800195, 8035.477693949874, 8137.918620813716, 8216.548089855116, 10381.740456488647, 12219.938900305442, 12287.078469114673, 12718.427670754652, 12778.808317875053, 10768.246222764645, 10773.569057237622, 10898.727531811768, 11147.777044459228, 11223.0, 10560.471976401303, 10610.0, 10706.625268115578, 10777.838332424863, 10866.782664346827, 9229.861552076858, 10877.0, 10896.836547451636, 10957.342559446459, 11295.926886945726, 7940.444168908352, 7941.960290198512, 8036.075851276944, 8136.430449868689, 8162.18378162179, 9077.727668169966, 9898.0, 10571.629992950517, 10792.190585706261, 10902.345859248471], "value": 9275.913457560937, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1466.0324186037215, 1486.0, 1638.2955329208444, 1809.4300295406954, 1836.4857839804852, 8024.037115546135, 8338.24955753982, 8426.368022398314, 8429.78925526862, 8448.493090414579, 7497.137829149649, 7720.073591169067, 7735.729249476255, 7963.761087167394, 8212.548309842956, 2273.783990520902, 2330.755270696578, 2337.9415514612188, 2339.0643742502966, 2348.0490401387465, 2308.6537019447132, 2338.298510446859, 2354.682117914085, 2358.3278765551822, 2367.1241640593003, 1927.7493925789627, 2293.3693234360603, 2298.793108620224, 2324.9535009299802, 2328.6972693549815, 2309.3302942146756, 2318.1654604342425, 2318.6174281243575, 2319.304208737372, 2319.431739223901, 2176.706144670465, 2269.2851751698163, 2270.4437412833963, 2270.829687773407, 2277.202978957368, 7951.165127661628, 8022.1175670676175, 8029.678812847479, 8038.236367545074, 8040.477368971056, 5057.367829021372, 5222.582193424517, 5237.659552129099, 5247.1866860636665, 5248.816291429782, 3201.183698156978, 3349.681780230874, 3362.680545348188, 3588.2105936693883, 3594.982025089884, 1061.3154515337642, 1075.2526993739355, 1083.3608171179042, 1097.3964319624254, 1106.8173751331021, 10662.466876656135, 10769.784604307948, 10907.0, 10920.399378034126, 10920.945395273053, 4456.732596044248, 4566.88582785433, 4658.208104622222, 4666.580007799314, 4682.297655351656, 9474.668386606574, 9489.288303377274, 9492.098250666175, 9496.050394960448, 9537.904620953701, 5195.688258704414, 5197.298364720715, 5197.792088316501, 5215.55667768244, 5219.034478621467, 3535.6641119093647, 3588.3540962626976, 4196.727212731163, 4759.190937540627, 4762.59517940972, 3813.142043040349, 3842.7886466244067, 3920.2355540669296, 3940.389239667856, 3956.090099277182, 1257.861635220125, 1283.8395200599925, 1287.7746394381134, 1297.8247936528448, 1300.830891984036, 813.2721214512977, 875.1205038935828, 877.7191298784328, 907.8365894138987, 915.9358844880927, 5770.394108618619, 5823.767049318065, 5851.063829787298, 5911.280810826982, 6045.274567051982, 876.9210771030638, 885.287343688337, 896.8834051573253, 903.503073309677, 970.7184916374173, 3712.2389910068373, 3738.457923601117, 3773.660370566607, 3804.7907365094634, 3820.465134881119, 9597.184239339735, 10012.349197302297, 10066.043725846033, 10085.848712269322, 10141.492925353703, 10055.798884022279, 10057.497125143713, 10073.949630251802, 10081.294309398418, 10107.241956853266, 7390.667419966115, 7669.616519174019, 7729.613519324123, 7804.843903121906, 7888.171741967011, 5471.9726401369335, 5580.246666700106, 5814.0, 5825.563082768894, 5842.853928651818, 7736.0, 9118.316126290687, 9135.771605709644, 9456.243500520079, 9473.431594104375, 9233.907660923305, 9257.305702072506, 9576.473293968898, 9606.855897161407, 9681.273904457054, 6598.0, 6810.182778066664, 6812.080369150101, 7015.228324884157, 7244.0, 7626.3898888089925, 7923.445358825057, 7925.326347260548, 8014.559199244098, 8033.55815430157, 7056.717731290896, 7139.678714457758, 7158.892616610651, 7201.963990180225, 7294.416446684359, 6852.794416167523, 6982.197047339418, 7038.824029399101, 7047.260037695968, 7155.606441645551, 6616.073749674953, 6619.569727967667, 6712.127423434922, 6720.966395168189, 6730.394264516217, 4678.806904239397, 4812.422509298906, 4817.373741413734, 4860.17377045896, 4905.435874874435, 4287.485501739811, 4749.0, 4765.76171191446, 4940.777665005012, 5023.623228257822], "value": 4404.8171418772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.1542135517676, 300.5506767382763, 306.191654033352, 310.6567243196268, 315.23869854301864, 4531.977340113295, 4905.681130726501, 5135.202679062331, 5178.275041494194, 5187.974060129694, 4093.8362465501345, 4237.834595486235, 4866.951330486694, 4978.0, 5023.874403139914, 3771.057235691077, 3883.669888059518, 3921.450996860442, 3922.3920292354587, 3974.8608798692053, 3241.0438920518504, 3306.6197387300526, 3916.020994751298, 3945.579591347113, 4011.338129208677, 3425.7944523328556, 3706.703463722909, 3876.379779235323, 3924.54867690215, 3933.8033098345113, 3914.393269043303, 3918.88243352697, 3940.251352243079, 3945.224648908003, 3957.6042395760483, 3114.5795317632283, 3386.5258863759095, 3391.4743214801747, 3400.931981360384, 3421.5209870618132, 4348.630366418826, 5007.2489126630935, 5036.84889453317, 5044.571211447032, 5051.318072060299, 3786.163710600357, 4234.343676730112, 4435.556444355571, 4484.372187893698, 4492.438445194351, 4488.304312831517, 5073.46728593496, 5158.92261616076, 5430.402655707869, 5487.42382049883, 3732.533433320835, 3738.9904725724173, 3756.19702542781, 3764.209516001643, 3765.868194613203, 4726.929096063562, 5596.972015139939, 5609.551235901118, 5619.213310136543, 5657.264555607827, 3152.511360739089, 3764.1906989997283, 3767.547894252707, 3778.8110594470168, 3782.71629627779, 3422.0, 3665.963340366562, 3670.064133645903, 3849.9615003850163, 3875.6511913927884, 4064.7357921734992, 4092.7339722917914, 4118.299889018874, 4287.378330142112, 4496.910061798746, 4776.259679749644, 5435.157550579666, 5439.700816455053, 5450.781968721207, 5471.868739101953, 1195.8863907928733, 1196.5154112584416, 1203.9405323315602, 1210.4189988805422, 1214.8967337776387, 764.7705688293489, 818.5620692929255, 828.0849661124371, 847.2671139464319, 853.7822855172016, 4145.139853501075, 4523.218220794188, 4761.523847615211, 4829.9275510867365, 4860.4896485869185, 4316.417283666728, 4434.55654434554, 4791.921817591014, 4863.902721945541, 5372.91940620891, 4170.812313445902, 4561.771911404417, 4793.304970779287, 4824.662273640812, 4841.540053694894, 2419.5040016796515, 2623.790096792252, 2775.500409926233, 2803.8738256778493, 2807.8066821600737, 4754.9286760698615, 4898.926516102261, 5567.3040869891265, 5606.748086110047, 5606.803761868315, 4791.353167322437, 4943.505649435027, 5304.363476382781, 5723.998300297515, 5784.4504772046585, 5262.158054711304, 5305.973470132624, 5587.513556467333, 5792.102224155263, 5820.272465941757, 4922.606191504743, 4986.576141027908, 5205.3071370810085, 5246.239295302236, 5416.539594134385, 5496.945030549803, 5517.0, 5517.420670829505, 5546.557894947262, 5555.972220138873, 5251.711155886463, 5780.537556995346, 5787.247657804459, 5846.970765146147, 5856.812379148929, 3271.3620843935205, 3608.62109478501, 3712.294664013829, 3738.691457989682, 3739.8691045813266, 4835.685680430761, 4909.797099710594, 5566.610337276352, 5605.579581531483, 5626.634268772516, 5017.749112544285, 5037.078494473123, 5444.972775136099, 5466.945330546802, 5525.309336332958, 4817.0, 5296.682199067915, 5367.893561425591, 5478.329519222051, 5526.502614764529, 5115.283860259492, 5142.6914385137015, 5285.788568457218, 5344.305240318734, 5369.436209197978, 4629.740638612418, 5125.769340379767, 5333.3066701330135, 5373.892522149613, 5516.779328826802, 5355.973220133875, 5371.570274377962, 5376.0, 5378.247045413567, 5407.0], "value": 3895.4290408283046, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [687.2920891481775, 745.6197339356926, 747.3124725252769, 769.9451751100991, 780.8789637606166, 11734.295942243461, 14882.330295136719, 14893.925530372333, 14907.0, 14952.476663316786, 13157.934210328958, 13266.005049621268, 13275.668108297297, 13284.867151328484, 13286.468541258339, 655.4003087175253, 674.2448457727352, 803.3492870774662, 814.4950130918838, 836.7908022994252, 823.7734622978669, 832.76682528892, 833.5082301442147, 840.0843081041663, 840.6889450903175, 868.9695860644879, 899.3210126354593, 899.7525680437866, 903.0382642485731, 904.3217586809892, 895.6104094718786, 896.2516298890434, 898.083954366549, 898.6630013744846, 898.7797989492583, 508.5169089365081, 526.2816255810791, 624.7126321891935, 649.1496140056541, 652.7160685101995, 13894.722105557934, 14164.85835141656, 14385.0, 14387.0, 14459.277036148256, 12961.935190324082, 13251.006174536948, 13315.267660278681, 13339.866601334057, 13388.665283367896, 11700.122490813223, 12022.879771202264, 12037.097217708619, 12144.453499592539, 12321.260724356478, 3791.639794219545, 4394.4946331172005, 4499.505054444008, 4595.4025976622825, 4617.2381557043045, 91893.0, 94414.52792736061, 95145.52427237888, 97223.0, 97998.0, 9097.493699521754, 10243.334183278062, 10552.47237638115, 10642.840357394645, 10764.569417223223, 20444.0, 20632.58734825325, 20659.0, 20701.58596828055, 20702.585948281252, 7371.299726525972, 7508.174100849012, 7522.811929701692, 7721.0, 7726.690932362642, 4142.689298302579, 4199.517055538591, 4211.0, 4371.650267978554, 4389.868303950887, 3286.4084464796565, 3343.7826541275226, 3376.544166537535, 3421.9828900855337, 3424.0, 586.601111244358, 702.8524009957864, 709.7835160276165, 712.4122598855964, 720.4524561333425, 560.9551235901118, 585.6925114314934, 609.8048624440137, 641.5252712992445, 650.6012074040756, 7992.841038049451, 8454.196851299117, 8927.375083744075, 9039.514133613178, 9190.218831399272, 631.4674690328544, 641.9807405777834, 647.4464332995221, 650.5283669339785, 659.6635715784888, 4277.0, 4353.586409291112, 4427.048184640318, 4512.2554778461845, 4552.256986739991, 78022.0, 104141.47929260305, 117092.0, 119750.0, 125200.0, 82358.17641823625, 94136.11727765406, 98819.0, 101093.98906010993, 105736.47131764446, 84431.0, 85130.57434712787, 85228.57385713156, 92712.6093108604, 92990.0, 9257.953710231406, 9652.972875696183, 9687.612495500101, 9711.0, 9741.756456088371, 39003.21993560056, 42958.57041429671, 51790.741046294526, 54642.72678636581, 57298.713506433865, 57685.42314576801, 68519.65740171267, 70198.64900675668, 76030.0, 78667.0, 10460.424676642553, 12195.329256890956, 12659.240445573296, 12665.746685066431, 12767.0, 13415.128016678884, 14178.574642760734, 14336.0, 14356.641083972985, 14390.712185756436, 12762.553310634083, 12799.680007999874, 12848.229106253655, 13427.865721343052, 13598.86401135976, 12933.288669123289, 12944.352782361033, 13118.672033199246, 13648.86351136476, 13690.86309136896, 10649.918622879663, 12797.808032879328, 13088.607341779762, 13166.539171128965, 13393.598192054254, 10761.677149685522, 10983.560657573607, 11071.391073490713, 11434.656960291204, 11456.656300311004, 9089.19235891639, 9605.847298324246, 11366.0, 11495.137864660017, 11559.19085664029], "value": 7321.478142516629, "unit": "score"}], "name": "dromaeo_css", "value": 7010.761175719942, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 114.0, 114.0, 115.0, 113.0, 114.0, 112.0, 112.0, 114.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 144.0, 146.0, 144.0, 113.0, 111.0, 110.0, 96.0, 148.0, 142.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 182.0, 179.0, 183.0, 183.0, 183.0, 182.0, 187.0, 222.0, 183.0], "value": 186.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 60.0, 101.0, 89.0, 50.0, 74.0, 83.0, 111.0, 83.0], "value": 79.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 71.0, 94.0, 70.0, 68.0, 69.0, 89.0, 68.0, 68.0, 91.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 131.0, 89.0, 86.0, 89.0, 84.0, 88.0, 87.0, 90.0, 86.0], "value": 94.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 94.0, 105.0, 151.0, 119.0, 94.0, 109.0], "value": 105.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 123.0, 79.0, 78.0, 80.0, 81.0, 83.0, 93.0, 122.0, 123.0], "value": 95.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 57.0, 59.0, 58.0, 57.0, 57.0, 56.0, 54.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 72.0, 72.0, 74.0, 71.0, 69.0, 69.0, 70.0, 68.0, 71.0], "value": 70.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 83.0, 84.0, 84.0, 84.0, 83.0, 85.0, 84.0, 84.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 141.0, 141.0, 139.0, 147.0, 152.0, 152.0, 187.0, 140.0], "value": 147.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 165.0, 165.0, 166.0, 164.0, 168.0, 167.0, 165.0, 164.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 77.0, 84.0, 85.0, 85.0, 82.0, 65.0], "value": 74.7, "unit": "score"}], "name": "kraken", "value": 1483.5, "alertThreshold": 2.0}]} 12:05:04 INFO - Return code: 0 12:05:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:05:04 INFO - # TBPL SUCCESS # 12:05:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:05:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:05:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:05:04 INFO - 2017-01-07 12:05:04.608 screenresolution[1652:9810] starting screenresolution argv=screenresolution get 12:05:04 INFO - 2017-01-07 12:05:04.611 screenresolution[1652:9810] Display 0: 1600x1200x32@60 12:05:04 INFO - 2017-01-07 12:05:04.617 screenresolution[1653:9813] starting screenresolution argv=screenresolution list 12:05:04 INFO - Available Modes on Display 0 12:05:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:05:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:05:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:05:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:05:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:05:04 INFO - Intel Iris: 12:05:04 INFO - Chipset Model: Intel Iris 12:05:04 INFO - Type: GPU 12:05:04 INFO - Bus: Built-In 12:05:04 INFO - VRAM (Dynamic, Max): 1536 MB 12:05:04 INFO - Vendor: Intel (0x8086) 12:05:04 INFO - Device ID: 0x0a2e 12:05:04 INFO - Revision ID: 0x0009 12:05:04 INFO - Displays: 12:05:04 INFO - Display: 12:05:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:05:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:05:04 INFO - Display Serial Number: 1600x1200 60 12:05:04 INFO - Main Display: Yes 12:05:04 INFO - Mirror: Off 12:05:04 INFO - Online: Yes 12:05:04 INFO - Rotation: Supported 12:05:04 INFO - Return code: 0 12:05:04 INFO - Running post-action listener: _resource_record_post_action 12:05:04 INFO - [mozharness: 2017-01-07 20:05:04.861172Z] Finished run-tests step (success) 12:05:04 INFO - Running post-run listener: _resource_record_post_run 12:05:04 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 37884928; Write bytes: 657387520; Read time: 241; Write time: 1894 12:05:04 INFO - TinderboxPrint: CPU usage
53.5% 12:05:04 INFO - TinderboxPrint: I/O read bytes / time
37,884,928 / 241 12:05:04 INFO - TinderboxPrint: I/O write bytes / time
657,387,520 / 1,894 12:05:04 INFO - TinderboxPrint: CPU idle
2,258.8 (46.5%) 12:05:04 INFO - TinderboxPrint: CPU system
973.3 (20.1%) 12:05:04 INFO - TinderboxPrint: CPU user
1,621.6 (33.4%) 12:05:04 INFO - TinderboxPrint: Swap in / out
386,940,928 / 0 12:05:04 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 340974080; Write bytes: 334178304; Read time: 27000; Write time: 687 12:05:04 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 36926464; Write bytes: 306554880; Read time: 227; Write time: 1153 12:05:05 INFO - Running post-run listener: _upload_blobber_files 12:05:05 INFO - Blob upload gear active. 12:05:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:05:05 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 12:05:05 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'] 12:05:05 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 12:05:05 INFO - (blobuploader) - INFO - Open directory for files ... 12:05:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:05:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:05:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:07 INFO - (blobuploader) - INFO - Done attempting. 12:05:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:05:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:05:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:08 INFO - (blobuploader) - INFO - Done attempting. 12:05:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:05:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:05:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:05:11 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:05:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:05:11 INFO - (blobuploader) - INFO - Done attempting. 12:05:11 INFO - (blobuploader) - INFO - Iteration through files over. 12:05:11 INFO - Return code: 0 12:05:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:05:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:05:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c5bbc00c426059a30c0d4cd6d8762211f27e22ca8122089bac4c4d9e049f1472350321a7f5fb82f9ca58cb0a654eeaccd094ad6c55864e0db227ed2d860e48a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b08f0621756d9236a4eb27d31c9263c9daa5e0805e20ebb9c5715d6654992304f88de954cbaff90564ac0660d181e71e56d35bbcec79cd0370d80aa6707c9d3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a37f878f3667848711d481fbcff7369510f2922f7943a799b30d6c5247612258a21e5e7edf62fe5189f6aea57bd264e6fdf23a97da089917682ad2418056739"} 12:05:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:05:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:05:11 INFO - Contents: 12:05:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8c5bbc00c426059a30c0d4cd6d8762211f27e22ca8122089bac4c4d9e049f1472350321a7f5fb82f9ca58cb0a654eeaccd094ad6c55864e0db227ed2d860e48a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b08f0621756d9236a4eb27d31c9263c9daa5e0805e20ebb9c5715d6654992304f88de954cbaff90564ac0660d181e71e56d35bbcec79cd0370d80aa6707c9d3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a37f878f3667848711d481fbcff7369510f2922f7943a799b30d6c5247612258a21e5e7edf62fe5189f6aea57bd264e6fdf23a97da089917682ad2418056739"} 12:05:11 INFO - Running post-run listener: copy_logs_to_upload_dir 12:05:11 INFO - Copying logs to upload dir... 12:05:11 INFO - mkdir: /builds/slave/test/build/upload/logs 12:05:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.201188 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2017-01-07 12:05:11.781970) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 12:05:11.785165) ========= 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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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/8c5bbc00c426059a30c0d4cd6d8762211f27e22ca8122089bac4c4d9e049f1472350321a7f5fb82f9ca58cb0a654eeaccd094ad6c55864e0db227ed2d860e48a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b08f0621756d9236a4eb27d31c9263c9daa5e0805e20ebb9c5715d6654992304f88de954cbaff90564ac0660d181e71e56d35bbcec79cd0370d80aa6707c9d3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a37f878f3667848711d481fbcff7369510f2922f7943a799b30d6c5247612258a21e5e7edf62fe5189f6aea57bd264e6fdf23a97da089917682ad2418056739"} build_url:https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011073 build_url: 'https://queue.taskcluster.net/v1/task/XGhwlRhKS_ON4_Ism_x0WQ/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/8c5bbc00c426059a30c0d4cd6d8762211f27e22ca8122089bac4c4d9e049f1472350321a7f5fb82f9ca58cb0a654eeaccd094ad6c55864e0db227ed2d860e48a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b08f0621756d9236a4eb27d31c9263c9daa5e0805e20ebb9c5715d6654992304f88de954cbaff90564ac0660d181e71e56d35bbcec79cd0370d80aa6707c9d3b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5a37f878f3667848711d481fbcff7369510f2922f7943a799b30d6c5247612258a21e5e7edf62fe5189f6aea57bd264e6fdf23a97da089917682ad2418056739"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-07 12:05:11.819327) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:05:11.819649) ========= 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.zuIAdQffU2/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.7cnXSwm1Xb/Listeners TMPDIR=/var/folders/94/32rsmpvx6xsdqlj3mz5wd0xr00000w/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.004625 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-07 12:05:11.867661) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 12:05:11.867982) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-07 12:05:11.868289) ========= ========= Total master_lag: 0.18 =========