builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0167 starttime: 1479144887.94 results: success (0) buildid: 20161114071242 builduid: 1ebf6085a6e64bb0b8786df7fc0cf755 revision: ebec82f380befccdb9653a607330835cd5f7b1be ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:47.936672) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:47.937109) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:47.937398) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:47.963367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:47.963642) ========= 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.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-14 09:34:47-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.56M=0.002s 2016-11-14 09:34:48 (7.56 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:48.102431) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:48.102740) ========= 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.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.020102 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:48.153543) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 09:34:48.153853) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ebec82f380befccdb9653a607330835cd5f7b1be --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 ebec82f380befccdb9653a607330835cd5f7b1be --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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-14 09:34:48,231 truncating revision to first 12 chars 2016-11-14 09:34:48,231 Setting DEBUG logging. 2016-11-14 09:34:48,231 attempt 1/10 2016-11-14 09:34:48,231 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ebec82f380be?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-14 09:34:49,309 unpacking tar archive at: mozilla-aurora-ebec82f380be/testing/mozharness/ program finished with exit code 0 elapsedTime=1.330108 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-14 09:34:49.509072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:49.512475) ========= script_repo_revision: ebec82f380befccdb9653a607330835cd5f7b1be ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:49.512898) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:49.513178) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-14 09:34:49.531510) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-14 09:34:49.531806) ========= /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.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:34:49 INFO - MultiFileLogger online at 20161114 09:34:49 in /builds/slave/test 09:34:49 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 09:34:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:34:49 INFO - {'append_to_log': False, 09:34:49 INFO - 'base_work_dir': '/builds/slave/test', 09:34:49 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:34:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:34:49 INFO - 'branch': 'Mozilla-Aurora', 09:34:49 INFO - 'buildbot_json_path': 'buildprops.json', 09:34:49 INFO - 'config_files': ('talos/mac_config.py',), 09:34:49 INFO - 'default_actions': ('clobber', 09:34:49 INFO - 'read-buildbot-config', 09:34:49 INFO - 'download-and-extract', 09:34:49 INFO - 'populate-webroot', 09:34:49 INFO - 'create-virtualenv', 09:34:49 INFO - 'install', 09:34:49 INFO - 'run-tests'), 09:34:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:34:49 INFO - 'download_minidump_stackwalk': True, 09:34:49 INFO - 'download_symbols': 'ondemand', 09:34:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:34:49 INFO - 'tooltool.py': '/tools/tooltool.py', 09:34:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:34:49 INFO - '/tools/misc-python/virtualenv.py')}, 09:34:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:34:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:34:49 INFO - 'installer_path': 'installer.exe', 09:34:49 INFO - 'log_level': 'info', 09:34:49 INFO - 'log_name': 'talos', 09:34:49 INFO - 'log_to_console': True, 09:34:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:34:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:34:49 INFO - 'opt_config_files': (), 09:34:49 INFO - 'pip_index': False, 09:34:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:49 INFO - 'cmd': ('bash', 09:34:49 INFO - '-c', 09:34:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:49 INFO - 'enabled': True, 09:34:49 INFO - 'halt_on_failure': False, 09:34:49 INFO - 'name': 'check_screen_resolution'},), 09:34:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:34:49 INFO - 'cmd': ('bash', 09:34:49 INFO - '-c', 09:34:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:34:49 INFO - 'enabled': True, 09:34:49 INFO - 'halt_on_failure': False, 09:34:49 INFO - 'name': 'check_screen_resolution'},), 09:34:49 INFO - 'run_cmd_checks_enabled': True, 09:34:49 INFO - 'sps_profile': False, 09:34:49 INFO - 'sps_profile_interval': 0, 09:34:49 INFO - 'suite': 'dromaeojs', 09:34:49 INFO - 'system_bits': '32', 09:34:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:34:49 INFO - 'title': 't-yosemite-r7-0167', 09:34:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:34:49 INFO - 'use_talos_json': True, 09:34:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:34:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:34:49 INFO - 'work_dir': 'build'} 09:34:49 INFO - [mozharness: 2016-11-14 17:34:49.731334Z] Running clobber step. 09:34:49 INFO - Running pre-action listener: _resource_record_pre_action 09:34:49 INFO - Running main action method: clobber 09:34:49 INFO - rmtree: /builds/slave/test/build 09:34:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:34:50 INFO - Running post-action listener: _resource_record_post_action 09:34:50 INFO - [mozharness: 2016-11-14 17:34:50.131094Z] Finished clobber step (success) 09:34:50 INFO - [mozharness: 2016-11-14 17:34:50.131224Z] Running read-buildbot-config step. 09:34:50 INFO - Running pre-action listener: _resource_record_pre_action 09:34:50 INFO - Running main action method: read_buildbot_config 09:34:50 INFO - Using buildbot properties: 09:34:50 INFO - { 09:34:50 INFO - "project": "", 09:34:50 INFO - "product": "firefox", 09:34:50 INFO - "script_repo_revision": "production", 09:34:50 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:34:50 INFO - "repository": "", 09:34:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:34:50 INFO - "buildid": "20161114071242", 09:34:50 INFO - "pgo_build": "False", 09:34:50 INFO - "basedir": "/builds/slave/test", 09:34:50 INFO - "buildnumber": 127, 09:34:50 INFO - "slavename": "t-yosemite-r7-0167", 09:34:50 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:34:50 INFO - "platform": "yosemite_r7", 09:34:50 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:34:50 INFO - "branch": "mozilla-aurora", 09:34:50 INFO - "repo_path": "releases/mozilla-aurora", 09:34:50 INFO - "revision": "ebec82f380befccdb9653a607330835cd5f7b1be", 09:34:50 INFO - "stage_platform": "macosx64", 09:34:50 INFO - "builduid": "1ebf6085a6e64bb0b8786df7fc0cf755", 09:34:50 INFO - "slavebuilddir": "test" 09:34:50 INFO - } 09:34:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:34:50 INFO - Running post-action listener: _resource_record_post_action 09:34:50 INFO - [mozharness: 2016-11-14 17:34:50.134601Z] Finished read-buildbot-config step (success) 09:34:50 INFO - [mozharness: 2016-11-14 17:34:50.134712Z] Running download-and-extract step. 09:34:50 INFO - Running pre-action listener: _resource_record_pre_action 09:34:50 INFO - Running main action method: download_and_extract 09:34:50 INFO - mkdir: /builds/slave/test/build/tests 09:34:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:34:50 INFO - trying https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:34:50 INFO - Downloading https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/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 09:34:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/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 09:34:51 INFO - Downloaded 1199 bytes. 09:34:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:34:51 INFO - Using the following test package requirements: 09:34:51 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:34:51 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:34:51 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:34:51 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'jsshell-mac.zip'], 09:34:51 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:34:51 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:34:51 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:34:51 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:34:51 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:34:51 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:34:51 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:34:51 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:34:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:34:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:34:51 INFO - Fetch https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:34:52 INFO - Http code: 200 09:34:52 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:34:52 INFO - x-amz-cf-id: -d73F-rAgKbaF5nRJucg_k13LhJBxF4Waxlx42azfw8mR6yZEeq0iQ== 09:34:52 INFO - x-amz-version-id: jhZYtQPhleXwbv0fjf2NmG_lXGAeDxgi 09:34:52 INFO - Expected file size: 16327516 09:34:52 INFO - Obtained file size: 16327516 09:34:59 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 09:34:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:34:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:34:59 INFO - Fetch https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:34:59 INFO - Http code: 200 09:34:59 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 09:34:59 INFO - x-amz-cf-id: eAJ9Nikeaw1W-DBA5IK08yEqfl-NI27dEmHOuN38L9FxxL7665rRiQ== 09:34:59 INFO - x-amz-version-id: jhZYtQPhleXwbv0fjf2NmG_lXGAeDxgi 09:35:00 INFO - Expected file size: 16327516 09:35:00 INFO - Obtained file size: 16327516 09:35:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:35:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:35:06 INFO - Fetch https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:35:07 INFO - Http code: 200 09:35:07 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 09:35:07 INFO - x-amz-cf-id: KaAIL00eaOQibP2R3DZRGrYnssMBk0iBjjSzr5rsVsVVWL2DhMKkdA== 09:35:07 INFO - x-amz-version-id: GrKxiLP4TIYTZ2YLbE7h16g.ZwcIRRAE 09:35:07 INFO - Expected file size: 14409038 09:35:07 INFO - Obtained file size: 14409038 09:35:07 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')]} 09:35:07 INFO - trying https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:35:07 INFO - Downloading https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:35:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/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 09:35:10 INFO - Downloaded 114360995 bytes. 09:35:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:35:10 INFO - mkdir: /builds/slave/test/properties 09:35:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:35:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:35:10 INFO - Contents: 09:35:10 INFO - build_url:https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:35:11 INFO - Running post-action listener: _resource_record_post_action 09:35:11 INFO - Running post-action listener: set_extra_try_arguments 09:35:11 INFO - [mozharness: 2016-11-14 17:35:11.485840Z] Finished download-and-extract step (success) 09:35:11 INFO - [mozharness: 2016-11-14 17:35:11.486005Z] Running populate-webroot step. 09:35:11 INFO - Running pre-action listener: _resource_record_pre_action 09:35:11 INFO - Running main action method: populate_webroot 09:35:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:35:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:35:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:35:11 INFO - '--tppagecycles', 09:35:11 INFO - '7'], 09:35:11 INFO - 'tests': ['tsvgm']}}, 09:35:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:35:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:35:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:35:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:35:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:35:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['damp', 'tps']}, 09:35:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'tests': ['damp', 'tps']}, 09:35:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['dromaeo_dom']}, 09:35:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:35:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['basic_compositor_video']}, 09:35:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:35:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'other-e10s': {'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['a11yr', 09:35:11 INFO - 'ts_paint', 09:35:11 INFO - 'tpaint', 09:35:11 INFO - 'sessionrestore', 09:35:11 INFO - 'sessionrestore_no_auto_restore', 09:35:11 INFO - 'tabpaint']}, 09:35:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['tsvgx', 09:35:11 INFO - 'tsvgr_opacity', 09:35:11 INFO - 'tart', 09:35:11 INFO - 'tscrollx', 09:35:11 INFO - 'cart']}, 09:35:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:35:11 INFO - 'tsvgr_opacity', 09:35:11 INFO - 'tart', 09:35:11 INFO - 'tscrollx', 09:35:11 INFO - 'cart']}, 09:35:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'talos_options': ['--disable-e10s'], 09:35:11 INFO - 'tests': ['tp5o']}, 09:35:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'tests': ['tp5o']}, 09:35:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'talos_options': ['--disable-e10s', 09:35:11 INFO - '--xperf_path', 09:35:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:11 INFO - 'tests': ['tp5n']}, 09:35:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:35:11 INFO - 'talos_options': ['--xperf_path', 09:35:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:35:11 INFO - 'tests': ['tp5n']}}, 09:35:11 INFO - 'talos.zip': {'path': '', 09:35:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:35:11 INFO - Running post-action listener: _resource_record_post_action 09:35:11 INFO - [mozharness: 2016-11-14 17:35:11.505228Z] Finished populate-webroot step (success) 09:35:11 INFO - [mozharness: 2016-11-14 17:35:11.505359Z] Running create-virtualenv step. 09:35:11 INFO - Running pre-action listener: _resource_record_pre_action 09:35:11 INFO - Running main action method: create_virtualenv 09:35:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:35:11 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 09:35:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:35:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:35:11 INFO - Using real prefix '/tools/python27' 09:35:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:35:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:35:15 INFO - Installing pip.................done. 09:35:15 INFO - Return code: 0 09:35:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:35:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:35:15 INFO - Reading from file tmpfile_stdout 09:35:15 INFO - Output received: 09:35:15 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) 09:35:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:35:15 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')]} 09:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:15 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')]} 09:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:15 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:15 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 09:35:15 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 09:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:15 INFO - 'HOME': '/Users/cltbld', 09:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:15 INFO - 'LOGNAME': 'cltbld', 09:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:15 INFO - 'MOZ_NO_REMOTE': '1', 09:35:15 INFO - 'NO_EM_RESTART': '1', 09:35:15 INFO - 'PAGER': '/bin/cat', 09:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:15 INFO - 'PWD': '/builds/slave/test', 09:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:15 INFO - 'SHELL': '/bin/bash', 09:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:15 INFO - 'USER': 'cltbld', 09:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:15 INFO - 'XPC_FLAGS': '0x0', 09:35:15 INFO - 'XPC_SERVICE_NAME': '0', 09:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:15 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 09:35:15 INFO - Cleaning up... 09:35:15 INFO - Return code: 0 09:35:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:35:15 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')]} 09:35:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:15 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')]} 09:35:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:15 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:15 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 09:35:15 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 09:35:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:15 INFO - 'HOME': '/Users/cltbld', 09:35:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:15 INFO - 'LOGNAME': 'cltbld', 09:35:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:15 INFO - 'MOZ_NO_REMOTE': '1', 09:35:15 INFO - 'NO_EM_RESTART': '1', 09:35:15 INFO - 'PAGER': '/bin/cat', 09:35:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:15 INFO - 'PWD': '/builds/slave/test', 09:35:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:15 INFO - 'SHELL': '/bin/bash', 09:35:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:15 INFO - 'USER': 'cltbld', 09:35:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:15 INFO - 'XPC_FLAGS': '0x0', 09:35:15 INFO - 'XPC_SERVICE_NAME': '0', 09:35:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:16 INFO - Downloading/unpacking psutil>=3.1.1 09:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:35:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:19 INFO - Installing collected packages: psutil 09:35:19 INFO - Running setup.py install for psutil 09:35:19 INFO - building 'psutil._psutil_osx' extension 09:35:19 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 09:35:19 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 09:35:19 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 09:35:19 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 09:35:19 INFO - building 'psutil._psutil_posix' extension 09:35:19 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 09:35:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:35:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:35:19 INFO - ^ 09:35:19 INFO - 1 warning generated. 09:35:19 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 09:35:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:35:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:35:20 INFO - Successfully installed psutil 09:35:20 INFO - Cleaning up... 09:35:20 INFO - Return code: 0 09:35:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:35:20 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')]} 09:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:20 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')]} 09:35:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:20 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:20 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 09:35:20 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 09:35:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:20 INFO - 'HOME': '/Users/cltbld', 09:35:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:20 INFO - 'LOGNAME': 'cltbld', 09:35:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:20 INFO - 'MOZ_NO_REMOTE': '1', 09:35:20 INFO - 'NO_EM_RESTART': '1', 09:35:20 INFO - 'PAGER': '/bin/cat', 09:35:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:20 INFO - 'PWD': '/builds/slave/test', 09:35:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:20 INFO - 'SHELL': '/bin/bash', 09:35:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:20 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:20 INFO - 'USER': 'cltbld', 09:35:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:20 INFO - 'XPC_FLAGS': '0x0', 09:35:20 INFO - 'XPC_SERVICE_NAME': '0', 09:35:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:35:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:35:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:35:23 INFO - Installing collected packages: mozsystemmonitor 09:35:23 INFO - Running setup.py install for mozsystemmonitor 09:35:23 INFO - Successfully installed mozsystemmonitor 09:35:23 INFO - Cleaning up... 09:35:23 INFO - Return code: 0 09:35:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:35: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')]} 09:35:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35: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')]} 09:35:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:23 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:23 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 09:35:23 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 09:35:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:23 INFO - 'HOME': '/Users/cltbld', 09:35:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:23 INFO - 'LOGNAME': 'cltbld', 09:35:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:23 INFO - 'MOZ_NO_REMOTE': '1', 09:35:23 INFO - 'NO_EM_RESTART': '1', 09:35:23 INFO - 'PAGER': '/bin/cat', 09:35:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:23 INFO - 'PWD': '/builds/slave/test', 09:35:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:23 INFO - 'SHELL': '/bin/bash', 09:35:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:23 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:23 INFO - 'USER': 'cltbld', 09:35:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:23 INFO - 'XPC_FLAGS': '0x0', 09:35:23 INFO - 'XPC_SERVICE_NAME': '0', 09:35:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:23 INFO - Downloading/unpacking jsonschema==2.5.1 09:35:23 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 09:35:23 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 09:35:23 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 09:35:23 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 09:35:23 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 09:35:23 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 09:35:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:35:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:35:26 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 09:35:26 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 09:35:26 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 09:35:26 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 09:35:26 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 09:35:26 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 09:35:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:35:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:35:26 INFO - warning: no files found matching '*.txt' 09:35:26 INFO - no previously-included directories found matching 'build' 09:35:26 INFO - no previously-included directories found matching 'dist' 09:35:26 INFO - no previously-included directories found matching '.git*' 09:35:26 INFO - Installing collected packages: jsonschema, functools32 09:35:26 INFO - Running setup.py install for functools32 09:35:26 INFO - warning: no files found matching '*.txt' 09:35:26 INFO - no previously-included directories found matching 'build' 09:35:26 INFO - no previously-included directories found matching 'dist' 09:35:26 INFO - no previously-included directories found matching '.git*' 09:35:26 INFO - Successfully installed jsonschema functools32 09:35:26 INFO - Cleaning up... 09:35:26 INFO - Return code: 0 09:35:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:35:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:26 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:26 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 09:35:26 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 09:35:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:26 INFO - 'HOME': '/Users/cltbld', 09:35:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:26 INFO - 'LOGNAME': 'cltbld', 09:35:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:26 INFO - 'MOZ_NO_REMOTE': '1', 09:35:26 INFO - 'NO_EM_RESTART': '1', 09:35:26 INFO - 'PAGER': '/bin/cat', 09:35:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:26 INFO - 'PWD': '/builds/slave/test', 09:35:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:26 INFO - 'SHELL': '/bin/bash', 09:35:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:26 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:26 INFO - 'USER': 'cltbld', 09:35:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:26 INFO - 'XPC_FLAGS': '0x0', 09:35:26 INFO - 'XPC_SERVICE_NAME': '0', 09:35:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:35:27 INFO - Cleaning up... 09:35:27 INFO - Return code: 0 09:35:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:35:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:35:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:27 INFO - 'HOME': '/Users/cltbld', 09:35:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:27 INFO - 'LOGNAME': 'cltbld', 09:35:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:27 INFO - 'MOZ_NO_REMOTE': '1', 09:35:27 INFO - 'NO_EM_RESTART': '1', 09:35:27 INFO - 'PAGER': '/bin/cat', 09:35:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:27 INFO - 'PWD': '/builds/slave/test', 09:35:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:27 INFO - 'SHELL': '/bin/bash', 09:35:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:27 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:27 INFO - 'USER': 'cltbld', 09:35:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:27 INFO - 'XPC_FLAGS': '0x0', 09:35:27 INFO - 'XPC_SERVICE_NAME': '0', 09:35:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:29 INFO - Downloading blobuploader-1.2.4.tar.gz 09:35:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:35:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:35:29 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 09:35:29 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 09:35:29 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 09:35:29 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 09:35:29 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 09:35:29 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 09:35:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:35:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:30 INFO - Downloading docopt-0.6.1.tar.gz 09:35:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:35:31 INFO - Installing collected packages: blobuploader, requests, docopt 09:35:31 INFO - Running setup.py install for blobuploader 09:35:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:35:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:35:31 INFO - Running setup.py install for requests 09:35:31 INFO - Running setup.py install for docopt 09:35:31 INFO - Successfully installed blobuploader requests docopt 09:35:31 INFO - Cleaning up... 09:35:31 INFO - Return code: 0 09:35:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:35:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:31 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:31 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 09:35:31 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 09:35:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:31 INFO - 'HOME': '/Users/cltbld', 09:35:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:31 INFO - 'LOGNAME': 'cltbld', 09:35:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:31 INFO - 'MOZ_NO_REMOTE': '1', 09:35:31 INFO - 'NO_EM_RESTART': '1', 09:35:31 INFO - 'PAGER': '/bin/cat', 09:35:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:31 INFO - 'PWD': '/builds/slave/test', 09:35:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:31 INFO - 'SHELL': '/bin/bash', 09:35:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:31 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:31 INFO - 'USER': 'cltbld', 09:35:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:31 INFO - 'XPC_FLAGS': '0x0', 09:35:31 INFO - 'XPC_SERVICE_NAME': '0', 09:35:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-yvJsmR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E_yMBh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-5eksYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-eRScdM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-iAwb2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3DpGIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3HoV2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:32 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-5tTTdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-E496mr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-HcTErr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-jaIapG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-f73gCj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-feioYy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-j4Nu9A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-8SoM2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-JVHJJx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:33 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dj5Ibn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:35:34 INFO - Running setup.py install for manifestparser 09:35:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:35:34 INFO - Running setup.py install for mozcrash 09:35:34 INFO - Running setup.py install for mozdebug 09:35:34 INFO - Running setup.py install for mozdevice 09:35:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:35:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:35:34 INFO - Running setup.py install for mozfile 09:35:34 INFO - Running setup.py install for mozhttpd 09:35:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:35:34 INFO - Running setup.py install for mozinfo 09:35:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Running setup.py install for mozInstall 09:35:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Running setup.py install for mozleak 09:35:35 INFO - Running setup.py install for mozlog 09:35:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Running setup.py install for moznetwork 09:35:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Running setup.py install for mozprocess 09:35:35 INFO - Running setup.py install for mozprofile 09:35:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:35:35 INFO - Running setup.py install for mozrunner 09:35:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:35:36 INFO - Running setup.py install for mozscreenshot 09:35:36 INFO - Running setup.py install for moztest 09:35:36 INFO - Running setup.py install for mozversion 09:35:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:35:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:35:36 INFO - Cleaning up... 09:35:36 INFO - Return code: 0 09:35:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:36 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')]} 09:35:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:36 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')]} 09:35:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:36 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:36 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 09:35:36 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 09:35:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:36 INFO - 'HOME': '/Users/cltbld', 09:35:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:36 INFO - 'LOGNAME': 'cltbld', 09:35:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:36 INFO - 'MOZ_NO_REMOTE': '1', 09:35:36 INFO - 'NO_EM_RESTART': '1', 09:35:36 INFO - 'PAGER': '/bin/cat', 09:35:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:36 INFO - 'PWD': '/builds/slave/test', 09:35:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:36 INFO - 'SHELL': '/bin/bash', 09:35:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:36 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:36 INFO - 'USER': 'cltbld', 09:35:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:36 INFO - 'XPC_FLAGS': '0x0', 09:35:36 INFO - 'XPC_SERVICE_NAME': '0', 09:35:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:35:36 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-3md2bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-kZjCHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-boTrk0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-fj424c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-U5Mr6Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Z4fXJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-dJj0Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-Ms5P3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-S7ST_u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:35:37 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)) 09:35:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:35:37 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-TJQeYM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-siT8er-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-GuFezr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-QYamFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-gzkaMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-LFdrOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-LhVvkh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:35:38 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)) 09:35:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:35:38 INFO - Running setup.py (path:/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/pip-x6tOUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:35:38 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)) 09:35:38 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)) 09:35:38 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)) 09:35:38 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)) 09:35:38 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)) 09:35:38 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)) 09:35:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:35:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:35:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:35:41 INFO - Downloading blessings-1.6.tar.gz 09:35:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:35:41 INFO - Installing collected packages: blessings 09:35:41 INFO - Running setup.py install for blessings 09:35:41 INFO - Successfully installed blessings 09:35:41 INFO - Cleaning up... 09:35:41 INFO - Return code: 0 09:35:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:35:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:41 INFO - Reading from file tmpfile_stdout 09:35:41 INFO - Current package versions: 09:35:41 INFO - blessings == 1.6 09:35:41 INFO - blobuploader == 1.2.4 09:35:41 INFO - docopt == 0.6.1 09:35:41 INFO - functools32 == 3.2.3-2 09:35:41 INFO - jsonschema == 2.5.1 09:35:41 INFO - manifestparser == 1.1 09:35:41 INFO - mozInstall == 1.12 09:35:41 INFO - mozcrash == 1.0 09:35:41 INFO - mozdebug == 0.1 09:35:41 INFO - mozdevice == 0.48 09:35:41 INFO - mozfile == 1.2 09:35:41 INFO - mozhttpd == 0.7 09:35:41 INFO - mozinfo == 0.9 09:35:41 INFO - mozleak == 0.1 09:35:41 INFO - mozlog == 3.4 09:35:41 INFO - moznetwork == 0.27 09:35:41 INFO - mozprocess == 0.23 09:35:41 INFO - mozprofile == 0.28 09:35:41 INFO - mozrunner == 6.13 09:35:41 INFO - mozscreenshot == 0.1 09:35:41 INFO - mozsystemmonitor == 0.3 09:35:41 INFO - moztest == 0.7 09:35:41 INFO - mozversion == 1.4 09:35:41 INFO - psutil == 3.1.1 09:35:41 INFO - requests == 1.2.3 09:35:41 INFO - wsgiref == 0.1.2 09:35:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:35:41 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')]} 09:35:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:41 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')]} 09:35:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:41 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:41 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 09:35:41 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 09:35:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:41 INFO - 'HOME': '/Users/cltbld', 09:35:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:41 INFO - 'LOGNAME': 'cltbld', 09:35:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:41 INFO - 'MOZ_NO_REMOTE': '1', 09:35:41 INFO - 'NO_EM_RESTART': '1', 09:35:41 INFO - 'PAGER': '/bin/cat', 09:35:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:41 INFO - 'PWD': '/builds/slave/test', 09:35:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:41 INFO - 'SHELL': '/bin/bash', 09:35:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:41 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:41 INFO - 'USER': 'cltbld', 09:35:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:41 INFO - 'XPC_FLAGS': '0x0', 09:35:41 INFO - 'XPC_SERVICE_NAME': '0', 09:35:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 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)) 09:35:42 INFO - Cleaning up... 09:35:42 INFO - Return code: 0 09:35:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:35:42 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')]} 09:35:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:35:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:42 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')]} 09:35:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:35:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:35:42 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 0x10a746490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a6bc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fad83daa020>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, '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.AmbMiU44mj/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.t1sn2zJcIl/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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 09:35:42 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 09:35:42 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 09:35:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:35:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:35:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:35:42 INFO - 'HOME': '/Users/cltbld', 09:35:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:35:42 INFO - 'LOGNAME': 'cltbld', 09:35:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:35:42 INFO - 'MOZ_NO_REMOTE': '1', 09:35:42 INFO - 'NO_EM_RESTART': '1', 09:35:42 INFO - 'PAGER': '/bin/cat', 09:35:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:35:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:35:42 INFO - 'PWD': '/builds/slave/test', 09:35:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:35:42 INFO - 'SHELL': '/bin/bash', 09:35:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:35:42 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:35:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:35:42 INFO - 'USER': 'cltbld', 09:35:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:35:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:35:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:35:42 INFO - 'XPC_FLAGS': '0x0', 09:35:42 INFO - 'XPC_SERVICE_NAME': '0', 09:35:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:35:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:35:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:35:42 INFO - Cleaning up... 09:35:42 INFO - Return code: 0 09:35:42 INFO - Running post-action listener: _resource_record_post_action 09:35:42 INFO - Running post-action listener: _start_resource_monitoring 09:35:42 INFO - Starting resource monitoring. 09:35:42 INFO - [mozharness: 2016-11-14 17:35:42.519619Z] Finished create-virtualenv step (success) 09:35:42 INFO - [mozharness: 2016-11-14 17:35:42.520690Z] Running install step. 09:35:42 INFO - Running pre-action listener: _resource_record_pre_action 09:35:42 INFO - Running main action method: install 09:35:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:35:42 INFO - Reading from file tmpfile_stdout 09:35:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:35:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:35:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:35:42 INFO - Reading from file tmpfile_stdout 09:35:42 INFO - Output received: 09:35:42 INFO - Usage: mozinstall [options] installer 09:35:42 INFO - Options: 09:35:42 INFO - -h, --help show this help message and exit 09:35:42 INFO - -d DEST, --destination=DEST 09:35:42 INFO - Directory to install application into. [default: 09:35:42 INFO - "/builds/slave/test"] 09:35:42 INFO - --app=APP Application being installed. [default: firefox] 09:35:42 INFO - mkdir: /builds/slave/test/build/application 09:35:42 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'] 09:35:42 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 09:36:15 INFO - Reading from file tmpfile_stdout 09:36:15 INFO - Output received: 09:36:15 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:36:15 INFO - Running post-action listener: _resource_record_post_action 09:36:15 INFO - [mozharness: 2016-11-14 17:36:15.133381Z] Finished install step (success) 09:36:15 INFO - [mozharness: 2016-11-14 17:36:15.133560Z] Running run-tests step. 09:36:15 INFO - Running pre-action listener: _resource_record_pre_action 09:36:15 INFO - Running main action method: run_tests 09:36:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:36:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:36:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:36:15 INFO - 2016-11-14 09:36:15.148 screenresolution[2293:289924] starting screenresolution argv=screenresolution get 09:36:15 INFO - 2016-11-14 09:36:15.151 screenresolution[2293:289924] Display 0: 1600x1200x32@60 09:36:15 INFO - 2016-11-14 09:36:15.156 screenresolution[2294:289926] starting screenresolution argv=screenresolution list 09:36:15 INFO - Available Modes on Display 0 09:36:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:36:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:36:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:36:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:36:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:36:15 INFO - Intel Iris: 09:36:15 INFO - Chipset Model: Intel Iris 09:36:15 INFO - Type: GPU 09:36:15 INFO - Bus: Built-In 09:36:15 INFO - VRAM (Dynamic, Max): 1536 MB 09:36:15 INFO - Vendor: Intel (0x8086) 09:36:15 INFO - Device ID: 0x0a2e 09:36:15 INFO - Revision ID: 0x0009 09:36:15 INFO - Displays: 09:36:15 INFO - Display: 09:36:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:36:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:36:15 INFO - Display Serial Number: 1600x1200 60 09:36:15 INFO - Main Display: Yes 09:36:15 INFO - Mirror: Off 09:36:15 INFO - Online: Yes 09:36:15 INFO - Rotation: Supported 09:36:15 INFO - Return code: 0 09:36:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:36:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:36:15 INFO - Python 2.7.3 09:36:15 INFO - Return code: 0 09:36:15 INFO - grabbing minidump binary from tooltool 09:36:15 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')]} 09:36:15 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 0x10a55ad30>, '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 0x10a3c0db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a552b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:36:15 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 09:36:15 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 09:36:15 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 09:36:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:36:15 INFO - Return code: 0 09:36:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:36:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:36:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:36:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:36:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:36:15 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/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 09:36:15 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/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0167 --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 09:36:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AmbMiU44mj/Render', 09:36:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:36:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:36:15 INFO - 'HOME': '/Users/cltbld', 09:36:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:36:15 INFO - 'LOGNAME': 'cltbld', 09:36:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:36:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:36:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:36:15 INFO - 'MOZ_NO_REMOTE': '1', 09:36:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:36:15 INFO - 'NO_EM_RESTART': '1', 09:36:15 INFO - 'PAGER': '/bin/cat', 09:36:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:36:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:36:15 INFO - 'PWD': '/builds/slave/test', 09:36:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:36:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:36:15 INFO - 'SHELL': '/bin/bash', 09:36:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.t1sn2zJcIl/Listeners', 09:36:15 INFO - 'TMPDIR': '/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/', 09:36:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:36:15 INFO - 'USER': 'cltbld', 09:36:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:36:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:36:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:36:15 INFO - 'XPC_FLAGS': '0x0', 09:36:15 INFO - 'XPC_SERVICE_NAME': '0', 09:36:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:36:15 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/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0167', '--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 09:36:15 INFO - mozversion application_buildid: 20161114071242 09:36:15 INFO - mozversion application_changeset: ebec82f380befccdb9653a607330835cd5f7b1be 09:36:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:36:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:36:15 INFO - mozversion application_name: Firefox 09:36:15 INFO - mozversion application_remotingname: firefox-dev 09:36:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:36:15 INFO - mozversion application_vendor: Mozilla 09:36:15 INFO - mozversion application_version: 52.0a2 09:36:15 INFO - mozversion platform_buildid: 20161114071242 09:36:15 INFO - mozversion platform_changeset: ebec82f380befccdb9653a607330835cd5f7b1be 09:36:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:36:15 INFO - mozversion platform_version: 52.0a2 09:36:15 INFO - using testdate: 1479144975 09:36:15 INFO - actual date: 1479144975 09:36:15 INFO - starting webserver on 'localhost:53791' 09:36:15 INFO - SUITE-START | Running 2 tests 09:36:15 INFO - TEST-START | dromaeo_css 09:36:15 INFO - operating with platform_type : mac_ 09:36:15 INFO - Initialising browser for dromaeo_css test... 09:36:15 INFO - TEST-INFO | started process 2304 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpo6teeV/profile http://localhost:53791/getInfo.html) 09:36:17 ERROR - PROCESS | 2304 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:36:18 INFO - PROCESS | 2304 | __metrics Screen width/height:1600/1200 09:36:18 INFO - PROCESS | 2304 | colorDepth:24 09:36:18 INFO - PROCESS | 2304 | Browser inner width/height: 1024/689 09:36:18 INFO - PROCESS | 2304 | __metrics 09:36:18 INFO - PROCESS | 2304 | 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" 09:36:18 INFO - PROCESS | 2304 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:36:19 INFO - TEST-INFO | 2304: exit 0 09:36:19 INFO - Browser initialized. 09:36:19 INFO - Running cycle 1/1 for dromaeo_css test... 09:36:19 INFO - TEST-INFO | started process 2307 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpo6teeV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:36:31 INFO - PROCESS | 2307 | RSS: Main: 217174016 09:36:31 INFO - PROCESS | 2307 | 09:37:22 INFO - PROCESS | 2307 | 1479145042272 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] 09:39:37 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53791/tests/dromaeo/cssquery-ext.html) 09:39:39 INFO - PROCESS | 2307 | RSS: Main: 425541632 09:39:39 INFO - PROCESS | 2307 | 09:42:42 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-ext.html (next: http://localhost:53791/tests/dromaeo/cssquery-jquery.html) 09:42:43 INFO - PROCESS | 2307 | RSS: Main: 350015488 09:42:43 INFO - PROCESS | 2307 | 09:45:45 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53791/tests/dromaeo/cssquery-mootools.html) 09:45:46 INFO - PROCESS | 2307 | RSS: Main: 328585216 09:45:46 INFO - PROCESS | 2307 | 09:48:48 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53791/tests/dromaeo/cssquery-prototype.html) 09:48:49 INFO - PROCESS | 2307 | RSS: Main: 326307840 09:48:49 INFO - PROCESS | 2307 | 09:51:52 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53791/tests/dromaeo/cssquery-yui.html) 09:51:53 INFO - PROCESS | 2307 | RSS: Main: 315224064 09:51:53 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | Cycle 1(1): loaded http://localhost:53791/tests/dromaeo/cssquery-yui.html (next: http://localhost:53791/tests/dromaeo/cssquery-dojo.html) 09:54:56 INFO - PROCESS | 2307 | RSS: Main: 372248576 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | __start_tp_report 09:54:56 INFO - PROCESS | 2307 | _x_x_mozilla_page_load 09:54:56 INFO - PROCESS | 2307 | _x_x_mozilla_page_load_details 09:54:56 INFO - PROCESS | 2307 | |i|pagename|runs| 09:54:56 INFO - PROCESS | 2307 | |0;dojo.html;3338.799672019679;3341.8329083545823;3362.29391827716;3366.5791776027995;3402.370561446133;5752.993226185411;5817.360090390053;5899.764009439623;5949.553783466246;5966.910496342558;5879.235699359077;5910.46805787479;5973.581849270553;5975.970120149394;6006.909896351558;5299.576033917287;5330.866728331784;5338.893222135555;5392.595555333346;5466.972665136669;5289.788408463657;5290.285811415448;5299.735013249322;5392.379876314215;5442.727863606824;5399.433059528752;5404.945950540484;5412.107002344608;5423.132298832187;5432.891342173154;5370.91943620846;5393.325834270716;5396.10964190908;5401.108817045183;5402.756875940572;5250.527452529291;5349.090654588729;5356.946430535722;5367.5169234769055;5395.757190926379;5973.074173503114;5978.641281523079;5979.372165922559;5983.1623572699855;5984.042553191469;6284.780032698834;6285.277193122758;6300.243970723497;6311.558190926637;6313.273973493016;6278.434940855345;6347.96826015867;6348.428641422294;6357.523185761041;6360.173177486943;5818.650880947157;5870.618409803349;5926.170336152899;5940.168376427304;5970.8655355482315;6247.781327653511;6322.74709011639;6350.8412289692205;6356.364363563699;6360.936390636127;6215.098810672517;6227.9377206227355;6240.968795155995;6295.181626388632;6406.071119687619;6339.5562310637815;6351.428371446592;6356.491480681534;6369.108324834527;6373.980163173963;6360.109584658059;6364;6366.236051673829;6376.936230637727;6384.648844313514;5312.415634280226;6231.158793562901;6248.9062664060075;6253.1245625612455;6255.468285195719;5016.54851063406;5019.774110165052;5031.295618613397;5054.570361519239;5064.240363945438;4521.796519156645;4523.36672865799;4536.500984891659;4551.06703125865;4584.816607335736;5834.3290521589715;5861.736221870026;5876.559258055578;5946.613470124428;6033.336333003366;6273.592216505913;6339.841503962438;6354.586951848115;6362.6500542469685;6371.904421433682;5861.70691465425;6001.579889407787;6011.368806275365;6014.977453832859;6038.516918646497;4505.774711264489;5468.207109969112;5523.7790488380815;5536.307961504812;5539.612227144061;6213.844653883689;6308.400701933309;6317.178766760292;6352.936470635419;6358.1734374531015;6325.05124231369;6325.525585581192;6337.144485494399;6348.650824204713;6398.360163983564;6325.146105275729;6335.65153916539;6393.073004414241;6419.967900160469;6423.229212494531;6117.632942023492;6135.04893764628;6169.753209871555;6244.313125556095;6323.399277068671;6233.252009758677;6308.558400911892;6324.936750632435;6350.364963503797;6351.2060992375955;6264.404881536247;6289.968550157403;6318.178636777189;6319.46284565817;6321.683915804099;6127.050307202391;6137.499563105221;6163.506919446523;6246.750129994749;6300.464460520907;6109.677964501176;6110.969445152924;6136.6011209271255;6143.204841796883;6171.84570385744;6092.9086063709965;6283.7800676976085;6291.056341548804;6303.149074875017;6303.212098487689;5990.7004649768205;5996.1005849122985;6025.728842202199;6088.51291896656;6111.205543279345 09:54:56 INFO - PROCESS | 2307 | |1;ext.html;482.74655805702025;503.17479333892413;508.6261597725671;517.2112528394198;535.0315928170013;30266;38325.04187395317;39689.603103968984;39929.40105898414;41541;40161;40384.59615403845;40713;40734.38898416526;40742.388864167064;7985.72049978248;9231.953840230806;9398.530073496331;9418.952905235465;9420.81158376832;9186.26509879208;9294.814103717956;9295.07049295072;9300.302477314195;9311.534423278843;9261.44431334122;9335.066493350645;9340.252779777635;9346;9376.343655944087;9225.630974761001;9288.97821239663;9289.767755806091;9291.953540232256;9293.674721384787;9175.862362064574;9184.540772961393;9257.981622021573;9259.583318750605;9271.397359171633;31101.66737494697;31309.530357044663;31328.843355783076;31343.216419589466;31352.529712054336;2814.2964258935267;2943.4260319237756;3424.520567120605;3469.2367679110807;3687.7418580699364;26997.325066873484;28210.294742631395;28968.855155724297;29241.561376579368;30793.692063079307;3131.9217019574467;3151.5745374374396;3177.6663450337614;3260.6902344277255;3261.5596894419186;486137;486544;487874.99999999645;487955;497160;47187;57786.13320800191;57939;57991.710041450366;58004.70997645069;34161.658383415845;34193;34661.30677386488;35489.8225508876;35622.46566301508;15948.840511594968;16092.75860862088;16104.919475402548;16114.274857631435;16169.434069807501;12220.1445898788;12323.198992065487;12347.444365003596;12358.505659773688;12361.443735031946;18591.34930277434;18593.628127437376;18598;18629.627407451775;18638.53403664919;7552.244775522404;7608.771736847903;7694.884576731353;7728.806779830436;7733.458657894006;835.4444294544181;934.4673536084366;940.0646356874953;1010.2372708604956;1037.4501514197486;28389.290267743057;29138.708612914023;29186.124416267547;29561.85219073934;29793.702062979526;824.0358780227149;848.3425345357274;877.6138499060393;911.7902882337099;986.9193233409405;12711.2373257603;12870.9356453219;15089.320980555902;15357.846421535865;15391.923040384727;670961;680963;681439;682009;682105;56036.439635603936;56616;56688.716556416955;56706.43293567094;56708;1217.8903898648987;1247.5882958623633;1250.0437165568528;1251.6182564317971;1351.2635613590617;21239.362819115453;25638.359041024123;30019.549706754853;30127;31497.8425107873;52400.47599524108;53108;53131.73434132805;53148;53165.93668126693;524359;596317.055244163;597894;601260;603167;25692.614610780478;26074.60888086718;34797.47803782996;35581.466278006366;35601.46597800983;37053.62946370609;38208.235835283696;38832.223355532165;47052;49145;34002.14994625055;37754.62245377511;38549.8435046949;39685.80942571728;40029;37919.43120853245;38116;38129.61870381261;39196.80401597974;39642.405363920145;37113.257734844614;37299.440508391854;37365.4395184067;38023.239535209694;39485;32272.83863580667;32291.838540808087;33453.49819752657;33735;34505.48241776426;25031;25740.356491087874;25803.483930321665;25952.480950381265;26287 09:54:56 INFO - PROCESS | 2307 | |2;jquery.html;324.5051296772422;371.4280008786468;372.34839031694537;389.57925440524235;402.26586479674603;18618.720719189223;19464;19763.209471621154;20235;20435.591288174226;20021.899890500565;20041.398758037245;20379.184832606716;20418.89790551049;20683.896580517114;4675.041616468633;5548.667079975195;5838.007538718428;5844.152597873306;5860.619059761123;5620.353659329188;5777.017906955828;5789.681567513785;5804.912926306088;5814.098814683731;5444.428335024825;5649.350324712669;5703.31560212775;5733.426657334275;5745.454181852718;5668.716564171796;5721.742521586539;5727.599068065237;5736.139579063153;5738.340090889538;5036.169032109734;5042.647014708972;5309.33633295838;5359.517643412111;5428.995635807389;12860.678483037906;12984.28586427746;13009.869901301056;13160.736785264242;13166.341682915816;2108.7153234313323;2307.849989750661;2361.728401233846;2376.572217000939;2458.7418321076207;9374.671886484008;9547.61809527618;10193.59225630974;10233.9488302558;10248.846267305997;1701.7617533545313;1756.2711474737969;1800.1449311577028;1804.2782886845237;1872.7284543741218;109191.90808091896;118563;119831.4008429961;122232.38883805524;124264.37867810694;11246.268992515461;11517.827232591519;11607;11622.360770157553;11720.304330003977;14730.189839558927;14797.112173269637;14799.334029968677;14801.333939972727;14813.037152585048;8022.9598852005365;8274.172582741798;8300.70947516834;8415.747527574182;8417.831643367099;8137.430379873471;8161.306288965386;8194.672213111528;8327.042390125092;8343.082260951413;4220.712682631765;4333.544977777351;4535.160995215896;4564.88587785308;4621.336318925184;1535.7696345548034;1714.2542993797738;1888.2447021191633;1904.828565429118;1907.1799126375793;680.2109552918689;693.5838496901819;703.5040296590892;743.85494828509;912.762681702762;19809.30667426662;20901.954902254827;21078.683819742717;21128.366149015554;21269.89365053165;827.9733130917618;913.2693844924034;929.8651695504115;951.5717926932908;996.3623281100101;7293.52592081513;11846.940765296118;12127.939360303142;12193.268403895618;12241;606136;606953;607735;620884;621141;81007.59496202481;98410;100439.99560004452;101865.49067254616;125075;931.6366617019368;949.924006079512;1001.1940387987739;1001.4842356186715;1003.1222680154865;15376.923115384352;17812.821871781118;18640.720389194445;19123.23507059702;19279.22883084661;55147.72426137843;55366.72316638391;56724;56890.14664779949;57198;71055.09196994008;72889.6355518219;75912.48175036578;76770.23229767631;78502.6074869622;7089.113860767404;7923.7226697065325;8037.879431808411;8094;8243.93901524619;10829.891701083203;11848.348340840992;12461.626151215478;12680.492780288685;13057;9046.326429610543;10926.890731092906;11233.943830280796;11348.602798901999;11385.658430247106;10635.680929572125;10748.086412655011;11164.888351116384;11165.575714100778;11435.828462573234;11147.777044458902;11148.609798657008;11173.720656983316;11333.773324533298;11639.47622356979;7374.188839227573;7490.838919967414;7492.363149132167;7545.773626790986;7736.961315193388;9353.392029518332;9389.38968967015;11004.339739615649;11037.392943388191;11079.058280046287 09:54:56 INFO - PROCESS | 2307 | |3;mootools.html;1289.1427200911394;1311.658968668146;1377.7451171533264;1493.3205391546846;1792.0860361215784;7492.400607951365;7801.336886364658;8017.759467215986;8376.958115209416;8384;8178.959105204465;8282.792930176749;8284.171582841727;8288.544130072843;8289.709860154895;2300.896459659315;2334.9533009339802;2340.578695834749;2342.6603142544323;2342.7071616047992;2312.271634435156;2354.75275096115;2363.4918492524102;2363.621820508719;2369.3484291819714;2293.3005433342905;2303.6199027160496;2331.4287999440076;2334.7315058768204;2335.532893421314;2251.6509940959095;2272.784085511874;2312.4218945263683;2313.097891822182;2320.4894923116885;2277.6355783074796;2336.4626135988647;2338.181636427254;2341.7658234176615;2342.6720259163562;8125.390595705346;8264.338852891755;8356;8358.164183581594;8398.244158025745;5047.4447810740785;5189.506996835295;5192.662476938987;5195.974020129913;5201.5838732901275;3393.440082386428;3515.806630635314;3534.3461459630053;3589.587197472298;3600.6579374959597;1052.163529993657;1063.3716008495064;1072.7908057928707;1081.9729506762394;1089.477051015517;10644.946775266151;10748.677539673823;10791.1906607004;10801.67594972152;10803.027727504485;4103.4050062740735;4351.586599273064;4427.800748966304;4435.157320109169;4451.421315229064;8624.396292259482;8625.78435539104;8629.13708629132;8642.395032347675;8658.39391242607;4365.541618130051;5028.270900719448;5054.342935418372;5058.570021548136;5065.772040258197;4029.234445455354;4239.236937351245;4386.670999674973;4422.889427764331;4734.242521196592;3658.432942893856;3681.7790932544135;3732.290864735746;3733.085394078468;3739.2147648993473;1136.420425582942;1137.556353022322;1138.08384250679;1142.7543078238218;1146.7821113988482;856.2507805670039;869.1048220333067;870.1211776106052;888.6800751729372;890.5057692980458;5634.915476267777;5747.942520574741;5879.735411906389;5964.701764911737;6099.115628233882;870.6517393042834;886.3618194899661;892.9151730585584;898;903.7379160043514;3116.5169398743233;3697.574778900461;3699.5560532735703;3841.673457756066;3899.980500097481;9422.293328000427;9882.65410710636;10102.747431314128;10158.542865571068;10192;9803;10130.290879638502;10140.898591014144;10153.64462243818;10155.136813370798;7717.112532058773;7828.060632724109;7883.802904927308;7887.526748395115;7908.802279942933;4722.716637001791;5362.1420572708175;5437;5535.418781027934;5604.047311956896;9230.492322922035;9256.443905217133;9310.115539023782;9340.859887101564;9465.100815422524;9465.526723663927;9501.144896959308;9507.286953478379;9579.323618366898;9580.56887440053;6661.267260601233;6785.321467853175;6790.694418751069;6790.694418751069;6796.4902632303765;7673.386129109546;7678.846423071395;7755.650995705094;7761.573113478813;7765.53406795594;7048.118985126859;7069.116360454943;7135;7145.9285407147345;7178.533395329496;6756.527043106936;6818.045473633596;6949.18878066007;7014.333638304353;7048.118985126859;6508.9349106508325;6580.210374754868;6647.567908086153;6650.068990341453;6656.134702488645;4640.211164102043;4700.130475861976;4772.5227477252;4800.255960326155;4869.853904382874;3971.543272523697;4771.498992605865;4799.592034676952;4862.319275301531;4868.269759536098 09:54:56 INFO - PROCESS | 2307 | |4;prototype.html;282.1605722974151;299.46993820936945;303.3856440707567;306.9969300306996;311.86901501369425;4295.140971805644;4631.309572006218;4944.876378090545;5034.370703662047;5049.545540901318;4349.543297953716;4669.346291519181;5087.465816089303;5150.3047088643025;5174.793008279665;3392.694657480826;3651.1298024094544;3755.493008443866;3946.822392992315;4019.437278780973;3280.8687652493873;3982.243373758991;3988.4814974053334;4003.299422601048;4019.9799001004953;3862.3114925246464;3920.4511368408444;4014.5985401459766;4042.3734321180264;4043.433919251307;3957.248122856648;3995.3407687731487;3998.8200530975887;4034.5763694812063;4040.525208298979;2927.8975235866856;3268.8038717677014;3436.055084851686;3472.5485686860798;3473.878414255489;4232.386303985935;4431.955680443219;4640.976795116037;4951.3810773653295;4951.480094590088;4204.453421055274;4529.456465224162;4533.569310915458;4596.621013695912;4620.491745907947;4292.849750258726;4527.70960276323;5382.219578161144;5429.239906413105;5441.537469315113;3460.52927505809;3712.2204337089242;3753.3244016077097;3784.9053773655605;3807.1243613968663;5416.133418653036;5663.582405723542;5671.695510032716;5695.231143795576;5722.396309679086;3838.347480928249;3843.26977874203;3844.8654297099465;3846.61521852133;3854.845806167778;3210.5665735125926;3306.818125003148;3732.384156614128;3914.1212218135456;3970.1067259866877;3711.888643340704;4079.6940229482316;4293.248681480728;4359.38968544404;4395.714228601173;4569.063342014879;4597.747123908217;5502.779888804403;5550.64097758451;5582.358028826656;1234.234774439835;1234.4074844074894;1235.178606226849;1257.5787111317627;1258.4337048328289;715.9606221657859;772.6059709548058;773.6943907156642;848.3467729847982;863.7581477186397;4448.844290449883;4690.085433340466;4985.731492171053;4991.775370108354;5010.549050585392;4596.88507787308;4920.138975679241;5093.541581257705;5249.6088536537845;5267.947320526822;4250.808713607895;4433.091216300651;4557.794899229542;4719.551642593948;4986.376702912136;2468.407582180264;2526.280010197085;2817.9295517611813;2826.3781967967016;2832.546792513188;4851.671197824993;5122.641415100908;5242.97378513105;5357.973210133924;5562.023153445306;4780.569748722632;4862.3922009748785;5403.621746477709;5405.702686352289;5465.890682186414;4639.916873151605;4943.65394422387;5282.917002014655;5474.385056408369;5589.301337332833;4746.359241502491;5257.658252213594;5270.657407268515;5277.472252774692;5288.471152884835;4359.367891655629;4807.375041244755;4880.31675565414;5148.712821794551;5198;4862.927056094232;5466.945330546802;5474.534664553398;5475.534579560782;5476.98765678883;3173.6032995875516;3503.8948831535095;3625.782453052825;3633.4368172933237;3655.7440979131206;4252.170826688766;4486.2597671385165;4498.54829118138;4667.673262871566;5043.293938848491;4998.500149985118;5269.8419047427105;5461.42276808691;5543.895418565616;5561.386148510193;4805.351277577623;5252.789888404421;5523.8895222094525;5547.472990065938;5600.803971860965;4536.977315113403;4857.53853383928;5104.438511763778;5423.511883930465;5446.526241127642;5056.266841307916;5473.429686212057;5516.862078448071;5537.612367134262;5556.222128901876;5443.482869127427;5459.535939445032;5470.890582188414;5490.478404551562;5587.804426845203 09:54:56 INFO - PROCESS | 2307 | |5;yui.html;661.9271880093189;677.9965650836763;697.8464737757691;753.4424525850868;787.094840932927;15902.681946361081;16582.83417165828;16585.75121373179;16586.58533536659;16603.75094373584;12191.695207619814;14942.252887355644;15064.171470569114;15072.095674259552;15076.095434273924;636.7453018792473;657.1850904877941;796.9043714754238;821.0967935271213;822.6914906909909;657.9934200658004;785.0892964161574;804.8873157757919;813.2396209544065;820.1880138662741;862.659249596409;866.8483015472299;867.6789587852503;867.8611422172454;868.179570306061;655.3053763011176;859.7936495241129;864.5547543015352;865.346663269234;865.4028720183054;503.69023050823847;620.1132380695603;635.3582881289884;642.9678516074177;644.165805282156;14401.279936003159;14443.78334324986;14445.855541444556;14447.638809029753;14451.855481445156;13141.474341026347;13321.800172997413;13468.528601498998;13502.459901603925;13536.796948045787;11904.345261010641;12061.577844775391;12135.211211271238;12340;12477.75044499105;4415.889602759957;4494.763939916516;4496.730196188207;4501.864944051684;4578.06149739306;89529.55235223848;93242.13515729648;93510.532447338;94673;97151.02848971561;10136.847947280796;10486.685399438029;10503.94748026255;10704.30422022566;11105.278156919774;20184;20570.897145514176;20574.897125514275;20590.382288531368;20610.89694551518;7372.705091796375;7395.038644976119;7435.739749108755;7527.360174385239;7642.19756925526;3386.9661303387143;3960.861369852099;3988.9202215956097;4026.1947610478023;4267.615914567704;3304.0253125328086;3407.0800883761244;3409.284050349405;3426.229098452828;3427.725781937439;628.896232121704;637.9330170332048;659.0608383054129;692.5706062241443;699.1889408286363;580.7386675995818;651.6851706959546;656.5469825820169;664.2560332427712;669.4343279928453;8431.704890328809;8984.8652270216;9078.280683169562;9342.953285233667;9407;608.2791891608458;627.847899105138;632.7503181217152;667.429347907532;668.8862893308052;3599.8380072896784;4495.527969563214;4520.751358675305;4587;4614.423197100363;118965;122053;124135;124477.37761311136;128221.35889320681;55097.724511377986;71234;89898;95234;96971.51514242384;70629.64685176645;74009.62995185098;83046;89421.55289223512;91060;9242.815143696955;9653.999542511701;9742.681009309237;9876.408560677772;9968.40189588627;49145;57188;60026.699866500385;61701.074483883676;62158.37841621707;70756.64621676858;85271;85460.57269713611;89234.55382723045;89392;12806.103572749818;12999.09006369545;13139.671508212372;13148.145370550883;13346.265955372548;13184.538541151014;13861.306934653428;14340.498082567061;14770.704585908437;15162.24188790578;13166.539171128965;13227.537036203687;13245.20528768277;13778.448862045407;13861.653458663613;13248.403821827847;13332.200067995951;13349.199048057148;13841;14019.859801401855;12903.806442903551;12947.805782913452;13348.599542013755;13406.128601640861;13629.250391228577;10856.185786065918;11053.557857685602;11369.658910232707;11622.94188529052;11686.474108664961;10701.411422371844;11382.073184751247;11467.483963221508;11606.013488853205;11633.88366116328 09:54:56 INFO - PROCESS | 2307 | __end_tp_report 09:54:56 INFO - PROCESS | 2307 | __start_cc_report 09:54:56 INFO - PROCESS | 2307 | _x_x_mozilla_cycle_collect,2525 09:54:56 INFO - PROCESS | 2307 | __end_cc_report 09:54:56 INFO - PROCESS | 2307 | __startTimestamp1479146096706__endTimestamp 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | ------- Summary: start ------- 09:54:56 INFO - PROCESS | 2307 | Number of tests: 6 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#0] dojo.html Cycles:165 Average:5865.90 Median:6110.97 stddev:647.49 (10.6%) stddev-sans-first:618.39 09:54:56 INFO - PROCESS | 2307 | Values: 3338.8 3341.8 3362.3 3366.6 3402.4 5753.0 5817.4 5899.8 5949.6 5966.9 5879.2 5910.5 5973.6 5976.0 6006.9 5299.6 5330.9 5338.9 5392.6 5467.0 5289.8 5290.3 5299.7 5392.4 5442.7 5399.4 5404.9 5412.1 5423.1 5432.9 5370.9 5393.3 5396.1 5401.1 5402.8 5250.5 5349.1 5356.9 5367.5 5395.8 5973.1 5978.6 5979.4 5983.2 5984.0 6284.8 6285.3 6300.2 6311.6 6313.3 6278.4 6348.0 6348.4 6357.5 6360.2 5818.7 5870.6 5926.2 5940.2 5970.9 6247.8 6322.7 6350.8 6356.4 6360.9 6215.1 6227.9 6241.0 6295.2 6406.1 6339.6 6351.4 6356.5 6369.1 6374.0 6360.1 6364.0 6366.2 6376.9 6384.6 5312.4 6231.2 6248.9 6253.1 6255.5 5016.5 5019.8 5031.3 5054.6 5064.2 4521.8 4523.4 4536.5 4551.1 4584.8 5834.3 5861.7 5876.6 5946.6 6033.3 6273.6 6339.8 6354.6 6362.7 6371.9 5861.7 6001.6 6011.4 6015.0 6038.5 4505.8 5468.2 5523.8 5536.3 5539.6 6213.8 6308.4 6317.2 6352.9 6358.2 6325.1 6325.5 6337.1 6348.7 6398.4 6325.1 6335.7 6393.1 6420.0 6423.2 6117.6 6135.0 6169.8 6244.3 6323.4 6233.3 6308.6 6324.9 6350.4 6351.2 6264.4 6290.0 6318.2 6319.5 6321.7 6127.1 6137.5 6163.5 6246.8 6300.5 6109.7 6111.0 6136.6 6143.2 6171.8 6092.9 6283.8 6291.1 6303.1 6303.2 5990.7 5996.1 6025.7 6088.5 6111.2 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#1] ext.html Cycles:180 Average:70035.05 Median:38691.03 stddev:158062.59 (408.5%) stddev-sans-first:158419.73 09:54:56 INFO - PROCESS | 2307 | Values: 482.7 503.2 508.6 517.2 535.0 30266.0 38325.0 39689.6 39929.4 41541.0 40161.0 40384.6 40713.0 40734.4 40742.4 7985.7 9232.0 9398.5 9419.0 9420.8 9186.3 9294.8 9295.1 9300.3 9311.5 9261.4 9335.1 9340.3 9346.0 9376.3 9225.6 9289.0 9289.8 9292.0 9293.7 9175.9 9184.5 9258.0 9259.6 9271.4 31101.7 31309.5 31328.8 31343.2 31352.5 2814.3 2943.4 3424.5 3469.2 3687.7 26997.3 28210.3 28968.9 29241.6 30793.7 3131.9 3151.6 3177.7 3260.7 3261.6 486137.0 486544.0 487875.0 487955.0 497160.0 47187.0 57786.1 57939.0 57991.7 58004.7 34161.7 34193.0 34661.3 35489.8 35622.5 15948.8 16092.8 16104.9 16114.3 16169.4 12220.1 12323.2 12347.4 12358.5 12361.4 18591.3 18593.6 18598.0 18629.6 18638.5 7552.2 7608.8 7694.9 7728.8 7733.5 835.4 934.5 940.1 1010.2 1037.5 28389.3 29138.7 29186.1 29561.9 29793.7 824.0 848.3 877.6 911.8 986.9 12711.2 12870.9 15089.3 15357.8 15391.9 670961.0 680963.0 681439.0 682009.0 682105.0 56036.4 56616.0 56688.7 56706.4 56708.0 1217.9 1247.6 1250.0 1251.6 1351.3 21239.4 25638.4 30019.5 30127.0 31497.8 52400.5 53108.0 53131.7 53148.0 53165.9 524359.0 596317.1 597894.0 601260.0 603167.0 25692.6 26074.6 34797.5 35581.5 35601.5 37053.6 38208.2 38832.2 47052.0 49145.0 34002.1 37754.6 38549.8 39685.8 40029.0 37919.4 38116.0 38129.6 39196.8 39642.4 37113.3 37299.4 37365.4 38023.2 39485.0 32272.8 32291.8 33453.5 33735.0 34505.5 25031.0 25740.4 25803.5 25952.5 26287.0 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#2] jquery.html Cycles:180 Average:34228.89 Median:347.97 stddev:101649.87 (29212.5%) stddev-sans-first:101903.15 09:54:56 INFO - PROCESS | 2307 | Values: 324.5 371.4 372.3 389.6 402.3 18618.7 19464.0 19763.2 20235.0 20435.6 20021.9 20041.4 20379.2 20418.9 20683.9 4675.0 5548.7 5838.0 5844.2 5860.6 5620.4 5777.0 5789.7 5804.9 5814.1 5444.4 5649.4 5703.3 5733.4 5745.5 5668.7 5721.7 5727.6 5736.1 5738.3 5036.2 5042.6 5309.3 5359.5 5429.0 12860.7 12984.3 13009.9 13160.7 13166.3 2108.7 2307.8 2361.7 2376.6 2458.7 9374.7 9547.6 10193.6 10233.9 10248.8 1701.8 1756.3 1800.1 1804.3 1872.7 109191.9 118563.0 119831.4 122232.4 124264.4 11246.3 11517.8 11607.0 11622.4 11720.3 14730.2 14797.1 14799.3 14801.3 14813.0 8023.0 8274.2 8300.7 8415.7 8417.8 8137.4 8161.3 8194.7 8327.0 8343.1 4220.7 4333.5 4535.2 4564.9 4621.3 1535.8 1714.3 1888.2 1904.8 1907.2 680.2 693.6 703.5 743.9 912.8 19809.3 20902.0 21078.7 21128.4 21269.9 828.0 913.3 929.9 951.6 996.4 7293.5 11846.9 12127.9 12193.3 12241.0 606136.0 606953.0 607735.0 620884.0 621141.0 81007.6 98410.0 100440.0 101865.5 125075.0 931.6 949.9 1001.2 1001.5 1003.1 15376.9 17812.8 18640.7 19123.2 19279.2 55147.7 55366.7 56724.0 56890.1 57198.0 71055.1 72889.6 75912.5 76770.2 78502.6 7089.1 7923.7 8037.9 8094.0 8243.9 10829.9 11848.3 12461.6 12680.5 13057.0 9046.3 10926.9 11233.9 11348.6 11385.7 10635.7 10748.1 11164.9 11165.6 11435.8 11147.8 11148.6 11173.7 11333.8 11639.5 7374.2 7490.8 7492.4 7545.8 7737.0 9353.4 9389.4 11004.3 11037.4 11079.1 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#3] mootools.html Cycles:180 Average:5365.99 Median:5050.89 stddev:2951.92 (58.4%) stddev-sans-first:2944.30 09:54:56 INFO - PROCESS | 2307 | Values: 1289.1 1311.7 1377.7 1493.3 1792.1 7492.4 7801.3 8017.8 8377.0 8384.0 8179.0 8282.8 8284.2 8288.5 8289.7 2300.9 2335.0 2340.6 2342.7 2342.7 2312.3 2354.8 2363.5 2363.6 2369.3 2293.3 2303.6 2331.4 2334.7 2335.5 2251.7 2272.8 2312.4 2313.1 2320.5 2277.6 2336.5 2338.2 2341.8 2342.7 8125.4 8264.3 8356.0 8358.2 8398.2 5047.4 5189.5 5192.7 5196.0 5201.6 3393.4 3515.8 3534.3 3589.6 3600.7 1052.2 1063.4 1072.8 1082.0 1089.5 10644.9 10748.7 10791.2 10801.7 10803.0 4103.4 4351.6 4427.8 4435.2 4451.4 8624.4 8625.8 8629.1 8642.4 8658.4 4365.5 5028.3 5054.3 5058.6 5065.8 4029.2 4239.2 4386.7 4422.9 4734.2 3658.4 3681.8 3732.3 3733.1 3739.2 1136.4 1137.6 1138.1 1142.8 1146.8 856.3 869.1 870.1 888.7 890.5 5634.9 5747.9 5879.7 5964.7 6099.1 870.7 886.4 892.9 898.0 903.7 3116.5 3697.6 3699.6 3841.7 3900.0 9422.3 9882.7 10102.7 10158.5 10192.0 9803.0 10130.3 10140.9 10153.6 10155.1 7717.1 7828.1 7883.8 7887.5 7908.8 4722.7 5362.1 5437.0 5535.4 5604.0 9230.5 9256.4 9310.1 9340.9 9465.1 9465.5 9501.1 9507.3 9579.3 9580.6 6661.3 6785.3 6790.7 6790.7 6796.5 7673.4 7678.8 7755.7 7761.6 7765.5 7048.1 7069.1 7135.0 7145.9 7178.5 6756.5 6818.0 6949.2 7014.3 7048.1 6508.9 6580.2 6647.6 6650.1 6656.1 4640.2 4700.1 4772.5 4800.3 4869.9 3971.5 4771.5 4799.6 4862.3 4868.3 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#4] prototype.html Cycles:180 Average:4282.17 Median:4654.33 stddev:1300.64 (27.9%) stddev-sans-first:1269.17 09:54:56 INFO - PROCESS | 2307 | Values: 282.2 299.5 303.4 307.0 311.9 4295.1 4631.3 4944.9 5034.4 5049.5 4349.5 4669.3 5087.5 5150.3 5174.8 3392.7 3651.1 3755.5 3946.8 4019.4 3280.9 3982.2 3988.5 4003.3 4020.0 3862.3 3920.5 4014.6 4042.4 4043.4 3957.2 3995.3 3998.8 4034.6 4040.5 2927.9 3268.8 3436.1 3472.5 3473.9 4232.4 4432.0 4641.0 4951.4 4951.5 4204.5 4529.5 4533.6 4596.6 4620.5 4292.8 4527.7 5382.2 5429.2 5441.5 3460.5 3712.2 3753.3 3784.9 3807.1 5416.1 5663.6 5671.7 5695.2 5722.4 3838.3 3843.3 3844.9 3846.6 3854.8 3210.6 3306.8 3732.4 3914.1 3970.1 3711.9 4079.7 4293.2 4359.4 4395.7 4569.1 4597.7 5502.8 5550.6 5582.4 1234.2 1234.4 1235.2 1257.6 1258.4 716.0 772.6 773.7 848.3 863.8 4448.8 4690.1 4985.7 4991.8 5010.5 4596.9 4920.1 5093.5 5249.6 5267.9 4250.8 4433.1 4557.8 4719.6 4986.4 2468.4 2526.3 2817.9 2826.4 2832.5 4851.7 5122.6 5243.0 5358.0 5562.0 4780.6 4862.4 5403.6 5405.7 5465.9 4639.9 4943.7 5282.9 5474.4 5589.3 4746.4 5257.7 5270.7 5277.5 5288.5 4359.4 4807.4 4880.3 5148.7 5198.0 4862.9 5466.9 5474.5 5475.5 5477.0 3173.6 3503.9 3625.8 3633.4 3655.7 4252.2 4486.3 4498.5 4667.7 5043.3 4998.5 5269.8 5461.4 5543.9 5561.4 4805.4 5252.8 5523.9 5547.5 5600.8 4537.0 4857.5 5104.4 5423.5 5446.5 5056.3 5473.4 5516.9 5537.6 5556.2 5443.5 5459.5 5470.9 5490.5 5587.8 09:54:56 INFO - PROCESS | 2307 | 09:54:56 INFO - PROCESS | 2307 | [#5] yui.html Cycles:180 Average:21216.31 Median:4511.31 stddev:31282.66 (693.4%) stddev-sans-first:31332.35 09:54:56 INFO - PROCESS | 2307 | Values: 661.9 678.0 697.8 753.4 787.1 15902.7 16582.8 16585.8 16586.6 16603.8 12191.7 14942.3 15064.2 15072.1 15076.1 636.7 657.2 796.9 821.1 822.7 658.0 785.1 804.9 813.2 820.2 862.7 866.8 867.7 867.9 868.2 655.3 859.8 864.6 865.3 865.4 503.7 620.1 635.4 643.0 644.2 14401.3 14443.8 14445.9 14447.6 14451.9 13141.5 13321.8 13468.5 13502.5 13536.8 11904.3 12061.6 12135.2 12340.0 12477.8 4415.9 4494.8 4496.7 4501.9 4578.1 89529.6 93242.1 93510.5 94673.0 97151.0 10136.8 10486.7 10503.9 10704.3 11105.3 20184.0 20570.9 20574.9 20590.4 20610.9 7372.7 7395.0 7435.7 7527.4 7642.2 3387.0 3960.9 3988.9 4026.2 4267.6 3304.0 3407.1 3409.3 3426.2 3427.7 628.9 637.9 659.1 692.6 699.2 580.7 651.7 656.5 664.3 669.4 8431.7 8984.9 9078.3 9343.0 9407.0 608.3 627.8 632.8 667.4 668.9 3599.8 4495.5 4520.8 4587.0 4614.4 118965.0 122053.0 124135.0 124477.4 128221.4 55097.7 71234.0 89898.0 95234.0 96971.5 70629.6 74009.6 83046.0 89421.6 91060.0 9242.8 9654.0 9742.7 9876.4 9968.4 49145.0 57188.0 60026.7 61701.1 62158.4 70756.6 85271.0 85460.6 89234.6 89392.0 12806.1 12999.1 13139.7 13148.1 13346.3 13184.5 13861.3 14340.5 14770.7 15162.2 13166.5 13227.5 13245.2 13778.4 13861.7 13248.4 13332.2 13349.2 13841.0 14019.9 12903.8 12947.8 13348.6 13406.1 13629.3 10856.2 11053.6 11369.7 11622.9 11686.5 10701.4 11382.1 11467.5 11606.0 11633.9 09:54:56 INFO - PROCESS | 2307 | -------- Summary: end -------- 09:54:56 INFO - PROCESS | 2307 | 09:54:57 INFO - TEST-INFO | 2307: exit 0 09:54:57 INFO - TEST-OK | dromaeo_css | took 1121636ms 09:54:57 INFO - TEST-START | kraken 09:54:57 INFO - operating with platform_type : mac_ 09:54:57 INFO - Initialising browser for kraken test... 09:54:57 INFO - TEST-INFO | started process 2310 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpy03WH2/profile http://localhost:53791/getInfo.html) 09:54:59 ERROR - PROCESS | 2310 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:54:59 INFO - PROCESS | 2310 | __metrics Screen width/height:1600/1200 09:54:59 INFO - PROCESS | 2310 | colorDepth:24 09:54:59 INFO - PROCESS | 2310 | Browser inner width/height: 1024/689 09:54:59 INFO - PROCESS | 2310 | __metrics 09:54:59 INFO - PROCESS | 2310 | 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" 09:54:59 INFO - PROCESS | 2310 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:55:00 INFO - TEST-INFO | 2310: exit 0 09:55:00 INFO - Browser initialized. 09:55:00 INFO - Running cycle 1/1 for kraken test... 09:55:00 INFO - TEST-INFO | started process 2311 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/T/tmpy03WH2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:55:12 INFO - PROCESS | 2311 | RSS: Main: 216977408 09:55:12 INFO - PROCESS | 2311 | 09:55:15 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53791/tests/kraken/driver.html?testName=audio-beat-detection) 09:55:16 INFO - PROCESS | 2311 | RSS: Main: 266813440 09:55:16 INFO - PROCESS | 2311 | 09:55:19 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53791/tests/kraken/driver.html?testName=audio-dft) 09:55:19 INFO - PROCESS | 2311 | RSS: Main: 278413312 09:55:19 INFO - PROCESS | 2311 | 09:55:22 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53791/tests/kraken/driver.html?testName=audio-fft) 09:55:23 INFO - PROCESS | 2311 | RSS: Main: 374112256 09:55:23 INFO - PROCESS | 2311 | 09:55:25 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53791/tests/kraken/driver.html?testName=audio-oscillator) 09:55:26 INFO - PROCESS | 2311 | RSS: Main: 277086208 09:55:26 INFO - PROCESS | 2311 | 09:55:28 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53791/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:55:28 INFO - PROCESS | 2311 | RSS: Main: 278679552 09:55:28 INFO - PROCESS | 2311 | 09:55:31 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53791/tests/kraken/driver.html?testName=imaging-darkroom) 09:55:32 INFO - PROCESS | 2311 | RSS: Main: 318734336 09:55:32 INFO - PROCESS | 2311 | 09:55:35 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53791/tests/kraken/driver.html?testName=imaging-desaturate) 09:55:35 INFO - PROCESS | 2311 | RSS: Main: 319365120 09:55:35 INFO - PROCESS | 2311 | 09:55:38 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53791/tests/kraken/driver.html?testName=json-parse-financial) 09:55:39 INFO - PROCESS | 2311 | RSS: Main: 319152128 09:55:39 INFO - PROCESS | 2311 | 09:55:40 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53791/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:55:41 INFO - PROCESS | 2311 | RSS: Main: 295927808 09:55:41 INFO - PROCESS | 2311 | 09:55:43 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:55:43 INFO - PROCESS | 2311 | RSS: Main: 296701952 09:55:43 INFO - PROCESS | 2311 | 09:55:45 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:55:46 INFO - PROCESS | 2311 | RSS: Main: 300130304 09:55:46 INFO - PROCESS | 2311 | 09:55:49 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:55:50 INFO - PROCESS | 2311 | RSS: Main: 316788736 09:55:50 INFO - PROCESS | 2311 | 09:55:53 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:55:53 INFO - PROCESS | 2311 | RSS: Main: 310784000 09:55:53 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | Cycle 1(1): loaded http://localhost:53791/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53791/tests/kraken/driver.html?testName=ai-astar) 09:55:55 INFO - PROCESS | 2311 | RSS: Main: 354885632 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | __start_tp_report 09:55:55 INFO - PROCESS | 2311 | _x_x_mozilla_page_load 09:55:55 INFO - PROCESS | 2311 | _x_x_mozilla_page_load_details 09:55:55 INFO - PROCESS | 2311 | |i|pagename|runs| 09:55:55 INFO - PROCESS | 2311 | |0;ai-astar;117;117;116;114;115;116;115;114;116;115 09:55:55 INFO - PROCESS | 2311 | |1;audio-beat-detection;149;109;110;146;114;111;97;133;172;138 09:55:55 INFO - PROCESS | 2311 | |2;audio-dft;184;182;185;188;182;183;183;184;182;182 09:55:55 INFO - PROCESS | 2311 | |3;audio-fft;81;59;59;96;62;58;59;59;82;59 09:55:55 INFO - PROCESS | 2311 | |4;audio-oscillator;90;69;96;69;68;69;89;68;69;93 09:55:55 INFO - PROCESS | 2311 | |5;imaging-gaussian-blur;88;85;88;87;88;85;88;87;87;85 09:55:55 INFO - PROCESS | 2311 | |6;imaging-darkroom;94;94;94;94;92;93;93;92;94;94 09:55:55 INFO - PROCESS | 2311 | |7;imaging-desaturate;82;81;82;79;80;81;80;78;124;131 09:55:55 INFO - PROCESS | 2311 | |8;json-parse-financial;58;56;53;53;54;56;57;57;57;57 09:55:55 INFO - PROCESS | 2311 | |9;json-stringify-tinderbox;65;77;69;77;73;71;70;72;70;72 09:55:55 INFO - PROCESS | 2311 | |10;stanford-crypto-aes;85;82;84;87;84;86;83;84;84;83 09:55:55 INFO - PROCESS | 2311 | |11;stanford-crypto-ccm;137;192;158;161;152;163;158;165;188;158 09:55:55 INFO - PROCESS | 2311 | |12;stanford-crypto-pbkdf2;168;170;168;168;168;167;170;165;169;167 09:55:55 INFO - PROCESS | 2311 | |13;stanford-crypto-sha256-iterative;66;69;66;67;65;66;66;66;66;66 09:55:55 INFO - PROCESS | 2311 | __end_tp_report 09:55:55 INFO - PROCESS | 2311 | __start_cc_report 09:55:55 INFO - PROCESS | 2311 | _x_x_mozilla_cycle_collect,2046 09:55:55 INFO - PROCESS | 2311 | __end_cc_report 09:55:55 INFO - PROCESS | 2311 | __startTimestamp1479146155845__endTimestamp 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | ------- Summary: start ------- 09:55:55 INFO - PROCESS | 2311 | Number of tests: 14 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#0] ai-astar Cycles:10 Average:115.50 Median:116.00 stddev:1.08 (0.9%) stddev-sans-first:1.00 09:55:55 INFO - PROCESS | 2311 | Values: 117.0 117.0 116.0 114.0 115.0 116.0 115.0 114.0 116.0 115.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:142.00 stddev:23.47 (16.5%) stddev-sans-first:23.62 09:55:55 INFO - PROCESS | 2311 | Values: 149.0 109.0 110.0 146.0 114.0 111.0 97.0 133.0 172.0 138.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#2] audio-dft Cycles:10 Average:183.50 Median:183.50 stddev:1.90 (1.0%) stddev-sans-first:2.01 09:55:55 INFO - PROCESS | 2311 | Values: 184.0 182.0 185.0 188.0 182.0 183.0 183.0 184.0 182.0 182.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#3] audio-fft Cycles:10 Average:67.40 Median:60.50 stddev:13.69 (22.6%) stddev-sans-first:13.61 09:55:55 INFO - PROCESS | 2311 | Values: 81.0 59.0 59.0 96.0 62.0 58.0 59.0 59.0 82.0 59.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.00 stddev:12.19 (15.4%) stddev-sans-first:12.13 09:55:55 INFO - PROCESS | 2311 | Values: 90.0 69.0 96.0 69.0 68.0 69.0 89.0 68.0 69.0 93.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#5] imaging-gaussian-blur Cycles:10 Average:86.80 Median:87.50 stddev:1.32 (1.5%) stddev-sans-first:1.32 09:55:55 INFO - PROCESS | 2311 | Values: 88.0 85.0 88.0 87.0 88.0 85.0 88.0 87.0 87.0 85.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:94.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 09:55:55 INFO - PROCESS | 2311 | Values: 94.0 94.0 94.0 94.0 92.0 93.0 93.0 92.0 94.0 94.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#7] imaging-desaturate Cycles:10 Average:89.80 Median:80.50 stddev:19.98 (24.8%) stddev-sans-first:20.99 09:55:55 INFO - PROCESS | 2311 | Values: 82.0 81.0 82.0 79.0 80.0 81.0 80.0 78.0 124.0 131.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:1.81 (3.2%) stddev-sans-first:1.74 09:55:55 INFO - PROCESS | 2311 | Values: 58.0 56.0 53.0 53.0 54.0 56.0 57.0 57.0 57.0 57.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#9] json-stringify-tinderbox Cycles:10 Average:71.60 Median:72.00 stddev:3.60 (5.0%) stddev-sans-first:2.92 09:55:55 INFO - PROCESS | 2311 | Values: 65.0 77.0 69.0 77.0 73.0 71.0 70.0 72.0 70.0 72.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.00 stddev:1.48 (1.8%) stddev-sans-first:1.54 09:55:55 INFO - PROCESS | 2311 | Values: 85.0 82.0 84.0 87.0 84.0 86.0 83.0 84.0 84.0 83.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#11] stanford-crypto-ccm Cycles:10 Average:163.20 Median:162.00 stddev:16.14 (10.0%) stddev-sans-first:14.07 09:55:55 INFO - PROCESS | 2311 | Values: 137.0 192.0 158.0 161.0 152.0 163.0 158.0 165.0 188.0 158.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.00 Median:168.00 stddev:1.49 (0.9%) stddev-sans-first:1.58 09:55:55 INFO - PROCESS | 2311 | Values: 168.0 170.0 168.0 168.0 168.0 167.0 170.0 165.0 169.0 167.0 09:55:55 INFO - PROCESS | 2311 | 09:55:55 INFO - PROCESS | 2311 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 09:55:55 INFO - PROCESS | 2311 | Values: 66.0 69.0 66.0 67.0 65.0 66.0 66.0 66.0 66.0 66.0 09:55:55 INFO - PROCESS | 2311 | -------- Summary: end -------- 09:55:55 INFO - PROCESS | 2311 | 09:55:56 INFO - TEST-INFO | 2311: exit 0 09:55:56 INFO - TEST-OK | kraken | took 59476ms 09:55:56 INFO - SUITE-END | took 1181s 09:55:57 INFO - Completed test suite (00:19:41) 09:55:57 INFO - javascript benchmark 09:55:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3338.799672019679, 3341.8329083545823, 3362.29391827716, 3366.5791776027995, 3402.370561446133, 5752.993226185411, 5817.360090390053, 5899.764009439623, 5949.553783466246, 5966.910496342558, 5879.235699359077, 5910.46805787479, 5973.581849270553, 5975.970120149394, 6006.909896351558, 5299.576033917287, 5330.866728331784, 5338.893222135555, 5392.595555333346, 5466.972665136669, 5289.788408463657, 5290.285811415448, 5299.735013249322, 5392.379876314215, 5442.727863606824, 5399.433059528752, 5404.945950540484, 5412.107002344608, 5423.132298832187, 5432.891342173154, 5370.91943620846, 5393.325834270716, 5396.10964190908, 5401.108817045183, 5402.756875940572, 5250.527452529291, 5349.090654588729, 5356.946430535722, 5367.5169234769055, 5395.757190926379, 5973.074173503114, 5978.641281523079, 5979.372165922559, 5983.1623572699855, 5984.042553191469, 6284.780032698834, 6285.277193122758, 6300.243970723497, 6311.558190926637, 6313.273973493016, 6278.434940855345, 6347.96826015867, 6348.428641422294, 6357.523185761041, 6360.173177486943, 5818.650880947157, 5870.618409803349, 5926.170336152899, 5940.168376427304, 5970.8655355482315, 6247.781327653511, 6322.74709011639, 6350.8412289692205, 6356.364363563699, 6360.936390636127, 6215.098810672517, 6227.9377206227355, 6240.968795155995, 6295.181626388632, 6406.071119687619, 6339.5562310637815, 6351.428371446592, 6356.491480681534, 6369.108324834527, 6373.980163173963, 6360.109584658059, 6364.0, 6366.236051673829, 6376.936230637727, 6384.648844313514, 5312.415634280226, 6231.158793562901, 6248.9062664060075, 6253.1245625612455, 6255.468285195719, 5016.54851063406, 5019.774110165052, 5031.295618613397, 5054.570361519239, 5064.240363945438, 4521.796519156645, 4523.36672865799, 4536.500984891659, 4551.06703125865, 4584.816607335736, 5834.3290521589715, 5861.736221870026, 5876.559258055578, 5946.613470124428, 6033.336333003366, 6273.592216505913, 6339.841503962438, 6354.586951848115, 6362.6500542469685, 6371.904421433682, 5861.70691465425, 6001.579889407787, 6011.368806275365, 6014.977453832859, 6038.516918646497, 4505.774711264489, 5468.207109969112, 5523.7790488380815, 5536.307961504812, 5539.612227144061, 6213.844653883689, 6308.400701933309, 6317.178766760292, 6352.936470635419, 6358.1734374531015, 6325.05124231369, 6325.525585581192, 6337.144485494399, 6348.650824204713, 6398.360163983564, 6325.146105275729, 6335.65153916539, 6393.073004414241, 6419.967900160469, 6423.229212494531, 6117.632942023492, 6135.04893764628, 6169.753209871555, 6244.313125556095, 6323.399277068671, 6233.252009758677, 6308.558400911892, 6324.936750632435, 6350.364963503797, 6351.2060992375955, 6264.404881536247, 6289.968550157403, 6318.178636777189, 6319.46284565817, 6321.683915804099, 6127.050307202391, 6137.499563105221, 6163.506919446523, 6246.750129994749, 6300.464460520907, 6109.677964501176, 6110.969445152924, 6136.6011209271255, 6143.204841796883, 6171.84570385744, 6092.9086063709965, 6283.7800676976085, 6291.056341548804, 6303.149074875017, 6303.212098487689, 5990.7004649768205, 5996.1005849122985, 6025.728842202199, 6088.51291896656, 6111.205543279345], "value": 5823.776603895481, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [482.74655805702025, 503.17479333892413, 508.6261597725671, 517.2112528394198, 535.0315928170013, 30266.0, 38325.04187395317, 39689.603103968984, 39929.40105898414, 41541.0, 40161.0, 40384.59615403845, 40713.0, 40734.38898416526, 40742.388864167064, 7985.72049978248, 9231.953840230806, 9398.530073496331, 9418.952905235465, 9420.81158376832, 9186.26509879208, 9294.814103717956, 9295.07049295072, 9300.302477314195, 9311.534423278843, 9261.44431334122, 9335.066493350645, 9340.252779777635, 9346.0, 9376.343655944087, 9225.630974761001, 9288.97821239663, 9289.767755806091, 9291.953540232256, 9293.674721384787, 9175.862362064574, 9184.540772961393, 9257.981622021573, 9259.583318750605, 9271.397359171633, 31101.66737494697, 31309.530357044663, 31328.843355783076, 31343.216419589466, 31352.529712054336, 2814.2964258935267, 2943.4260319237756, 3424.520567120605, 3469.2367679110807, 3687.7418580699364, 26997.325066873484, 28210.294742631395, 28968.855155724297, 29241.561376579368, 30793.692063079307, 3131.9217019574467, 3151.5745374374396, 3177.6663450337614, 3260.6902344277255, 3261.5596894419186, 486137.0, 486544.0, 487874.99999999645, 487955.0, 497160.0, 47187.0, 57786.13320800191, 57939.0, 57991.710041450366, 58004.70997645069, 34161.658383415845, 34193.0, 34661.30677386488, 35489.8225508876, 35622.46566301508, 15948.840511594968, 16092.75860862088, 16104.919475402548, 16114.274857631435, 16169.434069807501, 12220.1445898788, 12323.198992065487, 12347.444365003596, 12358.505659773688, 12361.443735031946, 18591.34930277434, 18593.628127437376, 18598.0, 18629.627407451775, 18638.53403664919, 7552.244775522404, 7608.771736847903, 7694.884576731353, 7728.806779830436, 7733.458657894006, 835.4444294544181, 934.4673536084366, 940.0646356874953, 1010.2372708604956, 1037.4501514197486, 28389.290267743057, 29138.708612914023, 29186.124416267547, 29561.85219073934, 29793.702062979526, 824.0358780227149, 848.3425345357274, 877.6138499060393, 911.7902882337099, 986.9193233409405, 12711.2373257603, 12870.9356453219, 15089.320980555902, 15357.846421535865, 15391.923040384727, 670961.0, 680963.0, 681439.0, 682009.0, 682105.0, 56036.439635603936, 56616.0, 56688.716556416955, 56706.43293567094, 56708.0, 1217.8903898648987, 1247.5882958623633, 1250.0437165568528, 1251.6182564317971, 1351.2635613590617, 21239.362819115453, 25638.359041024123, 30019.549706754853, 30127.0, 31497.8425107873, 52400.47599524108, 53108.0, 53131.73434132805, 53148.0, 53165.93668126693, 524359.0, 596317.055244163, 597894.0, 601260.0, 603167.0, 25692.614610780478, 26074.60888086718, 34797.47803782996, 35581.466278006366, 35601.46597800983, 37053.62946370609, 38208.235835283696, 38832.223355532165, 47052.0, 49145.0, 34002.14994625055, 37754.62245377511, 38549.8435046949, 39685.80942571728, 40029.0, 37919.43120853245, 38116.0, 38129.61870381261, 39196.80401597974, 39642.405363920145, 37113.257734844614, 37299.440508391854, 37365.4395184067, 38023.239535209694, 39485.0, 32272.83863580667, 32291.838540808087, 33453.49819752657, 33735.0, 34505.48241776426, 25031.0, 25740.356491087874, 25803.483930321665, 25952.480950381265, 26287.0], "value": 19075.10750302441, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.5051296772422, 371.4280008786468, 372.34839031694537, 389.57925440524235, 402.26586479674603, 18618.720719189223, 19464.0, 19763.209471621154, 20235.0, 20435.591288174226, 20021.899890500565, 20041.398758037245, 20379.184832606716, 20418.89790551049, 20683.896580517114, 4675.041616468633, 5548.667079975195, 5838.007538718428, 5844.152597873306, 5860.619059761123, 5620.353659329188, 5777.017906955828, 5789.681567513785, 5804.912926306088, 5814.098814683731, 5444.428335024825, 5649.350324712669, 5703.31560212775, 5733.426657334275, 5745.454181852718, 5668.716564171796, 5721.742521586539, 5727.599068065237, 5736.139579063153, 5738.340090889538, 5036.169032109734, 5042.647014708972, 5309.33633295838, 5359.517643412111, 5428.995635807389, 12860.678483037906, 12984.28586427746, 13009.869901301056, 13160.736785264242, 13166.341682915816, 2108.7153234313323, 2307.849989750661, 2361.728401233846, 2376.572217000939, 2458.7418321076207, 9374.671886484008, 9547.61809527618, 10193.59225630974, 10233.9488302558, 10248.846267305997, 1701.7617533545313, 1756.2711474737969, 1800.1449311577028, 1804.2782886845237, 1872.7284543741218, 109191.90808091896, 118563.0, 119831.4008429961, 122232.38883805524, 124264.37867810694, 11246.268992515461, 11517.827232591519, 11607.0, 11622.360770157553, 11720.304330003977, 14730.189839558927, 14797.112173269637, 14799.334029968677, 14801.333939972727, 14813.037152585048, 8022.9598852005365, 8274.172582741798, 8300.70947516834, 8415.747527574182, 8417.831643367099, 8137.430379873471, 8161.306288965386, 8194.672213111528, 8327.042390125092, 8343.082260951413, 4220.712682631765, 4333.544977777351, 4535.160995215896, 4564.88587785308, 4621.336318925184, 1535.7696345548034, 1714.2542993797738, 1888.2447021191633, 1904.828565429118, 1907.1799126375793, 680.2109552918689, 693.5838496901819, 703.5040296590892, 743.85494828509, 912.762681702762, 19809.30667426662, 20901.954902254827, 21078.683819742717, 21128.366149015554, 21269.89365053165, 827.9733130917618, 913.2693844924034, 929.8651695504115, 951.5717926932908, 996.3623281100101, 7293.52592081513, 11846.940765296118, 12127.939360303142, 12193.268403895618, 12241.0, 606136.0, 606953.0, 607735.0, 620884.0, 621141.0, 81007.59496202481, 98410.0, 100439.99560004452, 101865.49067254616, 125075.0, 931.6366617019368, 949.924006079512, 1001.1940387987739, 1001.4842356186715, 1003.1222680154865, 15376.923115384352, 17812.821871781118, 18640.720389194445, 19123.23507059702, 19279.22883084661, 55147.72426137843, 55366.72316638391, 56724.0, 56890.14664779949, 57198.0, 71055.09196994008, 72889.6355518219, 75912.48175036578, 76770.23229767631, 78502.6074869622, 7089.113860767404, 7923.7226697065325, 8037.879431808411, 8094.0, 8243.93901524619, 10829.891701083203, 11848.348340840992, 12461.626151215478, 12680.492780288685, 13057.0, 9046.326429610543, 10926.890731092906, 11233.943830280796, 11348.602798901999, 11385.658430247106, 10635.680929572125, 10748.086412655011, 11164.888351116384, 11165.575714100778, 11435.828462573234, 11147.777044458902, 11148.609798657008, 11173.720656983316, 11333.773324533298, 11639.47622356979, 7374.188839227573, 7490.838919967414, 7492.363149132167, 7545.773626790986, 7736.961315193388, 9353.392029518332, 9389.38968967015, 11004.339739615649, 11037.392943388191, 11079.058280046287], "value": 9116.61344151248, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1289.1427200911394, 1311.658968668146, 1377.7451171533264, 1493.3205391546846, 1792.0860361215784, 7492.400607951365, 7801.336886364658, 8017.759467215986, 8376.958115209416, 8384.0, 8178.959105204465, 8282.792930176749, 8284.171582841727, 8288.544130072843, 8289.709860154895, 2300.896459659315, 2334.9533009339802, 2340.578695834749, 2342.6603142544323, 2342.7071616047992, 2312.271634435156, 2354.75275096115, 2363.4918492524102, 2363.621820508719, 2369.3484291819714, 2293.3005433342905, 2303.6199027160496, 2331.4287999440076, 2334.7315058768204, 2335.532893421314, 2251.6509940959095, 2272.784085511874, 2312.4218945263683, 2313.097891822182, 2320.4894923116885, 2277.6355783074796, 2336.4626135988647, 2338.181636427254, 2341.7658234176615, 2342.6720259163562, 8125.390595705346, 8264.338852891755, 8356.0, 8358.164183581594, 8398.244158025745, 5047.4447810740785, 5189.506996835295, 5192.662476938987, 5195.974020129913, 5201.5838732901275, 3393.440082386428, 3515.806630635314, 3534.3461459630053, 3589.587197472298, 3600.6579374959597, 1052.163529993657, 1063.3716008495064, 1072.7908057928707, 1081.9729506762394, 1089.477051015517, 10644.946775266151, 10748.677539673823, 10791.1906607004, 10801.67594972152, 10803.027727504485, 4103.4050062740735, 4351.586599273064, 4427.800748966304, 4435.157320109169, 4451.421315229064, 8624.396292259482, 8625.78435539104, 8629.13708629132, 8642.395032347675, 8658.39391242607, 4365.541618130051, 5028.270900719448, 5054.342935418372, 5058.570021548136, 5065.772040258197, 4029.234445455354, 4239.236937351245, 4386.670999674973, 4422.889427764331, 4734.242521196592, 3658.432942893856, 3681.7790932544135, 3732.290864735746, 3733.085394078468, 3739.2147648993473, 1136.420425582942, 1137.556353022322, 1138.08384250679, 1142.7543078238218, 1146.7821113988482, 856.2507805670039, 869.1048220333067, 870.1211776106052, 888.6800751729372, 890.5057692980458, 5634.915476267777, 5747.942520574741, 5879.735411906389, 5964.701764911737, 6099.115628233882, 870.6517393042834, 886.3618194899661, 892.9151730585584, 898.0, 903.7379160043514, 3116.5169398743233, 3697.574778900461, 3699.5560532735703, 3841.673457756066, 3899.980500097481, 9422.293328000427, 9882.65410710636, 10102.747431314128, 10158.542865571068, 10192.0, 9803.0, 10130.290879638502, 10140.898591014144, 10153.64462243818, 10155.136813370798, 7717.112532058773, 7828.060632724109, 7883.802904927308, 7887.526748395115, 7908.802279942933, 4722.716637001791, 5362.1420572708175, 5437.0, 5535.418781027934, 5604.047311956896, 9230.492322922035, 9256.443905217133, 9310.115539023782, 9340.859887101564, 9465.100815422524, 9465.526723663927, 9501.144896959308, 9507.286953478379, 9579.323618366898, 9580.56887440053, 6661.267260601233, 6785.321467853175, 6790.694418751069, 6790.694418751069, 6796.4902632303765, 7673.386129109546, 7678.846423071395, 7755.650995705094, 7761.573113478813, 7765.53406795594, 7048.118985126859, 7069.116360454943, 7135.0, 7145.9285407147345, 7178.533395329496, 6756.527043106936, 6818.045473633596, 6949.18878066007, 7014.333638304353, 7048.118985126859, 6508.9349106508325, 6580.210374754868, 6647.567908086153, 6650.068990341453, 6656.134702488645, 4640.211164102043, 4700.130475861976, 4772.5227477252, 4800.255960326155, 4869.853904382874, 3971.543272523697, 4771.498992605865, 4799.592034676952, 4862.319275301531, 4868.269759536098], "value": 4342.8929753269795, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.1605722974151, 299.46993820936945, 303.3856440707567, 306.9969300306996, 311.86901501369425, 4295.140971805644, 4631.309572006218, 4944.876378090545, 5034.370703662047, 5049.545540901318, 4349.543297953716, 4669.346291519181, 5087.465816089303, 5150.3047088643025, 5174.793008279665, 3392.694657480826, 3651.1298024094544, 3755.493008443866, 3946.822392992315, 4019.437278780973, 3280.8687652493873, 3982.243373758991, 3988.4814974053334, 4003.299422601048, 4019.9799001004953, 3862.3114925246464, 3920.4511368408444, 4014.5985401459766, 4042.3734321180264, 4043.433919251307, 3957.248122856648, 3995.3407687731487, 3998.8200530975887, 4034.5763694812063, 4040.525208298979, 2927.8975235866856, 3268.8038717677014, 3436.055084851686, 3472.5485686860798, 3473.878414255489, 4232.386303985935, 4431.955680443219, 4640.976795116037, 4951.3810773653295, 4951.480094590088, 4204.453421055274, 4529.456465224162, 4533.569310915458, 4596.621013695912, 4620.491745907947, 4292.849750258726, 4527.70960276323, 5382.219578161144, 5429.239906413105, 5441.537469315113, 3460.52927505809, 3712.2204337089242, 3753.3244016077097, 3784.9053773655605, 3807.1243613968663, 5416.133418653036, 5663.582405723542, 5671.695510032716, 5695.231143795576, 5722.396309679086, 3838.347480928249, 3843.26977874203, 3844.8654297099465, 3846.61521852133, 3854.845806167778, 3210.5665735125926, 3306.818125003148, 3732.384156614128, 3914.1212218135456, 3970.1067259866877, 3711.888643340704, 4079.6940229482316, 4293.248681480728, 4359.38968544404, 4395.714228601173, 4569.063342014879, 4597.747123908217, 5502.779888804403, 5550.64097758451, 5582.358028826656, 1234.234774439835, 1234.4074844074894, 1235.178606226849, 1257.5787111317627, 1258.4337048328289, 715.9606221657859, 772.6059709548058, 773.6943907156642, 848.3467729847982, 863.7581477186397, 4448.844290449883, 4690.085433340466, 4985.731492171053, 4991.775370108354, 5010.549050585392, 4596.88507787308, 4920.138975679241, 5093.541581257705, 5249.6088536537845, 5267.947320526822, 4250.808713607895, 4433.091216300651, 4557.794899229542, 4719.551642593948, 4986.376702912136, 2468.407582180264, 2526.280010197085, 2817.9295517611813, 2826.3781967967016, 2832.546792513188, 4851.671197824993, 5122.641415100908, 5242.97378513105, 5357.973210133924, 5562.023153445306, 4780.569748722632, 4862.3922009748785, 5403.621746477709, 5405.702686352289, 5465.890682186414, 4639.916873151605, 4943.65394422387, 5282.917002014655, 5474.385056408369, 5589.301337332833, 4746.359241502491, 5257.658252213594, 5270.657407268515, 5277.472252774692, 5288.471152884835, 4359.367891655629, 4807.375041244755, 4880.31675565414, 5148.712821794551, 5198.0, 4862.927056094232, 5466.945330546802, 5474.534664553398, 5475.534579560782, 5476.98765678883, 3173.6032995875516, 3503.8948831535095, 3625.782453052825, 3633.4368172933237, 3655.7440979131206, 4252.170826688766, 4486.2597671385165, 4498.54829118138, 4667.673262871566, 5043.293938848491, 4998.500149985118, 5269.8419047427105, 5461.42276808691, 5543.895418565616, 5561.386148510193, 4805.351277577623, 5252.789888404421, 5523.8895222094525, 5547.472990065938, 5600.803971860965, 4536.977315113403, 4857.53853383928, 5104.438511763778, 5423.511883930465, 5446.526241127642, 5056.266841307916, 5473.429686212057, 5516.862078448071, 5537.612367134262, 5556.222128901876, 5443.482869127427, 5459.535939445032, 5470.890582188414, 5490.478404551562, 5587.804426845203], "value": 3866.6556076796205, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.9271880093189, 677.9965650836763, 697.8464737757691, 753.4424525850868, 787.094840932927, 15902.681946361081, 16582.83417165828, 16585.75121373179, 16586.58533536659, 16603.75094373584, 12191.695207619814, 14942.252887355644, 15064.171470569114, 15072.095674259552, 15076.095434273924, 636.7453018792473, 657.1850904877941, 796.9043714754238, 821.0967935271213, 822.6914906909909, 657.9934200658004, 785.0892964161574, 804.8873157757919, 813.2396209544065, 820.1880138662741, 862.659249596409, 866.8483015472299, 867.6789587852503, 867.8611422172454, 868.179570306061, 655.3053763011176, 859.7936495241129, 864.5547543015352, 865.346663269234, 865.4028720183054, 503.69023050823847, 620.1132380695603, 635.3582881289884, 642.9678516074177, 644.165805282156, 14401.279936003159, 14443.78334324986, 14445.855541444556, 14447.638809029753, 14451.855481445156, 13141.474341026347, 13321.800172997413, 13468.528601498998, 13502.459901603925, 13536.796948045787, 11904.345261010641, 12061.577844775391, 12135.211211271238, 12340.0, 12477.75044499105, 4415.889602759957, 4494.763939916516, 4496.730196188207, 4501.864944051684, 4578.06149739306, 89529.55235223848, 93242.13515729648, 93510.532447338, 94673.0, 97151.02848971561, 10136.847947280796, 10486.685399438029, 10503.94748026255, 10704.30422022566, 11105.278156919774, 20184.0, 20570.897145514176, 20574.897125514275, 20590.382288531368, 20610.89694551518, 7372.705091796375, 7395.038644976119, 7435.739749108755, 7527.360174385239, 7642.19756925526, 3386.9661303387143, 3960.861369852099, 3988.9202215956097, 4026.1947610478023, 4267.615914567704, 3304.0253125328086, 3407.0800883761244, 3409.284050349405, 3426.229098452828, 3427.725781937439, 628.896232121704, 637.9330170332048, 659.0608383054129, 692.5706062241443, 699.1889408286363, 580.7386675995818, 651.6851706959546, 656.5469825820169, 664.2560332427712, 669.4343279928453, 8431.704890328809, 8984.8652270216, 9078.280683169562, 9342.953285233667, 9407.0, 608.2791891608458, 627.847899105138, 632.7503181217152, 667.429347907532, 668.8862893308052, 3599.8380072896784, 4495.527969563214, 4520.751358675305, 4587.0, 4614.423197100363, 118965.0, 122053.0, 124135.0, 124477.37761311136, 128221.35889320681, 55097.724511377986, 71234.0, 89898.0, 95234.0, 96971.51514242384, 70629.64685176645, 74009.62995185098, 83046.0, 89421.55289223512, 91060.0, 9242.815143696955, 9653.999542511701, 9742.681009309237, 9876.408560677772, 9968.40189588627, 49145.0, 57188.0, 60026.699866500385, 61701.074483883676, 62158.37841621707, 70756.64621676858, 85271.0, 85460.57269713611, 89234.55382723045, 89392.0, 12806.103572749818, 12999.09006369545, 13139.671508212372, 13148.145370550883, 13346.265955372548, 13184.538541151014, 13861.306934653428, 14340.498082567061, 14770.704585908437, 15162.24188790578, 13166.539171128965, 13227.537036203687, 13245.20528768277, 13778.448862045407, 13861.653458663613, 13248.403821827847, 13332.200067995951, 13349.199048057148, 13841.0, 14019.859801401855, 12903.806442903551, 12947.805782913452, 13348.599542013755, 13406.128601640861, 13629.250391228577, 10856.185786065918, 11053.557857685602, 11369.658910232707, 11622.94188529052, 11686.474108664961, 10701.411422371844, 11382.073184751247, 11467.483963221508, 11606.013488853205, 11633.88366116328], "value": 7430.592978789435, "unit": "score"}], "name": "dromaeo_css", "value": 7084.0541374240975, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 114.0, 115.0, 116.0, 115.0, 114.0, 116.0, 115.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 109.0, 110.0, 146.0, 114.0, 111.0, 97.0, 133.0, 172.0, 138.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 185.0, 188.0, 182.0, 183.0, 183.0, 184.0, 182.0, 182.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 59.0, 96.0, 62.0, 58.0, 59.0, 59.0, 82.0, 59.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 69.0, 96.0, 69.0, 68.0, 69.0, 89.0, 68.0, 69.0, 93.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 87.0, 88.0, 85.0, 88.0, 87.0, 87.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 94.0, 92.0, 93.0, 93.0, 92.0, 94.0, 94.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 82.0, 79.0, 80.0, 81.0, 80.0, 78.0, 124.0, 131.0], "value": 89.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 53.0, 53.0, 54.0, 56.0, 57.0, 57.0, 57.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 69.0, 77.0, 73.0, 71.0, 70.0, 72.0, 70.0, 72.0], "value": 71.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 84.0, 87.0, 84.0, 86.0, 83.0, 84.0, 84.0, 83.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 192.0, 158.0, 161.0, 152.0, 163.0, 158.0, 165.0, 188.0, 158.0], "value": 163.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 168.0, 168.0, 168.0, 167.0, 170.0, 165.0, 169.0, 167.0], "value": 168.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 66.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1451.3999999999999, "alertThreshold": 2.0}]} 09:55:57 INFO - Return code: 0 09:55:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:55:57 ERROR - # TBPL SUCCESS # 09:55:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:55:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:55:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:55:57 INFO - 2016-11-14 09:55:57.199 screenresolution[2315:291937] starting screenresolution argv=screenresolution get 09:55:57 INFO - 2016-11-14 09:55:57.202 screenresolution[2315:291937] Display 0: 1600x1200x32@60 09:55:57 INFO - 2016-11-14 09:55:57.209 screenresolution[2316:291940] starting screenresolution argv=screenresolution list 09:55:57 INFO - Available Modes on Display 0 09:55:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:55:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:55:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:55:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:55:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:55:57 INFO - Intel Iris: 09:55:57 INFO - Chipset Model: Intel Iris 09:55:57 INFO - Type: GPU 09:55:57 INFO - Bus: Built-In 09:55:57 INFO - VRAM (Dynamic, Max): 1536 MB 09:55:57 INFO - Vendor: Intel (0x8086) 09:55:57 INFO - Device ID: 0x0a2e 09:55:57 INFO - Revision ID: 0x0009 09:55:57 INFO - Displays: 09:55:57 INFO - Display: 09:55:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:55:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:55:57 INFO - Display Serial Number: 1600x1200 60 09:55:57 INFO - Main Display: Yes 09:55:57 INFO - Mirror: Off 09:55:57 INFO - Online: Yes 09:55:57 INFO - Rotation: Supported 09:55:57 INFO - Return code: 0 09:55:57 INFO - Running post-action listener: _resource_record_post_action 09:55:57 INFO - [mozharness: 2016-11-14 17:55:57.447097Z] Finished run-tests step (success) 09:55:57 INFO - Running post-run listener: _resource_record_post_run 09:55:57 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 6943232; Write bytes: 653520896; Read time: 56; Write time: 1919 09:55:57 INFO - TinderboxPrint: CPU usage
53.4% 09:55:57 INFO - TinderboxPrint: I/O read bytes / time
6,943,232 / 56 09:55:57 INFO - TinderboxPrint: I/O write bytes / time
653,520,896 / 1,919 09:55:57 INFO - TinderboxPrint: CPU idle
2,263.1 (46.6%) 09:55:57 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 09:55:57 INFO - TinderboxPrint: CPU user
1,620.6 (33.4%) 09:55:57 INFO - TinderboxPrint: Swap in / out
367,435,776 / 0 09:55:57 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346137088; Write bytes: 346404352; Read time: 27092; Write time: 699 09:55:57 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 6943232; Write bytes: 296229376; Read time: 56; Write time: 1186 09:55:57 INFO - Running post-run listener: _upload_blobber_files 09:55:57 INFO - Blob upload gear active. 09:55:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:55:57 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 09:55:57 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'] 09:55:57 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 09:55:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:55:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:55:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:55:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:55:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:55:59 INFO - (blobuploader) - INFO - Done attempting. 09:55:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:55:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:55:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:56:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:00 INFO - (blobuploader) - INFO - Done attempting. 09:56:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:56:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:56:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:56:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:56:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:56:03 INFO - (blobuploader) - INFO - Done attempting. 09:56:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:56:03 INFO - Return code: 0 09:56:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:56:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:56:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec777a07329b24f5544b442a1c66d13c7e48ab1d1bfa2914302fe6c79e0296e00d51b0dc4efc005bfed75dc4038dbfdb5e66016059fc8c02a4590bdd79ef1c13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb479e362e80ca5ca08131df898078cbb7bd5c7941a744ad1e4c181b624432d9fa5d504b073ea79e22b3b27c05ed335e0e271f2ed5cab736a69253affb78ba2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cd62ca1b87630681e14d3a7402a1f85a6180dfeb74cd8b9688f4045f2f21069a91667dd68db6f292a3a0276d3f35ccbed1358fbd038e6a9c6f73b9c15564500"} 09:56:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:56:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:56:03 INFO - Contents: 09:56:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ec777a07329b24f5544b442a1c66d13c7e48ab1d1bfa2914302fe6c79e0296e00d51b0dc4efc005bfed75dc4038dbfdb5e66016059fc8c02a4590bdd79ef1c13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb479e362e80ca5ca08131df898078cbb7bd5c7941a744ad1e4c181b624432d9fa5d504b073ea79e22b3b27c05ed335e0e271f2ed5cab736a69253affb78ba2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cd62ca1b87630681e14d3a7402a1f85a6180dfeb74cd8b9688f4045f2f21069a91667dd68db6f292a3a0276d3f35ccbed1358fbd038e6a9c6f73b9c15564500"} 09:56:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:56:03 INFO - Copying logs to upload dir... 09:56:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:56:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.598232 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-14 09:56:03.181990) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 09:56:03.189607) ========= 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.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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/ec777a07329b24f5544b442a1c66d13c7e48ab1d1bfa2914302fe6c79e0296e00d51b0dc4efc005bfed75dc4038dbfdb5e66016059fc8c02a4590bdd79ef1c13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb479e362e80ca5ca08131df898078cbb7bd5c7941a744ad1e4c181b624432d9fa5d504b073ea79e22b3b27c05ed335e0e271f2ed5cab736a69253affb78ba2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cd62ca1b87630681e14d3a7402a1f85a6180dfeb74cd8b9688f4045f2f21069a91667dd68db6f292a3a0276d3f35ccbed1358fbd038e6a9c6f73b9c15564500"} build_url:https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011983 build_url: 'https://queue.taskcluster.net/v1/task/f9CiBrZQQmyzGMq46Oc_Jg/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/ec777a07329b24f5544b442a1c66d13c7e48ab1d1bfa2914302fe6c79e0296e00d51b0dc4efc005bfed75dc4038dbfdb5e66016059fc8c02a4590bdd79ef1c13", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cb479e362e80ca5ca08131df898078cbb7bd5c7941a744ad1e4c181b624432d9fa5d504b073ea79e22b3b27c05ed335e0e271f2ed5cab736a69253affb78ba2c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6cd62ca1b87630681e14d3a7402a1f85a6180dfeb74cd8b9688f4045f2f21069a91667dd68db6f292a3a0276d3f35ccbed1358fbd038e6a9c6f73b9c15564500"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-14 09:56:03.225766) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:56:03.226064) ========= 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.AmbMiU44mj/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.t1sn2zJcIl/Listeners TMPDIR=/var/folders/7r/ywr7vtgn4tj6q7dzmd3w9_0h00000w/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.005283 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-14 09:56:03.274629) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 09:56:03.274916) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-14 09:56:03.278246) ========= ========= Total master_lag: 0.20 =========