builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0286 starttime: 1479399603.36 results: success (0) buildid: 20161117060328 builduid: 90eed37f8d6b4a1c8ca3e39364311e86 revision: 64936ba0330a72cf5e05bca5f44b84562705fea0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.362211) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.362749) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.363088) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.388525) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.389034) ========= 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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-11-17 08:20:03-- 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% 19.5M=0.001s 2016-11-17 08:20:03 (19.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.076581 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.491319) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.491910) ========= 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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.036197 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:03.549586) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 08:20:03.549982) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 64936ba0330a72cf5e05bca5f44b84562705fea0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 64936ba0330a72cf5e05bca5f44b84562705fea0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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-11-17 08:20:03,650 truncating revision to first 12 chars 2016-11-17 08:20:03,650 Setting DEBUG logging. 2016-11-17 08:20:03,650 attempt 1/10 2016-11-17 08:20:03,650 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/64936ba0330a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 08:20:05,137 unpacking tar archive at: mozilla-aurora-64936ba0330a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.771283 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-17 08:20:05.343816) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:05.344200) ========= script_repo_revision: 64936ba0330a72cf5e05bca5f44b84562705fea0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:05.344729) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:05.345157) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 08:20:05.364651) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-17 08:20:05.365321) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:05 INFO - MultiFileLogger online at 20161117 08:20:05 in /builds/slave/test 08:20:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:20:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:20:05 INFO - {'append_to_log': False, 08:20:05 INFO - 'base_work_dir': '/builds/slave/test', 08:20:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:20:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:20:05 INFO - 'branch': 'Mozilla-Aurora', 08:20:05 INFO - 'buildbot_json_path': 'buildprops.json', 08:20:05 INFO - 'config_files': ('talos/mac_config.py',), 08:20:05 INFO - 'default_actions': ('clobber', 08:20:05 INFO - 'read-buildbot-config', 08:20:05 INFO - 'download-and-extract', 08:20:05 INFO - 'populate-webroot', 08:20:05 INFO - 'create-virtualenv', 08:20:05 INFO - 'install', 08:20:05 INFO - 'run-tests'), 08:20:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:20:05 INFO - 'download_minidump_stackwalk': True, 08:20:05 INFO - 'download_symbols': 'ondemand', 08:20:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:20:05 INFO - 'tooltool.py': '/tools/tooltool.py', 08:20:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:20:05 INFO - '/tools/misc-python/virtualenv.py')}, 08:20:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:20:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:20:05 INFO - 'installer_path': 'installer.exe', 08:20:05 INFO - 'log_level': 'info', 08:20:05 INFO - 'log_name': 'talos', 08:20:05 INFO - 'log_to_console': True, 08:20:05 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:20:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:20:05 INFO - 'opt_config_files': (), 08:20:05 INFO - 'pip_index': False, 08:20:05 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:05 INFO - 'cmd': ('bash', 08:20:05 INFO - '-c', 08:20:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:05 INFO - 'enabled': True, 08:20:05 INFO - 'halt_on_failure': False, 08:20:05 INFO - 'name': 'check_screen_resolution'},), 08:20:05 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:20:05 INFO - 'cmd': ('bash', 08:20:05 INFO - '-c', 08:20:05 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:20:05 INFO - 'enabled': True, 08:20:05 INFO - 'halt_on_failure': False, 08:20:05 INFO - 'name': 'check_screen_resolution'},), 08:20:05 INFO - 'run_cmd_checks_enabled': True, 08:20:05 INFO - 'sps_profile': False, 08:20:05 INFO - 'sps_profile_interval': 0, 08:20:05 INFO - 'suite': 'dromaeojs', 08:20:05 INFO - 'system_bits': '32', 08:20:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:20:05 INFO - 'title': 't-yosemite-r7-0286', 08:20:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:20:05 INFO - 'use_talos_json': True, 08:20:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:20:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:20:05 INFO - 'work_dir': 'build'} 08:20:05 INFO - [mozharness: 2016-11-17 16:20:05.568786Z] Running clobber step. 08:20:05 INFO - Running pre-action listener: _resource_record_pre_action 08:20:05 INFO - Running main action method: clobber 08:20:05 INFO - rmtree: /builds/slave/test/build 08:20:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:20:06 INFO - Running post-action listener: _resource_record_post_action 08:20:06 INFO - [mozharness: 2016-11-17 16:20:06.920952Z] Finished clobber step (success) 08:20:06 INFO - [mozharness: 2016-11-17 16:20:06.921122Z] Running read-buildbot-config step. 08:20:06 INFO - Running pre-action listener: _resource_record_pre_action 08:20:06 INFO - Running main action method: read_buildbot_config 08:20:06 INFO - Using buildbot properties: 08:20:06 INFO - { 08:20:06 INFO - "project": "", 08:20:06 INFO - "product": "firefox", 08:20:06 INFO - "script_repo_revision": "production", 08:20:06 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:20:06 INFO - "repository": "", 08:20:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:20:06 INFO - "buildid": "20161117060328", 08:20:06 INFO - "pgo_build": "False", 08:20:06 INFO - "basedir": "/builds/slave/test", 08:20:06 INFO - "buildnumber": 21, 08:20:06 INFO - "slavename": "t-yosemite-r7-0286", 08:20:06 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:20:06 INFO - "platform": "yosemite_r7", 08:20:06 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:20:06 INFO - "branch": "mozilla-aurora", 08:20:06 INFO - "repo_path": "releases/mozilla-aurora", 08:20:06 INFO - "revision": "64936ba0330a72cf5e05bca5f44b84562705fea0", 08:20:06 INFO - "stage_platform": "macosx64", 08:20:06 INFO - "builduid": "90eed37f8d6b4a1c8ca3e39364311e86", 08:20:06 INFO - "slavebuilddir": "test" 08:20:06 INFO - } 08:20:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:20:06 INFO - Running post-action listener: _resource_record_post_action 08:20:06 INFO - [mozharness: 2016-11-17 16:20:06.925082Z] Finished read-buildbot-config step (success) 08:20:06 INFO - [mozharness: 2016-11-17 16:20:06.925261Z] Running download-and-extract step. 08:20:06 INFO - Running pre-action listener: _resource_record_pre_action 08:20:06 INFO - Running main action method: download_and_extract 08:20:06 INFO - mkdir: /builds/slave/test/build/tests 08:20: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')]} 08:20:06 INFO - trying https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:06 INFO - Downloading https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:20:07 INFO - Downloaded 1199 bytes. 08:20:07 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:20:07 INFO - Using the following test package requirements: 08:20:07 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:07 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:20:07 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:20:07 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'jsshell-mac.zip'], 08:20:07 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:20:07 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:20:07 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:20:07 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:20:07 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:20:07 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:20:07 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:20:07 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:20:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:20:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:20:07 INFO - Fetch https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:20:08 INFO - Http code: 200 08:20:08 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 08:20:08 INFO - x-amz-cf-id: 3-2tuVuC5QEgGLmHOxSdIUb-HNcEsAIKLwwlOJLFUdNEATeGjXxmuw== 08:20:08 INFO - x-amz-version-id: vE8ATG25ZNGbSYCCmIBagZK8YKyGMPeW 08:20:08 INFO - Expected file size: 16327483 08:20:08 INFO - Obtained file size: 16327483 08:20:15 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:20:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:20:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:20:15 INFO - Fetch https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:20:15 INFO - Http code: 200 08:20:15 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 08:20:15 INFO - x-amz-cf-id: 86rEmKgHd8mjwuh-vP1heS4xzVhqxbHJFcfekT6k3zekSwj3kPCsSw== 08:20:15 INFO - x-amz-version-id: vE8ATG25ZNGbSYCCmIBagZK8YKyGMPeW 08:20:16 INFO - Expected file size: 16327483 08:20:16 INFO - Obtained file size: 16327483 08:20:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:20:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:20:22 INFO - Fetch https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:20:23 INFO - Http code: 200 08:20:23 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 08:20:23 INFO - x-amz-cf-id: 8nWjHT3zMOir0hs8WIOr3zEm1nz-Y4Y2QExUzFRUqK59Gv8DmRD5Hg== 08:20:23 INFO - x-amz-version-id: prRw.YuJzOBaqO_EZtM9Msp2uRlOAqsq 08:20:23 INFO - Expected file size: 14409038 08:20:23 INFO - Obtained file size: 14409038 08:20: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')]} 08:20:23 INFO - trying https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:23 INFO - Downloading https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:20:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:20:27 INFO - Downloaded 114364579 bytes. 08:20:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:27 INFO - mkdir: /builds/slave/test/properties 08:20:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:27 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:27 INFO - Contents: 08:20:27 INFO - build_url:https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:27 INFO - Running post-action listener: _resource_record_post_action 08:20:27 INFO - Running post-action listener: set_extra_try_arguments 08:20:27 INFO - [mozharness: 2016-11-17 16:20:27.798378Z] Finished download-and-extract step (success) 08:20:27 INFO - [mozharness: 2016-11-17 16:20:27.798511Z] Running populate-webroot step. 08:20:27 INFO - Running pre-action listener: _resource_record_pre_action 08:20:27 INFO - Running main action method: populate_webroot 08:20:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:27 INFO - '--tppagecycles', 08:20:27 INFO - '7'], 08:20:27 INFO - 'tests': ['tsvgm']}}, 08:20:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:20:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['damp', 'tps']}, 08:20:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'tests': ['damp', 'tps']}, 08:20:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['dromaeo_dom']}, 08:20:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['basic_compositor_video']}, 08:20:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:20:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['a11yr', 08:20:27 INFO - 'ts_paint', 08:20:27 INFO - 'tpaint', 08:20:27 INFO - 'sessionrestore', 08:20:27 INFO - 'sessionrestore_no_auto_restore', 08:20:27 INFO - 'tabpaint']}, 08:20:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['tsvgx', 08:20:27 INFO - 'tsvgr_opacity', 08:20:27 INFO - 'tart', 08:20:27 INFO - 'tscrollx', 08:20:27 INFO - 'cart']}, 08:20:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:27 INFO - 'tsvgr_opacity', 08:20:27 INFO - 'tart', 08:20:27 INFO - 'tscrollx', 08:20:27 INFO - 'cart']}, 08:20:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'talos_options': ['--disable-e10s'], 08:20:27 INFO - 'tests': ['tp5o']}, 08:20:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'tests': ['tp5o']}, 08:20:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'talos_options': ['--disable-e10s', 08:20:27 INFO - '--xperf_path', 08:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:27 INFO - 'tests': ['tp5n']}, 08:20:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:27 INFO - 'talos_options': ['--xperf_path', 08:20:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:27 INFO - 'tests': ['tp5n']}}, 08:20:27 INFO - 'talos.zip': {'path': '', 08:20:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:27 INFO - Running post-action listener: _resource_record_post_action 08:20:27 INFO - [mozharness: 2016-11-17 16:20:27.817406Z] Finished populate-webroot step (success) 08:20:27 INFO - [mozharness: 2016-11-17 16:20:27.817515Z] Running create-virtualenv step. 08:20:27 INFO - Running pre-action listener: _resource_record_pre_action 08:20:27 INFO - Running main action method: create_virtualenv 08:20:27 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:27 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 08:20:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:27 INFO - Using real prefix '/tools/python27' 08:20:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:28 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:31 INFO - Installing pip.................done. 08:20:31 INFO - Return code: 0 08:20:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:32 INFO - Reading from file tmpfile_stdout 08:20:32 INFO - Output received: 08:20:32 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) 08:20:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:32 INFO - 'HOME': '/Users/cltbld', 08:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:32 INFO - 'LOGNAME': 'cltbld', 08:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:32 INFO - 'MOZ_NO_REMOTE': '1', 08:20:32 INFO - 'NO_EM_RESTART': '1', 08:20:32 INFO - 'PAGER': '/bin/cat', 08:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:32 INFO - 'PWD': '/builds/slave/test', 08:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:32 INFO - 'SHELL': '/bin/bash', 08:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:32 INFO - 'USER': 'cltbld', 08:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:32 INFO - 'XPC_FLAGS': '0x0', 08:20:32 INFO - 'XPC_SERVICE_NAME': '0', 08:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:32 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 08:20:32 INFO - Cleaning up... 08:20:32 INFO - Return code: 0 08:20:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:20:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:32 INFO - 'HOME': '/Users/cltbld', 08:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:32 INFO - 'LOGNAME': 'cltbld', 08:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:32 INFO - 'MOZ_NO_REMOTE': '1', 08:20:32 INFO - 'NO_EM_RESTART': '1', 08:20:32 INFO - 'PAGER': '/bin/cat', 08:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:32 INFO - 'PWD': '/builds/slave/test', 08:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:32 INFO - 'SHELL': '/bin/bash', 08:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:32 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:32 INFO - 'USER': 'cltbld', 08:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:32 INFO - 'XPC_FLAGS': '0x0', 08:20:32 INFO - 'XPC_SERVICE_NAME': '0', 08:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:32 INFO - Downloading/unpacking psutil>=3.1.1 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:20:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:36 INFO - Installing collected packages: psutil 08:20:36 INFO - Running setup.py install for psutil 08:20:36 INFO - building 'psutil._psutil_osx' extension 08:20:36 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 08:20:36 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 08:20:36 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 08:20:36 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 08:20:36 INFO - building 'psutil._psutil_posix' extension 08:20:36 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 08:20:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:20:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:20:36 INFO - ^ 08:20:37 INFO - 1 warning generated. 08:20:37 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 08:20:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:37 INFO - Successfully installed psutil 08:20:37 INFO - Cleaning up... 08:20:37 INFO - Return code: 0 08:20:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:20:37 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')]} 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:37 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')]} 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:37 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:37 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 08:20:37 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 08:20:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:37 INFO - 'HOME': '/Users/cltbld', 08:20:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:37 INFO - 'LOGNAME': 'cltbld', 08:20:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:37 INFO - 'MOZ_NO_REMOTE': '1', 08:20:37 INFO - 'NO_EM_RESTART': '1', 08:20:37 INFO - 'PAGER': '/bin/cat', 08:20:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:37 INFO - 'PWD': '/builds/slave/test', 08:20:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:37 INFO - 'SHELL': '/bin/bash', 08:20:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:37 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:37 INFO - 'USER': 'cltbld', 08:20:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:37 INFO - 'XPC_FLAGS': '0x0', 08:20:37 INFO - 'XPC_SERVICE_NAME': '0', 08:20:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:20:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:20:40 INFO - Installing collected packages: mozsystemmonitor 08:20:40 INFO - Running setup.py install for mozsystemmonitor 08:20:40 INFO - Successfully installed mozsystemmonitor 08:20:40 INFO - Cleaning up... 08:20:40 INFO - Return code: 0 08:20:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:20:40 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')]} 08:20:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:40 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')]} 08:20:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:40 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:40 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 08:20:40 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 08:20:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:40 INFO - 'HOME': '/Users/cltbld', 08:20:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:40 INFO - 'LOGNAME': 'cltbld', 08:20:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:40 INFO - 'MOZ_NO_REMOTE': '1', 08:20:40 INFO - 'NO_EM_RESTART': '1', 08:20:40 INFO - 'PAGER': '/bin/cat', 08:20:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:40 INFO - 'PWD': '/builds/slave/test', 08:20:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:40 INFO - 'SHELL': '/bin/bash', 08:20:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:40 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:40 INFO - 'USER': 'cltbld', 08:20:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:40 INFO - 'XPC_FLAGS': '0x0', 08:20:40 INFO - 'XPC_SERVICE_NAME': '0', 08:20:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:40 INFO - Downloading/unpacking jsonschema==2.5.1 08:20:40 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 08:20:40 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 08:20:40 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 08:20:40 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 08:20:40 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 08:20:40 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 08:20:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:20:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:43 INFO - Downloading functools32-3.2.3-2.tar.gz 08:20:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:20:43 INFO - warning: no files found matching '*.txt' 08:20:43 INFO - no previously-included directories found matching 'build' 08:20:43 INFO - no previously-included directories found matching 'dist' 08:20:43 INFO - no previously-included directories found matching '.git*' 08:20:43 INFO - Installing collected packages: jsonschema, functools32 08:20:43 INFO - Running setup.py install for functools32 08:20:43 INFO - warning: no files found matching '*.txt' 08:20:43 INFO - no previously-included directories found matching 'build' 08:20:43 INFO - no previously-included directories found matching 'dist' 08:20:43 INFO - no previously-included directories found matching '.git*' 08:20:43 INFO - Successfully installed jsonschema functools32 08:20:43 INFO - Cleaning up... 08:20:44 INFO - Return code: 0 08:20:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:44 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 08:20:44 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 08:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:44 INFO - 'HOME': '/Users/cltbld', 08:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:44 INFO - 'LOGNAME': 'cltbld', 08:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:44 INFO - 'MOZ_NO_REMOTE': '1', 08:20:44 INFO - 'NO_EM_RESTART': '1', 08:20:44 INFO - 'PAGER': '/bin/cat', 08:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:44 INFO - 'PWD': '/builds/slave/test', 08:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:44 INFO - 'SHELL': '/bin/bash', 08:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:44 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:44 INFO - 'USER': 'cltbld', 08:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:44 INFO - 'XPC_FLAGS': '0x0', 08:20:44 INFO - 'XPC_SERVICE_NAME': '0', 08:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:44 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:20:44 INFO - Cleaning up... 08:20:44 INFO - Return code: 0 08:20:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:44 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:44 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 08:20:44 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 08:20:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:44 INFO - 'HOME': '/Users/cltbld', 08:20:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:44 INFO - 'LOGNAME': 'cltbld', 08:20:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:44 INFO - 'MOZ_NO_REMOTE': '1', 08:20:44 INFO - 'NO_EM_RESTART': '1', 08:20:44 INFO - 'PAGER': '/bin/cat', 08:20:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:44 INFO - 'PWD': '/builds/slave/test', 08:20:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:44 INFO - 'SHELL': '/bin/bash', 08:20:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:44 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:44 INFO - 'USER': 'cltbld', 08:20:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:44 INFO - 'XPC_FLAGS': '0x0', 08:20:44 INFO - 'XPC_SERVICE_NAME': '0', 08:20:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:44 INFO - Downloading/unpacking blobuploader==1.2.4 08:20:44 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 08:20:44 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 08:20:44 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 08:20:44 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 08:20:44 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 08:20:44 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 08:20:47 INFO - Downloading blobuploader-1.2.4.tar.gz 08:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:20:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:20:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:47 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 08:20:48 INFO - Downloading docopt-0.6.1.tar.gz 08:20:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:20:48 INFO - Installing collected packages: blobuploader, requests, docopt 08:20:48 INFO - Running setup.py install for blobuploader 08:20:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:20:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:20:48 INFO - Running setup.py install for requests 08:20:49 INFO - Running setup.py install for docopt 08:20:49 INFO - Successfully installed blobuploader requests docopt 08:20:49 INFO - Cleaning up... 08:20:49 INFO - Return code: 0 08:20:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:49 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:49 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 08:20:49 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 08:20:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:49 INFO - 'HOME': '/Users/cltbld', 08:20:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:49 INFO - 'LOGNAME': 'cltbld', 08:20:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:49 INFO - 'MOZ_NO_REMOTE': '1', 08:20:49 INFO - 'NO_EM_RESTART': '1', 08:20:49 INFO - 'PAGER': '/bin/cat', 08:20:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:49 INFO - 'PWD': '/builds/slave/test', 08:20:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:49 INFO - 'SHELL': '/bin/bash', 08:20:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:49 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:49 INFO - 'USER': 'cltbld', 08:20:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:49 INFO - 'XPC_FLAGS': '0x0', 08:20:49 INFO - 'XPC_SERVICE_NAME': '0', 08:20:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-A7PUlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Q30jQu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-S6Ryoh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-keGait-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:49 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-Oc1RKA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-1uNPlw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-NJScap-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-RsnK2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WhBSw5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-fpBanb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-d0_vwD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-pJzaCn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-YDwQnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:50 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-EzbpL2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:51 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-iX0hsV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:51 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-rvGzrr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:51 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9xkTAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:51 INFO - Running setup.py install for manifestparser 08:20:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:51 INFO - Running setup.py install for mozcrash 08:20:51 INFO - Running setup.py install for mozdebug 08:20:51 INFO - Running setup.py install for mozdevice 08:20:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:20:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:51 INFO - Running setup.py install for mozfile 08:20:52 INFO - Running setup.py install for mozhttpd 08:20:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Running setup.py install for mozinfo 08:20:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Running setup.py install for mozInstall 08:20:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Running setup.py install for mozleak 08:20:52 INFO - Running setup.py install for mozlog 08:20:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Running setup.py install for moznetwork 08:20:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:52 INFO - Running setup.py install for mozprocess 08:20:53 INFO - Running setup.py install for mozprofile 08:20:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Running setup.py install for mozrunner 08:20:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Running setup.py install for mozscreenshot 08:20:53 INFO - Running setup.py install for moztest 08:20:53 INFO - Running setup.py install for mozversion 08:20:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:53 INFO - Cleaning up... 08:20:53 INFO - Return code: 0 08:20:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:53 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')]} 08:20:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:53 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')]} 08:20:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:53 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:20:53 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 08:20:53 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 08:20:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:20:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:53 INFO - 'HOME': '/Users/cltbld', 08:20:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:53 INFO - 'LOGNAME': 'cltbld', 08:20:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:53 INFO - 'MOZ_NO_REMOTE': '1', 08:20:53 INFO - 'NO_EM_RESTART': '1', 08:20:53 INFO - 'PAGER': '/bin/cat', 08:20:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:53 INFO - 'PWD': '/builds/slave/test', 08:20:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:53 INFO - 'SHELL': '/bin/bash', 08:20:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:20:53 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:20:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:53 INFO - 'USER': 'cltbld', 08:20:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:53 INFO - 'XPC_FLAGS': '0x0', 08:20:53 INFO - 'XPC_SERVICE_NAME': '0', 08:20:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-VC8dSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-4CDj7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-FU3RWj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-LDOqGm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-8HjJBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-o9_ewz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-UXhtSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:54 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)) 08:20:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:54 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WqXs0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-lmf_kx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-i2ULPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-WDNQ_h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-9wXYtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-57m2HU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-K5sibp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-IwOqwa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-iKMAFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:55 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)) 08:20:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:55 INFO - Running setup.py (path:/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/pip-o663hc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:56 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)) 08:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:56 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)) 08:20:56 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)) 08:20:56 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)) 08:20:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:56 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 08:20:56 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 08:20:56 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 08:20:56 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 08:20:56 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 08:20:56 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 08:20:58 INFO - Downloading blessings-1.6.tar.gz 08:20:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:20:58 INFO - Installing collected packages: blessings 08:20:58 INFO - Running setup.py install for blessings 08:20:58 INFO - Successfully installed blessings 08:20:58 INFO - Cleaning up... 08:20:59 INFO - Return code: 0 08:20:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:20:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:59 INFO - Reading from file tmpfile_stdout 08:20:59 INFO - Current package versions: 08:20:59 INFO - blessings == 1.6 08:20:59 INFO - blobuploader == 1.2.4 08:20:59 INFO - docopt == 0.6.1 08:20:59 INFO - functools32 == 3.2.3-2 08:20:59 INFO - jsonschema == 2.5.1 08:20:59 INFO - manifestparser == 1.1 08:20:59 INFO - mozInstall == 1.12 08:20:59 INFO - mozcrash == 1.0 08:20:59 INFO - mozdebug == 0.1 08:20:59 INFO - mozdevice == 0.48 08:20:59 INFO - mozfile == 1.2 08:20:59 INFO - mozhttpd == 0.7 08:20:59 INFO - mozinfo == 0.9 08:20:59 INFO - mozleak == 0.1 08:20:59 INFO - mozlog == 3.4 08:20:59 INFO - moznetwork == 0.27 08:20:59 INFO - mozprocess == 0.23 08:20:59 INFO - mozprofile == 0.28 08:20:59 INFO - mozrunner == 6.13 08:20:59 INFO - mozscreenshot == 0.1 08:20:59 INFO - mozsystemmonitor == 0.3 08:20:59 INFO - moztest == 0.7 08:20:59 INFO - mozversion == 1.4 08:20:59 INFO - psutil == 3.1.1 08:20:59 INFO - requests == 1.2.3 08:20:59 INFO - wsgiref == 0.1.2 08:20:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:59 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')]} 08:20:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:00 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')]} 08:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:00 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:21:00 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 08:21:00 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 08:21:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:21:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:00 INFO - 'HOME': '/Users/cltbld', 08:21:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:00 INFO - 'LOGNAME': 'cltbld', 08:21:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:00 INFO - 'MOZ_NO_REMOTE': '1', 08:21:00 INFO - 'NO_EM_RESTART': '1', 08:21:00 INFO - 'PAGER': '/bin/cat', 08:21:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:00 INFO - 'PWD': '/builds/slave/test', 08:21:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:00 INFO - 'SHELL': '/bin/bash', 08:21:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:21:00 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:21:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:00 INFO - 'USER': 'cltbld', 08:21:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:00 INFO - 'XPC_FLAGS': '0x0', 08:21:00 INFO - 'XPC_SERVICE_NAME': '0', 08:21:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 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)) 08:21:00 INFO - Cleaning up... 08:21:00 INFO - Return code: 0 08:21:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:21:00 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')]} 08:21:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:21:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:00 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')]} 08:21:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:21:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:21:01 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 0x107586490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074fc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc1ea5abd30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, '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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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 08:21:01 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 08:21:01 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 08:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:01 INFO - 'HOME': '/Users/cltbld', 08:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:01 INFO - 'LOGNAME': 'cltbld', 08:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:01 INFO - 'MOZ_NO_REMOTE': '1', 08:21:01 INFO - 'NO_EM_RESTART': '1', 08:21:01 INFO - 'PAGER': '/bin/cat', 08:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:01 INFO - 'PWD': '/builds/slave/test', 08:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:01 INFO - 'SHELL': '/bin/bash', 08:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:21:01 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:01 INFO - 'USER': 'cltbld', 08:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:01 INFO - 'XPC_FLAGS': '0x0', 08:21:01 INFO - 'XPC_SERVICE_NAME': '0', 08:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:21:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:21:01 INFO - Cleaning up... 08:21:01 INFO - Return code: 0 08:21:01 INFO - Running post-action listener: _resource_record_post_action 08:21:01 INFO - Running post-action listener: _start_resource_monitoring 08:21:01 INFO - Starting resource monitoring. 08:21:01 INFO - [mozharness: 2016-11-17 16:21:01.327602Z] Finished create-virtualenv step (success) 08:21:01 INFO - [mozharness: 2016-11-17 16:21:01.328513Z] Running install step. 08:21:01 INFO - Running pre-action listener: _resource_record_pre_action 08:21:01 INFO - Running main action method: install 08:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:21:01 INFO - Reading from file tmpfile_stdout 08:21:01 INFO - Detecting whether we're running mozinstall >=1.0... 08:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:21:01 INFO - Reading from file tmpfile_stdout 08:21:01 INFO - Output received: 08:21:01 INFO - Usage: mozinstall [options] installer 08:21:01 INFO - Options: 08:21:01 INFO - -h, --help show this help message and exit 08:21:01 INFO - -d DEST, --destination=DEST 08:21:01 INFO - Directory to install application into. [default: 08:21:01 INFO - "/builds/slave/test"] 08:21:01 INFO - --app=APP Application being installed. [default: firefox] 08:21:01 INFO - mkdir: /builds/slave/test/build/application 08:21:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:21:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:21:33 INFO - Reading from file tmpfile_stdout 08:21:33 INFO - Output received: 08:21:33 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:21:33 INFO - Running post-action listener: _resource_record_post_action 08:21:33 INFO - [mozharness: 2016-11-17 16:21:33.949148Z] Finished install step (success) 08:21:33 INFO - [mozharness: 2016-11-17 16:21:33.949297Z] Running run-tests step. 08:21:33 INFO - Running pre-action listener: _resource_record_pre_action 08:21:33 INFO - Running main action method: run_tests 08:21:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:33 INFO - 2016-11-17 08:21:33.962 screenresolution[1627:7420] starting screenresolution argv=screenresolution get 08:21:33 INFO - 2016-11-17 08:21:33.964 screenresolution[1627:7420] Display 0: 1600x1200x32@60 08:21:33 INFO - 2016-11-17 08:21:33.969 screenresolution[1628:7423] starting screenresolution argv=screenresolution list 08:21:33 INFO - Available Modes on Display 0 08:21:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:34 INFO - Intel Iris: 08:21:34 INFO - Chipset Model: Intel Iris 08:21:34 INFO - Type: GPU 08:21:34 INFO - Bus: Built-In 08:21:34 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:34 INFO - Vendor: Intel (0x8086) 08:21:34 INFO - Device ID: 0x0a2e 08:21:34 INFO - Revision ID: 0x0009 08:21:34 INFO - Displays: 08:21:34 INFO - Display: 08:21:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:34 INFO - Display Serial Number: 1600x1200 60 08:21:34 INFO - Main Display: Yes 08:21:34 INFO - Mirror: Off 08:21:34 INFO - Online: Yes 08:21:34 INFO - Rotation: Supported 08:21:34 INFO - Return code: 0 08:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:34 INFO - Python 2.7.3 08:21:34 INFO - Return code: 0 08:21:34 INFO - grabbing minidump binary from tooltool 08:21: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')]} 08:21:34 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 0x10739ad30>, '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 0x107200db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107392b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:34 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 08:21:34 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 08:21:34 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 08:21:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:34 INFO - Return code: 0 08:21:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 08:21:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0286 --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 08:21:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.V1aaIVeYfr/Render', 08:21:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:34 INFO - 'HOME': '/Users/cltbld', 08:21:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:34 INFO - 'LOGNAME': 'cltbld', 08:21:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:34 INFO - 'MOZ_NO_REMOTE': '1', 08:21:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:34 INFO - 'NO_EM_RESTART': '1', 08:21:34 INFO - 'PAGER': '/bin/cat', 08:21:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:34 INFO - 'PWD': '/builds/slave/test', 08:21:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:34 INFO - 'SHELL': '/bin/bash', 08:21:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.LUoEzPs7lK/Listeners', 08:21:34 INFO - 'TMPDIR': '/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/', 08:21:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:34 INFO - 'USER': 'cltbld', 08:21:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:34 INFO - 'XPC_FLAGS': '0x0', 08:21:34 INFO - 'XPC_SERVICE_NAME': '0', 08:21:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0286', '--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 08:21:34 INFO - mozversion application_buildid: 20161117060328 08:21:34 INFO - mozversion application_changeset: 64936ba0330a72cf5e05bca5f44b84562705fea0 08:21:34 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:21:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:34 INFO - mozversion application_name: Firefox 08:21:34 INFO - mozversion application_remotingname: firefox-dev 08:21:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:21:34 INFO - mozversion application_vendor: Mozilla 08:21:34 INFO - mozversion application_version: 52.0a2 08:21:34 INFO - mozversion platform_buildid: 20161117060328 08:21:34 INFO - mozversion platform_changeset: 64936ba0330a72cf5e05bca5f44b84562705fea0 08:21:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:21:34 INFO - mozversion platform_version: 52.0a2 08:21:34 INFO - using testdate: 1479399694 08:21:34 INFO - actual date: 1479399694 08:21:34 INFO - starting webserver on 'localhost:49242' 08:21:34 INFO - SUITE-START | Running 2 tests 08:21:34 INFO - TEST-START | dromaeo_css 08:21:34 INFO - operating with platform_type : mac_ 08:21:34 INFO - Initialising browser for dromaeo_css test... 08:21:34 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpHYlXpG/profile http://localhost:49242/getInfo.html) 08:21:36 ERROR - PROCESS | 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:21:37 INFO - PROCESS | 1638 | __metrics Screen width/height:1600/1200 08:21:37 INFO - PROCESS | 1638 | colorDepth:24 08:21:37 INFO - PROCESS | 1638 | Browser inner width/height: 1024/689 08:21:37 INFO - PROCESS | 1638 | __metrics 08:21:37 INFO - PROCESS | 1638 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:21:37 INFO - PROCESS | 1638 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:21:37 INFO - TEST-INFO | 1638: exit 0 08:21:37 INFO - Browser initialized. 08:21:37 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:37 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpHYlXpG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:49 INFO - PROCESS | 1640 | RSS: Main: 233852928 08:21:49 INFO - PROCESS | 1640 | 08:22:41 INFO - PROCESS | 1640 | 1479399761210 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] 08:24:53 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 08:24:55 INFO - PROCESS | 1640 | RSS: Main: 389799936 08:24:55 INFO - PROCESS | 1640 | 08:27:58 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 08:27:59 INFO - PROCESS | 1640 | RSS: Main: 363331584 08:27:59 INFO - PROCESS | 1640 | 08:31:01 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 08:31:02 INFO - PROCESS | 1640 | RSS: Main: 333287424 08:31:02 INFO - PROCESS | 1640 | 08:34:04 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 08:34:05 INFO - PROCESS | 1640 | RSS: Main: 334397440 08:34:05 INFO - PROCESS | 1640 | 08:37:08 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 08:37:09 INFO - PROCESS | 1640 | RSS: Main: 322400256 08:37:09 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 08:40:12 INFO - PROCESS | 1640 | RSS: Main: 401555456 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | __start_tp_report 08:40:12 INFO - PROCESS | 1640 | _x_x_mozilla_page_load 08:40:12 INFO - PROCESS | 1640 | _x_x_mozilla_page_load_details 08:40:12 INFO - PROCESS | 1640 | |i|pagename|runs| 08:40:12 INFO - PROCESS | 1640 | |0;dojo.html;3272.854500924675;3308.4541050726625;3326.5010248462727;3343.247769251917;3381.966180338196;4892.6575139740235;5725;5725.942740572593;5750.6262092963925;5768.07710766277;5658.377578466376;5756.856078598037;5761.643631671673;5781.479666829984;5817.185594016831;5195.558377537905;5205.453427390126;5214.426413094576;5244.108501554726;5259.737013149346;5171.844844654666;5180.430152683201;5310.92033619496;5315.654482458628;5348.679079255237;4437.023854751949;5177.249298851664;5230.267762513232;5234.319538460014;5248.6588371755715;4841.298011788289;5227.954409118153;5232;5286.3127793387;5305.708186049766;5159.71621560814;5189.325387699575;5222.164453687392;5224.085784987612;5233.869153271161;5615.438456154393;5651.378348381716;5657.462541058593;5658.519025882805;5708.457696518824;6096.435975519046;6140.149282067162;6153.446189842935;6157.630542167484;6235.438810507031;6239.875202495925;6311.0217916223055;6323.557350985433;6337.683115844189;6338.30278669346;5664.603477756517;5754.856128596819;5754.884902302014;5788.3343415506915;5829.387914268941;6241.78153764616;6270.780522681775;6283.02613094971;6294.181756371734;6319.304876463585;6151.569390142737;6160.383961603804;6162.168107305456;6174.166487524217;6175.722092505848;6144.754209831646;6145.231846019247;6171.228596425447;6182.227221597301;6279.780207692709;6170.598911070766;6217.036359364306;6221.253449586078;6221.3156552779155;6241.157443745036;6118.449339559461;6143.078538219213;6198.287196972406;6203.286622038433;6206.317305096436;4862.95137048632;4908.607311415079;4920.335754673073;4925.482824303468;4949.331840201598;4367.388565600819;4422.867313980586;4475.530069342737;4476;4479.843205487792;5824.650520968756;5825.0679891217205;5825.097109947965;5836.533077353802;5872.207252021008;6190.845228869314;6305;6318.589291696025;6323.747050118036;6326.810195694136;5637.802676906289;5670.971645141831;5692.573057020657;5704.458076482728;5761.135829625589;5028.59771218309;5352.598555108382;5391.272178255964;5391.892162156735;5414.377346605112;6253.53098517613;6261.467775239155;6276.027215781653;6286.6856657166045;6288.088227207121;6038.094285857156;6174.166487524127;6230.43926046658;6251.812445626639;6270.780522681866;6201.596896201732;6217.906731398942;6243.219597550306;6274.843128921813;6280.780172694116;6164.753409863734;6207.782727604512;6216.564840461125;6225.3774622537385;6233.470155036691;5807.941920580909;6145.016797312409;6280.654563999024;6331.525135614939;6339.714712837841;6054.061620448838;6063.181470501426;6063.575549711478;6108.114323423168;6136.539759518143;5917.319508256605;6007.519398448201;6107.358727333566;6107.6640784757265;6128.417800309142;5928.9110663339225;5951.256092988377;5957.195778570011;5959.731812068554;5964.165016897725;5043.798248070036;5907.91138132937;6005.069214271794;6086.208792857077;6124.387561244018;5954.553408494294;5981.371955944737;5984.31180414258;5984.820455386345;6029.155918171372 08:40:12 INFO - PROCESS | 1640 | |1;ext.html;449.62905602877595;463.19635432524564;474.8148222193343;518.9195674670425;521.7313083761861;25613;30848.845755771246;31377.68622313779;38255.42616860746;39412.80293598528;40977.795111024476;41100.58899411005;41181.58818411815;41259;41325.380119298236;9208.769780755485;9290.535473226346;9400.7649808755;9425.481598512079;9430.952845235799;8442.366822488308;8641.135886411372;9270.39742416744;11003.449827508633;11285.15361347898;11217;11288.830667539953;11289.548418063308;11332.603358882441;11380.658580242605;11202.215844890861;11273.605423810128;11299.774004519864;11306.265092768943;11306.925842044993;10964.232503724743;11026.062784663312;11075.944620276847;11156.553737850476;11175.664730058112;38489.23021539554;38907.805460972515;38935.80532097322;38965;39084.43662253535;3234.66036066212;3285;3326.2183386904057;3475.2701932594186;3654.9817250913843;28298.575521367195;29647.407051859056;29702;29855.25361865949;30513.847430762704;3166.5725127108008;3174.793638413496;3212.710856022946;3236.3365510070616;3319.5850518685165;352430;352751.4724852744;352912.4708752905;353116;354238;28391.858040709663;32992.340153197285;58949.41050589439;59406.10890836641;59483.70258148682;28749.425011499654;34760.82619586886;34800;34801.47797783036;34831.4775278371;14317;14366.281685915663;14522.564323070113;14524.419023238952;14531.927340363442;11133.276337038229;11821.763564728657;11871.70320741971;12201.328926909106;12281.93859030699;7187.748428805071;7467.775966721007;7469.029026226556;7500.512466689648;7659.9234007660325;3184.1561986073707;3190.6809319067906;3197;3197.71220590148;3226.9838650806596;913.6436789652041;932.4125800745622;952.3952290295574;974.2449601558707;1081.3728037738092;27733.306667333076;28668.42663146767;29315.85342073276;29619.85190074036;29688.960886368874;856.6316483911853;1033.338663255517;1052.5894900988621;1053.441675911768;1105.38098664748;15161.241937903062;15262.618434539003;15313.540593782205;15521.456749013956;15614.45349412765;616053.9197304074;762917;771953;772726;968631;44425;54924.62688432821;55027.724861375435;55175.44824551784;55335;1216.4829947272374;1247.2080229054434;1335.1455068756004;1344.4890941442188;1366.842813076489;23571.646425303978;29708.405831883672;30498;30574.388512229187;30756.384872302875;54287.18569221386;54627.72686136703;54650.18024729712;54739;54881.72559137338;530499;626736;629429;630004;630556;26208.606870896572;26394.340141496617;26496.337591560365;26767;34620.653793461744;40267;46207;47036.059278814915;48389.03221935471;48538;38136;38321.4251786229;38889.22221555497;39208;39730;26747.598786018614;37390.62609373871;37818.0545486354;37830;38094.428583571826;36536;37414;37791.62208377991;38265;38357.84926452211;32274.35451291008;33696.663033369354;33904.99210931125;34323;34616.82691586526;25186;25227.74772252254;25288.747112529374;25327.98688052457;25761.355966100247 08:40:12 INFO - PROCESS | 1640 | |2;jquery.html;372.11993635053096;375.43497036959354;383.7869982159902;391.82172111689187;403.14734336877495;15002.324895379707;15144.096807578999;18263.72604410933;19939.60120797585;20261.08825102872;12322.81515777264;18749.906250468728;19661.311854085074;19936.60126797463;20109.095090720915;5544.22380866679;5571.8328450146355;5811.970940145294;5859.0625499920025;5992.970035149818;3759.398496240602;3977.840886364542;5903.88192236155;5939.435753603422;5947.940520594782;5593.916091258634;5856.677882716449;5923.555733319997;5979.372165922581;5991.970040149793;5637.80267690633;5854.121881717734;5898.292204935392;5952.910706339387;5971.5222782177325;5708.058170401879;5715.771369145229;5720.656760594336;5723.5421166307015;5732;14761;22614.09543618253;22668;23490.412739681473;23764;1857.1549944775095;2192.8355373347063;2193.046024979139;2247.2134752836378;2290.2900100968754;9747.90252097477;10104.545295461721;10189.337693050002;11020.338779673246;13554.661133471644;1766.8409843114052;1848.168324254091;1851.4445666300055;1859.1819599376367;1867.934622288227;125008;125833;132454;133383.33308333493;136119.31940340236;11295.830562541569;11463.484143213574;11540.538378464935;11590.420478976017;11671.474783634756;12755.872441275655;14528.273586320642;14546.345414456375;14553.12681239108;14577.125372477472;7451.962740186264;7518.323350898446;8258.504489730636;8350.248477637042;8400.873986890201;8236.258736713604;8244.29923456501;8397.28623067034;8412.453190542596;8421.24208821209;4029.7380670256925;4448.332750087513;4546.65900057497;4552.817887284537;4627.514111018295;1750.8202288151333;1766.2140347545476;1831.6519861226325;1837.1457272368414;1840.8435283000827;823.9011318641801;851.1743608699552;858.5664239558982;907.9727608171765;980.5538479991586;18910.716339254923;20099.497512562302;20289.086991085438;20366.694499582518;20714.275000375215;868.4572142410993;912.6258234123977;934.0985948559672;967.4920666650065;972.1833659725864;10163.237757168094;10565.577376904837;10630.52162652667;10679.626213082505;10703.78592428147;612593;616152;627390;632232;634029.829850857;106259;135337;135612.32193839166;136247;137261;797.9760607181794;930.1721467893715;940.6049459227141;949.6106596295624;953.8569214617863;13265.469381224642;17262;17433.651326973642;18336.616239888728;18509.9074504632;52934;53712.73143634257;54278.728606356715;54808;54998.45001550093;55654.72172639273;75492.86760698704;76773.61613191899;78085.21914781007;79746;7690.769276921701;7804.414668899969;7867.17394673551;8024.2376974187355;8088.110307866248;10504.947475262576;12015.879841201477;12055.15613907018;12152.392380380768;12330.876691233332;10203;10392.272540922366;10718.35689858611;10766.515506802369;10840.67477975662;4981.10340138764;6987.763915546976;8762;9563.139317461462;10364.951832753799;8847;10578.259521833397;10778.784424311627;10794.892051079703;11094.944525277322;7726.729564465441;7914.16901225363;8015.879761803694;8058.959705201437;8103.756887293391;10198.184145268213;10422.843657344994;10696.03735663794;10703.464826758787;10784.838227426751 08:40:12 INFO - PROCESS | 1640 | |3;mootools.html;1286.2925391034928;1366.904316697831;1409.4221369238608;1792.5608225984636;1831.3498707958674;6799.4560435165195;8491.872621910676;8496.957515212423;8521.275691566208;8525.74422767318;6653.966730166342;7499;7732.420068494871;8326.500409975406;8328.000639923217;2311.271949335958;2348.988255058731;2357.49313897512;2361.9291421257303;2361.929142125739;2346.061575369857;2372.406898275431;2374.1453076892303;2392.724836643782;2395.880205989702;2320.4894923116885;2338.602437585606;2350.976490235101;2359.9646005309933;2360.704911886014;2306.273523839985;2310.27226423676;2337.3689103941842;2343.6601692754457;2343.660169275454;2242.742084660252;2250.44864008319;2256.368216899271;2257.4694946687514;2261.3216035189375;8292.460990035628;8397.6221070052;8412.957935210346;8420.663173473054;8427.83144337116;5011.32347133136;5178.715170665612;5186.014657215154;5192.662476939025;5193.221016847466;3267.5425440438357;3365.8821941232186;3606.5672119345845;3615.963840361589;3629.4737263096704;1065.211743309953;1066.9093127084207;1099.4447803859;1120.5797825815318;1121.1199208621274;10806.78386432267;10945.726356841144;10973.122150227882;10988.615398461016;11010.284331518425;3832.9616703832603;4338.9566104338555;4412.845550405785;4602.767657686019;4719.221328480831;9347.252219822398;9374.531273436301;9374.718758437259;9377.859332110023;9379.812403751886;5124.436312005676;5133.383993920754;5134.05020071288;5140.228965655107;5144.588432925357;4247.44783178191;4379.211741886491;4618.792154353062;4695.929561056587;4729.196036673774;3740.121071548184;3773.584905660375;3820.7898565578603;3854.672352849983;3865.362215234511;1080.935143891369;1251.8497780266425;1254.2349167008117;1256.403208475985;1271.6375832887582;779.8752199648029;856.8757530158092;885.2298500304776;891.9920489846432;924.9861252081224;5496.780128794883;5665.688387138664;5838.299404071538;5856.824295271149;5930.584859059911;890.3678388344348;907.950062746542;926.4441335198961;931.4504442378893;941.204682043672;3651.2332410193626;3791.7156213284115;3798.9932667843045;3865.091703449687;3882.165334453106;9747.122758951587;10239.897601024044;10253.974602539687;10261.74345641365;10265.384076955257;9653;10063.446510441849;10219.795604088025;10248.48757562119;10254.025867542721;7826.2782187918565;7985.24140206691;7991.640376183086;8008.879866802003;8021.598920053974;5438.211459338295;5489.204065410573;5502.257195278587;5589.664620122643;5617.606767526234;9328.720138395858;9464.148226659634;9576.425414475154;9604.423734575948;9652.034796520291;9072.459711280851;9499.762505937408;9503.097205765442;9556.331056826233;9561.235101192027;6570.310117437601;6806.285340039422;6816.182058153054;7103.964480177566;7299.233580473974;7598.0502437195355;7748.496347737604;7862.2923936845955;7910.762677119696;7929.5242285463055;6969.477289203229;6979.755708550177;7003.19463261711;7113.893291600527;7262.564246145248;5763.7982670606325;6991;7018.684159212949;7071.7878463646175;7083.008378827071;6639.900401494078;6715.798526044031;6783.559068660521;6784.898226526508;6786.185657721105;4885.462599114024;4886.560209581013;4905;4956.207006878882;4958.776855041604;4822.42130944289;4836.854894353175;4939.777710003131;4991.5258050485145;5023.045621331935 08:40:12 INFO - PROCESS | 1640 | |4;prototype.html;264.6889904362374;296.8990543215306;309.19609016556956;311.83316925444893;312.12760334864055;4320.049589090403;4845.588125009374;5231.816886408977;5282.973585132074;5371.140617501201;4051.3720373342107;5132.948670513303;5207.427183009866;5237.638214064342;5307.867303317419;3346.933061338772;3368.983155084221;3595.7303202259804;4044.3933409988445;4094.754314741125;3566.6076731559506;3590.856365745367;3922.607739226069;4043.353063509839;4052.986753311672;3854.498915141042;3904.3362628353248;3955.09032922428;4018.2967980603426;4065;3942.467766851467;4027.7381970171846;4043.2722110020186;4068.5321188063454;4068.572799856017;3117.345357474933;3548.414511605582;3569.303985722785;3581.212133330663;3587.475322987746;4060.593940605945;5138.820141295037;5203.699075231192;5214.947850521484;5248.031988004498;4655.51117132703;4687.88280292992;4687.9765601171775;4700.615556799099;4737.526247375269;5594.9720251398885;5601.694999950008;5619.494245517923;5629.891567649106;5636.71816409182;3520.5883969480988;3895.3767397216593;3929.98035009826;3935.9016024599327;3946;5451.863703407407;5843.649381037151;5891.878923587548;5901.58688891782;5935.358765349378;3415.1803567143706;3686.4286035664513;3832.885013449601;3845.288621605012;3851.114243723903;3925.3130702127014;3928.253631810004;3929.646331830149;3930.8231129599235;3953;4057.411675307123;4133.545310015896;4434.246178149722;4540.046590216026;4580.290055041474;5052.797888084509;5217.817376391884;5615.382307946123;5616.6068375213345;5629.324481062299;1256.1897576063388;1264.1782841153324;1266.8353114095294;1272.7709012377861;1273.3505911984769;799.5003123048095;813.8931053766872;852.8294341131799;869.5478351257311;873.694207027548;4114.958850411517;4461.643068554507;4630.32860235264;4927.969298881162;5048.949510504922;4478.776061196927;4671.95328046722;4746.857594272177;5225.3207083079715;5364.7317634118135;4398.824047038082;4487.798049087799;4576.4737055239075;4876.195427754452;5074.619403544749;2402.4594466245303;2542.2585528912755;2770.279727270924;2798.614685730576;2805.9477695864052;5051.494850514919;5462.945370546244;5577;5755.395683453177;5805.909872445916;4781.282807578891;4792.616590672807;5164.457731938168;5324.600654950894;5655;4988;5275.762590683428;5455.809046683347;5556.888862222733;5619.971900140473;4839.830605928775;5016.874578135576;5043.720567892997;5202.583793296603;5447.338561738549;4846.389439867321;5007;5156.871078223075;5408.025585927802;5452.564884122977;5027.963674711747;5388.56891448691;5445.6385903524115;5692.402297758676;5705.7432415542235;3270.971997361651;3510.736694747853;3647.544056992876;3658.853645854136;3677.623627882811;5039.445660977363;5362.9463705364005;5436.374816895949;5478.972605136949;5495.53287970527;4744.129801470061;4790.197734325319;4853.635977301646;5311.88450425415;5323.866903327448;4555.647114640351;5175.896482070413;5256.89486210266;5277.709725964955;5344.625876188629;4955;5425.403205647454;5449.373322067854;5554.638948468337;5583.720813959367;5220.817271395483;5698.886022279614;5710.914336285042;5736.0248757712125;5773.364929857797;4645.5354464553275;5214;5452.972735136457;5618.438156184349;5648.057949294367 08:40:12 INFO - PROCESS | 1640 | |5;yui.html;747.6261869065468;769.2461387839913;778.1673609238118;783.6982761636768;788.6529926832194;13801.171929684226;14057.156570605744;14447.494337698183;14474.059186152906;14512.637184070403;12799.680007999805;12885.935570322135;12915.741685166291;12947;12948.74102517949;640.69887153038;658.8319978405524;662.7680311890824;781.5662307419394;802.1497212954287;785.0540099180474;794.6900708723603;812.7317985064944;814.2630919018268;815.388458656008;848.0755975986174;850.086157380815;851.6806197675871;851.9062903080656;855.5038077914794;672.8217022489044;686.4679873098339;691.3708525242006;845.4842546046906;845.9890430935035;514.2851436503263;514.9433562308143;613.1385403508078;621.7699451203039;627.7583130494746;13759.96800239986;13806.65483362914;13807.378667959965;13810.585682429542;13811.861881381157;12915.418806153746;13029.934850325688;13136.934315328363;13208.867911320956;13269;9562.521873906277;11802.704932376673;11858.102703567474;12131.93934030333;12203.511859525608;4351.325544540601;4443.577860103285;4499.6400287976885;4514.277715565494;4517.141743068806;83512;88343.55828220882;88935.55532222362;92187.0781292192;92190;10226.6420675276;10280.881246855786;10518.158547316196;10569.577216911237;10577.682669519927;19972.50068748293;20102.59794804125;20135.0939207736;20243;20476.38570842877;7404.111506619241;7441.590712510756;7494.100707914976;7498.625068746541;7500.849983000309;3729.533808273966;4158.147579746144;4243.639290660267;4259.148170365939;4362.6946113772365;3289.3256882339065;3336.399448099317;3373.1567108222944;3376.4260075786683;3387.8644854205554;525.7607788456244;541.7426722306873;578.069308413454;591.5711109445704;609.6128958111631;587.5916238214486;598.2163365990566;611.2023808929301;617.9309794056308;623.7536173211556;8483.034426708808;8751.956240218886;8774.429662071945;8777.605007774666;8919.152680495343;521.7704210147599;594.6580716088266;606.5602438232335;607.9392060793974;644.6486664767713;3949.565547789798;4248.065425606362;4252.55348188442;4262.232798096373;4348.804303806336;84991.15008849833;111083;112661.87338126678;113385.4330728341;113978;74120;96705;98198.50900745594;101644;102396.97603023874;80655.19344806594;81349.59325203336;81933.59033204796;82909;83986.5800671005;7929.11099358386;8268.338532917232;8366.832663346577;8382.664693412195;8474.237318641352;51583.226251607004;56213.718931405085;56358;57437.712811435675;59021.704891476984;79894;79896;81489.59255203686;83711.16288837034;85230;10366.06705396518;10485.056344928993;10745.623903163352;10839.916008399412;10861.837072443761;11527.250728702607;11631.360275184608;11709.531618735155;12222.266664000188;12418.192817466836;11036.724081898017;11288;11394.430278486208;11411.315321080761;11674.066074714172;7682.423818213544;12545.435455404346;12738.929774229762;12941.352932353157;12953.417096230503;12306.323152226714;12918.612441626767;13091.083624146599;13443.529476468277;13457.865421345661;9550.140487356171;9995.500224988577;10920.235583509078;11126.833097503382;11325.377104259345;10085.596576136872;10495.317804342998;11037.448127593427;11052.668419947415;11216.887831121585 08:40:12 INFO - PROCESS | 1640 | __end_tp_report 08:40:12 INFO - PROCESS | 1640 | __start_cc_report 08:40:12 INFO - PROCESS | 1640 | _x_x_mozilla_cycle_collect,1788 08:40:12 INFO - PROCESS | 1640 | __end_cc_report 08:40:12 INFO - PROCESS | 1640 | __startTimestamp1479400812941__endTimestamp 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | ------- Summary: start ------- 08:40:12 INFO - PROCESS | 1640 | Number of tests: 6 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#0] dojo.html Cycles:165 Average:5727.41 Median:5957.20 stddev:649.79 (10.9%) stddev-sans-first:622.60 08:40:12 INFO - PROCESS | 1640 | Values: 3272.9 3308.5 3326.5 3343.2 3382.0 4892.7 5725.0 5725.9 5750.6 5768.1 5658.4 5756.9 5761.6 5781.5 5817.2 5195.6 5205.5 5214.4 5244.1 5259.7 5171.8 5180.4 5310.9 5315.7 5348.7 4437.0 5177.2 5230.3 5234.3 5248.7 4841.3 5228.0 5232.0 5286.3 5305.7 5159.7 5189.3 5222.2 5224.1 5233.9 5615.4 5651.4 5657.5 5658.5 5708.5 6096.4 6140.1 6153.4 6157.6 6235.4 6239.9 6311.0 6323.6 6337.7 6338.3 5664.6 5754.9 5754.9 5788.3 5829.4 6241.8 6270.8 6283.0 6294.2 6319.3 6151.6 6160.4 6162.2 6174.2 6175.7 6144.8 6145.2 6171.2 6182.2 6279.8 6170.6 6217.0 6221.3 6221.3 6241.2 6118.4 6143.1 6198.3 6203.3 6206.3 4863.0 4908.6 4920.3 4925.5 4949.3 4367.4 4422.9 4475.5 4476.0 4479.8 5824.7 5825.1 5825.1 5836.5 5872.2 6190.8 6305.0 6318.6 6323.7 6326.8 5637.8 5671.0 5692.6 5704.5 5761.1 5028.6 5352.6 5391.3 5391.9 5414.4 6253.5 6261.5 6276.0 6286.7 6288.1 6038.1 6174.2 6230.4 6251.8 6270.8 6201.6 6217.9 6243.2 6274.8 6280.8 6164.8 6207.8 6216.6 6225.4 6233.5 5807.9 6145.0 6280.7 6331.5 6339.7 6054.1 6063.2 6063.6 6108.1 6136.5 5917.3 6007.5 6107.4 6107.7 6128.4 5928.9 5951.3 5957.2 5959.7 5964.2 5043.8 5907.9 6005.1 6086.2 6124.4 5954.6 5981.4 5984.3 5984.8 6029.2 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#1] ext.html Cycles:180 Average:69214.13 Median:34618.74 stddev:164627.49 (475.5%) stddev-sans-first:165008.35 08:40:12 INFO - PROCESS | 1640 | Values: 449.6 463.2 474.8 518.9 521.7 25613.0 30848.8 31377.7 38255.4 39412.8 40977.8 41100.6 41181.6 41259.0 41325.4 9208.8 9290.5 9400.8 9425.5 9431.0 8442.4 8641.1 9270.4 11003.4 11285.2 11217.0 11288.8 11289.5 11332.6 11380.7 11202.2 11273.6 11299.8 11306.3 11306.9 10964.2 11026.1 11075.9 11156.6 11175.7 38489.2 38907.8 38935.8 38965.0 39084.4 3234.7 3285.0 3326.2 3475.3 3655.0 28298.6 29647.4 29702.0 29855.3 30513.8 3166.6 3174.8 3212.7 3236.3 3319.6 352430.0 352751.5 352912.5 353116.0 354238.0 28391.9 32992.3 58949.4 59406.1 59483.7 28749.4 34760.8 34800.0 34801.5 34831.5 14317.0 14366.3 14522.6 14524.4 14531.9 11133.3 11821.8 11871.7 12201.3 12281.9 7187.7 7467.8 7469.0 7500.5 7659.9 3184.2 3190.7 3197.0 3197.7 3227.0 913.6 932.4 952.4 974.2 1081.4 27733.3 28668.4 29315.9 29619.9 29689.0 856.6 1033.3 1052.6 1053.4 1105.4 15161.2 15262.6 15313.5 15521.5 15614.5 616053.9 762917.0 771953.0 772726.0 968631.0 44425.0 54924.6 55027.7 55175.4 55335.0 1216.5 1247.2 1335.1 1344.5 1366.8 23571.6 29708.4 30498.0 30574.4 30756.4 54287.2 54627.7 54650.2 54739.0 54881.7 530499.0 626736.0 629429.0 630004.0 630556.0 26208.6 26394.3 26496.3 26767.0 34620.7 40267.0 46207.0 47036.1 48389.0 48538.0 38136.0 38321.4 38889.2 39208.0 39730.0 26747.6 37390.6 37818.1 37830.0 38094.4 36536.0 37414.0 37791.6 38265.0 38357.8 32274.4 33696.7 33905.0 34323.0 34616.8 25186.0 25227.7 25288.7 25328.0 25761.4 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#2] jquery.html Cycles:180 Average:35539.04 Median:2067.42 stddev:104437.29 (5051.6%) stddev-sans-first:104696.89 08:40:12 INFO - PROCESS | 1640 | Values: 372.1 375.4 383.8 391.8 403.1 15002.3 15144.1 18263.7 19939.6 20261.1 12322.8 18749.9 19661.3 19936.6 20109.1 5544.2 5571.8 5812.0 5859.1 5993.0 3759.4 3977.8 5903.9 5939.4 5947.9 5593.9 5856.7 5923.6 5979.4 5992.0 5637.8 5854.1 5898.3 5952.9 5971.5 5708.1 5715.8 5720.7 5723.5 5732.0 14761.0 22614.1 22668.0 23490.4 23764.0 1857.2 2192.8 2193.0 2247.2 2290.3 9747.9 10104.5 10189.3 11020.3 13554.7 1766.8 1848.2 1851.4 1859.2 1867.9 125008.0 125833.0 132454.0 133383.3 136119.3 11295.8 11463.5 11540.5 11590.4 11671.5 12755.9 14528.3 14546.3 14553.1 14577.1 7452.0 7518.3 8258.5 8350.2 8400.9 8236.3 8244.3 8397.3 8412.5 8421.2 4029.7 4448.3 4546.7 4552.8 4627.5 1750.8 1766.2 1831.7 1837.1 1840.8 823.9 851.2 858.6 908.0 980.6 18910.7 20099.5 20289.1 20366.7 20714.3 868.5 912.6 934.1 967.5 972.2 10163.2 10565.6 10630.5 10679.6 10703.8 612593.0 616152.0 627390.0 632232.0 634029.8 106259.0 135337.0 135612.3 136247.0 137261.0 798.0 930.2 940.6 949.6 953.9 13265.5 17262.0 17433.7 18336.6 18509.9 52934.0 53712.7 54278.7 54808.0 54998.5 55654.7 75492.9 76773.6 78085.2 79746.0 7690.8 7804.4 7867.2 8024.2 8088.1 10504.9 12015.9 12055.2 12152.4 12330.9 10203.0 10392.3 10718.4 10766.5 10840.7 4981.1 6987.8 8762.0 9563.1 10365.0 8847.0 10578.3 10778.8 10794.9 11094.9 7726.7 7914.2 8015.9 8059.0 8103.8 10198.2 10422.8 10696.0 10703.5 10784.8 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#3] mootools.html Cycles:180 Average:5439.04 Median:5133.72 stddev:2984.98 (58.1%) stddev-sans-first:2977.04 08:40:12 INFO - PROCESS | 1640 | Values: 1286.3 1366.9 1409.4 1792.6 1831.3 6799.5 8491.9 8497.0 8521.3 8525.7 6654.0 7499.0 7732.4 8326.5 8328.0 2311.3 2349.0 2357.5 2361.9 2361.9 2346.1 2372.4 2374.1 2392.7 2395.9 2320.5 2338.6 2351.0 2360.0 2360.7 2306.3 2310.3 2337.4 2343.7 2343.7 2242.7 2250.4 2256.4 2257.5 2261.3 8292.5 8397.6 8413.0 8420.7 8427.8 5011.3 5178.7 5186.0 5192.7 5193.2 3267.5 3365.9 3606.6 3616.0 3629.5 1065.2 1066.9 1099.4 1120.6 1121.1 10806.8 10945.7 10973.1 10988.6 11010.3 3833.0 4339.0 4412.8 4602.8 4719.2 9347.3 9374.5 9374.7 9377.9 9379.8 5124.4 5133.4 5134.1 5140.2 5144.6 4247.4 4379.2 4618.8 4695.9 4729.2 3740.1 3773.6 3820.8 3854.7 3865.4 1080.9 1251.8 1254.2 1256.4 1271.6 779.9 856.9 885.2 892.0 925.0 5496.8 5665.7 5838.3 5856.8 5930.6 890.4 908.0 926.4 931.5 941.2 3651.2 3791.7 3799.0 3865.1 3882.2 9747.1 10239.9 10254.0 10261.7 10265.4 9653.0 10063.4 10219.8 10248.5 10254.0 7826.3 7985.2 7991.6 8008.9 8021.6 5438.2 5489.2 5502.3 5589.7 5617.6 9328.7 9464.1 9576.4 9604.4 9652.0 9072.5 9499.8 9503.1 9556.3 9561.2 6570.3 6806.3 6816.2 7104.0 7299.2 7598.1 7748.5 7862.3 7910.8 7929.5 6969.5 6979.8 7003.2 7113.9 7262.6 5763.8 6991.0 7018.7 7071.8 7083.0 6639.9 6715.8 6783.6 6784.9 6786.2 4885.5 4886.6 4905.0 4956.2 4958.8 4822.4 4836.9 4939.8 4991.5 5023.0 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#4] prototype.html Cycles:180 Average:4383.92 Median:4850.01 stddev:1339.95 (27.6%) stddev-sans-first:1307.55 08:40:12 INFO - PROCESS | 1640 | Values: 264.7 296.9 309.2 311.8 312.1 4320.0 4845.6 5231.8 5283.0 5371.1 4051.4 5132.9 5207.4 5237.6 5307.9 3346.9 3369.0 3595.7 4044.4 4094.8 3566.6 3590.9 3922.6 4043.4 4053.0 3854.5 3904.3 3955.1 4018.3 4065.0 3942.5 4027.7 4043.3 4068.5 4068.6 3117.3 3548.4 3569.3 3581.2 3587.5 4060.6 5138.8 5203.7 5214.9 5248.0 4655.5 4687.9 4688.0 4700.6 4737.5 5595.0 5601.7 5619.5 5629.9 5636.7 3520.6 3895.4 3930.0 3935.9 3946.0 5451.9 5843.6 5891.9 5901.6 5935.4 3415.2 3686.4 3832.9 3845.3 3851.1 3925.3 3928.3 3929.6 3930.8 3953.0 4057.4 4133.5 4434.2 4540.0 4580.3 5052.8 5217.8 5615.4 5616.6 5629.3 1256.2 1264.2 1266.8 1272.8 1273.4 799.5 813.9 852.8 869.5 873.7 4115.0 4461.6 4630.3 4928.0 5048.9 4478.8 4672.0 4746.9 5225.3 5364.7 4398.8 4487.8 4576.5 4876.2 5074.6 2402.5 2542.3 2770.3 2798.6 2805.9 5051.5 5462.9 5577.0 5755.4 5805.9 4781.3 4792.6 5164.5 5324.6 5655.0 4988.0 5275.8 5455.8 5556.9 5620.0 4839.8 5016.9 5043.7 5202.6 5447.3 4846.4 5007.0 5156.9 5408.0 5452.6 5028.0 5388.6 5445.6 5692.4 5705.7 3271.0 3510.7 3647.5 3658.9 3677.6 5039.4 5362.9 5436.4 5479.0 5495.5 4744.1 4790.2 4853.6 5311.9 5323.9 4555.6 5175.9 5256.9 5277.7 5344.6 4955.0 5425.4 5449.4 5554.6 5583.7 5220.8 5698.9 5710.9 5736.0 5773.4 4645.5 5214.0 5453.0 5618.4 5648.1 08:40:12 INFO - PROCESS | 1640 | 08:40:12 INFO - PROCESS | 1640 | [#5] yui.html Cycles:180 Average:20400.35 Median:4515.71 stddev:30296.94 (670.9%) stddev-sans-first:30345.99 08:40:12 INFO - PROCESS | 1640 | Values: 747.6 769.2 778.2 783.7 788.7 13801.2 14057.2 14447.5 14474.1 14512.6 12799.7 12885.9 12915.7 12947.0 12948.7 640.7 658.8 662.8 781.6 802.1 785.1 794.7 812.7 814.3 815.4 848.1 850.1 851.7 851.9 855.5 672.8 686.5 691.4 845.5 846.0 514.3 514.9 613.1 621.8 627.8 13760.0 13806.7 13807.4 13810.6 13811.9 12915.4 13029.9 13136.9 13208.9 13269.0 9562.5 11802.7 11858.1 12131.9 12203.5 4351.3 4443.6 4499.6 4514.3 4517.1 83512.0 88343.6 88935.6 92187.1 92190.0 10226.6 10280.9 10518.2 10569.6 10577.7 19972.5 20102.6 20135.1 20243.0 20476.4 7404.1 7441.6 7494.1 7498.6 7500.8 3729.5 4158.1 4243.6 4259.1 4362.7 3289.3 3336.4 3373.2 3376.4 3387.9 525.8 541.7 578.1 591.6 609.6 587.6 598.2 611.2 617.9 623.8 8483.0 8752.0 8774.4 8777.6 8919.2 521.8 594.7 606.6 607.9 644.6 3949.6 4248.1 4252.6 4262.2 4348.8 84991.2 111083.0 112661.9 113385.4 113978.0 74120.0 96705.0 98198.5 101644.0 102397.0 80655.2 81349.6 81933.6 82909.0 83986.6 7929.1 8268.3 8366.8 8382.7 8474.2 51583.2 56213.7 56358.0 57437.7 59021.7 79894.0 79896.0 81489.6 83711.2 85230.0 10366.1 10485.1 10745.6 10839.9 10861.8 11527.3 11631.4 11709.5 12222.3 12418.2 11036.7 11288.0 11394.4 11411.3 11674.1 7682.4 12545.4 12738.9 12941.4 12953.4 12306.3 12918.6 13091.1 13443.5 13457.9 9550.1 9995.5 10920.2 11126.8 11325.4 10085.6 10495.3 11037.4 11052.7 11216.9 08:40:12 INFO - PROCESS | 1640 | -------- Summary: end -------- 08:40:12 INFO - PROCESS | 1640 | 08:40:13 INFO - TEST-INFO | 1640: exit 0 08:40:13 INFO - TEST-OK | dromaeo_css | took 1119016ms 08:40:13 INFO - TEST-START | kraken 08:40:13 INFO - operating with platform_type : mac_ 08:40:13 INFO - Initialising browser for kraken test... 08:40:13 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpb8xxtO/profile http://localhost:49242/getInfo.html) 08:40:15 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 08:40:15 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:40:15 INFO - PROCESS | 1651 | colorDepth:24 08:40:15 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:40:15 INFO - PROCESS | 1651 | __metrics 08:40:15 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:40:15 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:40:16 INFO - TEST-INFO | 1651: exit 0 08:40:16 INFO - Browser initialized. 08:40:16 INFO - Running cycle 1/1 for kraken test... 08:40:16 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/tmpb8xxtO/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:28 INFO - PROCESS | 1652 | RSS: Main: 237809664 08:40:28 INFO - PROCESS | 1652 | 08:40:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 08:40:32 INFO - PROCESS | 1652 | RSS: Main: 282222592 08:40:32 INFO - PROCESS | 1652 | 08:40:34 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 08:40:35 INFO - PROCESS | 1652 | RSS: Main: 296386560 08:40:35 INFO - PROCESS | 1652 | 08:40:38 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 08:40:39 INFO - PROCESS | 1652 | RSS: Main: 390389760 08:40:39 INFO - PROCESS | 1652 | 08:40:41 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 08:40:41 INFO - PROCESS | 1652 | RSS: Main: 293113856 08:40:41 INFO - PROCESS | 1652 | 08:40:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:40:44 INFO - PROCESS | 1652 | RSS: Main: 294096896 08:40:44 INFO - PROCESS | 1652 | 08:40:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 08:40:47 INFO - PROCESS | 1652 | RSS: Main: 333602816 08:40:47 INFO - PROCESS | 1652 | 08:40:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 08:40:51 INFO - PROCESS | 1652 | RSS: Main: 333688832 08:40:51 INFO - PROCESS | 1652 | 08:40:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 08:40:54 INFO - PROCESS | 1652 | RSS: Main: 330203136 08:40:54 INFO - PROCESS | 1652 | 08:40:56 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:40:57 INFO - PROCESS | 1652 | RSS: Main: 307707904 08:40:57 INFO - PROCESS | 1652 | 08:40:59 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:40:59 INFO - PROCESS | 1652 | RSS: Main: 309710848 08:40:59 INFO - PROCESS | 1652 | 08:41:01 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:41:02 INFO - PROCESS | 1652 | RSS: Main: 317894656 08:41:02 INFO - PROCESS | 1652 | 08:41:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:41:06 INFO - PROCESS | 1652 | RSS: Main: 331505664 08:41:06 INFO - PROCESS | 1652 | 08:41:09 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:41:09 INFO - PROCESS | 1652 | RSS: Main: 324657152 08:41:09 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 08:41:11 INFO - PROCESS | 1652 | RSS: Main: 368373760 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | __start_tp_report 08:41:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:41:11 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:41:11 INFO - PROCESS | 1652 | |i|pagename|runs| 08:41:11 INFO - PROCESS | 1652 | |0;ai-astar;105;115;115;114;115;114;114;114;114;114 08:41:11 INFO - PROCESS | 1652 | |1;audio-beat-detection;149;113;110;145;116;115;115;113;142;115 08:41:11 INFO - PROCESS | 1652 | |2;audio-dft;184;185;180;185;186;185;182;184;185;183 08:41:11 INFO - PROCESS | 1652 | |3;audio-fft;81;59;59;88;61;59;58;60;83;58 08:41:11 INFO - PROCESS | 1652 | |4;audio-oscillator;91;70;95;70;68;69;91;68;69;93 08:41:11 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;89;87;90;87;89;86;91;88;89;85 08:41:11 INFO - PROCESS | 1652 | |6;imaging-darkroom;95;99;97;99;97;97;98;98;114;155 08:41:11 INFO - PROCESS | 1652 | |7;imaging-desaturate;84;81;81;79;81;81;89;91;81;82 08:41:11 INFO - PROCESS | 1652 | |8;json-parse-financial;53;54;53;53;56;58;57;56;56;57 08:41:11 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;65;81;73;78;72;69;70;70;69;70 08:41:11 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;83;88;84;84;86;82;86;86;85;92 08:41:11 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;148;147;145;144;144;146;138;152;200;162 08:41:11 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;139;218;219;175;165;164;167;168;166;166 08:41:11 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;65;66;66;67;67;66;64;65;81;72 08:41:11 INFO - PROCESS | 1652 | __end_tp_report 08:41:11 INFO - PROCESS | 1652 | __start_cc_report 08:41:11 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2069 08:41:11 INFO - PROCESS | 1652 | __end_cc_report 08:41:11 INFO - PROCESS | 1652 | __startTimestamp1479400871749__endTimestamp 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:41:11 INFO - PROCESS | 1652 | Number of tests: 14 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:113.40 Median:114.00 stddev:2.99 (2.6%) stddev-sans-first:0.50 08:41:11 INFO - PROCESS | 1652 | Values: 105.0 115.0 115.0 114.0 115.0 114.0 114.0 114.0 114.0 114.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:115.50 stddev:15.38 (13.3%) stddev-sans-first:13.21 08:41:11 INFO - PROCESS | 1652 | Values: 149.0 113.0 110.0 145.0 116.0 115.0 115.0 113.0 142.0 115.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:183.90 Median:185.00 stddev:1.79 (1.0%) stddev-sans-first:1.90 08:41:11 INFO - PROCESS | 1652 | Values: 184.0 185.0 180.0 185.0 186.0 185.0 182.0 184.0 185.0 183.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.16 (20.1%) stddev-sans-first:11.73 08:41:11 INFO - PROCESS | 1652 | Values: 81.0 59.0 59.0 88.0 61.0 59.0 58.0 60.0 83.0 58.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:80.50 stddev:12.20 (15.2%) stddev-sans-first:12.06 08:41:11 INFO - PROCESS | 1652 | Values: 91.0 70.0 95.0 70.0 68.0 69.0 91.0 68.0 69.0 93.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.85 (2.1%) stddev-sans-first:1.94 08:41:11 INFO - PROCESS | 1652 | Values: 89.0 87.0 90.0 87.0 89.0 86.0 91.0 88.0 89.0 85.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:97.50 stddev:18.39 (18.9%) stddev-sans-first:19.15 08:41:11 INFO - PROCESS | 1652 | Values: 95.0 99.0 97.0 99.0 97.0 97.0 98.0 98.0 114.0 155.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:83.00 Median:81.50 stddev:3.92 (4.8%) stddev-sans-first:4.14 08:41:11 INFO - PROCESS | 1652 | Values: 84.0 81.0 81.0 79.0 81.0 81.0 89.0 91.0 81.0 82.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:56.00 stddev:1.89 (3.4%) stddev-sans-first:1.81 08:41:11 INFO - PROCESS | 1652 | Values: 53.0 54.0 53.0 53.0 56.0 58.0 57.0 56.0 56.0 57.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:71.00 stddev:4.67 (6.6%) stddev-sans-first:4.28 08:41:11 INFO - PROCESS | 1652 | Values: 65.0 81.0 73.0 78.0 72.0 69.0 70.0 70.0 69.0 70.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:85.60 Median:86.00 stddev:2.84 (3.3%) stddev-sans-first:2.85 08:41:11 INFO - PROCESS | 1652 | Values: 83.0 88.0 84.0 84.0 86.0 82.0 86.0 86.0 85.0 92.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:152.60 Median:147.50 stddev:17.80 (12.1%) stddev-sans-first:18.80 08:41:11 INFO - PROCESS | 1652 | Values: 148.0 147.0 145.0 144.0 144.0 146.0 138.0 152.0 200.0 162.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.70 Median:167.50 stddev:24.89 (14.9%) stddev-sans-first:22.80 08:41:11 INFO - PROCESS | 1652 | Values: 139.0 218.0 219.0 175.0 165.0 164.0 167.0 168.0 166.0 166.0 08:41:11 INFO - PROCESS | 1652 | 08:41:11 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.90 Median:66.50 stddev:5.09 (7.6%) stddev-sans-first:5.29 08:41:11 INFO - PROCESS | 1652 | Values: 65.0 66.0 66.0 67.0 67.0 66.0 64.0 65.0 81.0 72.0 08:41:11 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:41:11 INFO - PROCESS | 1652 | 08:41:12 INFO - TEST-INFO | 1652: exit 0 08:41:12 INFO - TEST-OK | kraken | took 59086ms 08:41:12 INFO - SUITE-END | took 1178s 08:41:12 INFO - Completed test suite (00:19:38) 08:41:12 INFO - javascript benchmark 08:41:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3272.854500924675, 3308.4541050726625, 3326.5010248462727, 3343.247769251917, 3381.966180338196, 4892.6575139740235, 5725.0, 5725.942740572593, 5750.6262092963925, 5768.07710766277, 5658.377578466376, 5756.856078598037, 5761.643631671673, 5781.479666829984, 5817.185594016831, 5195.558377537905, 5205.453427390126, 5214.426413094576, 5244.108501554726, 5259.737013149346, 5171.844844654666, 5180.430152683201, 5310.92033619496, 5315.654482458628, 5348.679079255237, 4437.023854751949, 5177.249298851664, 5230.267762513232, 5234.319538460014, 5248.6588371755715, 4841.298011788289, 5227.954409118153, 5232.0, 5286.3127793387, 5305.708186049766, 5159.71621560814, 5189.325387699575, 5222.164453687392, 5224.085784987612, 5233.869153271161, 5615.438456154393, 5651.378348381716, 5657.462541058593, 5658.519025882805, 5708.457696518824, 6096.435975519046, 6140.149282067162, 6153.446189842935, 6157.630542167484, 6235.438810507031, 6239.875202495925, 6311.0217916223055, 6323.557350985433, 6337.683115844189, 6338.30278669346, 5664.603477756517, 5754.856128596819, 5754.884902302014, 5788.3343415506915, 5829.387914268941, 6241.78153764616, 6270.780522681775, 6283.02613094971, 6294.181756371734, 6319.304876463585, 6151.569390142737, 6160.383961603804, 6162.168107305456, 6174.166487524217, 6175.722092505848, 6144.754209831646, 6145.231846019247, 6171.228596425447, 6182.227221597301, 6279.780207692709, 6170.598911070766, 6217.036359364306, 6221.253449586078, 6221.3156552779155, 6241.157443745036, 6118.449339559461, 6143.078538219213, 6198.287196972406, 6203.286622038433, 6206.317305096436, 4862.95137048632, 4908.607311415079, 4920.335754673073, 4925.482824303468, 4949.331840201598, 4367.388565600819, 4422.867313980586, 4475.530069342737, 4476.0, 4479.843205487792, 5824.650520968756, 5825.0679891217205, 5825.097109947965, 5836.533077353802, 5872.207252021008, 6190.845228869314, 6305.0, 6318.589291696025, 6323.747050118036, 6326.810195694136, 5637.802676906289, 5670.971645141831, 5692.573057020657, 5704.458076482728, 5761.135829625589, 5028.59771218309, 5352.598555108382, 5391.272178255964, 5391.892162156735, 5414.377346605112, 6253.53098517613, 6261.467775239155, 6276.027215781653, 6286.6856657166045, 6288.088227207121, 6038.094285857156, 6174.166487524127, 6230.43926046658, 6251.812445626639, 6270.780522681866, 6201.596896201732, 6217.906731398942, 6243.219597550306, 6274.843128921813, 6280.780172694116, 6164.753409863734, 6207.782727604512, 6216.564840461125, 6225.3774622537385, 6233.470155036691, 5807.941920580909, 6145.016797312409, 6280.654563999024, 6331.525135614939, 6339.714712837841, 6054.061620448838, 6063.181470501426, 6063.575549711478, 6108.114323423168, 6136.539759518143, 5917.319508256605, 6007.519398448201, 6107.358727333566, 6107.6640784757265, 6128.417800309142, 5928.9110663339225, 5951.256092988377, 5957.195778570011, 5959.731812068554, 5964.165016897725, 5043.798248070036, 5907.91138132937, 6005.069214271794, 6086.208792857077, 6124.387561244018, 5954.553408494294, 5981.371955944737, 5984.31180414258, 5984.820455386345, 6029.155918171372], "value": 5685.236966272169, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [449.62905602877595, 463.19635432524564, 474.8148222193343, 518.9195674670425, 521.7313083761861, 25613.0, 30848.845755771246, 31377.68622313779, 38255.42616860746, 39412.80293598528, 40977.795111024476, 41100.58899411005, 41181.58818411815, 41259.0, 41325.380119298236, 9208.769780755485, 9290.535473226346, 9400.7649808755, 9425.481598512079, 9430.952845235799, 8442.366822488308, 8641.135886411372, 9270.39742416744, 11003.449827508633, 11285.15361347898, 11217.0, 11288.830667539953, 11289.548418063308, 11332.603358882441, 11380.658580242605, 11202.215844890861, 11273.605423810128, 11299.774004519864, 11306.265092768943, 11306.925842044993, 10964.232503724743, 11026.062784663312, 11075.944620276847, 11156.553737850476, 11175.664730058112, 38489.23021539554, 38907.805460972515, 38935.80532097322, 38965.0, 39084.43662253535, 3234.66036066212, 3285.0, 3326.2183386904057, 3475.2701932594186, 3654.9817250913843, 28298.575521367195, 29647.407051859056, 29702.0, 29855.25361865949, 30513.847430762704, 3166.5725127108008, 3174.793638413496, 3212.710856022946, 3236.3365510070616, 3319.5850518685165, 352430.0, 352751.4724852744, 352912.4708752905, 353116.0, 354238.0, 28391.858040709663, 32992.340153197285, 58949.41050589439, 59406.10890836641, 59483.70258148682, 28749.425011499654, 34760.82619586886, 34800.0, 34801.47797783036, 34831.4775278371, 14317.0, 14366.281685915663, 14522.564323070113, 14524.419023238952, 14531.927340363442, 11133.276337038229, 11821.763564728657, 11871.70320741971, 12201.328926909106, 12281.93859030699, 7187.748428805071, 7467.775966721007, 7469.029026226556, 7500.512466689648, 7659.9234007660325, 3184.1561986073707, 3190.6809319067906, 3197.0, 3197.71220590148, 3226.9838650806596, 913.6436789652041, 932.4125800745622, 952.3952290295574, 974.2449601558707, 1081.3728037738092, 27733.306667333076, 28668.42663146767, 29315.85342073276, 29619.85190074036, 29688.960886368874, 856.6316483911853, 1033.338663255517, 1052.5894900988621, 1053.441675911768, 1105.38098664748, 15161.241937903062, 15262.618434539003, 15313.540593782205, 15521.456749013956, 15614.45349412765, 616053.9197304074, 762917.0, 771953.0, 772726.0, 968631.0, 44425.0, 54924.62688432821, 55027.724861375435, 55175.44824551784, 55335.0, 1216.4829947272374, 1247.2080229054434, 1335.1455068756004, 1344.4890941442188, 1366.842813076489, 23571.646425303978, 29708.405831883672, 30498.0, 30574.388512229187, 30756.384872302875, 54287.18569221386, 54627.72686136703, 54650.18024729712, 54739.0, 54881.72559137338, 530499.0, 626736.0, 629429.0, 630004.0, 630556.0, 26208.606870896572, 26394.340141496617, 26496.337591560365, 26767.0, 34620.653793461744, 40267.0, 46207.0, 47036.059278814915, 48389.03221935471, 48538.0, 38136.0, 38321.4251786229, 38889.22221555497, 39208.0, 39730.0, 26747.598786018614, 37390.62609373871, 37818.0545486354, 37830.0, 38094.428583571826, 36536.0, 37414.0, 37791.62208377991, 38265.0, 38357.84926452211, 32274.35451291008, 33696.663033369354, 33904.99210931125, 34323.0, 34616.82691586526, 25186.0, 25227.74772252254, 25288.747112529374, 25327.98688052457, 25761.355966100247], "value": 18383.16679886261, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.11993635053096, 375.43497036959354, 383.7869982159902, 391.82172111689187, 403.14734336877495, 15002.324895379707, 15144.096807578999, 18263.72604410933, 19939.60120797585, 20261.08825102872, 12322.81515777264, 18749.906250468728, 19661.311854085074, 19936.60126797463, 20109.095090720915, 5544.22380866679, 5571.8328450146355, 5811.970940145294, 5859.0625499920025, 5992.970035149818, 3759.398496240602, 3977.840886364542, 5903.88192236155, 5939.435753603422, 5947.940520594782, 5593.916091258634, 5856.677882716449, 5923.555733319997, 5979.372165922581, 5991.970040149793, 5637.80267690633, 5854.121881717734, 5898.292204935392, 5952.910706339387, 5971.5222782177325, 5708.058170401879, 5715.771369145229, 5720.656760594336, 5723.5421166307015, 5732.0, 14761.0, 22614.09543618253, 22668.0, 23490.412739681473, 23764.0, 1857.1549944775095, 2192.8355373347063, 2193.046024979139, 2247.2134752836378, 2290.2900100968754, 9747.90252097477, 10104.545295461721, 10189.337693050002, 11020.338779673246, 13554.661133471644, 1766.8409843114052, 1848.168324254091, 1851.4445666300055, 1859.1819599376367, 1867.934622288227, 125008.0, 125833.0, 132454.0, 133383.33308333493, 136119.31940340236, 11295.830562541569, 11463.484143213574, 11540.538378464935, 11590.420478976017, 11671.474783634756, 12755.872441275655, 14528.273586320642, 14546.345414456375, 14553.12681239108, 14577.125372477472, 7451.962740186264, 7518.323350898446, 8258.504489730636, 8350.248477637042, 8400.873986890201, 8236.258736713604, 8244.29923456501, 8397.28623067034, 8412.453190542596, 8421.24208821209, 4029.7380670256925, 4448.332750087513, 4546.65900057497, 4552.817887284537, 4627.514111018295, 1750.8202288151333, 1766.2140347545476, 1831.6519861226325, 1837.1457272368414, 1840.8435283000827, 823.9011318641801, 851.1743608699552, 858.5664239558982, 907.9727608171765, 980.5538479991586, 18910.716339254923, 20099.497512562302, 20289.086991085438, 20366.694499582518, 20714.275000375215, 868.4572142410993, 912.6258234123977, 934.0985948559672, 967.4920666650065, 972.1833659725864, 10163.237757168094, 10565.577376904837, 10630.52162652667, 10679.626213082505, 10703.78592428147, 612593.0, 616152.0, 627390.0, 632232.0, 634029.829850857, 106259.0, 135337.0, 135612.32193839166, 136247.0, 137261.0, 797.9760607181794, 930.1721467893715, 940.6049459227141, 949.6106596295624, 953.8569214617863, 13265.469381224642, 17262.0, 17433.651326973642, 18336.616239888728, 18509.9074504632, 52934.0, 53712.73143634257, 54278.728606356715, 54808.0, 54998.45001550093, 55654.72172639273, 75492.86760698704, 76773.61613191899, 78085.21914781007, 79746.0, 7690.769276921701, 7804.414668899969, 7867.17394673551, 8024.2376974187355, 8088.110307866248, 10504.947475262576, 12015.879841201477, 12055.15613907018, 12152.392380380768, 12330.876691233332, 10203.0, 10392.272540922366, 10718.35689858611, 10766.515506802369, 10840.67477975662, 4981.10340138764, 6987.763915546976, 8762.0, 9563.139317461462, 10364.951832753799, 8847.0, 10578.259521833397, 10778.784424311627, 10794.892051079703, 11094.944525277322, 7726.729564465441, 7914.16901225363, 8015.879761803694, 8058.959705201437, 8103.756887293391, 10198.184145268213, 10422.843657344994, 10696.03735663794, 10703.464826758787, 10784.838227426751], "value": 9184.8792153489, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1286.2925391034928, 1366.904316697831, 1409.4221369238608, 1792.5608225984636, 1831.3498707958674, 6799.4560435165195, 8491.872621910676, 8496.957515212423, 8521.275691566208, 8525.74422767318, 6653.966730166342, 7499.0, 7732.420068494871, 8326.500409975406, 8328.000639923217, 2311.271949335958, 2348.988255058731, 2357.49313897512, 2361.9291421257303, 2361.929142125739, 2346.061575369857, 2372.406898275431, 2374.1453076892303, 2392.724836643782, 2395.880205989702, 2320.4894923116885, 2338.602437585606, 2350.976490235101, 2359.9646005309933, 2360.704911886014, 2306.273523839985, 2310.27226423676, 2337.3689103941842, 2343.6601692754457, 2343.660169275454, 2242.742084660252, 2250.44864008319, 2256.368216899271, 2257.4694946687514, 2261.3216035189375, 8292.460990035628, 8397.6221070052, 8412.957935210346, 8420.663173473054, 8427.83144337116, 5011.32347133136, 5178.715170665612, 5186.014657215154, 5192.662476939025, 5193.221016847466, 3267.5425440438357, 3365.8821941232186, 3606.5672119345845, 3615.963840361589, 3629.4737263096704, 1065.211743309953, 1066.9093127084207, 1099.4447803859, 1120.5797825815318, 1121.1199208621274, 10806.78386432267, 10945.726356841144, 10973.122150227882, 10988.615398461016, 11010.284331518425, 3832.9616703832603, 4338.9566104338555, 4412.845550405785, 4602.767657686019, 4719.221328480831, 9347.252219822398, 9374.531273436301, 9374.718758437259, 9377.859332110023, 9379.812403751886, 5124.436312005676, 5133.383993920754, 5134.05020071288, 5140.228965655107, 5144.588432925357, 4247.44783178191, 4379.211741886491, 4618.792154353062, 4695.929561056587, 4729.196036673774, 3740.121071548184, 3773.584905660375, 3820.7898565578603, 3854.672352849983, 3865.362215234511, 1080.935143891369, 1251.8497780266425, 1254.2349167008117, 1256.403208475985, 1271.6375832887582, 779.8752199648029, 856.8757530158092, 885.2298500304776, 891.9920489846432, 924.9861252081224, 5496.780128794883, 5665.688387138664, 5838.299404071538, 5856.824295271149, 5930.584859059911, 890.3678388344348, 907.950062746542, 926.4441335198961, 931.4504442378893, 941.204682043672, 3651.2332410193626, 3791.7156213284115, 3798.9932667843045, 3865.091703449687, 3882.165334453106, 9747.122758951587, 10239.897601024044, 10253.974602539687, 10261.74345641365, 10265.384076955257, 9653.0, 10063.446510441849, 10219.795604088025, 10248.48757562119, 10254.025867542721, 7826.2782187918565, 7985.24140206691, 7991.640376183086, 8008.879866802003, 8021.598920053974, 5438.211459338295, 5489.204065410573, 5502.257195278587, 5589.664620122643, 5617.606767526234, 9328.720138395858, 9464.148226659634, 9576.425414475154, 9604.423734575948, 9652.034796520291, 9072.459711280851, 9499.762505937408, 9503.097205765442, 9556.331056826233, 9561.235101192027, 6570.310117437601, 6806.285340039422, 6816.182058153054, 7103.964480177566, 7299.233580473974, 7598.0502437195355, 7748.496347737604, 7862.2923936845955, 7910.762677119696, 7929.5242285463055, 6969.477289203229, 6979.755708550177, 7003.19463261711, 7113.893291600527, 7262.564246145248, 5763.7982670606325, 6991.0, 7018.684159212949, 7071.7878463646175, 7083.008378827071, 6639.900401494078, 6715.798526044031, 6783.559068660521, 6784.898226526508, 6786.185657721105, 4885.462599114024, 4886.560209581013, 4905.0, 4956.207006878882, 4958.776855041604, 4822.42130944289, 4836.854894353175, 4939.777710003131, 4991.5258050485145, 5023.045621331935], "value": 4411.98143990991, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [264.6889904362374, 296.8990543215306, 309.19609016556956, 311.83316925444893, 312.12760334864055, 4320.049589090403, 4845.588125009374, 5231.816886408977, 5282.973585132074, 5371.140617501201, 4051.3720373342107, 5132.948670513303, 5207.427183009866, 5237.638214064342, 5307.867303317419, 3346.933061338772, 3368.983155084221, 3595.7303202259804, 4044.3933409988445, 4094.754314741125, 3566.6076731559506, 3590.856365745367, 3922.607739226069, 4043.353063509839, 4052.986753311672, 3854.498915141042, 3904.3362628353248, 3955.09032922428, 4018.2967980603426, 4065.0, 3942.467766851467, 4027.7381970171846, 4043.2722110020186, 4068.5321188063454, 4068.572799856017, 3117.345357474933, 3548.414511605582, 3569.303985722785, 3581.212133330663, 3587.475322987746, 4060.593940605945, 5138.820141295037, 5203.699075231192, 5214.947850521484, 5248.031988004498, 4655.51117132703, 4687.88280292992, 4687.9765601171775, 4700.615556799099, 4737.526247375269, 5594.9720251398885, 5601.694999950008, 5619.494245517923, 5629.891567649106, 5636.71816409182, 3520.5883969480988, 3895.3767397216593, 3929.98035009826, 3935.9016024599327, 3946.0, 5451.863703407407, 5843.649381037151, 5891.878923587548, 5901.58688891782, 5935.358765349378, 3415.1803567143706, 3686.4286035664513, 3832.885013449601, 3845.288621605012, 3851.114243723903, 3925.3130702127014, 3928.253631810004, 3929.646331830149, 3930.8231129599235, 3953.0, 4057.411675307123, 4133.545310015896, 4434.246178149722, 4540.046590216026, 4580.290055041474, 5052.797888084509, 5217.817376391884, 5615.382307946123, 5616.6068375213345, 5629.324481062299, 1256.1897576063388, 1264.1782841153324, 1266.8353114095294, 1272.7709012377861, 1273.3505911984769, 799.5003123048095, 813.8931053766872, 852.8294341131799, 869.5478351257311, 873.694207027548, 4114.958850411517, 4461.643068554507, 4630.32860235264, 4927.969298881162, 5048.949510504922, 4478.776061196927, 4671.95328046722, 4746.857594272177, 5225.3207083079715, 5364.7317634118135, 4398.824047038082, 4487.798049087799, 4576.4737055239075, 4876.195427754452, 5074.619403544749, 2402.4594466245303, 2542.2585528912755, 2770.279727270924, 2798.614685730576, 2805.9477695864052, 5051.494850514919, 5462.945370546244, 5577.0, 5755.395683453177, 5805.909872445916, 4781.282807578891, 4792.616590672807, 5164.457731938168, 5324.600654950894, 5655.0, 4988.0, 5275.762590683428, 5455.809046683347, 5556.888862222733, 5619.971900140473, 4839.830605928775, 5016.874578135576, 5043.720567892997, 5202.583793296603, 5447.338561738549, 4846.389439867321, 5007.0, 5156.871078223075, 5408.025585927802, 5452.564884122977, 5027.963674711747, 5388.56891448691, 5445.6385903524115, 5692.402297758676, 5705.7432415542235, 3270.971997361651, 3510.736694747853, 3647.544056992876, 3658.853645854136, 3677.623627882811, 5039.445660977363, 5362.9463705364005, 5436.374816895949, 5478.972605136949, 5495.53287970527, 4744.129801470061, 4790.197734325319, 4853.635977301646, 5311.88450425415, 5323.866903327448, 4555.647114640351, 5175.896482070413, 5256.89486210266, 5277.709725964955, 5344.625876188629, 4955.0, 5425.403205647454, 5449.373322067854, 5554.638948468337, 5583.720813959367, 5220.817271395483, 5698.886022279614, 5710.914336285042, 5736.0248757712125, 5773.364929857797, 4645.5354464553275, 5214.0, 5452.972735136457, 5618.438156184349, 5648.057949294367], "value": 3954.6774097017064, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [747.6261869065468, 769.2461387839913, 778.1673609238118, 783.6982761636768, 788.6529926832194, 13801.171929684226, 14057.156570605744, 14447.494337698183, 14474.059186152906, 14512.637184070403, 12799.680007999805, 12885.935570322135, 12915.741685166291, 12947.0, 12948.74102517949, 640.69887153038, 658.8319978405524, 662.7680311890824, 781.5662307419394, 802.1497212954287, 785.0540099180474, 794.6900708723603, 812.7317985064944, 814.2630919018268, 815.388458656008, 848.0755975986174, 850.086157380815, 851.6806197675871, 851.9062903080656, 855.5038077914794, 672.8217022489044, 686.4679873098339, 691.3708525242006, 845.4842546046906, 845.9890430935035, 514.2851436503263, 514.9433562308143, 613.1385403508078, 621.7699451203039, 627.7583130494746, 13759.96800239986, 13806.65483362914, 13807.378667959965, 13810.585682429542, 13811.861881381157, 12915.418806153746, 13029.934850325688, 13136.934315328363, 13208.867911320956, 13269.0, 9562.521873906277, 11802.704932376673, 11858.102703567474, 12131.93934030333, 12203.511859525608, 4351.325544540601, 4443.577860103285, 4499.6400287976885, 4514.277715565494, 4517.141743068806, 83512.0, 88343.55828220882, 88935.55532222362, 92187.0781292192, 92190.0, 10226.6420675276, 10280.881246855786, 10518.158547316196, 10569.577216911237, 10577.682669519927, 19972.50068748293, 20102.59794804125, 20135.0939207736, 20243.0, 20476.38570842877, 7404.111506619241, 7441.590712510756, 7494.100707914976, 7498.625068746541, 7500.849983000309, 3729.533808273966, 4158.147579746144, 4243.639290660267, 4259.148170365939, 4362.6946113772365, 3289.3256882339065, 3336.399448099317, 3373.1567108222944, 3376.4260075786683, 3387.8644854205554, 525.7607788456244, 541.7426722306873, 578.069308413454, 591.5711109445704, 609.6128958111631, 587.5916238214486, 598.2163365990566, 611.2023808929301, 617.9309794056308, 623.7536173211556, 8483.034426708808, 8751.956240218886, 8774.429662071945, 8777.605007774666, 8919.152680495343, 521.7704210147599, 594.6580716088266, 606.5602438232335, 607.9392060793974, 644.6486664767713, 3949.565547789798, 4248.065425606362, 4252.55348188442, 4262.232798096373, 4348.804303806336, 84991.15008849833, 111083.0, 112661.87338126678, 113385.4330728341, 113978.0, 74120.0, 96705.0, 98198.50900745594, 101644.0, 102396.97603023874, 80655.19344806594, 81349.59325203336, 81933.59033204796, 82909.0, 83986.5800671005, 7929.11099358386, 8268.338532917232, 8366.832663346577, 8382.664693412195, 8474.237318641352, 51583.226251607004, 56213.718931405085, 56358.0, 57437.712811435675, 59021.704891476984, 79894.0, 79896.0, 81489.59255203686, 83711.16288837034, 85230.0, 10366.06705396518, 10485.056344928993, 10745.623903163352, 10839.916008399412, 10861.837072443761, 11527.250728702607, 11631.360275184608, 11709.531618735155, 12222.266664000188, 12418.192817466836, 11036.724081898017, 11288.0, 11394.430278486208, 11411.315321080761, 11674.066074714172, 7682.423818213544, 12545.435455404346, 12738.929774229762, 12941.352932353157, 12953.417096230503, 12306.323152226714, 12918.612441626767, 13091.083624146599, 13443.529476468277, 13457.865421345661, 9550.140487356171, 9995.500224988577, 10920.235583509078, 11126.833097503382, 11325.377104259345, 10085.596576136872, 10495.317804342998, 11037.448127593427, 11052.668419947415, 11216.887831121585], "value": 7044.740492779839, "unit": "score"}], "name": "dromaeo_css", "value": 7003.517231279934, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 115.0, 114.0, 115.0, 114.0, 114.0, 114.0, 114.0, 114.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 110.0, 145.0, 116.0, 115.0, 115.0, 113.0, 142.0, 115.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 180.0, 185.0, 186.0, 185.0, 182.0, 184.0, 185.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 88.0, 61.0, 59.0, 58.0, 60.0, 83.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 95.0, 70.0, 68.0, 69.0, 91.0, 68.0, 69.0, 93.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 90.0, 87.0, 89.0, 86.0, 91.0, 88.0, 89.0, 85.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 99.0, 97.0, 99.0, 97.0, 97.0, 98.0, 98.0, 114.0, 155.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 81.0, 79.0, 81.0, 81.0, 89.0, 91.0, 81.0, 82.0], "value": 83.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 53.0, 56.0, 58.0, 57.0, 56.0, 56.0, 57.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 81.0, 73.0, 78.0, 72.0, 69.0, 70.0, 70.0, 69.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 88.0, 84.0, 84.0, 86.0, 82.0, 86.0, 86.0, 85.0, 92.0], "value": 85.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 145.0, 144.0, 144.0, 146.0, 138.0, 152.0, 200.0, 162.0], "value": 152.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 218.0, 219.0, 175.0, 165.0, 164.0, 167.0, 168.0, 166.0, 166.0], "value": 174.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 67.0, 67.0, 66.0, 64.0, 65.0, 81.0, 72.0], "value": 67.9, "unit": "score"}], "name": "kraken", "value": 1449.4, "alertThreshold": 2.0}]} 08:41:12 INFO - Return code: 0 08:41:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:41:13 ERROR - # TBPL SUCCESS # 08:41:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:13 INFO - 2016-11-17 08:41:13.061 screenresolution[1656:9888] starting screenresolution argv=screenresolution get 08:41:13 INFO - 2016-11-17 08:41:13.065 screenresolution[1656:9888] Display 0: 1600x1200x32@60 08:41:13 INFO - 2016-11-17 08:41:13.071 screenresolution[1657:9891] starting screenresolution argv=screenresolution list 08:41:13 INFO - Available Modes on Display 0 08:41:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:13 INFO - Intel Iris: 08:41:13 INFO - Chipset Model: Intel Iris 08:41:13 INFO - Type: GPU 08:41:13 INFO - Bus: Built-In 08:41:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:13 INFO - Vendor: Intel (0x8086) 08:41:13 INFO - Device ID: 0x0a2e 08:41:13 INFO - Revision ID: 0x0009 08:41:13 INFO - Displays: 08:41:13 INFO - Display: 08:41:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:13 INFO - Display Serial Number: 1600x1200 60 08:41:13 INFO - Main Display: Yes 08:41:13 INFO - Mirror: Off 08:41:13 INFO - Online: Yes 08:41:13 INFO - Rotation: Supported 08:41:13 INFO - Return code: 0 08:41:13 INFO - Running post-action listener: _resource_record_post_action 08:41:13 INFO - [mozharness: 2016-11-17 16:41:13.304181Z] Finished run-tests step (success) 08:41:13 INFO - Running post-run listener: _resource_record_post_run 08:41:13 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 37585920; Write bytes: 657205760; Read time: 244; Write time: 1892 08:41:13 INFO - TinderboxPrint: CPU usage
53.6% 08:41:13 INFO - TinderboxPrint: I/O read bytes / time
37,585,920 / 244 08:41:13 INFO - TinderboxPrint: I/O write bytes / time
657,205,760 / 1,892 08:41:13 INFO - TinderboxPrint: CPU idle
2,246.7 (46.4%) 08:41:13 INFO - TinderboxPrint: CPU system
971.3 (20.0%) 08:41:13 INFO - TinderboxPrint: CPU user
1,627.7 (33.6%) 08:41:13 INFO - TinderboxPrint: Swap in / out
390,840,320 / 0 08:41:13 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346380800; Write bytes: 345028096; Read time: 27632; Write time: 720 08:41:13 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 35750912; Write bytes: 296877056; Read time: 227; Write time: 1122 08:41:13 INFO - Running post-run listener: _upload_blobber_files 08:41:13 INFO - Blob upload gear active. 08:41:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:41:13 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 08:41:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:41:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:41:14 INFO - (blobuploader) - INFO - Open directory for files ... 08:41:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:41:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:41:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:17 INFO - (blobuploader) - INFO - Done attempting. 08:41:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:41:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:41:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:19 INFO - (blobuploader) - INFO - Done attempting. 08:41:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:41:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:41:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:41:23 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:41:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:41:23 INFO - (blobuploader) - INFO - Done attempting. 08:41:23 INFO - (blobuploader) - INFO - Iteration through files over. 08:41:23 INFO - Return code: 0 08:41:23 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:41:23 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:41:23 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3f7d49db30efd52af0aed40f9f8fbcfb6e8a1f18983a6d1b1e3000484159e96e8e58825ff5ee175a8b8272b6591e88f99ac7cab04f054c5b945284ee7d91783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc165f45c250ac6f195d90ccca0c5907cf92a9d107c82001900ea9f670b14b496eeeb514ad6a3b5c8365a79748d5576e666b7b9075f0ec0291cf6dd38cfece", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e39a6d5edc56299e7aeba009d6d05e5317761a898e3b01d881545f9bfc26e56a54c679832eb26e9aae42a1b430cc2fb05d6f2e118a84205d03ac91b9d9e29df6"} 08:41:23 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:41:23 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:41:23 INFO - Contents: 08:41:23 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3f7d49db30efd52af0aed40f9f8fbcfb6e8a1f18983a6d1b1e3000484159e96e8e58825ff5ee175a8b8272b6591e88f99ac7cab04f054c5b945284ee7d91783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc165f45c250ac6f195d90ccca0c5907cf92a9d107c82001900ea9f670b14b496eeeb514ad6a3b5c8365a79748d5576e666b7b9075f0ec0291cf6dd38cfece", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e39a6d5edc56299e7aeba009d6d05e5317761a898e3b01d881545f9bfc26e56a54c679832eb26e9aae42a1b430cc2fb05d6f2e118a84205d03ac91b9d9e29df6"} 08:41:23 INFO - Running post-run listener: copy_logs_to_upload_dir 08:41:23 INFO - Copying logs to upload dir... 08:41:23 INFO - mkdir: /builds/slave/test/build/upload/logs 08:41:23 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.269992 ========= master_lag: 1.34 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-11-17 08:41:24.978485) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 08:41:24.982236) ========= 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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3f7d49db30efd52af0aed40f9f8fbcfb6e8a1f18983a6d1b1e3000484159e96e8e58825ff5ee175a8b8272b6591e88f99ac7cab04f054c5b945284ee7d91783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc165f45c250ac6f195d90ccca0c5907cf92a9d107c82001900ea9f670b14b496eeeb514ad6a3b5c8365a79748d5576e666b7b9075f0ec0291cf6dd38cfece", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e39a6d5edc56299e7aeba009d6d05e5317761a898e3b01d881545f9bfc26e56a54c679832eb26e9aae42a1b430cc2fb05d6f2e118a84205d03ac91b9d9e29df6"} build_url:https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/HmsHzj8wSTyuRXqrlyBcmw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a3f7d49db30efd52af0aed40f9f8fbcfb6e8a1f18983a6d1b1e3000484159e96e8e58825ff5ee175a8b8272b6591e88f99ac7cab04f054c5b945284ee7d91783", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f5bc165f45c250ac6f195d90ccca0c5907cf92a9d107c82001900ea9f670b14b496eeeb514ad6a3b5c8365a79748d5576e666b7b9075f0ec0291cf6dd38cfece", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e39a6d5edc56299e7aeba009d6d05e5317761a898e3b01d881545f9bfc26e56a54c679832eb26e9aae42a1b430cc2fb05d6f2e118a84205d03ac91b9d9e29df6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 08:41:25.015571) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:41:25.015907) ========= 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.V1aaIVeYfr/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.LUoEzPs7lK/Listeners TMPDIR=/var/folders/kt/md9tf2_d52xdhxcqh4xzhxzc00000w/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.004856 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 08:41:25.064269) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 08:41:25.064558) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 08:41:25.064853) ========= ========= Total master_lag: 1.48 =========