builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0140 starttime: 1482813095.41 results: success (0) buildid: 20161226193237 builduid: 236a126c81824d7781dd44c790b5f1e6 revision: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:35.406460) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:35.406908) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:35.407207) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:35.441536) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:35.441936) ========= 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.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-26 20:31:35-- 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% 53.0M=0s 2016-12-26 20:31:36 (53.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.632632 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:36.099061) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:36.099392) ========= 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.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.019650 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:36.147782) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 20:31:36.148628) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c --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 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-26 20:31:36,226 truncating revision to first 12 chars 2016-12-26 20:31:36,227 Setting DEBUG logging. 2016-12-26 20:31:36,227 attempt 1/10 2016-12-26 20:31:36,227 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8d9fb8fa1f3d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-26 20:31:36,979 unpacking tar archive at: mozilla-aurora-8d9fb8fa1f3d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.000625 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-26 20:31:37.748322) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.748604) ========= script_repo_revision: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.748972) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.749251) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-26 20:31:37.781491) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-26 20:31:37.781857) ========= /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.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 20:31:37 INFO - MultiFileLogger online at 20161226 20:31:37 in /builds/slave/test 20:31:37 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 20:31:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:31:37 INFO - {'append_to_log': False, 20:31:37 INFO - 'base_work_dir': '/builds/slave/test', 20:31:37 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 20:31:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:31:37 INFO - 'branch': 'Mozilla-Aurora', 20:31:37 INFO - 'buildbot_json_path': 'buildprops.json', 20:31:37 INFO - 'config_files': ('talos/mac_config.py',), 20:31:37 INFO - 'default_actions': ('clobber', 20:31:37 INFO - 'read-buildbot-config', 20:31:37 INFO - 'download-and-extract', 20:31:37 INFO - 'populate-webroot', 20:31:37 INFO - 'create-virtualenv', 20:31:37 INFO - 'install', 20:31:37 INFO - 'run-tests'), 20:31:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:31:37 INFO - 'download_minidump_stackwalk': True, 20:31:37 INFO - 'download_symbols': 'ondemand', 20:31:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:31:37 INFO - 'tooltool.py': '/tools/tooltool.py', 20:31:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:31:37 INFO - '/tools/misc-python/virtualenv.py')}, 20:31:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:31:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:31:37 INFO - 'installer_path': 'installer.exe', 20:31:37 INFO - 'log_level': 'info', 20:31:37 INFO - 'log_name': 'talos', 20:31:37 INFO - 'log_to_console': True, 20:31:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:31:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:31:37 INFO - 'opt_config_files': (), 20:31:37 INFO - 'pip_index': False, 20:31:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:37 INFO - 'cmd': ('bash', 20:31:37 INFO - '-c', 20:31:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:37 INFO - 'enabled': True, 20:31:37 INFO - 'halt_on_failure': False, 20:31:37 INFO - 'name': 'check_screen_resolution'},), 20:31:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:31:37 INFO - 'cmd': ('bash', 20:31:37 INFO - '-c', 20:31:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:31:37 INFO - 'enabled': True, 20:31:37 INFO - 'halt_on_failure': False, 20:31:37 INFO - 'name': 'check_screen_resolution'},), 20:31:37 INFO - 'run_cmd_checks_enabled': True, 20:31:37 INFO - 'sps_profile': False, 20:31:37 INFO - 'sps_profile_interval': 0, 20:31:37 INFO - 'suite': 'dromaeojs', 20:31:37 INFO - 'system_bits': '32', 20:31:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:31:37 INFO - 'title': 't-yosemite-r7-0140', 20:31:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:31:37 INFO - 'use_talos_json': True, 20:31:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:31:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:31:37 INFO - 'work_dir': 'build'} 20:31:37 INFO - [mozharness: 2016-12-27 04:31:37.992665Z] Running clobber step. 20:31:37 INFO - Running pre-action listener: _resource_record_pre_action 20:31:37 INFO - Running main action method: clobber 20:31:37 INFO - rmtree: /builds/slave/test/build 20:31:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:31:38 INFO - Running post-action listener: _resource_record_post_action 20:31:38 INFO - [mozharness: 2016-12-27 04:31:38.915857Z] Finished clobber step (success) 20:31:38 INFO - [mozharness: 2016-12-27 04:31:38.915997Z] Running read-buildbot-config step. 20:31:38 INFO - Running pre-action listener: _resource_record_pre_action 20:31:38 INFO - Running main action method: read_buildbot_config 20:31:38 INFO - Using buildbot properties: 20:31:38 INFO - { 20:31:38 INFO - "project": "", 20:31:38 INFO - "product": "firefox", 20:31:38 INFO - "script_repo_revision": "production", 20:31:38 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 20:31:38 INFO - "repository": "", 20:31:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 20:31:38 INFO - "buildid": "20161226193237", 20:31:38 INFO - "pgo_build": "False", 20:31:38 INFO - "basedir": "/builds/slave/test", 20:31:38 INFO - "buildnumber": 2, 20:31:38 INFO - "slavename": "t-yosemite-r7-0140", 20:31:38 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:31:38 INFO - "platform": "yosemite_r7", 20:31:38 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 20:31:38 INFO - "branch": "mozilla-aurora", 20:31:38 INFO - "repo_path": "releases/mozilla-aurora", 20:31:38 INFO - "revision": "8d9fb8fa1f3db54852a586c145f2ed5afe00f27c", 20:31:38 INFO - "stage_platform": "macosx64", 20:31:38 INFO - "builduid": "236a126c81824d7781dd44c790b5f1e6", 20:31:38 INFO - "slavebuilddir": "test" 20:31:38 INFO - } 20:31:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 20:31:38 INFO - Running post-action listener: _resource_record_post_action 20:31:38 INFO - [mozharness: 2016-12-27 04:31:38.919327Z] Finished read-buildbot-config step (success) 20:31:38 INFO - [mozharness: 2016-12-27 04:31:38.919434Z] Running download-and-extract step. 20:31:38 INFO - Running pre-action listener: _resource_record_pre_action 20:31:38 INFO - Running main action method: download_and_extract 20:31:38 INFO - mkdir: /builds/slave/test/build/tests 20:31:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:31:38 INFO - trying https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 20:31:38 INFO - Downloading https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/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 20:31:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/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 20:31:39 INFO - Downloaded 1199 bytes. 20:31:39 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 20:31:39 INFO - Using the following test package requirements: 20:31:39 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:31:39 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 20:31:39 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 20:31:39 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'jsshell-mac.zip'], 20:31:39 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 20:31:39 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 20:31:39 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 20:31:39 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 20:31:39 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 20:31:39 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 20:31:39 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 20:31:39 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 20:31:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:31:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:31:39 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:31:40 INFO - Http code: 200 20:31:40 INFO - via: 1.1 d8f42fc9558e3e49ebfdf8834baeb756.cloudfront.net (CloudFront) 20:31:40 INFO - x-amz-cf-id: mlFpd4Uzf8n1WoUTh58aiKbfNt6rURyTix5H2ziIVqAUrGmIKRNfHQ== 20:31:40 INFO - x-amz-version-id: yF6gys6MsIfR0K.ItVjHHtGb.1sOLXFR 20:31:40 INFO - Expected file size: 16268334 20:31:40 INFO - Obtained file size: 16268334 20:31:47 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 20:31:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 20:31:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 20:31:47 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 20:31:48 INFO - Http code: 200 20:31:48 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 20:31:48 INFO - x-amz-cf-id: ENwSpiHnRMrS-hrkPqi1LpZjJ7maE-k2Ws_o5Ko32ynmEsjnLN7OyQ== 20:31:48 INFO - x-amz-version-id: yF6gys6MsIfR0K.ItVjHHtGb.1sOLXFR 20:31:48 INFO - Expected file size: 16268334 20:31:48 INFO - Obtained file size: 16268334 20:31:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 20:31:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 20:31:55 INFO - Fetch https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 20:31:55 INFO - Http code: 200 20:31:55 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 20:31:55 INFO - x-amz-cf-id: eoMyzIv5GhV9D6rBp_Zd9zUBxenuEhLk3Ab4MfZi_tdcCKpuWmStag== 20:31:55 INFO - x-amz-version-id: 1LZiSNBSTtNb0pB.nZp1jHoAyYaIFq18 20:31:56 INFO - Expected file size: 14409038 20:31:56 INFO - Obtained file size: 14409038 20:31:56 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')]} 20:31:56 INFO - trying https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:31:56 INFO - Downloading https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 20:31:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/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 20:31:59 INFO - Downloaded 109821250 bytes. 20:31:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:31:59 INFO - mkdir: /builds/slave/test/properties 20:31:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:31:59 INFO - Writing to file /builds/slave/test/properties/build_url 20:31:59 INFO - Contents: 20:31:59 INFO - build_url:https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 20:31:59 INFO - Running post-action listener: _resource_record_post_action 20:31:59 INFO - Running post-action listener: set_extra_try_arguments 20:31:59 INFO - [mozharness: 2016-12-27 04:31:59.968504Z] Finished download-and-extract step (success) 20:31:59 INFO - [mozharness: 2016-12-27 04:31:59.968649Z] Running populate-webroot step. 20:31:59 INFO - Running pre-action listener: _resource_record_pre_action 20:31:59 INFO - Running main action method: populate_webroot 20:31:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:31:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:31:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:31:59 INFO - '--tppagecycles', 20:31:59 INFO - '7'], 20:31:59 INFO - 'tests': ['tsvgm']}}, 20:31:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:31:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:31:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:31:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:31:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:31:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:31:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['damp', 'tps']}, 20:31:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'tests': ['damp', 'tps']}, 20:31:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['dromaeo_dom']}, 20:31:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:31:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['basic_compositor_video']}, 20:31:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:31:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'other-e10s': {'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['a11yr', 20:31:59 INFO - 'ts_paint', 20:31:59 INFO - 'tpaint', 20:31:59 INFO - 'sessionrestore', 20:31:59 INFO - 'sessionrestore_no_auto_restore', 20:31:59 INFO - 'tabpaint']}, 20:31:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['tsvgx', 20:31:59 INFO - 'tsvgr_opacity', 20:31:59 INFO - 'tart', 20:31:59 INFO - 'tscrollx', 20:31:59 INFO - 'cart']}, 20:31:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:31:59 INFO - 'tsvgr_opacity', 20:31:59 INFO - 'tart', 20:31:59 INFO - 'tscrollx', 20:31:59 INFO - 'cart']}, 20:31:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'talos_options': ['--disable-e10s'], 20:31:59 INFO - 'tests': ['tp5o']}, 20:31:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'tests': ['tp5o']}, 20:31:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'talos_options': ['--disable-e10s', 20:31:59 INFO - '--xperf_path', 20:31:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:31:59 INFO - 'tests': ['tp5n']}, 20:31:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 20:31:59 INFO - 'talos_options': ['--xperf_path', 20:31:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:31:59 INFO - 'tests': ['tp5n']}}, 20:31:59 INFO - 'talos.zip': {'path': '', 20:31:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:31:59 INFO - Running post-action listener: _resource_record_post_action 20:31:59 INFO - [mozharness: 2016-12-27 04:31:59.987618Z] Finished populate-webroot step (success) 20:31:59 INFO - [mozharness: 2016-12-27 04:31:59.987754Z] Running create-virtualenv step. 20:31:59 INFO - Running pre-action listener: _resource_record_pre_action 20:31:59 INFO - Running main action method: create_virtualenv 20:31:59 INFO - Creating virtualenv /builds/slave/test/build/venv 20:31:59 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 20:31:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:32:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:32:00 INFO - Using real prefix '/tools/python27' 20:32:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:32:00 INFO - Installing distribute.............................................................................................................................................................................................done. 20:32:03 INFO - Installing pip.................done. 20:32:03 INFO - Return code: 0 20:32:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:32:03 INFO - Reading from file tmpfile_stdout 20:32:03 INFO - Output received: 20:32:03 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) 20:32:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:32:03 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')]} 20:32:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:03 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')]} 20:32:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:03 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:32:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:32:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:03 INFO - 'HOME': '/Users/cltbld', 20:32:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:03 INFO - 'LOGNAME': 'cltbld', 20:32:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:03 INFO - 'MOZ_NO_REMOTE': '1', 20:32:03 INFO - 'NO_EM_RESTART': '1', 20:32:03 INFO - 'PAGER': '/bin/cat', 20:32:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:03 INFO - 'PWD': '/builds/slave/test', 20:32:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:03 INFO - 'SHELL': '/bin/bash', 20:32:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:03 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:03 INFO - 'USER': 'cltbld', 20:32:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:03 INFO - 'XPC_FLAGS': '0x0', 20:32:03 INFO - 'XPC_SERVICE_NAME': '0', 20:32:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 20:32:04 INFO - Cleaning up... 20:32:04 INFO - Return code: 0 20:32:04 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:04 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:32:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:32:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:04 INFO - 'HOME': '/Users/cltbld', 20:32:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:04 INFO - 'LOGNAME': 'cltbld', 20:32:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:04 INFO - 'MOZ_NO_REMOTE': '1', 20:32:04 INFO - 'NO_EM_RESTART': '1', 20:32:04 INFO - 'PAGER': '/bin/cat', 20:32:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:04 INFO - 'PWD': '/builds/slave/test', 20:32:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:04 INFO - 'SHELL': '/bin/bash', 20:32:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:04 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:04 INFO - 'USER': 'cltbld', 20:32:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:04 INFO - 'XPC_FLAGS': '0x0', 20:32:04 INFO - 'XPC_SERVICE_NAME': '0', 20:32:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:04 INFO - Downloading/unpacking psutil>=3.1.1 20:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:32:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:08 INFO - Installing collected packages: psutil 20:32:08 INFO - Running setup.py install for psutil 20:32:08 INFO - building 'psutil._psutil_osx' extension 20:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 20:32:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 20:32:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 20:32:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 20:32:09 INFO - building 'psutil._psutil_posix' extension 20:32:09 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 20:32:09 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:32:09 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:32:09 INFO - ^ 20:32:09 INFO - 1 warning generated. 20:32:09 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 20:32:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:32:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:32:09 INFO - Successfully installed psutil 20:32:09 INFO - Cleaning up... 20:32:09 INFO - Return code: 0 20:32:09 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:09 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 20:32:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 20:32:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:09 INFO - 'HOME': '/Users/cltbld', 20:32:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:09 INFO - 'LOGNAME': 'cltbld', 20:32:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:09 INFO - 'MOZ_NO_REMOTE': '1', 20:32:09 INFO - 'NO_EM_RESTART': '1', 20:32:09 INFO - 'PAGER': '/bin/cat', 20:32:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:09 INFO - 'PWD': '/builds/slave/test', 20:32:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:09 INFO - 'SHELL': '/bin/bash', 20:32:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:09 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:09 INFO - 'USER': 'cltbld', 20:32:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:09 INFO - 'XPC_FLAGS': '0x0', 20:32:09 INFO - 'XPC_SERVICE_NAME': '0', 20:32:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:09 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:12 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:32:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:32:12 INFO - Installing collected packages: mozsystemmonitor 20:32:12 INFO - Running setup.py install for mozsystemmonitor 20:32:12 INFO - Successfully installed mozsystemmonitor 20:32:12 INFO - Cleaning up... 20:32:12 INFO - Return code: 0 20:32:12 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:12 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 20:32:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 20:32:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:12 INFO - 'HOME': '/Users/cltbld', 20:32:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:12 INFO - 'LOGNAME': 'cltbld', 20:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:12 INFO - 'MOZ_NO_REMOTE': '1', 20:32:12 INFO - 'NO_EM_RESTART': '1', 20:32:12 INFO - 'PAGER': '/bin/cat', 20:32:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:12 INFO - 'PWD': '/builds/slave/test', 20:32:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:12 INFO - 'SHELL': '/bin/bash', 20:32:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:12 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:12 INFO - 'USER': 'cltbld', 20:32:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:12 INFO - 'XPC_FLAGS': '0x0', 20:32:12 INFO - 'XPC_SERVICE_NAME': '0', 20:32:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:12 INFO - Downloading/unpacking jsonschema==2.5.1 20:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:15 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:32:15 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:15 INFO - Downloading functools32-3.2.3-2.tar.gz 20:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:32:15 INFO - warning: no files found matching '*.txt' 20:32:15 INFO - no previously-included directories found matching 'build' 20:32:15 INFO - no previously-included directories found matching 'dist' 20:32:15 INFO - no previously-included directories found matching '.git*' 20:32:15 INFO - Installing collected packages: jsonschema, functools32 20:32:16 INFO - Running setup.py install for functools32 20:32:16 INFO - warning: no files found matching '*.txt' 20:32:16 INFO - no previously-included directories found matching 'build' 20:32:16 INFO - no previously-included directories found matching 'dist' 20:32:16 INFO - no previously-included directories found matching '.git*' 20:32:16 INFO - Successfully installed jsonschema functools32 20:32:16 INFO - Cleaning up... 20:32:16 INFO - Return code: 0 20:32:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:32:16 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')]} 20:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:16 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')]} 20:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:16 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:16 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 20:32:16 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 20:32:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:16 INFO - 'HOME': '/Users/cltbld', 20:32:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:16 INFO - 'LOGNAME': 'cltbld', 20:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:16 INFO - 'MOZ_NO_REMOTE': '1', 20:32:16 INFO - 'NO_EM_RESTART': '1', 20:32:16 INFO - 'PAGER': '/bin/cat', 20:32:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:16 INFO - 'PWD': '/builds/slave/test', 20:32:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:16 INFO - 'SHELL': '/bin/bash', 20:32:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:16 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:16 INFO - 'USER': 'cltbld', 20:32:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:16 INFO - 'XPC_FLAGS': '0x0', 20:32:16 INFO - 'XPC_SERVICE_NAME': '0', 20:32:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:32:16 INFO - Cleaning up... 20:32:16 INFO - Return code: 0 20:32:16 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:32:16 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')]} 20:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:16 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')]} 20:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:16 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:32:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:32:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:16 INFO - 'HOME': '/Users/cltbld', 20:32:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:16 INFO - 'LOGNAME': 'cltbld', 20:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:16 INFO - 'MOZ_NO_REMOTE': '1', 20:32:16 INFO - 'NO_EM_RESTART': '1', 20:32:16 INFO - 'PAGER': '/bin/cat', 20:32:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:16 INFO - 'PWD': '/builds/slave/test', 20:32:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:16 INFO - 'SHELL': '/bin/bash', 20:32:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:16 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:16 INFO - 'USER': 'cltbld', 20:32:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:16 INFO - 'XPC_FLAGS': '0x0', 20:32:16 INFO - 'XPC_SERVICE_NAME': '0', 20:32:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:16 INFO - Downloading/unpacking blobuploader==1.2.4 20:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:19 INFO - Downloading blobuploader-1.2.4.tar.gz 20:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:32:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:32:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:20 INFO - Downloading docopt-0.6.1.tar.gz 20:32:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:32:20 INFO - Installing collected packages: blobuploader, requests, docopt 20:32:20 INFO - Running setup.py install for blobuploader 20:32:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:32:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:32:20 INFO - Running setup.py install for requests 20:32:21 INFO - Running setup.py install for docopt 20:32:21 INFO - Successfully installed blobuploader requests docopt 20:32:21 INFO - Cleaning up... 20:32:21 INFO - Return code: 0 20:32:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:21 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')]} 20:32:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:21 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')]} 20:32:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:21 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:32:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:21 INFO - 'HOME': '/Users/cltbld', 20:32:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:21 INFO - 'LOGNAME': 'cltbld', 20:32:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:21 INFO - 'MOZ_NO_REMOTE': '1', 20:32:21 INFO - 'NO_EM_RESTART': '1', 20:32:21 INFO - 'PAGER': '/bin/cat', 20:32:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:21 INFO - 'PWD': '/builds/slave/test', 20:32:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:21 INFO - 'SHELL': '/bin/bash', 20:32:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:21 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:21 INFO - 'USER': 'cltbld', 20:32:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:21 INFO - 'XPC_FLAGS': '0x0', 20:32:21 INFO - 'XPC_SERVICE_NAME': '0', 20:32:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:32:21 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-x9aYyy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:32:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:32:21 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-oZhHL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-uFtUjk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-_XPpgw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-AKHIHx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-bwV4fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-4qGZX9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-5LIktp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-jNzB1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-TcZuWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:32:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:32:22 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-eIse3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-q0_0KJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-PqzBkL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-Rs4BAi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-DhbRJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-8YUspm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:32:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:32:23 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-FUhb3z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:32:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:32:23 INFO - Running setup.py install for manifestparser 20:32:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:32:23 INFO - Running setup.py install for mozcrash 20:32:23 INFO - Running setup.py install for mozdebug 20:32:24 INFO - Running setup.py install for mozdevice 20:32:24 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Running setup.py install for mozfile 20:32:24 INFO - Running setup.py install for mozhttpd 20:32:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Running setup.py install for mozinfo 20:32:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Running setup.py install for mozInstall 20:32:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:32:24 INFO - Running setup.py install for mozleak 20:32:24 INFO - Running setup.py install for mozlog 20:32:25 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Running setup.py install for moznetwork 20:32:25 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Running setup.py install for mozprocess 20:32:25 INFO - Running setup.py install for mozprofile 20:32:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Running setup.py install for mozrunner 20:32:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:32:25 INFO - Running setup.py install for mozscreenshot 20:32:25 INFO - Running setup.py install for moztest 20:32:26 INFO - Running setup.py install for mozversion 20:32:26 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:32:26 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:32:26 INFO - Cleaning up... 20:32:26 INFO - Return code: 0 20:32:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:32:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:26 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 20:32:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:26 INFO - 'HOME': '/Users/cltbld', 20:32:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:26 INFO - 'LOGNAME': 'cltbld', 20:32:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:26 INFO - 'MOZ_NO_REMOTE': '1', 20:32:26 INFO - 'NO_EM_RESTART': '1', 20:32:26 INFO - 'PAGER': '/bin/cat', 20:32:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:26 INFO - 'PWD': '/builds/slave/test', 20:32:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:26 INFO - 'SHELL': '/bin/bash', 20:32:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:26 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:26 INFO - 'USER': 'cltbld', 20:32:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:26 INFO - 'XPC_FLAGS': '0x0', 20:32:26 INFO - 'XPC_SERVICE_NAME': '0', 20:32:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:32:26 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-WhghHL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 20:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:32:26 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-VflNqH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:32:26 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)) 20:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:32:26 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-obdJmt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:32:26 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-sBn2fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:32:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:32:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:32:26 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-iSwgjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-vwHOGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-P1PC4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-gRKmTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-qVIiLm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-rzl79N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:32:27 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)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-hyvNC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-J1O2ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-l8ulte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:32:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 20:32:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:32:27 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-DckabZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:32:28 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)) 20:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:28 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-sbvSZB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 20:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:32:28 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-vqrmU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:32:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:32:28 INFO - Running setup.py (path:/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/pip-ek1AZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 20:32:28 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)) 20:32:28 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)) 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:32:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:32:28 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:32:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:32:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:32:30 INFO - Downloading blessings-1.6.tar.gz 20:32:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:32:31 INFO - Installing collected packages: blessings 20:32:31 INFO - Running setup.py install for blessings 20:32:31 INFO - Successfully installed blessings 20:32:31 INFO - Cleaning up... 20:32:31 INFO - Return code: 0 20:32:31 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:32:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:32:31 INFO - Reading from file tmpfile_stdout 20:32:31 INFO - Current package versions: 20:32:31 INFO - blessings == 1.6 20:32:31 INFO - blobuploader == 1.2.4 20:32:31 INFO - docopt == 0.6.1 20:32:31 INFO - functools32 == 3.2.3-2 20:32:31 INFO - jsonschema == 2.5.1 20:32:31 INFO - manifestparser == 1.1 20:32:31 INFO - mozInstall == 1.12 20:32:31 INFO - mozcrash == 1.0 20:32:31 INFO - mozdebug == 0.1 20:32:31 INFO - mozdevice == 0.48 20:32:31 INFO - mozfile == 1.2 20:32:31 INFO - mozhttpd == 0.7 20:32:31 INFO - mozinfo == 0.9 20:32:31 INFO - mozleak == 0.1 20:32:31 INFO - mozlog == 3.4 20:32:31 INFO - moznetwork == 0.27 20:32:31 INFO - mozprocess == 0.23 20:32:31 INFO - mozprofile == 0.28 20:32:31 INFO - mozrunner == 6.13 20:32:31 INFO - mozscreenshot == 0.1 20:32:31 INFO - mozsystemmonitor == 0.3 20:32:31 INFO - moztest == 0.7 20:32:31 INFO - mozversion == 1.4 20:32:31 INFO - psutil == 3.1.1 20:32:31 INFO - requests == 1.2.3 20:32:31 INFO - wsgiref == 0.1.2 20:32:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:32:31 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')]} 20:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:31 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')]} 20:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:31 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 20:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 20:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:31 INFO - 'HOME': '/Users/cltbld', 20:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:31 INFO - 'LOGNAME': 'cltbld', 20:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:31 INFO - 'MOZ_NO_REMOTE': '1', 20:32:31 INFO - 'NO_EM_RESTART': '1', 20:32:31 INFO - 'PAGER': '/bin/cat', 20:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:31 INFO - 'PWD': '/builds/slave/test', 20:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:31 INFO - 'SHELL': '/bin/bash', 20:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:31 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:31 INFO - 'USER': 'cltbld', 20:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:31 INFO - 'XPC_FLAGS': '0x0', 20:32:31 INFO - 'XPC_SERVICE_NAME': '0', 20:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 20:32:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 20:32:31 INFO - Cleaning up... 20:32:31 INFO - Return code: 0 20:32:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:32:31 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')]} 20:32:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:32:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:31 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')]} 20:32:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:32:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:32:31 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 0x10b1f6490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b16c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe290c7bcc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, '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.awAEW5x7ul/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.MJa6yWtj66/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 20:32:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 20:32:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 20:32:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:32:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:32:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:32:31 INFO - 'HOME': '/Users/cltbld', 20:32:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:32:31 INFO - 'LOGNAME': 'cltbld', 20:32:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:32:31 INFO - 'MOZ_NO_REMOTE': '1', 20:32:31 INFO - 'NO_EM_RESTART': '1', 20:32:31 INFO - 'PAGER': '/bin/cat', 20:32:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:32:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:32:31 INFO - 'PWD': '/builds/slave/test', 20:32:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:32:31 INFO - 'SHELL': '/bin/bash', 20:32:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:32:31 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:32:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:32:31 INFO - 'USER': 'cltbld', 20:32:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:32:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:32:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:32:31 INFO - 'XPC_FLAGS': '0x0', 20:32:31 INFO - 'XPC_SERVICE_NAME': '0', 20:32:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:32:32 INFO - Cleaning up... 20:32:32 INFO - Return code: 0 20:32:32 INFO - Running post-action listener: _resource_record_post_action 20:32:32 INFO - Running post-action listener: _start_resource_monitoring 20:32:32 INFO - Starting resource monitoring. 20:32:32 INFO - [mozharness: 2016-12-27 04:32:32.211431Z] Finished create-virtualenv step (success) 20:32:32 INFO - [mozharness: 2016-12-27 04:32:32.212452Z] Running install step. 20:32:32 INFO - Running pre-action listener: _resource_record_pre_action 20:32:32 INFO - Running main action method: install 20:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:32:32 INFO - Reading from file tmpfile_stdout 20:32:32 INFO - Detecting whether we're running mozinstall >=1.0... 20:32:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:32:32 INFO - Reading from file tmpfile_stdout 20:32:32 INFO - Output received: 20:32:32 INFO - Usage: mozinstall [options] installer 20:32:32 INFO - Options: 20:32:32 INFO - -h, --help show this help message and exit 20:32:32 INFO - -d DEST, --destination=DEST 20:32:32 INFO - Directory to install application into. [default: 20:32:32 INFO - "/builds/slave/test"] 20:32:32 INFO - --app=APP Application being installed. [default: firefox] 20:32:32 INFO - mkdir: /builds/slave/test/build/application 20:32:32 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'] 20:32:32 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 20:33:03 INFO - Reading from file tmpfile_stdout 20:33:03 INFO - Output received: 20:33:03 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 20:33:03 INFO - Running post-action listener: _resource_record_post_action 20:33:03 INFO - [mozharness: 2016-12-27 04:33:03.330461Z] Finished install step (success) 20:33:03 INFO - [mozharness: 2016-12-27 04:33:03.330608Z] Running run-tests step. 20:33:03 INFO - Running pre-action listener: _resource_record_pre_action 20:33:03 INFO - Running main action method: run_tests 20:33:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:33:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:33:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:33:03 INFO - 2016-12-26 20:33:03.345 screenresolution[2297:24501] starting screenresolution argv=screenresolution get 20:33:03 INFO - 2016-12-26 20:33:03.348 screenresolution[2297:24501] Display 0: 1600x1200x32@60 20:33:03 INFO - 2016-12-26 20:33:03.354 screenresolution[2298:24503] starting screenresolution argv=screenresolution list 20:33:03 INFO - Available Modes on Display 0 20:33:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:33:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:33:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:33:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:33:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:33:03 INFO - Intel Iris: 20:33:03 INFO - Chipset Model: Intel Iris 20:33:03 INFO - Type: GPU 20:33:03 INFO - Bus: Built-In 20:33:03 INFO - VRAM (Dynamic, Max): 1536 MB 20:33:03 INFO - Vendor: Intel (0x8086) 20:33:03 INFO - Device ID: 0x0a2e 20:33:03 INFO - Revision ID: 0x0009 20:33:03 INFO - Displays: 20:33:03 INFO - Display: 20:33:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:33:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:33:03 INFO - Display Serial Number: 1600x1200 60 20:33:03 INFO - Main Display: Yes 20:33:03 INFO - Mirror: Off 20:33:03 INFO - Online: Yes 20:33:03 INFO - Rotation: Supported 20:33:03 INFO - Return code: 0 20:33:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:33:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:33:03 INFO - Python 2.7.3 20:33:03 INFO - Return code: 0 20:33:03 INFO - grabbing minidump binary from tooltool 20:33:03 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')]} 20:33:03 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 0x10b00ad30>, '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 0x10ae70db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b002b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:33:03 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:33:03 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:33:03 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:33:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:33:03 INFO - Return code: 0 20:33:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:33:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:33:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:33:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:33:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:33:03 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/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 20:33:03 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/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0140 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 20:33:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.awAEW5x7ul/Render', 20:33:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:33:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:33:03 INFO - 'HOME': '/Users/cltbld', 20:33:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:33:03 INFO - 'LOGNAME': 'cltbld', 20:33:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:33:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:33:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:33:03 INFO - 'MOZ_NO_REMOTE': '1', 20:33:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:33:03 INFO - 'NO_EM_RESTART': '1', 20:33:03 INFO - 'PAGER': '/bin/cat', 20:33:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:33:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:33:03 INFO - 'PWD': '/builds/slave/test', 20:33:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:33:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:33:03 INFO - 'SHELL': '/bin/bash', 20:33:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MJa6yWtj66/Listeners', 20:33:03 INFO - 'TMPDIR': '/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/', 20:33:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:33:03 INFO - 'USER': 'cltbld', 20:33:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:33:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:33:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:33:03 INFO - 'XPC_FLAGS': '0x0', 20:33:03 INFO - 'XPC_SERVICE_NAME': '0', 20:33:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:33:03 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/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0140', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 20:33:03 INFO - mozversion application_buildid: 20161226193237 20:33:03 INFO - mozversion application_changeset: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c 20:33:03 INFO - mozversion application_display_name: FirefoxDeveloperEdition 20:33:03 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:33:03 INFO - mozversion application_name: Firefox 20:33:03 INFO - mozversion application_remotingname: firefox-dev 20:33:03 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:33:03 INFO - mozversion application_vendor: Mozilla 20:33:03 INFO - mozversion application_version: 52.0a2 20:33:03 INFO - mozversion platform_buildid: 20161226193237 20:33:03 INFO - mozversion platform_changeset: 8d9fb8fa1f3db54852a586c145f2ed5afe00f27c 20:33:03 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 20:33:03 INFO - mozversion platform_version: 52.0a2 20:33:03 INFO - using testdate: 1482813183 20:33:03 INFO - actual date: 1482813183 20:33:03 INFO - starting webserver on 'localhost:53004' 20:33:03 INFO - SUITE-START | Running 2 tests 20:33:03 INFO - TEST-START | dromaeo_css 20:33:03 INFO - operating with platform_type : mac_ 20:33:03 INFO - Initialising browser for dromaeo_css test... 20:33:03 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpMLZdxg/profile http://localhost:53004/getInfo.html) 20:33:06 INFO - PROCESS | 2308 | __metrics Screen width/height:1600/1200 20:33:06 INFO - PROCESS | 2308 | colorDepth:24 20:33:06 INFO - PROCESS | 2308 | Browser inner width/height: 1024/689 20:33:06 INFO - PROCESS | 2308 | __metrics 20:33:06 INFO - PROCESS | 2308 | 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" 20:33:06 INFO - PROCESS | 2308 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:33:07 INFO - TEST-INFO | 2308: exit 0 20:33:07 INFO - Browser initialized. 20:33:07 INFO - Running cycle 1/1 for dromaeo_css test... 20:33:07 INFO - TEST-INFO | started process 2310 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmpMLZdxg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:33:19 INFO - PROCESS | 2310 | RSS: Main: 223318016 20:33:19 INFO - PROCESS | 2310 | 20:34:15 INFO - PROCESS | 2310 | 1482813255864 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 20:36:23 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53004/tests/dromaeo/cssquery-ext.html) 20:36:26 INFO - PROCESS | 2310 | RSS: Main: 414367744 20:36:26 INFO - PROCESS | 2310 | 20:39:29 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-ext.html (next: http://localhost:53004/tests/dromaeo/cssquery-jquery.html) 20:39:30 INFO - PROCESS | 2310 | RSS: Main: 348573696 20:39:30 INFO - PROCESS | 2310 | 20:42:32 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53004/tests/dromaeo/cssquery-mootools.html) 20:42:33 INFO - PROCESS | 2310 | RSS: Main: 312102912 20:42:33 INFO - PROCESS | 2310 | 20:45:35 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53004/tests/dromaeo/cssquery-prototype.html) 20:45:36 INFO - PROCESS | 2310 | RSS: Main: 330788864 20:45:36 INFO - PROCESS | 2310 | 20:48:39 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53004/tests/dromaeo/cssquery-yui.html) 20:48:40 INFO - PROCESS | 2310 | RSS: Main: 311844864 20:48:40 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | Cycle 1(1): loaded http://localhost:53004/tests/dromaeo/cssquery-yui.html (next: http://localhost:53004/tests/dromaeo/cssquery-dojo.html) 20:51:43 INFO - PROCESS | 2310 | RSS: Main: 458272768 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | __start_tp_report 20:51:43 INFO - PROCESS | 2310 | _x_x_mozilla_page_load 20:51:43 INFO - PROCESS | 2310 | _x_x_mozilla_page_load_details 20:51:43 INFO - PROCESS | 2310 | |i|pagename|runs| 20:51:43 INFO - PROCESS | 2310 | |0;dojo.html;3283.934321313574;3297.060337803725;3309.453940099882;3336.8998930032076;3373.915652108697;5657.377688454272;5737.5696822738255;5749.798757043504;5754.079347304427;5813.447722466364;5687.374388817238;5697.20239166516;5740;5777.82666520004;5789.2763404574425;5229.633925625208;5251.579873610102;5261.894762104746;5275.709835959021;5303;5111.2333150027425;5157.3811142662935;5159.948400515985;5336.359636843584;5342.011727830344;5243.764030618631;5250.6324557281005;5259.263703081571;5267.157254839227;5268.631195816294;4831.951680483186;5215.660982036194;5283.0490511707685;5284.180951952453;5295.7881684732565;5120.615953803443;5202.973985130088;5210;5270.183121616117;5278.47215278473;5868.354481007086;5887.470127688486;5896.439838215406;5920.467157955804;5942.049272116441;6085.847853803646;6116.974953843995;6119.8072250766345;6317.652529110897;6320.905186422207;5188.610854185913;6225.8132256032395;6296.779612713533;6364.109024736495;6376.458001069915;5834.970825145847;5847.912281315784;5851.444112809277;5856.502197313191;5884.823455296348;6259.812205633838;6362.6500542469685;6399.328070552525;6402.967985160138;6414.198225221848;6074.119252708421;6121.84695382619;6147.262328520606;6148.262208534915;6229;6314.873702525923;6326.905096423558;6326.9683651581445;6334;6351.745930162834;6182.814515564541;6269.153664255358;6290.968545157245;6295.464885484785;6317.936820631827;5993.220881285405;6102.786402475892;6155.199824022849;6164.969175154096;6218.875622487615;4900.558949694545;4954.405471343462;4960.652754307236;4975.751212439363;4976.2038073908;4401.911961760811;4403.119376124724;4415.668824838086;4427.269500532376;4456.532064133284;5586.24585680936;5678.687672177987;5713.857153571111;5730.390322982916;5757.625754325955;6179.073139029092;6184.226971628546;6270.467010304175;6272.090546870678;6274.309825919146;5766.538676905838;5775.826725198251;5783.652980821164;5783.768649254067;5809.912851307234;5436.320459942507;5448.128299472145;5448.42791506894;5450.454954504517;5451.127819548932;6268.373162683695;6268.811935641938;6290.21372328459;6368.331325210786;6383.170187875547;6234;6360.332165122595;6380.617162970237;6388.776392826415;6404.1674582304;5414.29614150158;6320.4311611955145;6381.585196962183;6386.744530218739;6409.455196308366;5884.146798714248;5956.970215148896;5975.760969561169;6236.968815155895;6257.217847769029;6191.226096737908;6202.4417802398;6257.186565746424;6298.433141017331;6339.175907132043;6146.631202127886;6175.011998080286;6176.969115154396;6183.505319574513;6185.938140618537;5913;6101.359357267601;6120.571559990758;6156.569040167145;6172.938270617236;5861.120831875252;5938.168656388023;5939.970300148471;5951.1073338999495;5952.196453478899;6079.969600151971;6082.330943596113;6091.878162436815;6096;6096.268447786294;5875.882482350415;5895.322037965689;5896.262967129109;5925.881482370242;5943.227380440515 20:51:43 INFO - PROCESS | 2310 | |1;ext.html;456.99461185392136;457.34827870284835;479.85554452630464;491.6386455954874;496.72928254101555;29150.833966641334;30683.539746903785;30714.539281910762;38456.23087538248;39370.803145984304;40106.39840402397;40182.19635607286;40207.798961005225;40307.79846100765;40642;9249.722508324761;9364.953175234114;9419;9445.76385590359;9465.57404916777;8427.620757065946;8649.48103113812;9358.970513243537;11124.276922000085;11314.321140731541;11236.21346505745;11238.662840114768;11243.494042768054;11253.606123785668;11300.095992320594;11200.327980321206;11264.774704505946;11279.210455268134;11285.266457680225;11291.548338066468;8346.374021948379;8554.101819308946;8564.229219370225;8654.350923680686;11125.276857004268;30624.08127756171;37604.81197593995;38182.618173818184;38197.61802381968;39560.40659390111;2736.904208352718;3143.2141964508874;3295;3644.817759112034;3725.254949010182;28108.578371324445;28670.426591468262;28702.569461458093;29073.12780616585;29745.851270743726;2621.357767346993;3020.5620185073262;3109.424756420047;3159.178613560468;3167.8891238806764;482646;487319.5634021843;488625;490457;494045;49995.50004499981;59548;60055.09917351243;60080.69959650174;60173.69913150494;51067.48932510702;61889;62983.79556715493;63124.684376577825;65583.34416655773;13478;14867.925660371846;15680;15694;15705.921470392575;11884.584039558575;11947.880521194851;11947.940260298818;11990.40047997614;12001.1599188056;16017.67964640724;16095.517134485985;16115.758263626056;18129.818701813077;18514.259429622663;6780.457563394916;7005.042151184564;7161.427085833121;7662;7712.730054448068;796.3151689546985;884.5533005832013;948.5921053946731;1012.3925644613321;1072.7747173093583;28126.71873281282;28368.85815570909;28677.574264585066;28822.567661485093;29147;1020.5866624017285;1030.294248439821;1042.2131290875493;1054.8312270036756;1120.6525976947173;14679.926600366929;14701.970862039769;14782.039167454297;14895.702085958437;14998.925005375122;676360;682385;682675;683323;684064;55096;55824.16263756047;55902.720486398124;56039;56082.71958640262;1218.214251807579;1263.0968857267098;1344.5226944434585;1370.4107233889524;1378.9241591712553;25509.23472295834;29095;29136.562951556167;29841.701582983893;30504.186976627923;53063.93872122613;54462.18306725482;54750;57162.42837571571;57359.13961290668;557198;563628;565032;565994;688169;38187.56311931939;38777;38849.6115038846;39020.2195956085;39282.803585981885;41897.58102418937;42923.78538107289;43262;43604.12791744084;44569;35250.823745881105;35572.82213588915;35983.64016359803;36128.91613251607;36140.91577252687;35742.821285893406;35834.10414739652;36029.63970360263;36816.81591592025;37421.625783741816;27753.44493110167;35547;35605.46591801177;35658.28683426369;36178.27643447064;30395.392092157592;32477.18807029843;32594.185145371557;32635.18412039718;33073.50389744204;24514.877425612758;24564.50870982606;24768.380790480383;24970.250892473254;25068.62397064082 20:51:43 INFO - PROCESS | 2310 | |2;jquery.html;345.21291455481503;346.50449856704904;374.24402706532805;380.695443645084;399.63034193371124;14832.925835370821;15128.546143615695;15690.215489225537;20081.196752129934;20607.793922060773;18992.955387453683;19438.514037149078;19892;20015.399538013884;20724;5524.917126243099;5977.282726072877;5979.551533634973;6009.669468179249;6154.876902461948;4802.83190088347;6657;6758.526903116784;6795.864082718343;6830.897536536956;5605.383407825157;5888.381719919411;6669.9666501667425;6735.292794256606;6757.087793147936;5939.346671866091;5973.193618861464;5975.52195824333;5988.880222395571;5992.550558708114;4966.304717339575;5748.367679555246;5788.363280039192;5831.241938548004;5839.9124013139835;13329.733405331936;13533.323333833268;13566.59300220995;22971;25469.872650636815;2208.0505382685437;2260.8530445520987;2315.22439982606;2320.791128798416;2565.910193143231;10500.842487362695;11339.262947908359;12251.816222756666;15557.377704891791;15586.968174869504;1573.9055656660523;1750.9124543772887;1841.4291569613463;1881.416760804155;1892.5174080609495;123033;126560;127180;135633.6436635641;137081;11214.327140371604;11343.659690209306;11398.316101033965;11588.6523404298;11885.346305953082;14732.410703571737;14872.107673539405;14876.92561537207;14878.851211487963;14923.104613723212;7155.642217889084;8116.83766324682;8150.2664760171865;8290.668373265122;8678.305735541142;8374.748757537283;8381.664733410595;8384.790380240544;8504.53225072627;8520.44617099899;4454.799534020977;4485.708428952108;4551.704139230939;4605.078984203173;4726.574608285202;1850.344755276183;1903.067496926496;1908.5705716214018;1909.5894382707506;1911.4456807525662;800.6757263308253;856.9048675987151;888.4314039015036;922.0088404964556;943.3962264150949;21145.471363216042;21609.783902160776;21662;21696.89151554232;21897.781022189574;717.2898830158106;907.7957459571544;934.5887809363858;941.4163218804248;953.3707752883064;10632.298832186967;11368.94315528417;11398.202125851276;11413.885861141449;11419.429028548539;527195;596646;603971;610413;621455;92331;100412;103789;132505;137638;847.8134810341714;926.1571969507851;1028.5011769291887;1028.922830787694;1029.130384824822;18969.90515047416;19632.411027669194;19759.9132047733;19822.50443738918;19824.10791514414;46843;50363.24455133102;55111.72444137914;55172.06897758559;57092.42907571037;63269.73460530856;77403;77507;79595;79740;7062.752803652053;7143.8571228576175;7176.7488137914925;7437.144728356308;7441.590712510864;11949.462274197507;12570.622881313575;12699.492020319083;12792.424340904869;13017.674558136123;10822;10987.725306867393;11237.775244495228;11371.658850234506;11475.540978360772;5158.922616160836;7746.883796743166;10720.785584288427;10858;11268.323900565993;10826.837597436202;11076.224664273746;11220.270682405617;11250.831237531607;11460.02589779878;7204.171520275236;7502.737404190827;7565.394768418623;7606.96196519036;7696.615169241628;10427.68716938493;10983.450827458755;11012.724681882697;11153.996140347082;11226.158038147008 20:51:43 INFO - PROCESS | 2310 | |3;mootools.html;1266.7149891274462;1438.654722866512;1536.5313579358292;1787.2761531579706;1787.4637608717383;7131.037309963153;7994.800129996745;8414.410991230616;8422.199891010352;8429.494230346183;6925.445964322855;7714.074311082664;7892.40806939479;8130.268275855172;8130.9186908130905;2298.7011688480475;2347.260612906929;2348.049040138738;2348.5420343033115;2348.765123487655;2327.453048533593;2366.976330236693;2377.41753270449;2377.797887179583;2386.9283921482297;2053.117159621362;2141.496748264157;2145.1633862793524;2225.0765932138224;2302.3208153594646;2268.8638681679067;2292.873891935943;2295.414669259345;2305.5388922215625;2307.14635584833;2282.1441959265276;2318.7217533895873;2321.3268152235833;2326.906923723049;2327.7090363704538;6997.160340759091;7687.000689910332;7786.377089832799;7817.413693972975;7863;5070.467600901889;5245.580353571705;5247.580193584504;5254.133068043769;5260.605454590921;3285.8685652573868;3364.1925937775004;3507.8947631571095;3580.946285805715;3588.0671025533547;1045.4145678420034;1075.763332066944;1092.3227598888661;1092.814221582333;1093.283899046126;10549.683509494727;10901.672949811438;10928.508217130247;10931.234813563055;11139.554417823358;4323.935140972888;4415.227335216325;4493.752843593568;4535.931961020521;4552.430946131733;8657.956710216535;8660.30717542595;8676.8698469523;8682.6092825823;8683.261922736638;5078.5937125029905;5080.060188865072;5097.668651537638;5125.769340379692;5134.845954621367;4529.547045295444;4773.832915847928;4825.9034819303415;4832.033593281358;4842.249451335048;3425.794452332868;3428.6914177724125;3477.547918770594;3512.613612502623;3516.0682419158943;1037.3412882819314;1038.3562191441354;1041.6458404142627;1050.8056009638242;1054.8945105489543;607.6718571971174;761.13611051457;765.7396485195061;808.4462143431768;878.6397576993402;5450.482204190595;5460.645058071213;5500.257465242221;5656.8868622627315;5886.205362276038;852.552409984763;862.9137086291446;889.6708217959267;931.5109567477128;992.6178421307849;3781.5084039074745;3809.2571948470336;3813.332666783357;3906.79642692989;3928.2339943710704;9911.80176396468;10171.288009839389;10272.075513203847;10398.532066057045;10444.529996150191;9873.950630246803;10047.799044019079;10085.092341689284;10108.29241953071;10304.793904121876;7474.626268686544;7751.9224807752325;7793.649285782153;7974.401919855918;7983;5450.700211488406;5465.207544905887;5628.240187574625;5703.458171473703;5952.375000625046;9351.778892668022;9453.621855125994;9547.522623868917;9560.569774360032;9569.3779904306;9541.427514349161;9656.613735450785;9682.322237443312;9784.80430391374;9881.901180988098;5988.820335389945;7317.341439270491;7321.816954576178;7330.413566914527;7332.19345871943;7695.115061767744;7801.570913599807;7807.765767026998;7808.5314881107315;7864.567448790142;7126.608036558039;7166.570005799668;7175.2465991070185;7200.855982880208;7221.458390620619;6063.757449701962;7011.509194356346;7060.646967651494;7138.785836424916;7202.279772022755;6590.670466476753;6617.569857959217;6731.831704207434;6745.93254067453;6750.122484077038;4297.785110744513;4731.810727570996;4829.1790395633525;4895.583875370491;4953.281774142802;3806.5051543299196;4926.605871530197;4949.356583644103;5029.170186919117;5052.090623687772 20:51:43 INFO - PROCESS | 2310 | |4;prototype.html;300.1775136127012;302.7245206861755;307.7291983054912;313.7427309606122;314.0327790405551;4548.9772551137285;4816.542428469299;4852.781624826883;5246.711430871301;5261.447548007462;4396.582324679151;4900.583450406711;5012.94728107097;5035.571976382012;5136.743162841853;3428.314337132571;3811.6950643948558;3925.0187453136714;3949.447077409165;4012.2978478766245;3322.9169270768184;3969.2458432897656;4007.5792041835625;4043.6360727534516;4057.3913912913154;3833.9616603834024;3941.467901833259;3951.427043078752;3952.0754641565177;3966.1472783351574;3977.880663580097;4000.83975647062;4018.6785057195502;4029.37544680575;4047.7571345719202;3106.829124398157;3297.686719761619;3471.8090505022215;3481.704055155315;3491.8603255869734;4368;4960.801567937314;4986.329579590842;4996.251312040793;5017.172166592508;3915.4322623219473;4147.921540399488;4362.018545827195;4473.507914129443;4559.153542815166;4698.483166851644;4832.1543729847135;5396.91904621431;5449.918251226235;5482.462712660242;3206.5350524174096;3810.7713537187856;3824.9235015299814;3843.7501562398634;3904.6138620789493;5007.824726134568;5615.578831587647;5666.546676265889;5710.428957104256;5741.626794258402;3454.240067185215;3710.9072273193387;3890.5720370759195;3898.7855667938534;3914.530256369254;3439.38606658941;3713.2759111973446;3891.1439483313625;3954.5397776945174;3963.7225394222705;3865.2849222893856;4039.1517781265675;4161.008148266547;4595.977020114945;4601.884952876205;4559.3844830948065;5058.91233384824;5201.817936372209;5221.921671174935;5503.697296648643;1128.8645362556547;1149.7010777197822;1250.2436026204198;1263.8925691316342;1275.1074248026234;709.7977076533164;845.5941148248875;847.4999750147442;880.9295256379603;896.6729240723765;4206.9482629342665;4252.957470425256;4569.29175977724;4901.632377571696;4932.894454239845;4401.40581021558;4495.550444955544;4741.881452963704;4868.736037742396;5248.553872920845;4019.5578486366476;4442.334124703237;4709.293605959134;4747.643926705442;4860.805567777249;2352.564775516535;2724.0601992312622;2727.6044973478733;2731.426400455887;2744.4922689302543;5177.5214439509555;5206.7503799087945;5310.707911064851;5430.809921652803;5529.751161197675;4563.54364563541;4734.857954261378;5112.795488180431;5859.560532960045;5930.080837470199;5485.259489968883;5665.5467562594895;5987.629030807535;6005.879882402328;6022.427869352405;4681.882952926067;5446.428125046813;5679.716014199356;5714.685692286964;5720.199172115824;5133.666311689728;5511.669299842022;5531.917021244765;5665.348484924121;5673.659580425187;5425.83344580917;5555.861103472445;5856.385079566584;5873.177755114373;5886.970565147318;3057.6330840299306;3281.7538684599226;3696.168362118502;3763.322601931569;3816.8091595419564;4619.353290539394;4845.916256009949;5416.2688037114485;5556.888862222814;5573.888522229614;4939.616907265828;5348.566402286872;5391.946080539301;5411.458854114557;5464.726763661881;4936.247632090556;5172.301739265151;5376.623636345418;5377.300950876517;5394.406615272238;4484.506704262463;5354.919676204782;5371.650842695212;5379.54273886724;5396.730163491889;5205.6616319939085;5337.653052551727;5356.1697936819855;5357.839264821906;5509.586780991362;4355.647950106907;4973.428055773632;4997.025580011863;5417.539509141609;5452.427495113115 20:51:43 INFO - PROCESS | 2310 | |5;yui.html;690.5925503952667;711.3491155592632;727.9162896266931;753.2542782645183;760.8059944714097;14259.3583288752;14660.926695366508;14680.265986700675;14695.118292902433;14745.778813317795;12904.74190516189;12910.225386476817;12930.806037909439;12978.74042519149;13076.934615326934;590.5600327755807;635.6186288227073;655.7475371981798;656.6125985668455;803.4416080823844;543.9918401223985;544.7140251368024;784.3293983643988;808.9716859909904;814.4339683919678;868.4051424774018;874.0647507167333;879.560219890055;882.0782282514756;882.3823323673422;868.695956415256;871.9825603487894;872.4459967920385;873.0701802580231;873.6942070275353;495.3362494257676;497.3906963969145;649.1560970738021;651.4950913042383;651.7555916531301;12981.286029268387;13473.326333683375;13526.256055916921;13817.861821381859;13845.653858653513;13248.337583120901;13641;13643.658908527266;13706.794398084037;13713.245771482565;11081.058110060654;11270.830937535944;11304.604338848183;11317.377544235063;11374.37440940748;3473.7221022318154;3560.287942411528;3566.4115420955513;3737.1404576947452;3748.981255093707;62315.68842155806;87912;89377.10622893753;89399;90299.54850225772;10012.749681257881;10270.003144795375;10354.53404596795;10451.790964180675;10511.52698128586;20781.58436831255;20940;21037.78962210389;21136.682949755766;21220.151193952068;6926.272741362185;7044.823879403056;7127.322904324081;7212.242714515005;7533.849323013509;4085.3667681509414;4191.91616167675;4218.346156345771;4258.42511260982;4293.441852559147;3375.8987230383127;3488.302339532104;3500.1599616091953;3503.3168532136497;3509.192885636318;638.7796210307437;642.5405834828027;645.2353960556756;646.0503060501056;660.9966950165315;548.6022633590694;579.6435192356755;630.5901164243279;635.7838334966134;710.0627172132838;8689.652413903374;8839.425437346552;8960.775980600538;9112.453252804853;9180.265578753684;567.5005994724615;631.5389765471232;644.2591020326662;660.3627499463041;669.2003056347716;4188.099558594917;4380.211561918885;4400.317950717644;4412.757298348622;4515.819367225275;78345;81965.18034819576;82682;98064.50967745259;114182.28726569108;87254.12745872587;88451.11548884558;88568.11431885598;93431;94128.52935735277;58132;58616.70691646515;72581.27418725957;73877.93520261547;79233.60383198046;9202.309826762885;9476;9543.33196676226;9564.187044101329;9593.808123837345;43743.68768936937;43800;51290;51862;52706.472935270154;68771.96842047473;70444;73228.26771732215;73664;74121.2587874136;11747.353895535827;12083.818742719042;12105.092118091354;12131.514739410677;12756.681082973;13504.324783760969;14517.201553914636;14544.345504452536;14585.416583336972;14764.274762190964;12215.08386871006;12826.038047146314;12945.611631651065;13525.729485410433;13809.585712428643;12587.370631468573;12948.676283092998;13092.083554151119;13318.733625327246;13463;10658.467076645982;12634.178778379375;13248;13373.331333433094;13642.386092625657;9971.252156088178;10679.54370494806;10812.756532998492;11144.108471322113;11674.182807203753;10842.566297348018;11198.16013798952;11241.988221060144;11367.829482557934;11586.536538538701 20:51:43 INFO - PROCESS | 2310 | __end_tp_report 20:51:43 INFO - PROCESS | 2310 | __start_cc_report 20:51:43 INFO - PROCESS | 2310 | _x_x_mozilla_cycle_collect,2459 20:51:43 INFO - PROCESS | 2310 | __end_cc_report 20:51:43 INFO - PROCESS | 2310 | __startTimestamp1482814303509__endTimestamp 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | ------- Summary: start ------- 20:51:43 INFO - PROCESS | 2310 | Number of tests: 6 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#0] dojo.html Cycles:165 Average:5761.30 Median:5925.88 stddev:648.84 (10.9%) stddev-sans-first:621.04 20:51:43 INFO - PROCESS | 2310 | Values: 3283.9 3297.1 3309.5 3336.9 3373.9 5657.4 5737.6 5749.8 5754.1 5813.4 5687.4 5697.2 5740.0 5777.8 5789.3 5229.6 5251.6 5261.9 5275.7 5303.0 5111.2 5157.4 5159.9 5336.4 5342.0 5243.8 5250.6 5259.3 5267.2 5268.6 4832.0 5215.7 5283.0 5284.2 5295.8 5120.6 5203.0 5210.0 5270.2 5278.5 5868.4 5887.5 5896.4 5920.5 5942.0 6085.8 6117.0 6119.8 6317.7 6320.9 5188.6 6225.8 6296.8 6364.1 6376.5 5835.0 5847.9 5851.4 5856.5 5884.8 6259.8 6362.7 6399.3 6403.0 6414.2 6074.1 6121.8 6147.3 6148.3 6229.0 6314.9 6326.9 6327.0 6334.0 6351.7 6182.8 6269.2 6291.0 6295.5 6317.9 5993.2 6102.8 6155.2 6165.0 6218.9 4900.6 4954.4 4960.7 4975.8 4976.2 4401.9 4403.1 4415.7 4427.3 4456.5 5586.2 5678.7 5713.9 5730.4 5757.6 6179.1 6184.2 6270.5 6272.1 6274.3 5766.5 5775.8 5783.7 5783.8 5809.9 5436.3 5448.1 5448.4 5450.5 5451.1 6268.4 6268.8 6290.2 6368.3 6383.2 6234.0 6360.3 6380.6 6388.8 6404.2 5414.3 6320.4 6381.6 6386.7 6409.5 5884.1 5957.0 5975.8 6237.0 6257.2 6191.2 6202.4 6257.2 6298.4 6339.2 6146.6 6175.0 6177.0 6183.5 6185.9 5913.0 6101.4 6120.6 6156.6 6172.9 5861.1 5938.2 5940.0 5951.1 5952.2 6080.0 6082.3 6091.9 6096.0 6096.3 5875.9 5895.3 5896.3 5925.9 5943.2 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#1] ext.html Cycles:180 Average:71052.55 Median:36006.64 stddev:158582.05 (440.4%) stddev-sans-first:158938.34 20:51:43 INFO - PROCESS | 2310 | Values: 457.0 457.3 479.9 491.6 496.7 29150.8 30683.5 30714.5 38456.2 39370.8 40106.4 40182.2 40207.8 40307.8 40642.0 9249.7 9365.0 9419.0 9445.8 9465.6 8427.6 8649.5 9359.0 11124.3 11314.3 11236.2 11238.7 11243.5 11253.6 11300.1 11200.3 11264.8 11279.2 11285.3 11291.5 8346.4 8554.1 8564.2 8654.4 11125.3 30624.1 37604.8 38182.6 38197.6 39560.4 2736.9 3143.2 3295.0 3644.8 3725.3 28108.6 28670.4 28702.6 29073.1 29745.9 2621.4 3020.6 3109.4 3159.2 3167.9 482646.0 487319.6 488625.0 490457.0 494045.0 49995.5 59548.0 60055.1 60080.7 60173.7 51067.5 61889.0 62983.8 63124.7 65583.3 13478.0 14867.9 15680.0 15694.0 15705.9 11884.6 11947.9 11947.9 11990.4 12001.2 16017.7 16095.5 16115.8 18129.8 18514.3 6780.5 7005.0 7161.4 7662.0 7712.7 796.3 884.6 948.6 1012.4 1072.8 28126.7 28368.9 28677.6 28822.6 29147.0 1020.6 1030.3 1042.2 1054.8 1120.7 14679.9 14702.0 14782.0 14895.7 14998.9 676360.0 682385.0 682675.0 683323.0 684064.0 55096.0 55824.2 55902.7 56039.0 56082.7 1218.2 1263.1 1344.5 1370.4 1378.9 25509.2 29095.0 29136.6 29841.7 30504.2 53063.9 54462.2 54750.0 57162.4 57359.1 557198.0 563628.0 565032.0 565994.0 688169.0 38187.6 38777.0 38849.6 39020.2 39282.8 41897.6 42923.8 43262.0 43604.1 44569.0 35250.8 35572.8 35983.6 36128.9 36140.9 35742.8 35834.1 36029.6 36816.8 37421.6 27753.4 35547.0 35605.5 35658.3 36178.3 30395.4 32477.2 32594.2 32635.2 33073.5 24514.9 24564.5 24768.4 24970.3 25068.6 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#2] jquery.html Cycles:180 Average:34460.06 Median:12643.11 stddev:99019.88 (783.2%) stddev-sans-first:99264.52 20:51:43 INFO - PROCESS | 2310 | Values: 345.2 346.5 374.2 380.7 399.6 14832.9 15128.5 15690.2 20081.2 20607.8 18993.0 19438.5 19892.0 20015.4 20724.0 5524.9 5977.3 5979.6 6009.7 6154.9 4802.8 6657.0 6758.5 6795.9 6830.9 5605.4 5888.4 6670.0 6735.3 6757.1 5939.3 5973.2 5975.5 5988.9 5992.6 4966.3 5748.4 5788.4 5831.2 5839.9 13329.7 13533.3 13566.6 22971.0 25469.9 2208.1 2260.9 2315.2 2320.8 2565.9 10500.8 11339.3 12251.8 15557.4 15587.0 1573.9 1750.9 1841.4 1881.4 1892.5 123033.0 126560.0 127180.0 135633.6 137081.0 11214.3 11343.7 11398.3 11588.7 11885.3 14732.4 14872.1 14876.9 14878.9 14923.1 7155.6 8116.8 8150.3 8290.7 8678.3 8374.7 8381.7 8384.8 8504.5 8520.4 4454.8 4485.7 4551.7 4605.1 4726.6 1850.3 1903.1 1908.6 1909.6 1911.4 800.7 856.9 888.4 922.0 943.4 21145.5 21609.8 21662.0 21696.9 21897.8 717.3 907.8 934.6 941.4 953.4 10632.3 11368.9 11398.2 11413.9 11419.4 527195.0 596646.0 603971.0 610413.0 621455.0 92331.0 100412.0 103789.0 132505.0 137638.0 847.8 926.2 1028.5 1028.9 1029.1 18969.9 19632.4 19759.9 19822.5 19824.1 46843.0 50363.2 55111.7 55172.1 57092.4 63269.7 77403.0 77507.0 79595.0 79740.0 7062.8 7143.9 7176.7 7437.1 7441.6 11949.5 12570.6 12699.5 12792.4 13017.7 10822.0 10987.7 11237.8 11371.7 11475.5 5158.9 7746.9 10720.8 10858.0 11268.3 10826.8 11076.2 11220.3 11250.8 11460.0 7204.2 7502.7 7565.4 7607.0 7696.6 10427.7 10983.5 11012.7 11154.0 11226.2 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#3] mootools.html Cycles:180 Average:5385.28 Median:5088.86 stddev:2973.69 (58.4%) stddev-sans-first:2965.92 20:51:43 INFO - PROCESS | 2310 | Values: 1266.7 1438.7 1536.5 1787.3 1787.5 7131.0 7994.8 8414.4 8422.2 8429.5 6925.4 7714.1 7892.4 8130.3 8130.9 2298.7 2347.3 2348.0 2348.5 2348.8 2327.5 2367.0 2377.4 2377.8 2386.9 2053.1 2141.5 2145.2 2225.1 2302.3 2268.9 2292.9 2295.4 2305.5 2307.1 2282.1 2318.7 2321.3 2326.9 2327.7 6997.2 7687.0 7786.4 7817.4 7863.0 5070.5 5245.6 5247.6 5254.1 5260.6 3285.9 3364.2 3507.9 3580.9 3588.1 1045.4 1075.8 1092.3 1092.8 1093.3 10549.7 10901.7 10928.5 10931.2 11139.6 4323.9 4415.2 4493.8 4535.9 4552.4 8658.0 8660.3 8676.9 8682.6 8683.3 5078.6 5080.1 5097.7 5125.8 5134.8 4529.5 4773.8 4825.9 4832.0 4842.2 3425.8 3428.7 3477.5 3512.6 3516.1 1037.3 1038.4 1041.6 1050.8 1054.9 607.7 761.1 765.7 808.4 878.6 5450.5 5460.6 5500.3 5656.9 5886.2 852.6 862.9 889.7 931.5 992.6 3781.5 3809.3 3813.3 3906.8 3928.2 9911.8 10171.3 10272.1 10398.5 10444.5 9874.0 10047.8 10085.1 10108.3 10304.8 7474.6 7751.9 7793.6 7974.4 7983.0 5450.7 5465.2 5628.2 5703.5 5952.4 9351.8 9453.6 9547.5 9560.6 9569.4 9541.4 9656.6 9682.3 9784.8 9881.9 5988.8 7317.3 7321.8 7330.4 7332.2 7695.1 7801.6 7807.8 7808.5 7864.6 7126.6 7166.6 7175.2 7200.9 7221.5 6063.8 7011.5 7060.6 7138.8 7202.3 6590.7 6617.6 6731.8 6745.9 6750.1 4297.8 4731.8 4829.2 4895.6 4953.3 3806.5 4926.6 4949.4 5029.2 5052.1 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#4] prototype.html Cycles:180 Average:4339.80 Median:4782.09 stddev:1339.27 (28.0%) stddev-sans-first:1308.25 20:51:43 INFO - PROCESS | 2310 | Values: 300.2 302.7 307.7 313.7 314.0 4549.0 4816.5 4852.8 5246.7 5261.4 4396.6 4900.6 5012.9 5035.6 5136.7 3428.3 3811.7 3925.0 3949.4 4012.3 3322.9 3969.2 4007.6 4043.6 4057.4 3834.0 3941.5 3951.4 3952.1 3966.1 3977.9 4000.8 4018.7 4029.4 4047.8 3106.8 3297.7 3471.8 3481.7 3491.9 4368.0 4960.8 4986.3 4996.3 5017.2 3915.4 4147.9 4362.0 4473.5 4559.2 4698.5 4832.2 5396.9 5449.9 5482.5 3206.5 3810.8 3824.9 3843.8 3904.6 5007.8 5615.6 5666.5 5710.4 5741.6 3454.2 3710.9 3890.6 3898.8 3914.5 3439.4 3713.3 3891.1 3954.5 3963.7 3865.3 4039.2 4161.0 4596.0 4601.9 4559.4 5058.9 5201.8 5221.9 5503.7 1128.9 1149.7 1250.2 1263.9 1275.1 709.8 845.6 847.5 880.9 896.7 4206.9 4253.0 4569.3 4901.6 4932.9 4401.4 4495.6 4741.9 4868.7 5248.6 4019.6 4442.3 4709.3 4747.6 4860.8 2352.6 2724.1 2727.6 2731.4 2744.5 5177.5 5206.8 5310.7 5430.8 5529.8 4563.5 4734.9 5112.8 5859.6 5930.1 5485.3 5665.5 5987.6 6005.9 6022.4 4681.9 5446.4 5679.7 5714.7 5720.2 5133.7 5511.7 5531.9 5665.3 5673.7 5425.8 5555.9 5856.4 5873.2 5887.0 3057.6 3281.8 3696.2 3763.3 3816.8 4619.4 4845.9 5416.3 5556.9 5573.9 4939.6 5348.6 5391.9 5411.5 5464.7 4936.2 5172.3 5376.6 5377.3 5394.4 4484.5 5354.9 5371.7 5379.5 5396.7 5205.7 5337.7 5356.2 5357.8 5509.6 4355.6 4973.4 4997.0 5417.5 5452.4 20:51:43 INFO - PROCESS | 2310 | 20:51:43 INFO - PROCESS | 2310 | [#5] yui.html Cycles:180 Average:19081.12 Median:4464.29 stddev:26870.38 (601.9%) stddev-sans-first:26910.28 20:51:43 INFO - PROCESS | 2310 | Values: 690.6 711.3 727.9 753.3 760.8 14259.4 14660.9 14680.3 14695.1 14745.8 12904.7 12910.2 12930.8 12978.7 13076.9 590.6 635.6 655.7 656.6 803.4 544.0 544.7 784.3 809.0 814.4 868.4 874.1 879.6 882.1 882.4 868.7 872.0 872.4 873.1 873.7 495.3 497.4 649.2 651.5 651.8 12981.3 13473.3 13526.3 13817.9 13845.7 13248.3 13641.0 13643.7 13706.8 13713.2 11081.1 11270.8 11304.6 11317.4 11374.4 3473.7 3560.3 3566.4 3737.1 3749.0 62315.7 87912.0 89377.1 89399.0 90299.5 10012.7 10270.0 10354.5 10451.8 10511.5 20781.6 20940.0 21037.8 21136.7 21220.2 6926.3 7044.8 7127.3 7212.2 7533.8 4085.4 4191.9 4218.3 4258.4 4293.4 3375.9 3488.3 3500.2 3503.3 3509.2 638.8 642.5 645.2 646.1 661.0 548.6 579.6 630.6 635.8 710.1 8689.7 8839.4 8960.8 9112.5 9180.3 567.5 631.5 644.3 660.4 669.2 4188.1 4380.2 4400.3 4412.8 4515.8 78345.0 81965.2 82682.0 98064.5 114182.3 87254.1 88451.1 88568.1 93431.0 94128.5 58132.0 58616.7 72581.3 73877.9 79233.6 9202.3 9476.0 9543.3 9564.2 9593.8 43743.7 43800.0 51290.0 51862.0 52706.5 68772.0 70444.0 73228.3 73664.0 74121.3 11747.4 12083.8 12105.1 12131.5 12756.7 13504.3 14517.2 14544.3 14585.4 14764.3 12215.1 12826.0 12945.6 13525.7 13809.6 12587.4 12948.7 13092.1 13318.7 13463.0 10658.5 12634.2 13248.0 13373.3 13642.4 9971.3 10679.5 10812.8 11144.1 11674.2 10842.6 11198.2 11242.0 11367.8 11586.5 20:51:43 INFO - PROCESS | 2310 | -------- Summary: end -------- 20:51:43 INFO - PROCESS | 2310 | 20:51:44 INFO - TEST-INFO | 2310: exit 0 20:51:44 INFO - TEST-OK | dromaeo_css | took 1120230ms 20:51:44 INFO - TEST-START | kraken 20:51:44 INFO - operating with platform_type : mac_ 20:51:44 INFO - Initialising browser for kraken test... 20:51:44 INFO - TEST-INFO | started process 2313 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp5GgG1p/profile http://localhost:53004/getInfo.html) 20:51:46 INFO - PROCESS | 2313 | __metrics Screen width/height:1600/1200 20:51:46 INFO - PROCESS | 2313 | colorDepth:24 20:51:46 INFO - PROCESS | 2313 | Browser inner width/height: 1024/689 20:51:46 INFO - PROCESS | 2313 | __metrics 20:51:46 INFO - PROCESS | 2313 | 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" 20:51:46 INFO - PROCESS | 2313 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:51:47 INFO - TEST-INFO | 2313: exit 0 20:51:47 INFO - Browser initialized. 20:51:47 INFO - Running cycle 1/1 for kraken test... 20:51:47 INFO - TEST-INFO | started process 2314 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/T/tmp5GgG1p/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:51:59 INFO - PROCESS | 2314 | RSS: Main: 222826496 20:51:59 INFO - PROCESS | 2314 | 20:52:02 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53004/tests/kraken/driver.html?testName=audio-beat-detection) 20:52:03 INFO - PROCESS | 2314 | RSS: Main: 273555456 20:52:03 INFO - PROCESS | 2314 | 20:52:06 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53004/tests/kraken/driver.html?testName=audio-dft) 20:52:06 INFO - PROCESS | 2314 | RSS: Main: 284995584 20:52:06 INFO - PROCESS | 2314 | 20:52:09 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53004/tests/kraken/driver.html?testName=audio-fft) 20:52:10 INFO - PROCESS | 2314 | RSS: Main: 382676992 20:52:10 INFO - PROCESS | 2314 | 20:52:12 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53004/tests/kraken/driver.html?testName=audio-oscillator) 20:52:13 INFO - PROCESS | 2314 | RSS: Main: 282664960 20:52:13 INFO - PROCESS | 2314 | 20:52:15 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53004/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:52:15 INFO - PROCESS | 2314 | RSS: Main: 283594752 20:52:15 INFO - PROCESS | 2314 | 20:52:18 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53004/tests/kraken/driver.html?testName=imaging-darkroom) 20:52:19 INFO - PROCESS | 2314 | RSS: Main: 326537216 20:52:19 INFO - PROCESS | 2314 | 20:52:22 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53004/tests/kraken/driver.html?testName=imaging-desaturate) 20:52:22 INFO - PROCESS | 2314 | RSS: Main: 325701632 20:52:22 INFO - PROCESS | 2314 | 20:52:25 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53004/tests/kraken/driver.html?testName=json-parse-financial) 20:52:26 INFO - PROCESS | 2314 | RSS: Main: 324870144 20:52:26 INFO - PROCESS | 2314 | 20:52:27 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53004/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:52:28 INFO - PROCESS | 2314 | RSS: Main: 299409408 20:52:28 INFO - PROCESS | 2314 | 20:52:30 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:52:30 INFO - PROCESS | 2314 | RSS: Main: 301535232 20:52:30 INFO - PROCESS | 2314 | 20:52:32 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:52:33 INFO - PROCESS | 2314 | RSS: Main: 303644672 20:52:33 INFO - PROCESS | 2314 | 20:52:36 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:52:37 INFO - PROCESS | 2314 | RSS: Main: 320323584 20:52:37 INFO - PROCESS | 2314 | 20:52:40 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:52:40 INFO - PROCESS | 2314 | RSS: Main: 313217024 20:52:40 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | Cycle 1(1): loaded http://localhost:53004/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53004/tests/kraken/driver.html?testName=ai-astar) 20:52:42 INFO - PROCESS | 2314 | RSS: Main: 357433344 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | __start_tp_report 20:52:42 INFO - PROCESS | 2314 | _x_x_mozilla_page_load 20:52:42 INFO - PROCESS | 2314 | _x_x_mozilla_page_load_details 20:52:42 INFO - PROCESS | 2314 | |i|pagename|runs| 20:52:42 INFO - PROCESS | 2314 | |0;ai-astar;116;115;114;114;115;117;115;115;115;115 20:52:42 INFO - PROCESS | 2314 | |1;audio-beat-detection;146;110;110;147;109;109;112;109;140;111 20:52:42 INFO - PROCESS | 2314 | |2;audio-dft;182;186;183;184;185;186;186;183;186;187 20:52:42 INFO - PROCESS | 2314 | |3;audio-fft;82;58;79;116;89;83;60;55;82;59 20:52:42 INFO - PROCESS | 2314 | |4;audio-oscillator;84;72;127;102;70;71;90;69;70;93 20:52:42 INFO - PROCESS | 2314 | |5;imaging-gaussian-blur;88;86;89;85;86;84;88;87;135;84 20:52:42 INFO - PROCESS | 2314 | |6;imaging-darkroom;94;94;94;95;94;94;94;94;94;94 20:52:42 INFO - PROCESS | 2314 | |7;imaging-desaturate;85;84;79;82;82;84;87;83;80;84 20:52:42 INFO - PROCESS | 2314 | |8;json-parse-financial;56;55;56;57;59;60;59;59;58;58 20:52:42 INFO - PROCESS | 2314 | |9;json-stringify-tinderbox;64;64;77;77;72;69;70;69;70;72 20:52:42 INFO - PROCESS | 2314 | |10;stanford-crypto-aes;85;84;82;85;84;82;83;82;84;83 20:52:42 INFO - PROCESS | 2314 | |11;stanford-crypto-ccm;140;143;157;147;150;145;144;143;190;139 20:52:42 INFO - PROCESS | 2314 | |12;stanford-crypto-pbkdf2;173;166;164;163;167;165;165;192;163;172 20:52:42 INFO - PROCESS | 2314 | |13;stanford-crypto-sha256-iterative;69;67;66;71;84;87;85;86;75;67 20:52:42 INFO - PROCESS | 2314 | __end_tp_report 20:52:42 INFO - PROCESS | 2314 | __start_cc_report 20:52:42 INFO - PROCESS | 2314 | _x_x_mozilla_cycle_collect,2034 20:52:42 INFO - PROCESS | 2314 | __end_cc_report 20:52:42 INFO - PROCESS | 2314 | __startTimestamp1482814362919__endTimestamp 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | ------- Summary: start ------- 20:52:42 INFO - PROCESS | 2314 | Number of tests: 14 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#0] ai-astar Cycles:10 Average:115.10 Median:115.00 stddev:0.88 (0.8%) stddev-sans-first:0.87 20:52:42 INFO - PROCESS | 2314 | Values: 116.0 115.0 114.0 114.0 115.0 117.0 115.0 115.0 115.0 115.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#1] audio-beat-detection Cycles:10 Average:120.30 Median:111.50 stddev:16.71 (15.0%) stddev-sans-first:14.91 20:52:42 INFO - PROCESS | 2314 | Values: 146.0 110.0 110.0 147.0 109.0 109.0 112.0 109.0 140.0 111.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#2] audio-dft Cycles:10 Average:184.80 Median:186.00 stddev:1.69 (0.9%) stddev-sans-first:1.45 20:52:42 INFO - PROCESS | 2314 | Values: 182.0 186.0 183.0 184.0 185.0 186.0 186.0 183.0 186.0 187.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#3] audio-fft Cycles:10 Average:76.30 Median:80.50 stddev:18.88 (23.5%) stddev-sans-first:19.91 20:52:42 INFO - PROCESS | 2314 | Values: 82.0 58.0 79.0 116.0 89.0 83.0 60.0 55.0 82.0 59.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#4] audio-oscillator Cycles:10 Average:84.80 Median:71.50 stddev:18.90 (26.4%) stddev-sans-first:20.04 20:52:42 INFO - PROCESS | 2314 | Values: 84.0 72.0 127.0 102.0 70.0 71.0 90.0 69.0 70.0 93.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:86.50 stddev:15.48 (17.9%) stddev-sans-first:16.38 20:52:42 INFO - PROCESS | 2314 | Values: 88.0 86.0 89.0 85.0 86.0 84.0 88.0 87.0 135.0 84.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 20:52:42 INFO - PROCESS | 2314 | Values: 94.0 94.0 94.0 95.0 94.0 94.0 94.0 94.0 94.0 94.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#7] imaging-desaturate Cycles:10 Average:83.00 Median:84.00 stddev:2.36 (2.8%) stddev-sans-first:2.39 20:52:42 INFO - PROCESS | 2314 | Values: 85.0 84.0 79.0 82.0 82.0 84.0 87.0 83.0 80.0 84.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:58.50 stddev:1.64 (2.8%) stddev-sans-first:1.62 20:52:42 INFO - PROCESS | 2314 | Values: 56.0 55.0 56.0 57.0 59.0 60.0 59.0 59.0 58.0 58.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:4.45 (6.3%) stddev-sans-first:4.08 20:52:42 INFO - PROCESS | 2314 | Values: 64.0 64.0 77.0 77.0 72.0 69.0 70.0 69.0 70.0 72.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:1.17 (1.4%) stddev-sans-first:1.09 20:52:42 INFO - PROCESS | 2314 | Values: 85.0 84.0 82.0 85.0 84.0 82.0 83.0 82.0 84.0 83.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:146.00 stddev:15.05 (10.3%) stddev-sans-first:15.54 20:52:42 INFO - PROCESS | 2314 | Values: 140.0 143.0 157.0 147.0 150.0 145.0 144.0 143.0 190.0 139.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.00 Median:166.50 stddev:8.79 (5.3%) stddev-sans-first:9.21 20:52:42 INFO - PROCESS | 2314 | Values: 173.0 166.0 164.0 163.0 167.0 165.0 165.0 192.0 163.0 172.0 20:52:42 INFO - PROCESS | 2314 | 20:52:42 INFO - PROCESS | 2314 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:79.50 stddev:8.83 (11.1%) stddev-sans-first:9.03 20:52:42 INFO - PROCESS | 2314 | Values: 69.0 67.0 66.0 71.0 84.0 87.0 85.0 86.0 75.0 67.0 20:52:42 INFO - PROCESS | 2314 | -------- Summary: end -------- 20:52:42 INFO - PROCESS | 2314 | 20:52:43 INFO - TEST-INFO | 2314: exit 0 20:52:43 INFO - TEST-OK | kraken | took 59820ms 20:52:43 INFO - SUITE-END | took 1180s 20:52:44 INFO - Completed test suite (00:19:40) 20:52:44 INFO - javascript benchmark 20:52:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3283.934321313574, 3297.060337803725, 3309.453940099882, 3336.8998930032076, 3373.915652108697, 5657.377688454272, 5737.5696822738255, 5749.798757043504, 5754.079347304427, 5813.447722466364, 5687.374388817238, 5697.20239166516, 5740.0, 5777.82666520004, 5789.2763404574425, 5229.633925625208, 5251.579873610102, 5261.894762104746, 5275.709835959021, 5303.0, 5111.2333150027425, 5157.3811142662935, 5159.948400515985, 5336.359636843584, 5342.011727830344, 5243.764030618631, 5250.6324557281005, 5259.263703081571, 5267.157254839227, 5268.631195816294, 4831.951680483186, 5215.660982036194, 5283.0490511707685, 5284.180951952453, 5295.7881684732565, 5120.615953803443, 5202.973985130088, 5210.0, 5270.183121616117, 5278.47215278473, 5868.354481007086, 5887.470127688486, 5896.439838215406, 5920.467157955804, 5942.049272116441, 6085.847853803646, 6116.974953843995, 6119.8072250766345, 6317.652529110897, 6320.905186422207, 5188.610854185913, 6225.8132256032395, 6296.779612713533, 6364.109024736495, 6376.458001069915, 5834.970825145847, 5847.912281315784, 5851.444112809277, 5856.502197313191, 5884.823455296348, 6259.812205633838, 6362.6500542469685, 6399.328070552525, 6402.967985160138, 6414.198225221848, 6074.119252708421, 6121.84695382619, 6147.262328520606, 6148.262208534915, 6229.0, 6314.873702525923, 6326.905096423558, 6326.9683651581445, 6334.0, 6351.745930162834, 6182.814515564541, 6269.153664255358, 6290.968545157245, 6295.464885484785, 6317.936820631827, 5993.220881285405, 6102.786402475892, 6155.199824022849, 6164.969175154096, 6218.875622487615, 4900.558949694545, 4954.405471343462, 4960.652754307236, 4975.751212439363, 4976.2038073908, 4401.911961760811, 4403.119376124724, 4415.668824838086, 4427.269500532376, 4456.532064133284, 5586.24585680936, 5678.687672177987, 5713.857153571111, 5730.390322982916, 5757.625754325955, 6179.073139029092, 6184.226971628546, 6270.467010304175, 6272.090546870678, 6274.309825919146, 5766.538676905838, 5775.826725198251, 5783.652980821164, 5783.768649254067, 5809.912851307234, 5436.320459942507, 5448.128299472145, 5448.42791506894, 5450.454954504517, 5451.127819548932, 6268.373162683695, 6268.811935641938, 6290.21372328459, 6368.331325210786, 6383.170187875547, 6234.0, 6360.332165122595, 6380.617162970237, 6388.776392826415, 6404.1674582304, 5414.29614150158, 6320.4311611955145, 6381.585196962183, 6386.744530218739, 6409.455196308366, 5884.146798714248, 5956.970215148896, 5975.760969561169, 6236.968815155895, 6257.217847769029, 6191.226096737908, 6202.4417802398, 6257.186565746424, 6298.433141017331, 6339.175907132043, 6146.631202127886, 6175.011998080286, 6176.969115154396, 6183.505319574513, 6185.938140618537, 5913.0, 6101.359357267601, 6120.571559990758, 6156.569040167145, 6172.938270617236, 5861.120831875252, 5938.168656388023, 5939.970300148471, 5951.1073338999495, 5952.196453478899, 6079.969600151971, 6082.330943596113, 6091.878162436815, 6096.0, 6096.268447786294, 5875.882482350415, 5895.322037965689, 5896.262967129109, 5925.881482370242, 5943.227380440515], "value": 5718.978958572483, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.99461185392136, 457.34827870284835, 479.85554452630464, 491.6386455954874, 496.72928254101555, 29150.833966641334, 30683.539746903785, 30714.539281910762, 38456.23087538248, 39370.803145984304, 40106.39840402397, 40182.19635607286, 40207.798961005225, 40307.79846100765, 40642.0, 9249.722508324761, 9364.953175234114, 9419.0, 9445.76385590359, 9465.57404916777, 8427.620757065946, 8649.48103113812, 9358.970513243537, 11124.276922000085, 11314.321140731541, 11236.21346505745, 11238.662840114768, 11243.494042768054, 11253.606123785668, 11300.095992320594, 11200.327980321206, 11264.774704505946, 11279.210455268134, 11285.266457680225, 11291.548338066468, 8346.374021948379, 8554.101819308946, 8564.229219370225, 8654.350923680686, 11125.276857004268, 30624.08127756171, 37604.81197593995, 38182.618173818184, 38197.61802381968, 39560.40659390111, 2736.904208352718, 3143.2141964508874, 3295.0, 3644.817759112034, 3725.254949010182, 28108.578371324445, 28670.426591468262, 28702.569461458093, 29073.12780616585, 29745.851270743726, 2621.357767346993, 3020.5620185073262, 3109.424756420047, 3159.178613560468, 3167.8891238806764, 482646.0, 487319.5634021843, 488625.0, 490457.0, 494045.0, 49995.50004499981, 59548.0, 60055.09917351243, 60080.69959650174, 60173.69913150494, 51067.48932510702, 61889.0, 62983.79556715493, 63124.684376577825, 65583.34416655773, 13478.0, 14867.925660371846, 15680.0, 15694.0, 15705.921470392575, 11884.584039558575, 11947.880521194851, 11947.940260298818, 11990.40047997614, 12001.1599188056, 16017.67964640724, 16095.517134485985, 16115.758263626056, 18129.818701813077, 18514.259429622663, 6780.457563394916, 7005.042151184564, 7161.427085833121, 7662.0, 7712.730054448068, 796.3151689546985, 884.5533005832013, 948.5921053946731, 1012.3925644613321, 1072.7747173093583, 28126.71873281282, 28368.85815570909, 28677.574264585066, 28822.567661485093, 29147.0, 1020.5866624017285, 1030.294248439821, 1042.2131290875493, 1054.8312270036756, 1120.6525976947173, 14679.926600366929, 14701.970862039769, 14782.039167454297, 14895.702085958437, 14998.925005375122, 676360.0, 682385.0, 682675.0, 683323.0, 684064.0, 55096.0, 55824.16263756047, 55902.720486398124, 56039.0, 56082.71958640262, 1218.214251807579, 1263.0968857267098, 1344.5226944434585, 1370.4107233889524, 1378.9241591712553, 25509.23472295834, 29095.0, 29136.562951556167, 29841.701582983893, 30504.186976627923, 53063.93872122613, 54462.18306725482, 54750.0, 57162.42837571571, 57359.13961290668, 557198.0, 563628.0, 565032.0, 565994.0, 688169.0, 38187.56311931939, 38777.0, 38849.6115038846, 39020.2195956085, 39282.803585981885, 41897.58102418937, 42923.78538107289, 43262.0, 43604.12791744084, 44569.0, 35250.823745881105, 35572.82213588915, 35983.64016359803, 36128.91613251607, 36140.91577252687, 35742.821285893406, 35834.10414739652, 36029.63970360263, 36816.81591592025, 37421.625783741816, 27753.44493110167, 35547.0, 35605.46591801177, 35658.28683426369, 36178.27643447064, 30395.392092157592, 32477.18807029843, 32594.185145371557, 32635.18412039718, 33073.50389744204, 24514.877425612758, 24564.50870982606, 24768.380790480383, 24970.250892473254, 25068.62397064082], "value": 19593.992279568516, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.21291455481503, 346.50449856704904, 374.24402706532805, 380.695443645084, 399.63034193371124, 14832.925835370821, 15128.546143615695, 15690.215489225537, 20081.196752129934, 20607.793922060773, 18992.955387453683, 19438.514037149078, 19892.0, 20015.399538013884, 20724.0, 5524.917126243099, 5977.282726072877, 5979.551533634973, 6009.669468179249, 6154.876902461948, 4802.83190088347, 6657.0, 6758.526903116784, 6795.864082718343, 6830.897536536956, 5605.383407825157, 5888.381719919411, 6669.9666501667425, 6735.292794256606, 6757.087793147936, 5939.346671866091, 5973.193618861464, 5975.52195824333, 5988.880222395571, 5992.550558708114, 4966.304717339575, 5748.367679555246, 5788.363280039192, 5831.241938548004, 5839.9124013139835, 13329.733405331936, 13533.323333833268, 13566.59300220995, 22971.0, 25469.872650636815, 2208.0505382685437, 2260.8530445520987, 2315.22439982606, 2320.791128798416, 2565.910193143231, 10500.842487362695, 11339.262947908359, 12251.816222756666, 15557.377704891791, 15586.968174869504, 1573.9055656660523, 1750.9124543772887, 1841.4291569613463, 1881.416760804155, 1892.5174080609495, 123033.0, 126560.0, 127180.0, 135633.6436635641, 137081.0, 11214.327140371604, 11343.659690209306, 11398.316101033965, 11588.6523404298, 11885.346305953082, 14732.410703571737, 14872.107673539405, 14876.92561537207, 14878.851211487963, 14923.104613723212, 7155.642217889084, 8116.83766324682, 8150.2664760171865, 8290.668373265122, 8678.305735541142, 8374.748757537283, 8381.664733410595, 8384.790380240544, 8504.53225072627, 8520.44617099899, 4454.799534020977, 4485.708428952108, 4551.704139230939, 4605.078984203173, 4726.574608285202, 1850.344755276183, 1903.067496926496, 1908.5705716214018, 1909.5894382707506, 1911.4456807525662, 800.6757263308253, 856.9048675987151, 888.4314039015036, 922.0088404964556, 943.3962264150949, 21145.471363216042, 21609.783902160776, 21662.0, 21696.89151554232, 21897.781022189574, 717.2898830158106, 907.7957459571544, 934.5887809363858, 941.4163218804248, 953.3707752883064, 10632.298832186967, 11368.94315528417, 11398.202125851276, 11413.885861141449, 11419.429028548539, 527195.0, 596646.0, 603971.0, 610413.0, 621455.0, 92331.0, 100412.0, 103789.0, 132505.0, 137638.0, 847.8134810341714, 926.1571969507851, 1028.5011769291887, 1028.922830787694, 1029.130384824822, 18969.90515047416, 19632.411027669194, 19759.9132047733, 19822.50443738918, 19824.10791514414, 46843.0, 50363.24455133102, 55111.72444137914, 55172.06897758559, 57092.42907571037, 63269.73460530856, 77403.0, 77507.0, 79595.0, 79740.0, 7062.752803652053, 7143.8571228576175, 7176.7488137914925, 7437.144728356308, 7441.590712510864, 11949.462274197507, 12570.622881313575, 12699.492020319083, 12792.424340904869, 13017.674558136123, 10822.0, 10987.725306867393, 11237.775244495228, 11371.658850234506, 11475.540978360772, 5158.922616160836, 7746.883796743166, 10720.785584288427, 10858.0, 11268.323900565993, 10826.837597436202, 11076.224664273746, 11220.270682405617, 11250.831237531607, 11460.02589779878, 7204.171520275236, 7502.737404190827, 7565.394768418623, 7606.96196519036, 7696.615169241628, 10427.68716938493, 10983.450827458755, 11012.724681882697, 11153.996140347082, 11226.158038147008], "value": 9402.018354953008, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1266.7149891274462, 1438.654722866512, 1536.5313579358292, 1787.2761531579706, 1787.4637608717383, 7131.037309963153, 7994.800129996745, 8414.410991230616, 8422.199891010352, 8429.494230346183, 6925.445964322855, 7714.074311082664, 7892.40806939479, 8130.268275855172, 8130.9186908130905, 2298.7011688480475, 2347.260612906929, 2348.049040138738, 2348.5420343033115, 2348.765123487655, 2327.453048533593, 2366.976330236693, 2377.41753270449, 2377.797887179583, 2386.9283921482297, 2053.117159621362, 2141.496748264157, 2145.1633862793524, 2225.0765932138224, 2302.3208153594646, 2268.8638681679067, 2292.873891935943, 2295.414669259345, 2305.5388922215625, 2307.14635584833, 2282.1441959265276, 2318.7217533895873, 2321.3268152235833, 2326.906923723049, 2327.7090363704538, 6997.160340759091, 7687.000689910332, 7786.377089832799, 7817.413693972975, 7863.0, 5070.467600901889, 5245.580353571705, 5247.580193584504, 5254.133068043769, 5260.605454590921, 3285.8685652573868, 3364.1925937775004, 3507.8947631571095, 3580.946285805715, 3588.0671025533547, 1045.4145678420034, 1075.763332066944, 1092.3227598888661, 1092.814221582333, 1093.283899046126, 10549.683509494727, 10901.672949811438, 10928.508217130247, 10931.234813563055, 11139.554417823358, 4323.935140972888, 4415.227335216325, 4493.752843593568, 4535.931961020521, 4552.430946131733, 8657.956710216535, 8660.30717542595, 8676.8698469523, 8682.6092825823, 8683.261922736638, 5078.5937125029905, 5080.060188865072, 5097.668651537638, 5125.769340379692, 5134.845954621367, 4529.547045295444, 4773.832915847928, 4825.9034819303415, 4832.033593281358, 4842.249451335048, 3425.794452332868, 3428.6914177724125, 3477.547918770594, 3512.613612502623, 3516.0682419158943, 1037.3412882819314, 1038.3562191441354, 1041.6458404142627, 1050.8056009638242, 1054.8945105489543, 607.6718571971174, 761.13611051457, 765.7396485195061, 808.4462143431768, 878.6397576993402, 5450.482204190595, 5460.645058071213, 5500.257465242221, 5656.8868622627315, 5886.205362276038, 852.552409984763, 862.9137086291446, 889.6708217959267, 931.5109567477128, 992.6178421307849, 3781.5084039074745, 3809.2571948470336, 3813.332666783357, 3906.79642692989, 3928.2339943710704, 9911.80176396468, 10171.288009839389, 10272.075513203847, 10398.532066057045, 10444.529996150191, 9873.950630246803, 10047.799044019079, 10085.092341689284, 10108.29241953071, 10304.793904121876, 7474.626268686544, 7751.9224807752325, 7793.649285782153, 7974.401919855918, 7983.0, 5450.700211488406, 5465.207544905887, 5628.240187574625, 5703.458171473703, 5952.375000625046, 9351.778892668022, 9453.621855125994, 9547.522623868917, 9560.569774360032, 9569.3779904306, 9541.427514349161, 9656.613735450785, 9682.322237443312, 9784.80430391374, 9881.901180988098, 5988.820335389945, 7317.341439270491, 7321.816954576178, 7330.413566914527, 7332.19345871943, 7695.115061767744, 7801.570913599807, 7807.765767026998, 7808.5314881107315, 7864.567448790142, 7126.608036558039, 7166.570005799668, 7175.2465991070185, 7200.855982880208, 7221.458390620619, 6063.757449701962, 7011.509194356346, 7060.646967651494, 7138.785836424916, 7202.279772022755, 6590.670466476753, 6617.569857959217, 6731.831704207434, 6745.93254067453, 6750.122484077038, 4297.785110744513, 4731.810727570996, 4829.1790395633525, 4895.583875370491, 4953.281774142802, 3806.5051543299196, 4926.605871530197, 4949.356583644103, 5029.170186919117, 5052.090623687772], "value": 4339.833583053944, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.1775136127012, 302.7245206861755, 307.7291983054912, 313.7427309606122, 314.0327790405551, 4548.9772551137285, 4816.542428469299, 4852.781624826883, 5246.711430871301, 5261.447548007462, 4396.582324679151, 4900.583450406711, 5012.94728107097, 5035.571976382012, 5136.743162841853, 3428.314337132571, 3811.6950643948558, 3925.0187453136714, 3949.447077409165, 4012.2978478766245, 3322.9169270768184, 3969.2458432897656, 4007.5792041835625, 4043.6360727534516, 4057.3913912913154, 3833.9616603834024, 3941.467901833259, 3951.427043078752, 3952.0754641565177, 3966.1472783351574, 3977.880663580097, 4000.83975647062, 4018.6785057195502, 4029.37544680575, 4047.7571345719202, 3106.829124398157, 3297.686719761619, 3471.8090505022215, 3481.704055155315, 3491.8603255869734, 4368.0, 4960.801567937314, 4986.329579590842, 4996.251312040793, 5017.172166592508, 3915.4322623219473, 4147.921540399488, 4362.018545827195, 4473.507914129443, 4559.153542815166, 4698.483166851644, 4832.1543729847135, 5396.91904621431, 5449.918251226235, 5482.462712660242, 3206.5350524174096, 3810.7713537187856, 3824.9235015299814, 3843.7501562398634, 3904.6138620789493, 5007.824726134568, 5615.578831587647, 5666.546676265889, 5710.428957104256, 5741.626794258402, 3454.240067185215, 3710.9072273193387, 3890.5720370759195, 3898.7855667938534, 3914.530256369254, 3439.38606658941, 3713.2759111973446, 3891.1439483313625, 3954.5397776945174, 3963.7225394222705, 3865.2849222893856, 4039.1517781265675, 4161.008148266547, 4595.977020114945, 4601.884952876205, 4559.3844830948065, 5058.91233384824, 5201.817936372209, 5221.921671174935, 5503.697296648643, 1128.8645362556547, 1149.7010777197822, 1250.2436026204198, 1263.8925691316342, 1275.1074248026234, 709.7977076533164, 845.5941148248875, 847.4999750147442, 880.9295256379603, 896.6729240723765, 4206.9482629342665, 4252.957470425256, 4569.29175977724, 4901.632377571696, 4932.894454239845, 4401.40581021558, 4495.550444955544, 4741.881452963704, 4868.736037742396, 5248.553872920845, 4019.5578486366476, 4442.334124703237, 4709.293605959134, 4747.643926705442, 4860.805567777249, 2352.564775516535, 2724.0601992312622, 2727.6044973478733, 2731.426400455887, 2744.4922689302543, 5177.5214439509555, 5206.7503799087945, 5310.707911064851, 5430.809921652803, 5529.751161197675, 4563.54364563541, 4734.857954261378, 5112.795488180431, 5859.560532960045, 5930.080837470199, 5485.259489968883, 5665.5467562594895, 5987.629030807535, 6005.879882402328, 6022.427869352405, 4681.882952926067, 5446.428125046813, 5679.716014199356, 5714.685692286964, 5720.199172115824, 5133.666311689728, 5511.669299842022, 5531.917021244765, 5665.348484924121, 5673.659580425187, 5425.83344580917, 5555.861103472445, 5856.385079566584, 5873.177755114373, 5886.970565147318, 3057.6330840299306, 3281.7538684599226, 3696.168362118502, 3763.322601931569, 3816.8091595419564, 4619.353290539394, 4845.916256009949, 5416.2688037114485, 5556.888862222814, 5573.888522229614, 4939.616907265828, 5348.566402286872, 5391.946080539301, 5411.458854114557, 5464.726763661881, 4936.247632090556, 5172.301739265151, 5376.623636345418, 5377.300950876517, 5394.406615272238, 4484.506704262463, 5354.919676204782, 5371.650842695212, 5379.54273886724, 5396.730163491889, 5205.6616319939085, 5337.653052551727, 5356.1697936819855, 5357.839264821906, 5509.586780991362, 4355.647950106907, 4973.428055773632, 4997.025580011863, 5417.539509141609, 5452.427495113115], "value": 3915.222030531402, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.5925503952667, 711.3491155592632, 727.9162896266931, 753.2542782645183, 760.8059944714097, 14259.3583288752, 14660.926695366508, 14680.265986700675, 14695.118292902433, 14745.778813317795, 12904.74190516189, 12910.225386476817, 12930.806037909439, 12978.74042519149, 13076.934615326934, 590.5600327755807, 635.6186288227073, 655.7475371981798, 656.6125985668455, 803.4416080823844, 543.9918401223985, 544.7140251368024, 784.3293983643988, 808.9716859909904, 814.4339683919678, 868.4051424774018, 874.0647507167333, 879.560219890055, 882.0782282514756, 882.3823323673422, 868.695956415256, 871.9825603487894, 872.4459967920385, 873.0701802580231, 873.6942070275353, 495.3362494257676, 497.3906963969145, 649.1560970738021, 651.4950913042383, 651.7555916531301, 12981.286029268387, 13473.326333683375, 13526.256055916921, 13817.861821381859, 13845.653858653513, 13248.337583120901, 13641.0, 13643.658908527266, 13706.794398084037, 13713.245771482565, 11081.058110060654, 11270.830937535944, 11304.604338848183, 11317.377544235063, 11374.37440940748, 3473.7221022318154, 3560.287942411528, 3566.4115420955513, 3737.1404576947452, 3748.981255093707, 62315.68842155806, 87912.0, 89377.10622893753, 89399.0, 90299.54850225772, 10012.749681257881, 10270.003144795375, 10354.53404596795, 10451.790964180675, 10511.52698128586, 20781.58436831255, 20940.0, 21037.78962210389, 21136.682949755766, 21220.151193952068, 6926.272741362185, 7044.823879403056, 7127.322904324081, 7212.242714515005, 7533.849323013509, 4085.3667681509414, 4191.91616167675, 4218.346156345771, 4258.42511260982, 4293.441852559147, 3375.8987230383127, 3488.302339532104, 3500.1599616091953, 3503.3168532136497, 3509.192885636318, 638.7796210307437, 642.5405834828027, 645.2353960556756, 646.0503060501056, 660.9966950165315, 548.6022633590694, 579.6435192356755, 630.5901164243279, 635.7838334966134, 710.0627172132838, 8689.652413903374, 8839.425437346552, 8960.775980600538, 9112.453252804853, 9180.265578753684, 567.5005994724615, 631.5389765471232, 644.2591020326662, 660.3627499463041, 669.2003056347716, 4188.099558594917, 4380.211561918885, 4400.317950717644, 4412.757298348622, 4515.819367225275, 78345.0, 81965.18034819576, 82682.0, 98064.50967745259, 114182.28726569108, 87254.12745872587, 88451.11548884558, 88568.11431885598, 93431.0, 94128.52935735277, 58132.0, 58616.70691646515, 72581.27418725957, 73877.93520261547, 79233.60383198046, 9202.309826762885, 9476.0, 9543.33196676226, 9564.187044101329, 9593.808123837345, 43743.68768936937, 43800.0, 51290.0, 51862.0, 52706.472935270154, 68771.96842047473, 70444.0, 73228.26771732215, 73664.0, 74121.2587874136, 11747.353895535827, 12083.818742719042, 12105.092118091354, 12131.514739410677, 12756.681082973, 13504.324783760969, 14517.201553914636, 14544.345504452536, 14585.416583336972, 14764.274762190964, 12215.08386871006, 12826.038047146314, 12945.611631651065, 13525.729485410433, 13809.585712428643, 12587.370631468573, 12948.676283092998, 13092.083554151119, 13318.733625327246, 13463.0, 10658.467076645982, 12634.178778379375, 13248.0, 13373.331333433094, 13642.386092625657, 9971.252156088178, 10679.54370494806, 10812.756532998492, 11144.108471322113, 11674.182807203753, 10842.566297348018, 11198.16013798952, 11241.988221060144, 11367.829482557934, 11586.536538538701], "value": 7062.359423457071, "unit": "score"}], "name": "dromaeo_css", "value": 7084.596656674627, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 115.0, 114.0, 114.0, 115.0, 117.0, 115.0, 115.0, 115.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 110.0, 147.0, 109.0, 109.0, 112.0, 109.0, 140.0, 111.0], "value": 120.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 186.0, 183.0, 184.0, 185.0, 186.0, 186.0, 183.0, 186.0, 187.0], "value": 184.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 79.0, 116.0, 89.0, 83.0, 60.0, 55.0, 82.0, 59.0], "value": 76.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 127.0, 102.0, 70.0, 71.0, 90.0, 69.0, 70.0, 93.0], "value": 84.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 86.0, 84.0, 88.0, 87.0, 135.0, 84.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 95.0, 94.0, 94.0, 94.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 79.0, 82.0, 82.0, 84.0, 87.0, 83.0, 80.0, 84.0], "value": 83.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 57.0, 59.0, 60.0, 59.0, 59.0, 58.0, 58.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 77.0, 77.0, 72.0, 69.0, 70.0, 69.0, 70.0, 72.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 82.0, 85.0, 84.0, 82.0, 83.0, 82.0, 84.0, 83.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 143.0, 157.0, 147.0, 150.0, 145.0, 144.0, 143.0, 190.0, 139.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 166.0, 164.0, 163.0, 167.0, 165.0, 165.0, 192.0, 163.0, 172.0], "value": 169.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 66.0, 71.0, 84.0, 87.0, 85.0, 86.0, 75.0, 67.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1455.6000000000001, "alertThreshold": 2.0}]} 20:52:44 INFO - Return code: 0 20:52:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:52:44 INFO - # TBPL SUCCESS # 20:52:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:52:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:52:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:52:44 INFO - 2016-12-26 20:52:44.232 screenresolution[2318:26508] starting screenresolution argv=screenresolution get 20:52:44 INFO - 2016-12-26 20:52:44.237 screenresolution[2318:26508] Display 0: 1600x1200x32@60 20:52:44 INFO - 2016-12-26 20:52:44.243 screenresolution[2319:26511] starting screenresolution argv=screenresolution list 20:52:44 INFO - Available Modes on Display 0 20:52:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:52:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:52:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:52:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:52:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:52:44 INFO - Intel Iris: 20:52:44 INFO - Chipset Model: Intel Iris 20:52:44 INFO - Type: GPU 20:52:44 INFO - Bus: Built-In 20:52:44 INFO - VRAM (Dynamic, Max): 1536 MB 20:52:44 INFO - Vendor: Intel (0x8086) 20:52:44 INFO - Device ID: 0x0a2e 20:52:44 INFO - Revision ID: 0x0009 20:52:44 INFO - Displays: 20:52:44 INFO - Display: 20:52:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:52:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:52:44 INFO - Display Serial Number: 1600x1200 60 20:52:44 INFO - Main Display: Yes 20:52:44 INFO - Mirror: Off 20:52:44 INFO - Online: Yes 20:52:44 INFO - Rotation: Supported 20:52:44 INFO - Return code: 0 20:52:44 INFO - Running post-action listener: _resource_record_post_action 20:52:44 INFO - [mozharness: 2016-12-27 04:52:44.493034Z] Finished run-tests step (success) 20:52:44 INFO - Running post-run listener: _resource_record_post_run 20:52:44 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 372736; Write bytes: 655179264; Read time: 5; Write time: 2121 20:52:44 INFO - TinderboxPrint: CPU usage
53.5% 20:52:44 INFO - TinderboxPrint: I/O read bytes / time
372,736 / 5 20:52:44 INFO - TinderboxPrint: I/O write bytes / time
655,179,264 / 2,121 20:52:44 INFO - TinderboxPrint: CPU idle
2,251.4 (46.5%) 20:52:44 INFO - TinderboxPrint: CPU system
974.9 (20.1%) 20:52:44 INFO - TinderboxPrint: CPU user
1,619.4 (33.4%) 20:52:44 INFO - TinderboxPrint: Swap in / out
348,704,768 / 0 20:52:44 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349827584; Write bytes: 352311296; Read time: 26892; Write time: 721 20:52:44 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 253952; Write bytes: 301590016; Read time: 4; Write time: 1393 20:52:44 INFO - Running post-run listener: _upload_blobber_files 20:52:44 INFO - Blob upload gear active. 20:52:44 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:52:44 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:52:44 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'] 20:52:44 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 20:52:45 INFO - (blobuploader) - INFO - Open directory for files ... 20:52:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:52:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:52:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:46 INFO - (blobuploader) - INFO - Done attempting. 20:52:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:52:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:52:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:47 INFO - (blobuploader) - INFO - Done attempting. 20:52:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:52:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:52:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:52:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:52:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:52:49 INFO - (blobuploader) - INFO - Done attempting. 20:52:49 INFO - (blobuploader) - INFO - Iteration through files over. 20:52:49 INFO - Return code: 0 20:52:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:52:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:52:49 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b15b16dac7e538a59a0b0c88815a65bbdcc0c3e78b918d82f780398da179f15c7b78b8daba38473b78668a77c9c2b8133141f1a6cae7e5c79ce09ff8ae858eea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870a73b832527ac0807f8a6fe521606ff65b500ca385d7d55ae5f48777c0816ae1829ec3513a2aab4d65c95ad0ae1b62635dad2541ebcb4ca1efa489ff9a70e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a76c420b3cb35c55bc9b7f5faeacfe03478a658b4174b777acbdd9deaf0fc7d4d75e71a3737fb04687126902a2fdf5464a6a9f9165cb11da6436031172e60d6"} 20:52:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:52:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:52:49 INFO - Contents: 20:52:49 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b15b16dac7e538a59a0b0c88815a65bbdcc0c3e78b918d82f780398da179f15c7b78b8daba38473b78668a77c9c2b8133141f1a6cae7e5c79ce09ff8ae858eea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870a73b832527ac0807f8a6fe521606ff65b500ca385d7d55ae5f48777c0816ae1829ec3513a2aab4d65c95ad0ae1b62635dad2541ebcb4ca1efa489ff9a70e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a76c420b3cb35c55bc9b7f5faeacfe03478a658b4174b777acbdd9deaf0fc7d4d75e71a3737fb04687126902a2fdf5464a6a9f9165cb11da6436031172e60d6"} 20:52:49 INFO - Running post-run listener: copy_logs_to_upload_dir 20:52:49 INFO - Copying logs to upload dir... 20:52:49 INFO - mkdir: /builds/slave/test/build/upload/logs 20:52:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.103680 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-26 20:52:49.931246) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:49.934440) ========= 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.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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/b15b16dac7e538a59a0b0c88815a65bbdcc0c3e78b918d82f780398da179f15c7b78b8daba38473b78668a77c9c2b8133141f1a6cae7e5c79ce09ff8ae858eea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870a73b832527ac0807f8a6fe521606ff65b500ca385d7d55ae5f48777c0816ae1829ec3513a2aab4d65c95ad0ae1b62635dad2541ebcb4ca1efa489ff9a70e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a76c420b3cb35c55bc9b7f5faeacfe03478a658b4174b777acbdd9deaf0fc7d4d75e71a3737fb04687126902a2fdf5464a6a9f9165cb11da6436031172e60d6"} build_url:https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011631 build_url: 'https://queue.taskcluster.net/v1/task/YisAC9TUTJ-jOhElm4nzMQ/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/b15b16dac7e538a59a0b0c88815a65bbdcc0c3e78b918d82f780398da179f15c7b78b8daba38473b78668a77c9c2b8133141f1a6cae7e5c79ce09ff8ae858eea", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/870a73b832527ac0807f8a6fe521606ff65b500ca385d7d55ae5f48777c0816ae1829ec3513a2aab4d65c95ad0ae1b62635dad2541ebcb4ca1efa489ff9a70e0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9a76c420b3cb35c55bc9b7f5faeacfe03478a658b4174b777acbdd9deaf0fc7d4d75e71a3737fb04687126902a2fdf5464a6a9f9165cb11da6436031172e60d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:49.970683) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:49.971010) ========= 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.awAEW5x7ul/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.MJa6yWtj66/Listeners TMPDIR=/var/folders/2x/nv87v62s4vz7ppmnpm2v5q4000000w/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.005306 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-26 20:52:50.019806) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 20:52:50.020142) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-26 20:52:50.020457) ========= ========= Total master_lag: 0.77 =========