builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0024 starttime: 1476282214.97 results: success (0) buildid: 20161012051247 builduid: 8eefdbdd4b2043f0ad22a091a8ccd2a3 revision: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:34.971587) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:34.972027) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:34.972297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:34.996599) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:34.996957) ========= 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.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-10-12 07:23: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% 10.1M=0.001s 2016-10-12 07:23:35 (10.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.090013 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:35.109336) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:35.109644) ========= 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.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.029178 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:35.159057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:23:35.159415) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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-10-12 07:23:35,233 truncating revision to first 12 chars 2016-10-12 07:23:35,233 Setting DEBUG logging. 2016-10-12 07:23:35,233 attempt 1/10 2016-10-12 07:23:35,233 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/4756eb1a2f82?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-12 07:23:36,317 unpacking tar archive at: larch-4756eb1a2f82/testing/mozharness/ program finished with exit code 0 elapsedTime=1.344569 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-12 07:23:36.526393) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:36.526697) ========= script_repo_revision: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:36.527085) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:36.527344) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-12 07:23:36.546332) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-12 07:23:36.546715) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:23:36 INFO - MultiFileLogger online at 20161012 07:23:36 in /builds/slave/test 07:23:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 07:23:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:23:36 INFO - {'append_to_log': False, 07:23:36 INFO - 'base_work_dir': '/builds/slave/test', 07:23:36 INFO - 'blob_upload_branch': 'Larch', 07:23:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:23:36 INFO - 'branch': 'Larch', 07:23:36 INFO - 'buildbot_json_path': 'buildprops.json', 07:23:36 INFO - 'config_files': ('talos/mac_config.py',), 07:23:36 INFO - 'default_actions': ('clobber', 07:23:36 INFO - 'read-buildbot-config', 07:23:36 INFO - 'download-and-extract', 07:23:36 INFO - 'populate-webroot', 07:23:36 INFO - 'create-virtualenv', 07:23:36 INFO - 'install', 07:23:36 INFO - 'run-tests'), 07:23:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:23:36 INFO - 'download_minidump_stackwalk': True, 07:23:36 INFO - 'download_symbols': 'ondemand', 07:23:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:23:36 INFO - 'tooltool.py': '/tools/tooltool.py', 07:23:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:23:36 INFO - '/tools/misc-python/virtualenv.py')}, 07:23:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:23:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:23:36 INFO - 'installer_path': 'installer.exe', 07:23:36 INFO - 'log_level': 'info', 07:23:36 INFO - 'log_name': 'talos', 07:23:36 INFO - 'log_to_console': True, 07:23:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:23:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:23:36 INFO - 'opt_config_files': (), 07:23:36 INFO - 'pip_index': False, 07:23:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:23:36 INFO - 'cmd': ('bash', 07:23:36 INFO - '-c', 07:23:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:23:36 INFO - 'enabled': True, 07:23:36 INFO - 'halt_on_failure': False, 07:23:36 INFO - 'name': 'check_screen_resolution'},), 07:23:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:23:36 INFO - 'cmd': ('bash', 07:23:36 INFO - '-c', 07:23:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:23:36 INFO - 'enabled': True, 07:23:36 INFO - 'halt_on_failure': False, 07:23:36 INFO - 'name': 'check_screen_resolution'},), 07:23:36 INFO - 'run_cmd_checks_enabled': True, 07:23:36 INFO - 'sps_profile': False, 07:23:36 INFO - 'sps_profile_interval': 0, 07:23:36 INFO - 'suite': 'dromaeojs', 07:23:36 INFO - 'system_bits': '32', 07:23:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:23:36 INFO - 'title': 't-yosemite-r7-0024', 07:23:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:23:36 INFO - 'use_talos_json': True, 07:23:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:23:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:23:36 INFO - 'work_dir': 'build'} 07:23:36 INFO - [mozharness: 2016-10-12 14:23:36.769640Z] Running clobber step. 07:23:36 INFO - Running pre-action listener: _resource_record_pre_action 07:23:36 INFO - Running main action method: clobber 07:23:36 INFO - rmtree: /builds/slave/test/build 07:23:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:23:39 INFO - Running post-action listener: _resource_record_post_action 07:23:39 INFO - [mozharness: 2016-10-12 14:23:39.007154Z] Finished clobber step (success) 07:23:39 INFO - [mozharness: 2016-10-12 14:23:39.007323Z] Running read-buildbot-config step. 07:23:39 INFO - Running pre-action listener: _resource_record_pre_action 07:23:39 INFO - Running main action method: read_buildbot_config 07:23:39 INFO - Using buildbot properties: 07:23:39 INFO - { 07:23:39 INFO - "project": "", 07:23:39 INFO - "product": "firefox", 07:23:39 INFO - "script_repo_revision": "production", 07:23:39 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 07:23:39 INFO - "repository": "", 07:23:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 07:23:39 INFO - "buildid": "20161012051247", 07:23:39 INFO - "pgo_build": "False", 07:23:39 INFO - "basedir": "/builds/slave/test", 07:23:39 INFO - "buildnumber": 0, 07:23:39 INFO - "slavename": "t-yosemite-r7-0024", 07:23:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 07:23:39 INFO - "platform": "yosemite_r7", 07:23:39 INFO - "scheduler": "tests-larch-macosx64-talos", 07:23:39 INFO - "branch": "larch", 07:23:39 INFO - "repo_path": "projects/larch", 07:23:39 INFO - "revision": "4756eb1a2f82a6fb2e7ab5e3708d14aa76816076", 07:23:39 INFO - "stage_platform": "macosx64", 07:23:39 INFO - "builduid": "8eefdbdd4b2043f0ad22a091a8ccd2a3", 07:23:39 INFO - "slavebuilddir": "test" 07:23:39 INFO - } 07:23:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:23:39 INFO - Running post-action listener: _resource_record_post_action 07:23:39 INFO - [mozharness: 2016-10-12 14:23:39.010668Z] Finished read-buildbot-config step (success) 07:23:39 INFO - [mozharness: 2016-10-12 14:23:39.010776Z] Running download-and-extract step. 07:23:39 INFO - Running pre-action listener: _resource_record_pre_action 07:23:39 INFO - Running main action method: download_and_extract 07:23:39 INFO - mkdir: /builds/slave/test/build/tests 07:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:39 INFO - trying https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:23:39 INFO - Downloading https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:23:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 07:23:40 INFO - Downloaded 1199 bytes. 07:23:40 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:23:40 INFO - Using the following test package requirements: 07:23:40 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:23:40 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:23:40 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:23:40 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'jsshell-mac.zip'], 07:23:40 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:23:40 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:23:40 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:23:40 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:23:40 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:23:40 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:23:40 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:23:40 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:23:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:23:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:23:40 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:23:41 INFO - Http code: 200 07:23:41 INFO - via: 1.1 30bb04916f91d64c600e15c15000042d.cloudfront.net (CloudFront) 07:23:41 INFO - x-amz-cf-id: 85n-vn8uTDxMhxmtiCpicxT6ecnh4P9CV5n0es1GNvYNLO3zwzP64w== 07:23:41 INFO - x-amz-version-id: ew.BVovNkNFCrWirFVzGxILJjuqnwZsD 07:23:41 INFO - Expected file size: 16508508 07:23:41 INFO - Obtained file size: 16508508 07:23:48 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 07:23:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:23:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:23:48 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:23:49 INFO - Http code: 200 07:23:49 INFO - via: 1.1 9767e262e4af15c8d83a726bb0f44fa5.cloudfront.net (CloudFront) 07:23:49 INFO - x-amz-cf-id: FjwCOCtG7T7CZwp_o-zWmaVmHJXskgUdrl93zrbmKwKDvXq2MpjPoA== 07:23:49 INFO - x-amz-version-id: ew.BVovNkNFCrWirFVzGxILJjuqnwZsD 07:23:49 INFO - Expected file size: 16508508 07:23:49 INFO - Obtained file size: 16508508 07:23:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:23:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:23:55 INFO - Fetch https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:23:56 INFO - Http code: 200 07:23:56 INFO - via: 1.1 d01884a3320364227d925bce1a38f0ad.cloudfront.net (CloudFront) 07:23:56 INFO - x-amz-cf-id: bU-p_IHHzsWi0xvtIBTITlUXc3XdLdq1lVhiswbJy2vEKFNT86U8Og== 07:23:56 INFO - x-amz-version-id: m5pAWezr_1W.v7_QeyxzhUaG7S_Kzs_V 07:23:57 INFO - Expected file size: 14409006 07:23:57 INFO - Obtained file size: 14409006 07:23:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:23:57 INFO - trying https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:23:57 INFO - Downloading https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:23:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 07:24:01 INFO - Downloaded 111696945 bytes. 07:24:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:24:01 INFO - mkdir: /builds/slave/test/properties 07:24:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:24:01 INFO - Writing to file /builds/slave/test/properties/build_url 07:24:01 INFO - Contents: 07:24:01 INFO - build_url:https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:24:02 INFO - Running post-action listener: _resource_record_post_action 07:24:02 INFO - Running post-action listener: set_extra_try_arguments 07:24:02 INFO - [mozharness: 2016-10-12 14:24:02.466988Z] Finished download-and-extract step (success) 07:24:02 INFO - [mozharness: 2016-10-12 14:24:02.467106Z] Running populate-webroot step. 07:24:02 INFO - Running pre-action listener: _resource_record_pre_action 07:24:02 INFO - Running main action method: populate_webroot 07:24:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:24:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:24:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:24:02 INFO - '--tppagecycles', 07:24:02 INFO - '7'], 07:24:02 INFO - 'tests': ['tsvgm']}}, 07:24:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:24:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:24:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:24:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:24:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:24:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['damp', 'tps']}, 07:24:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'tests': ['damp', 'tps']}, 07:24:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['dromaeo_dom']}, 07:24:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:24:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['basic_compositor_video']}, 07:24:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:24:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'other-e10s': {'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['a11yr', 07:24:02 INFO - 'ts_paint', 07:24:02 INFO - 'tpaint', 07:24:02 INFO - 'sessionrestore', 07:24:02 INFO - 'sessionrestore_no_auto_restore', 07:24:02 INFO - 'tabpaint']}, 07:24:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['tsvgx', 07:24:02 INFO - 'tsvgr_opacity', 07:24:02 INFO - 'tart', 07:24:02 INFO - 'tscrollx', 07:24:02 INFO - 'cart']}, 07:24:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:24:02 INFO - 'tsvgr_opacity', 07:24:02 INFO - 'tart', 07:24:02 INFO - 'tscrollx', 07:24:02 INFO - 'cart']}, 07:24:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'talos_options': ['--disable-e10s'], 07:24:02 INFO - 'tests': ['tp5o']}, 07:24:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'tests': ['tp5o']}, 07:24:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'talos_options': ['--disable-e10s', 07:24:02 INFO - '--xperf_path', 07:24:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:02 INFO - 'tests': ['tp5n']}, 07:24:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:24:02 INFO - 'talos_options': ['--xperf_path', 07:24:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:24:02 INFO - 'tests': ['tp5n']}}, 07:24:02 INFO - 'talos.zip': {'path': '', 07:24:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:24:02 INFO - Running post-action listener: _resource_record_post_action 07:24:02 INFO - [mozharness: 2016-10-12 14:24:02.486049Z] Finished populate-webroot step (success) 07:24:02 INFO - [mozharness: 2016-10-12 14:24:02.486187Z] Running create-virtualenv step. 07:24:02 INFO - Running pre-action listener: _resource_record_pre_action 07:24:02 INFO - Running main action method: create_virtualenv 07:24:02 INFO - Creating virtualenv /builds/slave/test/build/venv 07:24:02 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 07:24:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:24:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:24:02 INFO - Using real prefix '/tools/python27' 07:24:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:24:03 INFO - Installing distribute.............................................................................................................................................................................................done. 07:24:06 INFO - Installing pip.................done. 07:24:06 INFO - Return code: 0 07:24:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:24:06 INFO - Reading from file tmpfile_stdout 07:24:06 INFO - Output received: 07:24:06 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) 07:24:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:24:06 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')]} 07:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:06 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')]} 07:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:06 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:06 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 07:24:06 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 07:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:06 INFO - 'HOME': '/Users/cltbld', 07:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:06 INFO - 'LOGNAME': 'cltbld', 07:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:06 INFO - 'MOZ_NO_REMOTE': '1', 07:24:06 INFO - 'NO_EM_RESTART': '1', 07:24:06 INFO - 'PAGER': '/bin/cat', 07:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:06 INFO - 'PWD': '/builds/slave/test', 07:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:06 INFO - 'SHELL': '/bin/bash', 07:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:06 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:06 INFO - 'USER': 'cltbld', 07:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:06 INFO - 'XPC_FLAGS': '0x0', 07:24:06 INFO - 'XPC_SERVICE_NAME': '0', 07:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:06 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 07:24:06 INFO - Cleaning up... 07:24:06 INFO - Return code: 0 07:24:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:24:06 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')]} 07:24:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:06 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')]} 07:24:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:06 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:06 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 07:24:06 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 07:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:06 INFO - 'HOME': '/Users/cltbld', 07:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:06 INFO - 'LOGNAME': 'cltbld', 07:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:06 INFO - 'MOZ_NO_REMOTE': '1', 07:24:06 INFO - 'NO_EM_RESTART': '1', 07:24:06 INFO - 'PAGER': '/bin/cat', 07:24:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:06 INFO - 'PWD': '/builds/slave/test', 07:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:06 INFO - 'SHELL': '/bin/bash', 07:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:06 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:06 INFO - 'USER': 'cltbld', 07:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:06 INFO - 'XPC_FLAGS': '0x0', 07:24:06 INFO - 'XPC_SERVICE_NAME': '0', 07:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:06 INFO - Downloading/unpacking psutil>=3.1.1 07:24:06 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 07:24:06 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 07:24:06 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 07:24:06 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 07:24:06 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 07:24:06 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 07:24:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:24:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:10 INFO - Installing collected packages: psutil 07:24:10 INFO - Running setup.py install for psutil 07:24:10 INFO - building 'psutil._psutil_osx' extension 07:24:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 07:24:11 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 07:24:11 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 07:24:11 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 07:24:11 INFO - building 'psutil._psutil_posix' extension 07:24:11 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 07:24:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:24:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:24:11 INFO - ^ 07:24:11 INFO - 1 warning generated. 07:24:11 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 07:24:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:24:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:24:11 INFO - Successfully installed psutil 07:24:11 INFO - Cleaning up... 07:24:11 INFO - Return code: 0 07:24:11 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:24:11 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')]} 07:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:11 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')]} 07:24:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:11 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:11 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 07:24:11 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 07:24:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:11 INFO - 'HOME': '/Users/cltbld', 07:24:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:11 INFO - 'LOGNAME': 'cltbld', 07:24:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:11 INFO - 'MOZ_NO_REMOTE': '1', 07:24:11 INFO - 'NO_EM_RESTART': '1', 07:24:11 INFO - 'PAGER': '/bin/cat', 07:24:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:11 INFO - 'PWD': '/builds/slave/test', 07:24:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:11 INFO - 'SHELL': '/bin/bash', 07:24:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:11 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:11 INFO - 'USER': 'cltbld', 07:24:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:11 INFO - 'XPC_FLAGS': '0x0', 07:24:11 INFO - 'XPC_SERVICE_NAME': '0', 07:24:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:11 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:24:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:24:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:24:14 INFO - Installing collected packages: mozsystemmonitor 07:24:14 INFO - Running setup.py install for mozsystemmonitor 07:24:14 INFO - Successfully installed mozsystemmonitor 07:24:14 INFO - Cleaning up... 07:24:14 INFO - Return code: 0 07:24:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:14 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:14 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 07:24:14 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 07:24:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:14 INFO - 'HOME': '/Users/cltbld', 07:24:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:14 INFO - 'LOGNAME': 'cltbld', 07:24:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:14 INFO - 'MOZ_NO_REMOTE': '1', 07:24:14 INFO - 'NO_EM_RESTART': '1', 07:24:14 INFO - 'PAGER': '/bin/cat', 07:24:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:14 INFO - 'PWD': '/builds/slave/test', 07:24:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:14 INFO - 'SHELL': '/bin/bash', 07:24:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:14 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:14 INFO - 'USER': 'cltbld', 07:24:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:14 INFO - 'XPC_FLAGS': '0x0', 07:24:14 INFO - 'XPC_SERVICE_NAME': '0', 07:24:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:14 INFO - Downloading/unpacking jsonschema==2.5.1 07:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:24:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:24:17 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 07:24:17 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 07:24:17 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 07:24:17 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 07:24:17 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 07:24:17 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 07:24:17 INFO - Downloading functools32-3.2.3-2.tar.gz 07:24:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:24:17 INFO - warning: no files found matching '*.txt' 07:24:17 INFO - no previously-included directories found matching 'build' 07:24:17 INFO - no previously-included directories found matching 'dist' 07:24:17 INFO - no previously-included directories found matching '.git*' 07:24:17 INFO - Installing collected packages: jsonschema, functools32 07:24:17 INFO - Running setup.py install for functools32 07:24:18 INFO - warning: no files found matching '*.txt' 07:24:18 INFO - no previously-included directories found matching 'build' 07:24:18 INFO - no previously-included directories found matching 'dist' 07:24:18 INFO - no previously-included directories found matching '.git*' 07:24:18 INFO - Successfully installed jsonschema functools32 07:24:18 INFO - Cleaning up... 07:24:18 INFO - Return code: 0 07:24:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:24:18 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')]} 07:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:18 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')]} 07:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:18 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:18 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 07:24:18 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 07:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:18 INFO - 'HOME': '/Users/cltbld', 07:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:18 INFO - 'LOGNAME': 'cltbld', 07:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:18 INFO - 'MOZ_NO_REMOTE': '1', 07:24:18 INFO - 'NO_EM_RESTART': '1', 07:24:18 INFO - 'PAGER': '/bin/cat', 07:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:18 INFO - 'PWD': '/builds/slave/test', 07:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:18 INFO - 'SHELL': '/bin/bash', 07:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:18 INFO - 'USER': 'cltbld', 07:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:18 INFO - 'XPC_FLAGS': '0x0', 07:24:18 INFO - 'XPC_SERVICE_NAME': '0', 07:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:24:18 INFO - Cleaning up... 07:24:18 INFO - Return code: 0 07:24:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:24:18 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')]} 07:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:18 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')]} 07:24:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:18 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:18 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 07:24:18 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 07:24:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:18 INFO - 'HOME': '/Users/cltbld', 07:24:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:18 INFO - 'LOGNAME': 'cltbld', 07:24:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:18 INFO - 'MOZ_NO_REMOTE': '1', 07:24:18 INFO - 'NO_EM_RESTART': '1', 07:24:18 INFO - 'PAGER': '/bin/cat', 07:24:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:18 INFO - 'PWD': '/builds/slave/test', 07:24:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:18 INFO - 'SHELL': '/bin/bash', 07:24:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:18 INFO - 'USER': 'cltbld', 07:24:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:18 INFO - 'XPC_FLAGS': '0x0', 07:24:18 INFO - 'XPC_SERVICE_NAME': '0', 07:24:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:18 INFO - Downloading/unpacking blobuploader==1.2.4 07:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:21 INFO - Downloading blobuploader-1.2.4.tar.gz 07:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:24:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:24:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:22 INFO - Downloading docopt-0.6.1.tar.gz 07:24:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:24:22 INFO - Installing collected packages: blobuploader, requests, docopt 07:24:22 INFO - Running setup.py install for blobuploader 07:24:22 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:24:22 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:24:22 INFO - Running setup.py install for requests 07:24:23 INFO - Running setup.py install for docopt 07:24:23 INFO - Successfully installed blobuploader requests docopt 07:24:23 INFO - Cleaning up... 07:24:23 INFO - Return code: 0 07:24:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 07:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 07:24:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:23 INFO - 'HOME': '/Users/cltbld', 07:24:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:23 INFO - 'LOGNAME': 'cltbld', 07:24:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:23 INFO - 'MOZ_NO_REMOTE': '1', 07:24:23 INFO - 'NO_EM_RESTART': '1', 07:24:23 INFO - 'PAGER': '/bin/cat', 07:24:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:23 INFO - 'PWD': '/builds/slave/test', 07:24:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:23 INFO - 'SHELL': '/bin/bash', 07:24:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:23 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:23 INFO - 'USER': 'cltbld', 07:24:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:23 INFO - 'XPC_FLAGS': '0x0', 07:24:23 INFO - 'XPC_SERVICE_NAME': '0', 07:24:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:24:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-AgjmzO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:24:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-sVAN2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:24:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:24:23 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-IASjvg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-z3nR2u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-eS7sx_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EA2FGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-j1UBuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qlgJvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-agOMvT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ldFtR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Cj13D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:24:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:24:24 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-GGMDNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:24:25 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-LzTlK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:24:25 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-A9A8CI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:25 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-L9t2O7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:24:25 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9WD3QF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:24:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:24:25 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-asIkZP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:24:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:24:25 INFO - Running setup.py install for manifestparser 07:24:25 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:24:25 INFO - Running setup.py install for mozcrash 07:24:25 INFO - Running setup.py install for mozdebug 07:24:25 INFO - Running setup.py install for mozdevice 07:24:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Running setup.py install for mozfile 07:24:26 INFO - Running setup.py install for mozhttpd 07:24:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Running setup.py install for mozinfo 07:24:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Running setup.py install for mozInstall 07:24:26 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:24:26 INFO - Running setup.py install for mozleak 07:24:26 INFO - Running setup.py install for mozlog 07:24:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Running setup.py install for moznetwork 07:24:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Running setup.py install for mozprocess 07:24:27 INFO - Running setup.py install for mozprofile 07:24:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Running setup.py install for mozrunner 07:24:27 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:24:27 INFO - Running setup.py install for mozscreenshot 07:24:27 INFO - Running setup.py install for moztest 07:24:28 INFO - Running setup.py install for mozversion 07:24:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:24:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:24:28 INFO - Cleaning up... 07:24:28 INFO - Return code: 0 07:24:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:28 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:28 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 07:24:28 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 07:24:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:28 INFO - 'HOME': '/Users/cltbld', 07:24:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:28 INFO - 'LOGNAME': 'cltbld', 07:24:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:28 INFO - 'MOZ_NO_REMOTE': '1', 07:24:28 INFO - 'NO_EM_RESTART': '1', 07:24:28 INFO - 'PAGER': '/bin/cat', 07:24:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:28 INFO - 'PWD': '/builds/slave/test', 07:24:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:28 INFO - 'SHELL': '/bin/bash', 07:24:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:28 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:28 INFO - 'USER': 'cltbld', 07:24:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:28 INFO - 'XPC_FLAGS': '0x0', 07:24:28 INFO - 'XPC_SERVICE_NAME': '0', 07:24:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-iA32HW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-LyB_ze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-fg0dlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ZKTMcJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:24:28 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)) 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3S0r4h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:24:28 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 07:24:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:24:28 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-4SGrkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:24:29 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)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Oy9E86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:24:29 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)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-doSW_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:24:29 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)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0rxcvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-QRZ6gn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-ZMB9aH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-3BP0hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:24:29 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)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ot3MZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-zKiIuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:24:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 07:24:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:29 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-8Yl0IU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:24:30 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)) 07:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:24:30 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Rd2I7Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:24:30 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)) 07:24:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:24:30 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-O3JsAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:24:30 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)) 07:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:24:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 07:24:30 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)) 07:24:30 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)) 07:24:30 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)) 07:24:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:24:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:24:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:24:32 INFO - Downloading blessings-1.6.tar.gz 07:24:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:24:32 INFO - Installing collected packages: blessings 07:24:32 INFO - Running setup.py install for blessings 07:24:33 INFO - Successfully installed blessings 07:24:33 INFO - Cleaning up... 07:24:33 INFO - Return code: 0 07:24:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:24:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:24:33 INFO - Reading from file tmpfile_stdout 07:24:33 INFO - Current package versions: 07:24:33 INFO - blessings == 1.6 07:24:33 INFO - blobuploader == 1.2.4 07:24:33 INFO - docopt == 0.6.1 07:24:33 INFO - functools32 == 3.2.3-2 07:24:33 INFO - jsonschema == 2.5.1 07:24:33 INFO - manifestparser == 1.1 07:24:33 INFO - mozInstall == 1.12 07:24:33 INFO - mozcrash == 0.17 07:24:33 INFO - mozdebug == 0.1 07:24:33 INFO - mozdevice == 0.48 07:24:33 INFO - mozfile == 1.2 07:24:33 INFO - mozhttpd == 0.7 07:24:33 INFO - mozinfo == 0.9 07:24:33 INFO - mozleak == 0.1 07:24:33 INFO - mozlog == 3.4 07:24:33 INFO - moznetwork == 0.27 07:24:33 INFO - mozprocess == 0.23 07:24:33 INFO - mozprofile == 0.28 07:24:33 INFO - mozrunner == 6.12 07:24:33 INFO - mozscreenshot == 0.1 07:24:33 INFO - mozsystemmonitor == 0.3 07:24:33 INFO - moztest == 0.7 07:24:33 INFO - mozversion == 1.4 07:24:33 INFO - psutil == 3.1.1 07:24:33 INFO - requests == 1.2.3 07:24:33 INFO - wsgiref == 0.1.2 07:24:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:34 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 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:34 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 07:24:34 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 07:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:34 INFO - 'HOME': '/Users/cltbld', 07:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:34 INFO - 'LOGNAME': 'cltbld', 07:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:34 INFO - 'MOZ_NO_REMOTE': '1', 07:24:34 INFO - 'NO_EM_RESTART': '1', 07:24:34 INFO - 'PAGER': '/bin/cat', 07:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:34 INFO - 'PWD': '/builds/slave/test', 07:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:34 INFO - 'SHELL': '/bin/bash', 07:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:34 INFO - 'USER': 'cltbld', 07:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:34 INFO - 'XPC_FLAGS': '0x0', 07:24:34 INFO - 'XPC_SERVICE_NAME': '0', 07:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 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)) 07:24:34 INFO - Cleaning up... 07:24:34 INFO - Return code: 0 07:24:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:24:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:24:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:24:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:24:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100cc3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100c39570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9ab248bab0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, '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.g8TvkroR7H/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.wp1BDsrO6m/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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 07:24:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 07:24:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 07:24:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:24:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:24:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:24:34 INFO - 'HOME': '/Users/cltbld', 07:24:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:24:34 INFO - 'LOGNAME': 'cltbld', 07:24:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:24:34 INFO - 'MOZ_NO_REMOTE': '1', 07:24:34 INFO - 'NO_EM_RESTART': '1', 07:24:34 INFO - 'PAGER': '/bin/cat', 07:24:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:24:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:24:34 INFO - 'PWD': '/builds/slave/test', 07:24:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:24:34 INFO - 'SHELL': '/bin/bash', 07:24:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:24:34 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:24:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:24:34 INFO - 'USER': 'cltbld', 07:24:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:24:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:24:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:24:34 INFO - 'XPC_FLAGS': '0x0', 07:24:34 INFO - 'XPC_SERVICE_NAME': '0', 07:24:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:24:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:24:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:24:35 INFO - Cleaning up... 07:24:35 INFO - Return code: 0 07:24:35 INFO - Running post-action listener: _resource_record_post_action 07:24:35 INFO - Running post-action listener: _start_resource_monitoring 07:24:35 INFO - Starting resource monitoring. 07:24:35 INFO - [mozharness: 2016-10-12 14:24:35.209178Z] Finished create-virtualenv step (success) 07:24:35 INFO - [mozharness: 2016-10-12 14:24:35.210150Z] Running install step. 07:24:35 INFO - Running pre-action listener: _resource_record_pre_action 07:24:35 INFO - Running main action method: install 07:24:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:24:35 INFO - Reading from file tmpfile_stdout 07:24:35 INFO - Detecting whether we're running mozinstall >=1.0... 07:24:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:24:35 INFO - Reading from file tmpfile_stdout 07:24:35 INFO - Output received: 07:24:35 INFO - Usage: mozinstall [options] installer 07:24:35 INFO - Options: 07:24:35 INFO - -h, --help show this help message and exit 07:24:35 INFO - -d DEST, --destination=DEST 07:24:35 INFO - Directory to install application into. [default: 07:24:35 INFO - "/builds/slave/test"] 07:24:35 INFO - --app=APP Application being installed. [default: firefox] 07:24:35 INFO - mkdir: /builds/slave/test/build/application 07:24:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 07:24:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 07:25:09 INFO - Reading from file tmpfile_stdout 07:25:09 INFO - Output received: 07:25:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:25:09 INFO - Running post-action listener: _resource_record_post_action 07:25:09 INFO - [mozharness: 2016-10-12 14:25:09.716259Z] Finished install step (success) 07:25:09 INFO - [mozharness: 2016-10-12 14:25:09.716392Z] Running run-tests step. 07:25:09 INFO - Running pre-action listener: _resource_record_pre_action 07:25:09 INFO - Running main action method: run_tests 07:25:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:25:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:25:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:25:09 INFO - 2016-10-12 07:25:09.731 screenresolution[1632:7359] starting screenresolution argv=screenresolution get 07:25:09 INFO - 2016-10-12 07:25:09.733 screenresolution[1632:7359] Display 0: 1600x1200x32@60 07:25:09 INFO - 2016-10-12 07:25:09.740 screenresolution[1633:7361] starting screenresolution argv=screenresolution list 07:25:09 INFO - Available Modes on Display 0 07:25:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:25:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:25:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:25:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:25:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:25:09 INFO - Intel Iris: 07:25:09 INFO - Chipset Model: Intel Iris 07:25:09 INFO - Type: GPU 07:25:09 INFO - Bus: Built-In 07:25:09 INFO - VRAM (Dynamic, Max): 1536 MB 07:25:09 INFO - Vendor: Intel (0x8086) 07:25:09 INFO - Device ID: 0x0a2e 07:25:09 INFO - Revision ID: 0x0009 07:25:09 INFO - Displays: 07:25:09 INFO - Display: 07:25:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:25:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:25:09 INFO - Display Serial Number: 1600x1200 60 07:25:09 INFO - Main Display: Yes 07:25:09 INFO - Mirror: Off 07:25:09 INFO - Online: Yes 07:25:09 INFO - Rotation: Supported 07:25:09 INFO - Return code: 0 07:25:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:25:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:25:09 INFO - Python 2.7.3 07:25:09 INFO - Return code: 0 07:25:09 INFO - grabbing minidump binary from tooltool 07:25: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')]} 07:25:09 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 0x100ad7d30>, '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 0x1008fddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100acfb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:25:09 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 07:25:09 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 07:25:09 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 07:25:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:25:10 INFO - Return code: 0 07:25:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:25:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:25:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:25:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:25:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:25:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 07:25:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0024 --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 07:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.g8TvkroR7H/Render', 07:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:25:10 INFO - 'HOME': '/Users/cltbld', 07:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:25:10 INFO - 'LOGNAME': 'cltbld', 07:25:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:25:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:25:10 INFO - 'MOZ_NO_REMOTE': '1', 07:25:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:25:10 INFO - 'NO_EM_RESTART': '1', 07:25:10 INFO - 'PAGER': '/bin/cat', 07:25:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:25:10 INFO - 'PWD': '/builds/slave/test', 07:25:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:25:10 INFO - 'SHELL': '/bin/bash', 07:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wp1BDsrO6m/Listeners', 07:25:10 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 07:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:25:10 INFO - 'USER': 'cltbld', 07:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:25:10 INFO - 'XPC_FLAGS': '0x0', 07:25:10 INFO - 'XPC_SERVICE_NAME': '0', 07:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:25:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--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 07:25:10 INFO - mozversion application_buildid: 20161012051247 07:25:10 INFO - mozversion application_changeset: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 07:25:10 INFO - mozversion application_display_name: Nightly 07:25:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:25:10 INFO - mozversion application_name: Firefox 07:25:10 INFO - mozversion application_remotingname: firefox 07:25:10 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 07:25:10 INFO - mozversion application_vendor: Mozilla 07:25:10 INFO - mozversion application_version: 52.0a1 07:25:10 INFO - mozversion platform_buildid: 20161012051247 07:25:10 INFO - mozversion platform_changeset: 4756eb1a2f82a6fb2e7ab5e3708d14aa76816076 07:25:10 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 07:25:10 INFO - mozversion platform_version: 52.0a1 07:25:10 INFO - using testdate: 1476282310 07:25:10 INFO - actual date: 1476282310 07:25:10 INFO - starting webserver on 'localhost:49266' 07:25:10 INFO - SUITE-START | Running 2 tests 07:25:10 INFO - TEST-START | dromaeo_css 07:25:10 INFO - operating with platform_type : mac_ 07:25:10 INFO - Initialising browser for dromaeo_css test... 07:25:10 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpguy8H4/profile http://localhost:49266/getInfo.html) 07:25:12 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 07:25:12 INFO - PROCESS | 1643 | colorDepth:24 07:25:12 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 07:25:12 INFO - PROCESS | 1643 | __metrics 07:25:12 INFO - PROCESS | 1643 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:25:12 INFO - PROCESS | 1643 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:25:13 INFO - TEST-INFO | 1643: exit 0 07:25:13 INFO - Browser initialized. 07:25:13 INFO - Running cycle 1/1 for dromaeo_css test... 07:25:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpguy8H4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:25:25 INFO - PROCESS | 1645 | RSS: Main: 246079488 07:25:25 INFO - PROCESS | 1645 | 07:26:17 INFO - PROCESS | 1645 | 1476282377072 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] 07:28:30 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 07:28:32 INFO - PROCESS | 1645 | RSS: Main: 363016192 07:28:32 INFO - PROCESS | 1645 | 07:31:36 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 07:31:36 INFO - PROCESS | 1645 | RSS: Main: 372391936 07:31:36 INFO - PROCESS | 1645 | 07:34:39 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 07:34:39 INFO - PROCESS | 1645 | RSS: Main: 346595328 07:34:39 INFO - PROCESS | 1645 | 07:37:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 07:37:42 INFO - PROCESS | 1645 | RSS: Main: 345546752 07:37:42 INFO - PROCESS | 1645 | 07:40:46 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 07:40:46 INFO - PROCESS | 1645 | RSS: Main: 345808896 07:40:46 INFO - PROCESS | 1645 | 07:43:49 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 07:43:50 INFO - PROCESS | 1645 | RSS: Main: 574484480 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | __start_tp_report 07:43:50 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 07:43:50 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 07:43:50 INFO - PROCESS | 1645 | |i|pagename|runs| 07:43:50 INFO - PROCESS | 1645 | |0;dojo.html;3317.1707073231687;3318.70131688148;3336.582927134108;3368.6126095499026;3384.9153771155734;5505.146702261146;5572.4148964358765;5574.86062848429;5621.57838162138;5696.231008813809;5317.2821669074665;5605.299337582802;5725.942740572593;5750.309962804459;5783.508401785843;5037.924431133536;5126.461721519224;5156.613254005946;5158.948410515903;5180.430152683201;5248.370195576536;5249.685018898878;5276.15581506959;5282.841514754564;5331.280277162572;5096.261042148904;5154.381474223117;5232.424433312332;5243.554297884684;5264.078786212397;5112.233165025239;5159.845204643867;5163.32876726027;5184.818531351422;5198.142306519419;5136.1525348317855;5162.303089082963;5167.844964651066;5168.22476628505;5191.8182863599595;5653.180288858092;5658.12299093637;5664.660120392789;5689.260396148516;5734.856628584277;6019.729112189962;6069.787557435468;6092.3603021682975;6160.938390616081;6181.6600086995195;6121.665994010868;6136.815895523141;6171.938280617182;6258.092576576371;6267.62394256346;5074.873128171826;5486.76547776755;5734.856628584319;5743.310802703702;5785.971070144622;6145.678679084019;6222.719977601018;6239.844003899848;6281.811545653638;6315.368463153648;5893.734781934823;5955.34491205967;5959.344472108065;5986.700664966735;6025;6144.139820425144;6160.322364539897;6161.938380616226;6173.444390004921;6190.876182476325;5966.731497082642;6034.456898879122;6050.971334872994;6069.969650151721;6071.5446341523675;6047.78832740852;6118.174046503754;6133.548895741652;6141.424638644149;6142.6314421134875;4858.955324605226;4875.585575226146;4877.244027175793;4879.902401951941;4884.267359896044;4350.456192975878;4361.5856493633055;4477.888052798706;4478.57453541913;4491.393661855608;5624.268845050117;5688.345840228344;5691.516221121168;5708.0581704019205;5720.570957178228;6170.753169873155;6187.443130118221;6199.783007594712;6203.689815509207;6207.224096987877;5690.516306113944;5748.31020277561;5769.971150144306;5802.506786923159;5813.767449302065;5383.16560933056;5387.730613469311;5393.919091213635;5403.405625381205;5442.102053161184;6285.057241413824;6289.40250676185;6292.2134733158355;6296.811095667137;6308.4322410981395;6099.8545999513535;6139.2468284888055;6152.107944348134;6228.906566401417;6261.123442717932;6274.843128921813;6293.905591416224;6297.937020629918;6303.558750887394;6320;6126.172966649446;6164.198654174928;6167.938320616737;6186.072089186658;6193.318734939063;6096.451319381277;6121.8493044937295;6138.32709113608;6139.0321984548755;6178.598391104564;5964.821055368346;6053.213082299273;6057.21256236704;6079.209702738793;6119.296280927751;5999.640021598718;6001;6018.187544681412;6031.698415079316;6047.909281360871;5622.128570071645;5696.686682232517;5740.339860916049;5838.854028649318;5863.20846685692;5007.474215207497;5957.970210148921;5992.131140984446;6021.488173505301;6121.910283036266;5889.38161493037;5917.437843405042;5918.201042859159;5955.285365756137;5981.401859814157 07:43:50 INFO - PROCESS | 1645 | |1;ext.html;469.3546373736113;499.82541028582835;515.6622412319931;543.9691784069187;546.8960897429489;22031.669524957117;39202.607973920254;40673.59326406735;40744.59255407445;42559.787201063955;41739.37390939131;42069.15861682765;42088.57911420885;42116.789416052874;42147.78926105365;9408.623655053807;9619.182369498601;9624.56689448973;9627.13355797978;9634.373765705242;8577.699780507683;8775.648974041063;11271.26736762112;11600.941995290012;11602.419879006058;11498.827517587242;11578.710532236677;11580.710482237928;11612.303261804318;11705.648830535098;8511.616977236039;8714.912850871473;11504.3097414155;11547.249428787101;11584.826227606593;8618.65525378984;8675.392722509427;8686.218240358336;9499.952500237523;11168.329900205932;39105.804470977746;39375.60624393748;39382;39433;39477.01307467308;3280.1626592997122;3402.795832250048;3416.4113687135355;3645.9791258447676;3918.9804050979847;29869.850650746608;30857.69142308593;30902;31057.844710776528;31257.68742312593;3060.3726236121543;3137.921551961196;3304.7686661933676;3349.1961929137074;3356.228067544454;453022;484853.99999999645;489601;492159;492176;56024.43975560274;56215.71892140513;56253.43746562564;56416.717916410154;56507.15239271414;59156.704216479506;59240.70379648074;59271.96188323954;59299;59352.03239837991;15390.230488475532;15867.841321586637;15874.285657145456;15888.76166857498;15903.52289431319;11252.381119038531;11499.425028748528;12375.62873113808;12455.314957677414;12459.190152640049;14744.705105897821;15150.894066716297;15152.848471515365;15477.5356739298;15497.302621382065;5939.524838012949;6105.358937311607;7008.824779380557;7102.609356485443;7149.714011439588;834.874768784675;880.6785523284084;933.4119504711988;985.5466485416645;1143.3482914738686;27512.58731119035;28260.43479130406;28510;28894.7110528892;29021.85489072541;973.4889183178747;1017.5161685800975;1038.6624347087227;1041.0422411381546;1057.7408534908843;14833.18417487027;14879.553613391616;15268.923655381652;15280.388784448498;15302.387904483696;455156;456300;456738;457217;458835;53617.1957420639;53735.92528149415;53837.46162538403;53894.46105538973;53932.73033634807;1226.791445454275;1267.4930027988878;1327.3694994877355;1354.3837553912947;1370.095736813698;28841;30296.970302969527;30363.24091897632;30686.693133068384;30734.85131267886;39338;47379.526204737514;47943;47951;48055.75972120117;410538;493016;493214;493712;493734;25347.87326063358;25850.870745646152;25859.0949316773;26133.60799588046;37386.62613373831;44093.33859992038;45791.313130302406;46217.075658487316;46238.76880615576;46802.53197467982;28664.570031449963;30357.241068973453;33923.32153356968;35241.94274171779;38910.80544597259;33008;38037;38486.80756596199;38944.80527597344;39308;36503.817480912425;36958.44562331621;37581;37733.4339984906;37935;32291.218844043015;34963.65036349706;34964.825175874976;35102.12244693903;35202.722256040244;21406;24294.14970476025;24948.875255623607;25008.49983000366;25273.368165796 07:43:50 INFO - PROCESS | 1645 | |2;jquery.html;352.44841822547727;371.8178092734561;372.11065553327546;380.93904975203975;404.8097394224715;14764.261786910653;15140.84859151408;15665.45170919018;16488.67022659546;20532.589348213027;19393.903030484864;20002.799972000314;20565.177392904265;20713.68929466059;20938.37184884456;5820.883582328351;5941.836948183279;6136.785212517563;6145.446909778119;6153.723082461278;4679.321498382733;6033.456988871001;6068;6094.8781024379705;6106.694665266741;5880.26496687914;5938.228030356069;6045.063015232646;6048.576599638027;6058.454739073504;5876.29484461863;6029.336772954972;6029.487493563074;6032.004719221323;6037.426444487766;5051.646384753069;5223.216517522402;5230.398504172031;5327.920081198785;5390.272313237702;12971.41628626714;13282.73434531304;13291.5379308276;15567.532974010797;24995.62506562403;2066.987176283625;2311.9653205201935;2338.3452633262714;2359.5634807560655;2862.398896231773;9884.308098433112;10038.640919383939;10174.593016279414;10333.121684656813;12980.831725144688;1705.2326453096032;1811.7554130192386;1816.0693716559604;1830.6613276543883;1868.5422071592548;122518;124984;127974;131192.34403828016;132287;9250.491222982666;11374.691910430241;11686.649400517997;11731.829661325119;11983.16117871758;11917.34454605005;14386.208758518173;14427;14427.278636068364;14436;8166.673333066729;8470.237678608955;8473.449225800427;8613.827723445496;8629.654813807503;8442.57787110642;8448.957755211184;8450.830983380298;8637.730984099695;8644.178803013703;4343.19650864591;4368.912621747547;4466.9553304466535;4547.977260113678;4564.338170965191;1590.6739118480684;1811.3660218923271;1874.0229058343307;1876.498800959227;1876.7654043244595;869.3523325122824;874.3136637739344;920.9125133112343;928.3362395886974;962.8317178605356;19998.90000550017;20898.47753806167;20916.47708807292;20960.685589716166;21553.461163471038;879.090141703334;882.2809410330689;911.1526280559144;915.1854849184232;955.19763398744;10579.84130238047;10589.364638121737;10838.620648277272;11066.225364224427;11148.665540033811;420794;522694;605620.9718951377;627791;630380;95769.38176515613;126687.73312266944;129506;133720;136881.3155934214;789.2896393246125;954.179405711088;1002.9348092374119;1007.3049595778889;1011.2668315471225;19102.808971910657;19379;19627.60744785125;19633.803661963197;19634.50913727111;51344.716382090744;54958.62603434946;57121.71439142944;57410;57795.42204578069;74276.62861685872;76905.23094768981;77526.83709744246;78954.21045789469;81202;7654.540727556365;7680.116786569616;7841.5687137208;8016.39877009215;8317.708880189164;10559.574457448454;12707.745845082862;12894.548690795777;13141.474341026253;13188.208707477583;10753.838692419464;10896.727581810519;10961.342319460859;11328.603498877499;11517.539298428304;8636.524991125549;10731.40977246259;10860.185486088418;11229.71925701864;11370.374629395132;11163.44182790841;11307.886921130683;11339.886601134212;11473;11851.466683999068;7737.684593619211;7863.29204525119;7952.681892724227;8231.506109633441;8330.333573314;7138;10726.249162558546;10917.836232456679;11284.717882053013;11435.771284574428 07:43:50 INFO - PROCESS | 1645 | |3;mootools.html;1371.759942010148;1415.8301003879535;1476.8080149580553;1755.5874369523167;1788.7138057910736;8162.7143049993265;8660.913390866097;8666.090060543647;8669.046404895473;8684.175003374678;7513.699452021912;7858.410619203554;8193.754187374388;8514.48913065215;8520.744377668665;2296.2537175418015;2322.3033090072995;2324.244620498345;2325.232673217834;2329.429289823987;2287.942801429969;2295.116380193621;2331.5103828196097;2331.813454923602;2336.754640762721;2036.2669439001947;2110.862793918398;2121.5756848630335;2245.404967683557;2330.568844763716;2313.6066868632374;2323.94190145246;2325.4535184231777;2327.464683122891;2335.4161459635093;2299.2642354446584;2313.6760853480528;2313.803326717214;2326.4183954011496;2333.1600623775425;8471.195236452528;8678.871746672956;8685.869711954325;8686.348523860735;8730.519821409884;4332.761698106603;5235.55497782689;5247.658902171346;5252.71110088945;5255.369355677343;2867.526858068416;3402.4215883299653;3466.497357883118;3478.669526394988;3490.8254587270535;987.3335498538457;1048.7535429174138;1053.462734005655;1752.8948263104128;1827.232562323827;11194.66416007521;11197.328160310408;11292.322460652387;11294.20940534163;11308.604198853001;4229.534751177368;4297.247981603207;4456.6211871991245;4458.71018383804;4466.486354069324;8808.20726134651;8808.25129863956;8816.338774591932;8829.911700883038;8836.469811811312;5092.821751238763;5094.267948897319;5099.4390617032095;5100.413452452941;5105.361829771278;4552.704074235164;4673.976630116827;4794.664373493892;4802.903941921142;4805.711657300573;3197.7601679873637;3481.2515309208643;3499.492573576868;3506.368853606324;3582.444721068239;1101.5098281264761;1103.2994048779083;1107.4850194659523;1120.8374785656035;1125.3754166437707;758.12058012705;840.8528507511161;876.5660997806127;886.2378354615026;941.30343545777;4339.20934598932;5507.201455788888;5815.447532484492;5825.300963884361;5927.170196172539;765.7855800375904;935.653808090997;938.3666025432942;944.3767113704922;963.4989805301344;3791.0711875590655;3837.9616203838164;3858.7298889078056;3953.2884080865147;3958.762474251554;8662.740117796477;10614.257002009785;10616.991385818335;10633.5214915329;10645.361278323171;10476.161907047417;10594.364338139736;10596.205284603686;10610.946945265225;10617.309874858109;7599.924000760032;7863.13505514393;8049.919500805034;8080.757577272691;8184.713535026364;5825.2135961644635;5843.795467158629;5922.466977972003;5992.370801065826;6067.726952287069;9710.902890971283;9714.805703886024;9783.80432391334;9960.950195248977;9967.651132210338;9544.618215271312;9892.554835032297;9947.850782238416;10112.949435252776;10122.696319110144;7038.542494738031;7307.817304567216;7378.188399275968;7467.551946883204;7468.141163766019;8034.959825200836;8077.63650635711;8157;8160.387970902088;8164;6782.457403407814;6989.790306290819;7072.292770722886;7129.180144283474;7154.141503019671;7000.144384201487;7104.18301895268;7136.3220494052985;7235.34881860614;7281.635918203962;6793.490488213502;6816.0457535944015;6885.173779146535;6898.827529311808;6909.861802764017;4861.586765125004;4891.1685013548495;4904.901901961869;4939.333190019302;5042.697438153723;4873.220284754422;4893.486183950777;4951.158303088556;4990.076835785391;5035.546800787946 07:43:50 INFO - PROCESS | 1645 | |4;prototype.html;280.8623774350569;297.80642582321474;298.09825278532435;305.691251835646;311;5103.387593488782;5196.324477817883;5337.2261022151815;5403.78384864606;5540.833774986757;5021.874453138674;5197.948020519803;5374.811881584145;5395.460453954603;5412.809181979958;3632.400653892105;4091.8977025574377;4107.466029416171;4123.381492776092;4175.206710724968;3360.958102988069;3688.6680198782105;3983.1635356575007;4023.9738866588978;4025.778582177979;3310.874302737069;3960.5049368828895;4012.4382586437923;4059.219434687234;4092.5907409259135;4062.9187416251657;4072.6945479089186;4075.3886916962397;4078.551359350484;4084.9591504085174;3431.5024321473247;3561.216532362876;3570.517980072683;3589.3898037333715;3596.6403359663823;5063.8480845574695;5123.38519377677;5146.742662866879;5170.715610641413;5226.346706661668;3919.862804801847;4198.622124008823;4415.514293427753;4671.43942726875;4692.483826779052;5015.423226328946;5336.212368856438;5583.58123140762;5609.747561359749;5686.2039314496005;3427.8628854846024;3615.1504396466808;3873.612638736132;3877.7479463835043;3878.340682084024;5652.2869022868845;5811.128330750421;5851.385604511507;5876.382979787147;5898.321693005273;3793.79134147619;3973.1854969731135;3984.2629113614075;3994.0014996250356;4031.576684448091;3254.772165948408;3399.2861499084975;4002.358443427324;4005.859794907164;4027.3153563894207;4334.674899382559;4508.542844149946;4512.661550383668;4530.864074077783;4626.86119416418;4350.847720329836;4459.620932220732;5569.582281328917;5651.66090034591;5664.8867022659315;1194.9163558550993;1260.155695683894;1359.34071975092;1386.9583912482642;1396.8393634731933;815.9143289954469;838.9119142490073;850.4982060584161;853.4068822168533;867.2195024477894;3942.0539070622845;4079.5104587449696;4207.453031105937;4446.244138496463;4453.35225966388;4263.445752052214;4383;4497.8200871964755;4539.094979213306;4834.347363106006;3946.4869566956113;4138.275801734684;4250.638695710837;4393.275109606943;4527.162474942146;2393.80849532037;2444.963325550118;2472.1347528364927;2515.91179321163;2597.8830952607177;4282.828686852491;4735.384400027974;4998.650634201919;5003.5997120230295;5004.795013186245;4364.363363738598;4380.956190438119;4625.398698169284;4854.994629430734;4930.605551555867;4759.50025247351;4930.482299358516;4998.375203099613;5039.319691841701;5043.9747801260755;4295.957040429681;4704.647151463722;4766.6901651392545;4904.901901961869;4914.606831453547;3810.7234076583545;4048.0487085534287;4436.223660859272;4702.388689470347;4766.737829419277;4131.710780245354;4403.8458653946955;4492.752898590609;4974.7512624369365;5020.3724534433195;2895.8552072395873;3189.7767156298837;3267.8954992756503;3398.49022646605;3405;3955.288048151245;4517.367568540341;4751.717036400083;4769.689970151929;5104.983531505109;4962.776675049559;4991.550760431579;5437.574328173384;5525;5678.630888992202;4729.716217026989;5615.747291371817;5633.943660563506;5643.689597072201;5691.402402747817;4837.992233223173;5182.792688292426;5238.869028274171;5487.75305111279;5503.881005812871;5371.636479948967;5399;5492.231087647652;5595.356533998643;5610.7194640268635;5481.780728770805;5528.364238112668;5555.555555555504;5583.269186552182;5593.916091258553 07:43:50 INFO - PROCESS | 1645 | |5;yui.html;595.9314678811936;733.4059411876384;769.0771074710349;785.3010820369868;801.3428988229653;14808.703825923489;15156.848431515682;15741.60645983851;15982.360705571764;16055.678886422294;14046.859531404683;14359.569212923603;14365.281735913215;14393.136411815298;14397.568072957802;829.1915382502058;847.4576271186446;847.9576021198947;850.0776657326808;852.1393392673414;682.5426963934151;700.8002719225021;700.9228984811666;823.9258466737992;849.307814131484;885.3094586222757;892.5492626223748;893.5710858787786;893.8703887936246;895.073598825216;883.5582208895552;887.0419946457843;887.2458410351214;888.7244954064261;889.0575989451203;527.6174773289334;540.5567434703545;658.4140115297389;659.0838734159522;659.9439047680953;11141.275817071946;14537.709245815131;14595.124292542481;14874.181919994397;14890.329935152835;14117.011809173362;14641.341139648635;14665.780013299809;14721.852781472262;14836.33236504349;11822.349770762605;11841.644750657493;11912.285262884254;12105.455254513568;12300.507979680802;3705.4256590228556;3733.757305775143;3772.830222639988;3779.5653499847595;3896.8051597420017;114938;117731.2340314896;117889;118676;120019;10691.465426728633;10727.946360268304;11052;11223.214374993671;11436.885631143748;22670.319890403316;22819;22823.885880570822;22857.428564285692;22892.542149156925;7640.503367281109;7941.444098913021;8005.359571234288;8007.559584222924;8088.150744171895;3720.4419337099653;3800.2969450651717;3866.478025466582;3970.4441378207075;3980.44273801668;3481.70405515534;3579.6599323064265;3603.7837729736298;3609.242059167552;3610.115521697201;615.5844804756771;615.6306216270292;622.3714048810734;637.1812221295705;638.8850006998692;651.2965996723622;652.422605993699;656.947444204472;667.3726696904894;676.1413005483107;7883.526988380716;8843.46939183651;8846.95576522126;9297.72106836796;9436.292278079172;634.1217413881715;661.9602823830586;666.5771264556537;680.9659517024129;681.6285124607101;3801.334766415866;4119.848316038233;4544.727316361029;4664.440267167962;4698.201305778026;118866.40566797284;133988.3300583491;140002;155539.22230388774;171369.57880264966;119002.4049879745;120702;121215;122517.38741306415;125178;81546;94114.99999999863;95686;100733;101894;9211.525575161832;9560.28297877648;9567;9592.95203523978;9801.657991898968;53039.46960530345;56898.7155064222;59474.70262648659;59709;62584;74711;89271;92091;93371.59942601003;93587.53206233925;12306.630801075982;12578.55975040869;12714.427850746553;13055.869441305465;13127.949764018664;12877.8068328977;13887;14416.495422660157;14700.632484187554;14820;13245.668858278621;13275.40260688252;13351.599452016455;13417.865821341662;14069.507567235098;13362.933185334012;13516.053876228569;13632.863671363557;13722.794158087836;13982.525576879832;12332.691682708004;12370.381480926098;12451.626451206479;12530.937345313216;12901.032422568533;10815.119644876655;11141.442927853737;11192.272502287326;11365.31808091517;11602.6519204424;9787.902120978699;10748.462576871281;11213.887861121611;11381.089512838787;11489.482973266056 07:43:50 INFO - PROCESS | 1645 | __end_tp_report 07:43:50 INFO - PROCESS | 1645 | __start_cc_report 07:43:50 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1929 07:43:50 INFO - PROCESS | 1645 | __end_cc_report 07:43:50 INFO - PROCESS | 1645 | __startTimestamp1476283430090__endTimestamp 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | ------- Summary: start ------- 07:43:50 INFO - PROCESS | 1645 | Number of tests: 6 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5684.41 Median:5893.73 stddev:623.79 (10.6%) stddev-sans-first:597.43 07:43:50 INFO - PROCESS | 1645 | Values: 3317.2 3318.7 3336.6 3368.6 3384.9 5505.1 5572.4 5574.9 5621.6 5696.2 5317.3 5605.3 5725.9 5750.3 5783.5 5037.9 5126.5 5156.6 5158.9 5180.4 5248.4 5249.7 5276.2 5282.8 5331.3 5096.3 5154.4 5232.4 5243.6 5264.1 5112.2 5159.8 5163.3 5184.8 5198.1 5136.2 5162.3 5167.8 5168.2 5191.8 5653.2 5658.1 5664.7 5689.3 5734.9 6019.7 6069.8 6092.4 6160.9 6181.7 6121.7 6136.8 6171.9 6258.1 6267.6 5074.9 5486.8 5734.9 5743.3 5786.0 6145.7 6222.7 6239.8 6281.8 6315.4 5893.7 5955.3 5959.3 5986.7 6025.0 6144.1 6160.3 6161.9 6173.4 6190.9 5966.7 6034.5 6051.0 6070.0 6071.5 6047.8 6118.2 6133.5 6141.4 6142.6 4859.0 4875.6 4877.2 4879.9 4884.3 4350.5 4361.6 4477.9 4478.6 4491.4 5624.3 5688.3 5691.5 5708.1 5720.6 6170.8 6187.4 6199.8 6203.7 6207.2 5690.5 5748.3 5770.0 5802.5 5813.8 5383.2 5387.7 5393.9 5403.4 5442.1 6285.1 6289.4 6292.2 6296.8 6308.4 6099.9 6139.2 6152.1 6228.9 6261.1 6274.8 6293.9 6297.9 6303.6 6320.0 6126.2 6164.2 6167.9 6186.1 6193.3 6096.5 6121.8 6138.3 6139.0 6178.6 5964.8 6053.2 6057.2 6079.2 6119.3 5999.6 6001.0 6018.2 6031.7 6047.9 5622.1 5696.7 5740.3 5838.9 5863.2 5007.5 5958.0 5992.1 6021.5 6121.9 5889.4 5917.4 5918.2 5955.3 5981.4 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:61303.73 Median:37834.22 stddev:125517.17 (331.8%) stddev-sans-first:125786.17 07:43:50 INFO - PROCESS | 1645 | Values: 469.4 499.8 515.7 544.0 546.9 22031.7 39202.6 40673.6 40744.6 42559.8 41739.4 42069.2 42088.6 42116.8 42147.8 9408.6 9619.2 9624.6 9627.1 9634.4 8577.7 8775.6 11271.3 11600.9 11602.4 11498.8 11578.7 11580.7 11612.3 11705.6 8511.6 8714.9 11504.3 11547.2 11584.8 8618.7 8675.4 8686.2 9500.0 11168.3 39105.8 39375.6 39382.0 39433.0 39477.0 3280.2 3402.8 3416.4 3646.0 3919.0 29869.9 30857.7 30902.0 31057.8 31257.7 3060.4 3137.9 3304.8 3349.2 3356.2 453022.0 484854.0 489601.0 492159.0 492176.0 56024.4 56215.7 56253.4 56416.7 56507.2 59156.7 59240.7 59272.0 59299.0 59352.0 15390.2 15867.8 15874.3 15888.8 15903.5 11252.4 11499.4 12375.6 12455.3 12459.2 14744.7 15150.9 15152.8 15477.5 15497.3 5939.5 6105.4 7008.8 7102.6 7149.7 834.9 880.7 933.4 985.5 1143.3 27512.6 28260.4 28510.0 28894.7 29021.9 973.5 1017.5 1038.7 1041.0 1057.7 14833.2 14879.6 15268.9 15280.4 15302.4 455156.0 456300.0 456738.0 457217.0 458835.0 53617.2 53735.9 53837.5 53894.5 53932.7 1226.8 1267.5 1327.4 1354.4 1370.1 28841.0 30297.0 30363.2 30686.7 30734.9 39338.0 47379.5 47943.0 47951.0 48055.8 410538.0 493016.0 493214.0 493712.0 493734.0 25347.9 25850.9 25859.1 26133.6 37386.6 44093.3 45791.3 46217.1 46238.8 46802.5 28664.6 30357.2 33923.3 35241.9 38910.8 33008.0 38037.0 38486.8 38944.8 39308.0 36503.8 36958.4 37581.0 37733.4 37935.0 32291.2 34963.7 34964.8 35102.1 35202.7 21406.0 24294.1 24948.9 25008.5 25273.4 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33850.04 Median:13677.59 stddev:95299.92 (696.8%) stddev-sans-first:95534.08 07:43:50 INFO - PROCESS | 1645 | Values: 352.4 371.8 372.1 380.9 404.8 14764.3 15140.8 15665.5 16488.7 20532.6 19393.9 20002.8 20565.2 20713.7 20938.4 5820.9 5941.8 6136.8 6145.4 6153.7 4679.3 6033.5 6068.0 6094.9 6106.7 5880.3 5938.2 6045.1 6048.6 6058.5 5876.3 6029.3 6029.5 6032.0 6037.4 5051.6 5223.2 5230.4 5327.9 5390.3 12971.4 13282.7 13291.5 15567.5 24995.6 2067.0 2312.0 2338.3 2359.6 2862.4 9884.3 10038.6 10174.6 10333.1 12980.8 1705.2 1811.8 1816.1 1830.7 1868.5 122518.0 124984.0 127974.0 131192.3 132287.0 9250.5 11374.7 11686.6 11731.8 11983.2 11917.3 14386.2 14427.0 14427.3 14436.0 8166.7 8470.2 8473.4 8613.8 8629.7 8442.6 8449.0 8450.8 8637.7 8644.2 4343.2 4368.9 4467.0 4548.0 4564.3 1590.7 1811.4 1874.0 1876.5 1876.8 869.4 874.3 920.9 928.3 962.8 19998.9 20898.5 20916.5 20960.7 21553.5 879.1 882.3 911.2 915.2 955.2 10579.8 10589.4 10838.6 11066.2 11148.7 420794.0 522694.0 605621.0 627791.0 630380.0 95769.4 126687.7 129506.0 133720.0 136881.3 789.3 954.2 1002.9 1007.3 1011.3 19102.8 19379.0 19627.6 19633.8 19634.5 51344.7 54958.6 57121.7 57410.0 57795.4 74276.6 76905.2 77526.8 78954.2 81202.0 7654.5 7680.1 7841.6 8016.4 8317.7 10559.6 12707.7 12894.5 13141.5 13188.2 10753.8 10896.7 10961.3 11328.6 11517.5 8636.5 10731.4 10860.2 11229.7 11370.4 11163.4 11307.9 11339.9 11473.0 11851.5 7737.7 7863.3 7952.7 8231.5 8330.3 7138.0 10726.2 10917.8 11284.7 11435.8 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5524.63 Median:5039.12 stddev:3093.30 (61.4%) stddev-sans-first:3086.24 07:43:50 INFO - PROCESS | 1645 | Values: 1371.8 1415.8 1476.8 1755.6 1788.7 8162.7 8660.9 8666.1 8669.0 8684.2 7513.7 7858.4 8193.8 8514.5 8520.7 2296.3 2322.3 2324.2 2325.2 2329.4 2287.9 2295.1 2331.5 2331.8 2336.8 2036.3 2110.9 2121.6 2245.4 2330.6 2313.6 2323.9 2325.5 2327.5 2335.4 2299.3 2313.7 2313.8 2326.4 2333.2 8471.2 8678.9 8685.9 8686.3 8730.5 4332.8 5235.6 5247.7 5252.7 5255.4 2867.5 3402.4 3466.5 3478.7 3490.8 987.3 1048.8 1053.5 1752.9 1827.2 11194.7 11197.3 11292.3 11294.2 11308.6 4229.5 4297.2 4456.6 4458.7 4466.5 8808.2 8808.3 8816.3 8829.9 8836.5 5092.8 5094.3 5099.4 5100.4 5105.4 4552.7 4674.0 4794.7 4802.9 4805.7 3197.8 3481.3 3499.5 3506.4 3582.4 1101.5 1103.3 1107.5 1120.8 1125.4 758.1 840.9 876.6 886.2 941.3 4339.2 5507.2 5815.4 5825.3 5927.2 765.8 935.7 938.4 944.4 963.5 3791.1 3838.0 3858.7 3953.3 3958.8 8662.7 10614.3 10617.0 10633.5 10645.4 10476.2 10594.4 10596.2 10610.9 10617.3 7599.9 7863.1 8049.9 8080.8 8184.7 5825.2 5843.8 5922.5 5992.4 6067.7 9710.9 9714.8 9783.8 9961.0 9967.7 9544.6 9892.6 9947.9 10112.9 10122.7 7038.5 7307.8 7378.2 7467.6 7468.1 8035.0 8077.6 8157.0 8160.4 8164.0 6782.5 6989.8 7072.3 7129.2 7154.1 7000.1 7104.2 7136.3 7235.3 7281.6 6793.5 6816.0 6885.2 6898.8 6909.9 4861.6 4891.2 4904.9 4939.3 5042.7 4873.2 4893.5 4951.2 4990.1 5035.5 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4242.54 Median:4515.01 stddev:1289.61 (28.6%) stddev-sans-first:1258.48 07:43:50 INFO - PROCESS | 1645 | Values: 280.9 297.8 298.1 305.7 311.0 5103.4 5196.3 5337.2 5403.8 5540.8 5021.9 5197.9 5374.8 5395.5 5412.8 3632.4 4091.9 4107.5 4123.4 4175.2 3361.0 3688.7 3983.2 4024.0 4025.8 3310.9 3960.5 4012.4 4059.2 4092.6 4062.9 4072.7 4075.4 4078.6 4085.0 3431.5 3561.2 3570.5 3589.4 3596.6 5063.8 5123.4 5146.7 5170.7 5226.3 3919.9 4198.6 4415.5 4671.4 4692.5 5015.4 5336.2 5583.6 5609.7 5686.2 3427.9 3615.2 3873.6 3877.7 3878.3 5652.3 5811.1 5851.4 5876.4 5898.3 3793.8 3973.2 3984.3 3994.0 4031.6 3254.8 3399.3 4002.4 4005.9 4027.3 4334.7 4508.5 4512.7 4530.9 4626.9 4350.8 4459.6 5569.6 5651.7 5664.9 1194.9 1260.2 1359.3 1387.0 1396.8 815.9 838.9 850.5 853.4 867.2 3942.1 4079.5 4207.5 4446.2 4453.4 4263.4 4383.0 4497.8 4539.1 4834.3 3946.5 4138.3 4250.6 4393.3 4527.2 2393.8 2445.0 2472.1 2515.9 2597.9 4282.8 4735.4 4998.7 5003.6 5004.8 4364.4 4381.0 4625.4 4855.0 4930.6 4759.5 4930.5 4998.4 5039.3 5044.0 4296.0 4704.6 4766.7 4904.9 4914.6 3810.7 4048.0 4436.2 4702.4 4766.7 4131.7 4403.8 4492.8 4974.8 5020.4 2895.9 3189.8 3267.9 3398.5 3405.0 3955.3 4517.4 4751.7 4769.7 5105.0 4962.8 4991.6 5437.6 5525.0 5678.6 4729.7 5615.7 5633.9 5643.7 5691.4 4838.0 5182.8 5238.9 5487.8 5503.9 5371.6 5399.0 5492.2 5595.4 5610.7 5481.8 5528.4 5555.6 5583.3 5593.9 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:24079.29 Median:3657.77 stddev:38228.57 (1045.1%) stddev-sans-first:38295.15 07:43:50 INFO - PROCESS | 1645 | Values: 595.9 733.4 769.1 785.3 801.3 14808.7 15156.8 15741.6 15982.4 16055.7 14046.9 14359.6 14365.3 14393.1 14397.6 829.2 847.5 848.0 850.1 852.1 682.5 700.8 700.9 823.9 849.3 885.3 892.5 893.6 893.9 895.1 883.6 887.0 887.2 888.7 889.1 527.6 540.6 658.4 659.1 659.9 11141.3 14537.7 14595.1 14874.2 14890.3 14117.0 14641.3 14665.8 14721.9 14836.3 11822.3 11841.6 11912.3 12105.5 12300.5 3705.4 3733.8 3772.8 3779.6 3896.8 114938.0 117731.2 117889.0 118676.0 120019.0 10691.5 10727.9 11052.0 11223.2 11436.9 22670.3 22819.0 22823.9 22857.4 22892.5 7640.5 7941.4 8005.4 8007.6 8088.2 3720.4 3800.3 3866.5 3970.4 3980.4 3481.7 3579.7 3603.8 3609.2 3610.1 615.6 615.6 622.4 637.2 638.9 651.3 652.4 656.9 667.4 676.1 7883.5 8843.5 8847.0 9297.7 9436.3 634.1 662.0 666.6 681.0 681.6 3801.3 4119.8 4544.7 4664.4 4698.2 118866.4 133988.3 140002.0 155539.2 171369.6 119002.4 120702.0 121215.0 122517.4 125178.0 81546.0 94115.0 95686.0 100733.0 101894.0 9211.5 9560.3 9567.0 9593.0 9801.7 53039.5 56898.7 59474.7 59709.0 62584.0 74711.0 89271.0 92091.0 93371.6 93587.5 12306.6 12578.6 12714.4 13055.9 13127.9 12877.8 13887.0 14416.5 14700.6 14820.0 13245.7 13275.4 13351.6 13417.9 14069.5 13362.9 13516.1 13632.9 13722.8 13982.5 12332.7 12370.4 12451.6 12530.9 12901.0 10815.1 11141.4 11192.3 11365.3 11602.7 9787.9 10748.5 11213.9 11381.1 11489.5 07:43:50 INFO - PROCESS | 1645 | -------- Summary: end -------- 07:43:50 INFO - PROCESS | 1645 | 07:43:50 INFO - TEST-INFO | 1645: exit 0 07:43:50 INFO - TEST-OK | dromaeo_css | took 1120600ms 07:43:50 INFO - TEST-START | kraken 07:43:50 INFO - operating with platform_type : mac_ 07:43:50 INFO - Initialising browser for kraken test... 07:43:50 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpZtDypR/profile http://localhost:49266/getInfo.html) 07:43:53 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 07:43:53 INFO - PROCESS | 1656 | colorDepth:24 07:43:53 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 07:43:53 INFO - PROCESS | 1656 | __metrics 07:43:53 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 07:43:53 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:43:53 INFO - TEST-INFO | 1656: exit 0 07:43:53 INFO - Browser initialized. 07:43:53 INFO - Running cycle 1/1 for kraken test... 07:43:53 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpZtDypR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:44:05 INFO - PROCESS | 1657 | RSS: Main: 245485568 07:44:05 INFO - PROCESS | 1657 | 07:44:08 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 07:44:09 INFO - PROCESS | 1657 | RSS: Main: 295882752 07:44:09 INFO - PROCESS | 1657 | 07:44:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 07:44:12 INFO - PROCESS | 1657 | RSS: Main: 295972864 07:44:12 INFO - PROCESS | 1657 | 07:44:16 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 07:44:16 INFO - PROCESS | 1657 | RSS: Main: 386334720 07:44:16 INFO - PROCESS | 1657 | 07:44:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 07:44:19 INFO - PROCESS | 1657 | RSS: Main: 288522240 07:44:19 INFO - PROCESS | 1657 | 07:44:21 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:44:22 INFO - PROCESS | 1657 | RSS: Main: 292933632 07:44:22 INFO - PROCESS | 1657 | 07:44:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 07:44:25 INFO - PROCESS | 1657 | RSS: Main: 329109504 07:44:25 INFO - PROCESS | 1657 | 07:44:28 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 07:44:29 INFO - PROCESS | 1657 | RSS: Main: 331350016 07:44:29 INFO - PROCESS | 1657 | 07:44:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 07:44:32 INFO - PROCESS | 1657 | RSS: Main: 330379264 07:44:32 INFO - PROCESS | 1657 | 07:44:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:44:34 INFO - PROCESS | 1657 | RSS: Main: 301613056 07:44:34 INFO - PROCESS | 1657 | 07:44:36 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:44:37 INFO - PROCESS | 1657 | RSS: Main: 306126848 07:44:37 INFO - PROCESS | 1657 | 07:44:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:44:40 INFO - PROCESS | 1657 | RSS: Main: 313180160 07:44:40 INFO - PROCESS | 1657 | 07:44:43 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:44:43 INFO - PROCESS | 1657 | RSS: Main: 327757824 07:44:43 INFO - PROCESS | 1657 | 07:44:46 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:44:47 INFO - PROCESS | 1657 | RSS: Main: 321019904 07:44:47 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 07:44:49 INFO - PROCESS | 1657 | RSS: Main: 365412352 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | __start_tp_report 07:44:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 07:44:49 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 07:44:49 INFO - PROCESS | 1657 | |i|pagename|runs| 07:44:49 INFO - PROCESS | 1657 | |0;ai-astar;113;114;113;113;113;113;113;110;112;112 07:44:49 INFO - PROCESS | 1657 | |1;audio-beat-detection;147;111;118;149;114;111;118;144;178;114 07:44:49 INFO - PROCESS | 1657 | |2;audio-dft;183;184;185;182;186;183;182;181;219;238 07:44:49 INFO - PROCESS | 1657 | |3;audio-fft;84;75;85;113;60;58;59;58;83;58 07:44:49 INFO - PROCESS | 1657 | |4;audio-oscillator;85;86;125;102;77;68;92;68;68;89 07:44:49 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;94;89;91;90;90;91;90;91;94;87 07:44:49 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;93;96;95;94;95;94;95;94;95 07:44:49 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;87;86;86;85;84;84;83;85;84 07:44:49 INFO - PROCESS | 1657 | |8;json-parse-financial;56;53;53;51;54;54;54;54;53;55 07:44:49 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;64;68;80;76;71;70;69;70;70;71 07:44:49 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;89;86;87;88;87;85;85;86;87;89 07:44:49 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;146;146;143;142;158;156;161;147;178;166 07:44:49 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;205;192;172;178;173;173;172;170;172;176 07:44:49 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;69;69;69;69;67;67;67;67;70 07:44:49 INFO - PROCESS | 1657 | __end_tp_report 07:44:49 INFO - PROCESS | 1657 | __start_cc_report 07:44:49 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2072 07:44:49 INFO - PROCESS | 1657 | __end_cc_report 07:44:49 INFO - PROCESS | 1657 | __startTimestamp1476283489467__endTimestamp 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | ------- Summary: start ------- 07:44:49 INFO - PROCESS | 1657 | Number of tests: 14 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:112.60 Median:113.00 stddev:1.07 (1.0%) stddev-sans-first:1.13 07:44:49 INFO - PROCESS | 1657 | Values: 113.0 114.0 113.0 113.0 113.0 113.0 113.0 110.0 112.0 112.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:131.00 stddev:22.78 (17.4%) stddev-sans-first:23.36 07:44:49 INFO - PROCESS | 1657 | Values: 147.0 111.0 118.0 149.0 114.0 111.0 118.0 144.0 178.0 114.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:192.30 Median:184.50 stddev:19.65 (10.7%) stddev-sans-first:20.55 07:44:49 INFO - PROCESS | 1657 | Values: 183.0 184.0 185.0 182.0 186.0 183.0 182.0 181.0 219.0 238.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:73.30 Median:67.50 stddev:18.28 (27.1%) stddev-sans-first:18.98 07:44:49 INFO - PROCESS | 1657 | Values: 84.0 75.0 85.0 113.0 60.0 58.0 59.0 58.0 83.0 58.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:86.00 Median:81.00 stddev:17.88 (22.1%) stddev-sans-first:18.96 07:44:49 INFO - PROCESS | 1657 | Values: 85.0 86.0 125.0 102.0 77.0 68.0 92.0 68.0 68.0 89.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:90.70 Median:91.00 stddev:2.11 (2.3%) stddev-sans-first:1.87 07:44:49 INFO - PROCESS | 1657 | Values: 94.0 89.0 91.0 90.0 90.0 91.0 90.0 91.0 94.0 87.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 07:44:49 INFO - PROCESS | 1657 | Values: 94.0 93.0 96.0 95.0 94.0 95.0 94.0 95.0 94.0 95.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:84.90 Median:85.00 stddev:1.20 (1.4%) stddev-sans-first:1.27 07:44:49 INFO - PROCESS | 1657 | Values: 85.0 87.0 86.0 86.0 85.0 84.0 84.0 83.0 85.0 84.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:54.00 stddev:1.34 (2.5%) stddev-sans-first:1.13 07:44:49 INFO - PROCESS | 1657 | Values: 56.0 53.0 53.0 51.0 54.0 54.0 54.0 54.0 53.0 55.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:4.36 (6.2%) stddev-sans-first:3.84 07:44:49 INFO - PROCESS | 1657 | Values: 64.0 68.0 80.0 76.0 71.0 70.0 69.0 70.0 70.0 71.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:86.90 Median:87.00 stddev:1.45 (1.7%) stddev-sans-first:1.32 07:44:49 INFO - PROCESS | 1657 | Values: 89.0 86.0 87.0 88.0 87.0 85.0 85.0 86.0 87.0 89.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:157.00 stddev:11.69 (7.4%) stddev-sans-first:12.01 07:44:49 INFO - PROCESS | 1657 | Values: 146.0 146.0 143.0 142.0 158.0 156.0 161.0 147.0 178.0 166.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.30 Median:174.50 stddev:11.30 (6.5%) stddev-sans-first:6.69 07:44:49 INFO - PROCESS | 1657 | Values: 205.0 192.0 172.0 178.0 173.0 173.0 172.0 170.0 172.0 176.0 07:44:49 INFO - PROCESS | 1657 | 07:44:49 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.00 stddev:1.14 (1.6%) stddev-sans-first:1.20 07:44:49 INFO - PROCESS | 1657 | Values: 68.0 69.0 69.0 69.0 69.0 67.0 67.0 67.0 67.0 70.0 07:44:49 INFO - PROCESS | 1657 | -------- Summary: end -------- 07:44:49 INFO - PROCESS | 1657 | 07:44:50 INFO - TEST-INFO | 1657: exit 0 07:44:50 INFO - TEST-OK | kraken | took 59610ms 07:44:50 INFO - SUITE-END | took 1180s 07:44:50 INFO - Completed test suite (00:19:40) 07:44:50 INFO - javascript benchmark 07:44:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.1707073231687, 3318.70131688148, 3336.582927134108, 3368.6126095499026, 3384.9153771155734, 5505.146702261146, 5572.4148964358765, 5574.86062848429, 5621.57838162138, 5696.231008813809, 5317.2821669074665, 5605.299337582802, 5725.942740572593, 5750.309962804459, 5783.508401785843, 5037.924431133536, 5126.461721519224, 5156.613254005946, 5158.948410515903, 5180.430152683201, 5248.370195576536, 5249.685018898878, 5276.15581506959, 5282.841514754564, 5331.280277162572, 5096.261042148904, 5154.381474223117, 5232.424433312332, 5243.554297884684, 5264.078786212397, 5112.233165025239, 5159.845204643867, 5163.32876726027, 5184.818531351422, 5198.142306519419, 5136.1525348317855, 5162.303089082963, 5167.844964651066, 5168.22476628505, 5191.8182863599595, 5653.180288858092, 5658.12299093637, 5664.660120392789, 5689.260396148516, 5734.856628584277, 6019.729112189962, 6069.787557435468, 6092.3603021682975, 6160.938390616081, 6181.6600086995195, 6121.665994010868, 6136.815895523141, 6171.938280617182, 6258.092576576371, 6267.62394256346, 5074.873128171826, 5486.76547776755, 5734.856628584319, 5743.310802703702, 5785.971070144622, 6145.678679084019, 6222.719977601018, 6239.844003899848, 6281.811545653638, 6315.368463153648, 5893.734781934823, 5955.34491205967, 5959.344472108065, 5986.700664966735, 6025.0, 6144.139820425144, 6160.322364539897, 6161.938380616226, 6173.444390004921, 6190.876182476325, 5966.731497082642, 6034.456898879122, 6050.971334872994, 6069.969650151721, 6071.5446341523675, 6047.78832740852, 6118.174046503754, 6133.548895741652, 6141.424638644149, 6142.6314421134875, 4858.955324605226, 4875.585575226146, 4877.244027175793, 4879.902401951941, 4884.267359896044, 4350.456192975878, 4361.5856493633055, 4477.888052798706, 4478.57453541913, 4491.393661855608, 5624.268845050117, 5688.345840228344, 5691.516221121168, 5708.0581704019205, 5720.570957178228, 6170.753169873155, 6187.443130118221, 6199.783007594712, 6203.689815509207, 6207.224096987877, 5690.516306113944, 5748.31020277561, 5769.971150144306, 5802.506786923159, 5813.767449302065, 5383.16560933056, 5387.730613469311, 5393.919091213635, 5403.405625381205, 5442.102053161184, 6285.057241413824, 6289.40250676185, 6292.2134733158355, 6296.811095667137, 6308.4322410981395, 6099.8545999513535, 6139.2468284888055, 6152.107944348134, 6228.906566401417, 6261.123442717932, 6274.843128921813, 6293.905591416224, 6297.937020629918, 6303.558750887394, 6320.0, 6126.172966649446, 6164.198654174928, 6167.938320616737, 6186.072089186658, 6193.318734939063, 6096.451319381277, 6121.8493044937295, 6138.32709113608, 6139.0321984548755, 6178.598391104564, 5964.821055368346, 6053.213082299273, 6057.21256236704, 6079.209702738793, 6119.296280927751, 5999.640021598718, 6001.0, 6018.187544681412, 6031.698415079316, 6047.909281360871, 5622.128570071645, 5696.686682232517, 5740.339860916049, 5838.854028649318, 5863.20846685692, 5007.474215207497, 5957.970210148921, 5992.131140984446, 6021.488173505301, 6121.910283036266, 5889.38161493037, 5917.437843405042, 5918.201042859159, 5955.285365756137, 5981.401859814157], "value": 5644.876103263017, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.3546373736113, 499.82541028582835, 515.6622412319931, 543.9691784069187, 546.8960897429489, 22031.669524957117, 39202.607973920254, 40673.59326406735, 40744.59255407445, 42559.787201063955, 41739.37390939131, 42069.15861682765, 42088.57911420885, 42116.789416052874, 42147.78926105365, 9408.623655053807, 9619.182369498601, 9624.56689448973, 9627.13355797978, 9634.373765705242, 8577.699780507683, 8775.648974041063, 11271.26736762112, 11600.941995290012, 11602.419879006058, 11498.827517587242, 11578.710532236677, 11580.710482237928, 11612.303261804318, 11705.648830535098, 8511.616977236039, 8714.912850871473, 11504.3097414155, 11547.249428787101, 11584.826227606593, 8618.65525378984, 8675.392722509427, 8686.218240358336, 9499.952500237523, 11168.329900205932, 39105.804470977746, 39375.60624393748, 39382.0, 39433.0, 39477.01307467308, 3280.1626592997122, 3402.795832250048, 3416.4113687135355, 3645.9791258447676, 3918.9804050979847, 29869.850650746608, 30857.69142308593, 30902.0, 31057.844710776528, 31257.68742312593, 3060.3726236121543, 3137.921551961196, 3304.7686661933676, 3349.1961929137074, 3356.228067544454, 453022.0, 484853.99999999645, 489601.0, 492159.0, 492176.0, 56024.43975560274, 56215.71892140513, 56253.43746562564, 56416.717916410154, 56507.15239271414, 59156.704216479506, 59240.70379648074, 59271.96188323954, 59299.0, 59352.03239837991, 15390.230488475532, 15867.841321586637, 15874.285657145456, 15888.76166857498, 15903.52289431319, 11252.381119038531, 11499.425028748528, 12375.62873113808, 12455.314957677414, 12459.190152640049, 14744.705105897821, 15150.894066716297, 15152.848471515365, 15477.5356739298, 15497.302621382065, 5939.524838012949, 6105.358937311607, 7008.824779380557, 7102.609356485443, 7149.714011439588, 834.874768784675, 880.6785523284084, 933.4119504711988, 985.5466485416645, 1143.3482914738686, 27512.58731119035, 28260.43479130406, 28510.0, 28894.7110528892, 29021.85489072541, 973.4889183178747, 1017.5161685800975, 1038.6624347087227, 1041.0422411381546, 1057.7408534908843, 14833.18417487027, 14879.553613391616, 15268.923655381652, 15280.388784448498, 15302.387904483696, 455156.0, 456300.0, 456738.0, 457217.0, 458835.0, 53617.1957420639, 53735.92528149415, 53837.46162538403, 53894.46105538973, 53932.73033634807, 1226.791445454275, 1267.4930027988878, 1327.3694994877355, 1354.3837553912947, 1370.095736813698, 28841.0, 30296.970302969527, 30363.24091897632, 30686.693133068384, 30734.85131267886, 39338.0, 47379.526204737514, 47943.0, 47951.0, 48055.75972120117, 410538.0, 493016.0, 493214.0, 493712.0, 493734.0, 25347.87326063358, 25850.870745646152, 25859.0949316773, 26133.60799588046, 37386.62613373831, 44093.33859992038, 45791.313130302406, 46217.075658487316, 46238.76880615576, 46802.53197467982, 28664.570031449963, 30357.241068973453, 33923.32153356968, 35241.94274171779, 38910.80544597259, 33008.0, 38037.0, 38486.80756596199, 38944.80527597344, 39308.0, 36503.817480912425, 36958.44562331621, 37581.0, 37733.4339984906, 37935.0, 32291.218844043015, 34963.65036349706, 34964.825175874976, 35102.12244693903, 35202.722256040244, 21406.0, 24294.14970476025, 24948.875255623607, 25008.49983000366, 25273.368165796], "value": 19253.414000188284, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.44841822547727, 371.8178092734561, 372.11065553327546, 380.93904975203975, 404.8097394224715, 14764.261786910653, 15140.84859151408, 15665.45170919018, 16488.67022659546, 20532.589348213027, 19393.903030484864, 20002.799972000314, 20565.177392904265, 20713.68929466059, 20938.37184884456, 5820.883582328351, 5941.836948183279, 6136.785212517563, 6145.446909778119, 6153.723082461278, 4679.321498382733, 6033.456988871001, 6068.0, 6094.8781024379705, 6106.694665266741, 5880.26496687914, 5938.228030356069, 6045.063015232646, 6048.576599638027, 6058.454739073504, 5876.29484461863, 6029.336772954972, 6029.487493563074, 6032.004719221323, 6037.426444487766, 5051.646384753069, 5223.216517522402, 5230.398504172031, 5327.920081198785, 5390.272313237702, 12971.41628626714, 13282.73434531304, 13291.5379308276, 15567.532974010797, 24995.62506562403, 2066.987176283625, 2311.9653205201935, 2338.3452633262714, 2359.5634807560655, 2862.398896231773, 9884.308098433112, 10038.640919383939, 10174.593016279414, 10333.121684656813, 12980.831725144688, 1705.2326453096032, 1811.7554130192386, 1816.0693716559604, 1830.6613276543883, 1868.5422071592548, 122518.0, 124984.0, 127974.0, 131192.34403828016, 132287.0, 9250.491222982666, 11374.691910430241, 11686.649400517997, 11731.829661325119, 11983.16117871758, 11917.34454605005, 14386.208758518173, 14427.0, 14427.278636068364, 14436.0, 8166.673333066729, 8470.237678608955, 8473.449225800427, 8613.827723445496, 8629.654813807503, 8442.57787110642, 8448.957755211184, 8450.830983380298, 8637.730984099695, 8644.178803013703, 4343.19650864591, 4368.912621747547, 4466.9553304466535, 4547.977260113678, 4564.338170965191, 1590.6739118480684, 1811.3660218923271, 1874.0229058343307, 1876.498800959227, 1876.7654043244595, 869.3523325122824, 874.3136637739344, 920.9125133112343, 928.3362395886974, 962.8317178605356, 19998.90000550017, 20898.47753806167, 20916.47708807292, 20960.685589716166, 21553.461163471038, 879.090141703334, 882.2809410330689, 911.1526280559144, 915.1854849184232, 955.19763398744, 10579.84130238047, 10589.364638121737, 10838.620648277272, 11066.225364224427, 11148.665540033811, 420794.0, 522694.0, 605620.9718951377, 627791.0, 630380.0, 95769.38176515613, 126687.73312266944, 129506.0, 133720.0, 136881.3155934214, 789.2896393246125, 954.179405711088, 1002.9348092374119, 1007.3049595778889, 1011.2668315471225, 19102.808971910657, 19379.0, 19627.60744785125, 19633.803661963197, 19634.50913727111, 51344.716382090744, 54958.62603434946, 57121.71439142944, 57410.0, 57795.42204578069, 74276.62861685872, 76905.23094768981, 77526.83709744246, 78954.21045789469, 81202.0, 7654.540727556365, 7680.116786569616, 7841.5687137208, 8016.39877009215, 8317.708880189164, 10559.574457448454, 12707.745845082862, 12894.548690795777, 13141.474341026253, 13188.208707477583, 10753.838692419464, 10896.727581810519, 10961.342319460859, 11328.603498877499, 11517.539298428304, 8636.524991125549, 10731.40977246259, 10860.185486088418, 11229.71925701864, 11370.374629395132, 11163.44182790841, 11307.886921130683, 11339.886601134212, 11473.0, 11851.466683999068, 7737.684593619211, 7863.29204525119, 7952.681892724227, 8231.506109633441, 8330.333573314, 7138.0, 10726.249162558546, 10917.836232456679, 11284.717882053013, 11435.771284574428], "value": 9331.111901248854, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.759942010148, 1415.8301003879535, 1476.8080149580553, 1755.5874369523167, 1788.7138057910736, 8162.7143049993265, 8660.913390866097, 8666.090060543647, 8669.046404895473, 8684.175003374678, 7513.699452021912, 7858.410619203554, 8193.754187374388, 8514.48913065215, 8520.744377668665, 2296.2537175418015, 2322.3033090072995, 2324.244620498345, 2325.232673217834, 2329.429289823987, 2287.942801429969, 2295.116380193621, 2331.5103828196097, 2331.813454923602, 2336.754640762721, 2036.2669439001947, 2110.862793918398, 2121.5756848630335, 2245.404967683557, 2330.568844763716, 2313.6066868632374, 2323.94190145246, 2325.4535184231777, 2327.464683122891, 2335.4161459635093, 2299.2642354446584, 2313.6760853480528, 2313.803326717214, 2326.4183954011496, 2333.1600623775425, 8471.195236452528, 8678.871746672956, 8685.869711954325, 8686.348523860735, 8730.519821409884, 4332.761698106603, 5235.55497782689, 5247.658902171346, 5252.71110088945, 5255.369355677343, 2867.526858068416, 3402.4215883299653, 3466.497357883118, 3478.669526394988, 3490.8254587270535, 987.3335498538457, 1048.7535429174138, 1053.462734005655, 1752.8948263104128, 1827.232562323827, 11194.66416007521, 11197.328160310408, 11292.322460652387, 11294.20940534163, 11308.604198853001, 4229.534751177368, 4297.247981603207, 4456.6211871991245, 4458.71018383804, 4466.486354069324, 8808.20726134651, 8808.25129863956, 8816.338774591932, 8829.911700883038, 8836.469811811312, 5092.821751238763, 5094.267948897319, 5099.4390617032095, 5100.413452452941, 5105.361829771278, 4552.704074235164, 4673.976630116827, 4794.664373493892, 4802.903941921142, 4805.711657300573, 3197.7601679873637, 3481.2515309208643, 3499.492573576868, 3506.368853606324, 3582.444721068239, 1101.5098281264761, 1103.2994048779083, 1107.4850194659523, 1120.8374785656035, 1125.3754166437707, 758.12058012705, 840.8528507511161, 876.5660997806127, 886.2378354615026, 941.30343545777, 4339.20934598932, 5507.201455788888, 5815.447532484492, 5825.300963884361, 5927.170196172539, 765.7855800375904, 935.653808090997, 938.3666025432942, 944.3767113704922, 963.4989805301344, 3791.0711875590655, 3837.9616203838164, 3858.7298889078056, 3953.2884080865147, 3958.762474251554, 8662.740117796477, 10614.257002009785, 10616.991385818335, 10633.5214915329, 10645.361278323171, 10476.161907047417, 10594.364338139736, 10596.205284603686, 10610.946945265225, 10617.309874858109, 7599.924000760032, 7863.13505514393, 8049.919500805034, 8080.757577272691, 8184.713535026364, 5825.2135961644635, 5843.795467158629, 5922.466977972003, 5992.370801065826, 6067.726952287069, 9710.902890971283, 9714.805703886024, 9783.80432391334, 9960.950195248977, 9967.651132210338, 9544.618215271312, 9892.554835032297, 9947.850782238416, 10112.949435252776, 10122.696319110144, 7038.542494738031, 7307.817304567216, 7378.188399275968, 7467.551946883204, 7468.141163766019, 8034.959825200836, 8077.63650635711, 8157.0, 8160.387970902088, 8164.0, 6782.457403407814, 6989.790306290819, 7072.292770722886, 7129.180144283474, 7154.141503019671, 7000.144384201487, 7104.18301895268, 7136.3220494052985, 7235.34881860614, 7281.635918203962, 6793.490488213502, 6816.0457535944015, 6885.173779146535, 6898.827529311808, 6909.861802764017, 4861.586765125004, 4891.1685013548495, 4904.901901961869, 4939.333190019302, 5042.697438153723, 4873.220284754422, 4893.486183950777, 4951.158303088556, 4990.076835785391, 5035.546800787946], "value": 4452.234764949679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.8623774350569, 297.80642582321474, 298.09825278532435, 305.691251835646, 311.0, 5103.387593488782, 5196.324477817883, 5337.2261022151815, 5403.78384864606, 5540.833774986757, 5021.874453138674, 5197.948020519803, 5374.811881584145, 5395.460453954603, 5412.809181979958, 3632.400653892105, 4091.8977025574377, 4107.466029416171, 4123.381492776092, 4175.206710724968, 3360.958102988069, 3688.6680198782105, 3983.1635356575007, 4023.9738866588978, 4025.778582177979, 3310.874302737069, 3960.5049368828895, 4012.4382586437923, 4059.219434687234, 4092.5907409259135, 4062.9187416251657, 4072.6945479089186, 4075.3886916962397, 4078.551359350484, 4084.9591504085174, 3431.5024321473247, 3561.216532362876, 3570.517980072683, 3589.3898037333715, 3596.6403359663823, 5063.8480845574695, 5123.38519377677, 5146.742662866879, 5170.715610641413, 5226.346706661668, 3919.862804801847, 4198.622124008823, 4415.514293427753, 4671.43942726875, 4692.483826779052, 5015.423226328946, 5336.212368856438, 5583.58123140762, 5609.747561359749, 5686.2039314496005, 3427.8628854846024, 3615.1504396466808, 3873.612638736132, 3877.7479463835043, 3878.340682084024, 5652.2869022868845, 5811.128330750421, 5851.385604511507, 5876.382979787147, 5898.321693005273, 3793.79134147619, 3973.1854969731135, 3984.2629113614075, 3994.0014996250356, 4031.576684448091, 3254.772165948408, 3399.2861499084975, 4002.358443427324, 4005.859794907164, 4027.3153563894207, 4334.674899382559, 4508.542844149946, 4512.661550383668, 4530.864074077783, 4626.86119416418, 4350.847720329836, 4459.620932220732, 5569.582281328917, 5651.66090034591, 5664.8867022659315, 1194.9163558550993, 1260.155695683894, 1359.34071975092, 1386.9583912482642, 1396.8393634731933, 815.9143289954469, 838.9119142490073, 850.4982060584161, 853.4068822168533, 867.2195024477894, 3942.0539070622845, 4079.5104587449696, 4207.453031105937, 4446.244138496463, 4453.35225966388, 4263.445752052214, 4383.0, 4497.8200871964755, 4539.094979213306, 4834.347363106006, 3946.4869566956113, 4138.275801734684, 4250.638695710837, 4393.275109606943, 4527.162474942146, 2393.80849532037, 2444.963325550118, 2472.1347528364927, 2515.91179321163, 2597.8830952607177, 4282.828686852491, 4735.384400027974, 4998.650634201919, 5003.5997120230295, 5004.795013186245, 4364.363363738598, 4380.956190438119, 4625.398698169284, 4854.994629430734, 4930.605551555867, 4759.50025247351, 4930.482299358516, 4998.375203099613, 5039.319691841701, 5043.9747801260755, 4295.957040429681, 4704.647151463722, 4766.6901651392545, 4904.901901961869, 4914.606831453547, 3810.7234076583545, 4048.0487085534287, 4436.223660859272, 4702.388689470347, 4766.737829419277, 4131.710780245354, 4403.8458653946955, 4492.752898590609, 4974.7512624369365, 5020.3724534433195, 2895.8552072395873, 3189.7767156298837, 3267.8954992756503, 3398.49022646605, 3405.0, 3955.288048151245, 4517.367568540341, 4751.717036400083, 4769.689970151929, 5104.983531505109, 4962.776675049559, 4991.550760431579, 5437.574328173384, 5525.0, 5678.630888992202, 4729.716217026989, 5615.747291371817, 5633.943660563506, 5643.689597072201, 5691.402402747817, 4837.992233223173, 5182.792688292426, 5238.869028274171, 5487.75305111279, 5503.881005812871, 5371.636479948967, 5399.0, 5492.231087647652, 5595.356533998643, 5610.7194640268635, 5481.780728770805, 5528.364238112668, 5555.555555555504, 5583.269186552182, 5593.916091258553], "value": 3839.027286523888, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.9314678811936, 733.4059411876384, 769.0771074710349, 785.3010820369868, 801.3428988229653, 14808.703825923489, 15156.848431515682, 15741.60645983851, 15982.360705571764, 16055.678886422294, 14046.859531404683, 14359.569212923603, 14365.281735913215, 14393.136411815298, 14397.568072957802, 829.1915382502058, 847.4576271186446, 847.9576021198947, 850.0776657326808, 852.1393392673414, 682.5426963934151, 700.8002719225021, 700.9228984811666, 823.9258466737992, 849.307814131484, 885.3094586222757, 892.5492626223748, 893.5710858787786, 893.8703887936246, 895.073598825216, 883.5582208895552, 887.0419946457843, 887.2458410351214, 888.7244954064261, 889.0575989451203, 527.6174773289334, 540.5567434703545, 658.4140115297389, 659.0838734159522, 659.9439047680953, 11141.275817071946, 14537.709245815131, 14595.124292542481, 14874.181919994397, 14890.329935152835, 14117.011809173362, 14641.341139648635, 14665.780013299809, 14721.852781472262, 14836.33236504349, 11822.349770762605, 11841.644750657493, 11912.285262884254, 12105.455254513568, 12300.507979680802, 3705.4256590228556, 3733.757305775143, 3772.830222639988, 3779.5653499847595, 3896.8051597420017, 114938.0, 117731.2340314896, 117889.0, 118676.0, 120019.0, 10691.465426728633, 10727.946360268304, 11052.0, 11223.214374993671, 11436.885631143748, 22670.319890403316, 22819.0, 22823.885880570822, 22857.428564285692, 22892.542149156925, 7640.503367281109, 7941.444098913021, 8005.359571234288, 8007.559584222924, 8088.150744171895, 3720.4419337099653, 3800.2969450651717, 3866.478025466582, 3970.4441378207075, 3980.44273801668, 3481.70405515534, 3579.6599323064265, 3603.7837729736298, 3609.242059167552, 3610.115521697201, 615.5844804756771, 615.6306216270292, 622.3714048810734, 637.1812221295705, 638.8850006998692, 651.2965996723622, 652.422605993699, 656.947444204472, 667.3726696904894, 676.1413005483107, 7883.526988380716, 8843.46939183651, 8846.95576522126, 9297.72106836796, 9436.292278079172, 634.1217413881715, 661.9602823830586, 666.5771264556537, 680.9659517024129, 681.6285124607101, 3801.334766415866, 4119.848316038233, 4544.727316361029, 4664.440267167962, 4698.201305778026, 118866.40566797284, 133988.3300583491, 140002.0, 155539.22230388774, 171369.57880264966, 119002.4049879745, 120702.0, 121215.0, 122517.38741306415, 125178.0, 81546.0, 94114.99999999863, 95686.0, 100733.0, 101894.0, 9211.525575161832, 9560.28297877648, 9567.0, 9592.95203523978, 9801.657991898968, 53039.46960530345, 56898.7155064222, 59474.70262648659, 59709.0, 62584.0, 74711.0, 89271.0, 92091.0, 93371.59942601003, 93587.53206233925, 12306.630801075982, 12578.55975040869, 12714.427850746553, 13055.869441305465, 13127.949764018664, 12877.8068328977, 13887.0, 14416.495422660157, 14700.632484187554, 14820.0, 13245.668858278621, 13275.40260688252, 13351.599452016455, 13417.865821341662, 14069.507567235098, 13362.933185334012, 13516.053876228569, 13632.863671363557, 13722.794158087836, 13982.525576879832, 12332.691682708004, 12370.381480926098, 12451.626451206479, 12530.937345313216, 12901.032422568533, 10815.119644876655, 11141.442927853737, 11192.272502287326, 11365.31808091517, 11602.6519204424, 9787.902120978699, 10748.462576871281, 11213.887861121611, 11381.089512838787, 11489.482973266056], "value": 7659.92052791459, "unit": "score"}], "name": "dromaeo_css", "value": 7142.681349307028, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 110.0, 112.0, 112.0], "value": 112.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 118.0, 149.0, 114.0, 111.0, 118.0, 144.0, 178.0, 114.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 185.0, 182.0, 186.0, 183.0, 182.0, 181.0, 219.0, 238.0], "value": 192.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 75.0, 85.0, 113.0, 60.0, 58.0, 59.0, 58.0, 83.0, 58.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 125.0, 102.0, 77.0, 68.0, 92.0, 68.0, 68.0, 89.0], "value": 86.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 91.0, 90.0, 90.0, 91.0, 90.0, 91.0, 94.0, 87.0], "value": 90.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 96.0, 95.0, 94.0, 95.0, 94.0, 95.0, 94.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 87.0, 86.0, 86.0, 85.0, 84.0, 84.0, 83.0, 85.0, 84.0], "value": 84.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 51.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 80.0, 76.0, 71.0, 70.0, 69.0, 70.0, 70.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 87.0, 88.0, 87.0, 85.0, 85.0, 86.0, 87.0, 89.0], "value": 86.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 143.0, 142.0, 158.0, 156.0, 161.0, 147.0, 178.0, 166.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 192.0, 172.0, 178.0, 173.0, 173.0, 172.0, 170.0, 172.0, 176.0], "value": 178.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 69.0, 69.0, 69.0, 67.0, 67.0, 67.0, 67.0, 70.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1477.0, "alertThreshold": 2.0}]} 07:44:50 INFO - Return code: 0 07:44:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:44:50 INFO - # TBPL SUCCESS # 07:44:50 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:44:50 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:44:50 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:44:50 INFO - 2016-10-12 07:44:50.806 screenresolution[1661:9704] starting screenresolution argv=screenresolution get 07:44:50 INFO - 2016-10-12 07:44:50.809 screenresolution[1661:9704] Display 0: 1600x1200x32@60 07:44:50 INFO - 2016-10-12 07:44:50.815 screenresolution[1662:9708] starting screenresolution argv=screenresolution list 07:44:50 INFO - Available Modes on Display 0 07:44:50 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:44:50 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:44:50 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:44:50 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:44:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:44:51 INFO - Intel Iris: 07:44:51 INFO - Chipset Model: Intel Iris 07:44:51 INFO - Type: GPU 07:44:51 INFO - Bus: Built-In 07:44:51 INFO - VRAM (Dynamic, Max): 1536 MB 07:44:51 INFO - Vendor: Intel (0x8086) 07:44:51 INFO - Device ID: 0x0a2e 07:44:51 INFO - Revision ID: 0x0009 07:44:51 INFO - Displays: 07:44:51 INFO - Display: 07:44:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:44:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:44:51 INFO - Display Serial Number: 1600x1200 60 07:44:51 INFO - Main Display: Yes 07:44:51 INFO - Mirror: Off 07:44:51 INFO - Online: Yes 07:44:51 INFO - Rotation: Supported 07:44:51 INFO - Return code: 0 07:44:51 INFO - Running post-action listener: _resource_record_post_action 07:44:51 INFO - [mozharness: 2016-10-12 14:44:51.065324Z] Finished run-tests step (success) 07:44:51 INFO - Running post-run listener: _resource_record_post_run 07:44:51 INFO - Total resource usage - Wall time: 1215s; CPU: 54.0%; Read bytes: 38700032; Write bytes: 707448832; Read time: 381; Write time: 1959 07:44:51 INFO - TinderboxPrint: CPU usage
53.8% 07:44:51 INFO - TinderboxPrint: I/O read bytes / time
38,700,032 / 381 07:44:51 INFO - TinderboxPrint: I/O write bytes / time
707,448,832 / 1,959 07:44:51 INFO - TinderboxPrint: CPU idle
2,245.8 (46.2%) 07:44:51 INFO - TinderboxPrint: CPU system
975.3 (20.1%) 07:44:51 INFO - TinderboxPrint: CPU user
1,640.5 (33.7%) 07:44:51 INFO - TinderboxPrint: Swap in / out
446,095,360 / 0 07:44:51 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397466112; Write bytes: 392255488; Read time: 29553; Write time: 737 07:44:51 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 36684800; Write bytes: 296011776; Read time: 358; Write time: 1178 07:44:51 INFO - Running post-run listener: _upload_blobber_files 07:44:51 INFO - Blob upload gear active. 07:44:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:44:51 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 07:44:51 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:44:51 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:44:51 INFO - (blobuploader) - INFO - Open directory for files ... 07:44:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:44:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:44:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:44:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:44:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:44:53 INFO - (blobuploader) - INFO - Done attempting. 07:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:44:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:44:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:44:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:44:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:44:53 INFO - (blobuploader) - INFO - Done attempting. 07:44:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:44:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:44:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:44:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:44:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:44:56 INFO - (blobuploader) - INFO - Done attempting. 07:44:56 INFO - (blobuploader) - INFO - Iteration through files over. 07:44:56 INFO - Return code: 0 07:44:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:44:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:44:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/254e660168cfc13fd5bae868942200c724ed7803d284ebab92a2215ab92dc56614f0a35ee2dd6e5f528e4dea5c1449003b38cc5db85124d4dc128a221a90b413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ed2b91d93d2245433cf4d19024a3db5df13fe13f9e3a5b33bc4bb86a7f3aa4e09b8239381171bb451413f6bd80d467fcd6a3e00f0b206848c61c075745cff51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7c9d5fd9dcf20b64825a3e8f4af3fe3b1dd32bce13f941d18a38b824e14c8b12312bdcaf93cac07c25b88a5e3c0b4d480c6444efcd0e4aa091b87fe463005f67"} 07:44:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:44:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:44:56 INFO - Contents: 07:44:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/254e660168cfc13fd5bae868942200c724ed7803d284ebab92a2215ab92dc56614f0a35ee2dd6e5f528e4dea5c1449003b38cc5db85124d4dc128a221a90b413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ed2b91d93d2245433cf4d19024a3db5df13fe13f9e3a5b33bc4bb86a7f3aa4e09b8239381171bb451413f6bd80d467fcd6a3e00f0b206848c61c075745cff51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7c9d5fd9dcf20b64825a3e8f4af3fe3b1dd32bce13f941d18a38b824e14c8b12312bdcaf93cac07c25b88a5e3c0b4d480c6444efcd0e4aa091b87fe463005f67"} 07:44:56 INFO - Running post-run listener: copy_logs_to_upload_dir 07:44:56 INFO - Copying logs to upload dir... 07:44:56 INFO - mkdir: /builds/slave/test/build/upload/logs 07:44:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.812130 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-10-12 07:44:56.398975) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 07:44:56.402316) ========= 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.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/Larch/sha512/254e660168cfc13fd5bae868942200c724ed7803d284ebab92a2215ab92dc56614f0a35ee2dd6e5f528e4dea5c1449003b38cc5db85124d4dc128a221a90b413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ed2b91d93d2245433cf4d19024a3db5df13fe13f9e3a5b33bc4bb86a7f3aa4e09b8239381171bb451413f6bd80d467fcd6a3e00f0b206848c61c075745cff51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7c9d5fd9dcf20b64825a3e8f4af3fe3b1dd32bce13f941d18a38b824e14c8b12312bdcaf93cac07c25b88a5e3c0b4d480c6444efcd0e4aa091b87fe463005f67"} build_url:https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011135 build_url: 'https://queue.taskcluster.net/v1/task/J4nAncBSSCC1sSHVX9-GAw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/254e660168cfc13fd5bae868942200c724ed7803d284ebab92a2215ab92dc56614f0a35ee2dd6e5f528e4dea5c1449003b38cc5db85124d4dc128a221a90b413", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2ed2b91d93d2245433cf4d19024a3db5df13fe13f9e3a5b33bc4bb86a7f3aa4e09b8239381171bb451413f6bd80d467fcd6a3e00f0b206848c61c075745cff51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/7c9d5fd9dcf20b64825a3e8f4af3fe3b1dd32bce13f941d18a38b824e14c8b12312bdcaf93cac07c25b88a5e3c0b4d480c6444efcd0e4aa091b87fe463005f67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-12 07:44:56.436478) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:44:56.436957) ========= 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.g8TvkroR7H/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.wp1BDsrO6m/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005081 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-12 07:44:56.485747) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 07:44:56.486100) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-12 07:44:56.486437) ========= ========= Total master_lag: 0.17 =========