builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0180 starttime: 1483980525.14 results: success (0) buildid: 20170109063808 builduid: a7a9b49f365d4acaa2c116a54b2d1900 revision: bb6a56511146b31c57a2a3c792ccd464891b0593 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.140849) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.141314) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.141591) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.167965) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.168284) ========= 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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-09 08:48:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2017-01-09 08:48:45 (14.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.474573 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.686174) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.686507) ========= 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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.025464 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.734950) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:45.735325) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev bb6a56511146b31c57a2a3c792ccd464891b0593 --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 bb6a56511146b31c57a2a3c792ccd464891b0593 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-09 08:48:45,827 truncating revision to first 12 chars 2017-01-09 08:48:45,827 Setting DEBUG logging. 2017-01-09 08:48:45,827 attempt 1/10 2017-01-09 08:48:45,827 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/bb6a56511146?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-09 08:48:46,469 unpacking tar archive at: mozilla-aurora-bb6a56511146/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911579 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.732250) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.732544) ========= script_repo_revision: bb6a56511146b31c57a2a3c792ccd464891b0593 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.732933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.733211) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-09 08:48:46.760435) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-09 08:48:46.760780) ========= /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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:48:46 INFO - MultiFileLogger online at 20170109 08:48:46 in /builds/slave/test 08:48:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:48:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:48:46 INFO - {'append_to_log': False, 08:48:46 INFO - 'base_work_dir': '/builds/slave/test', 08:48:46 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:48:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:48:46 INFO - 'branch': 'Mozilla-Aurora', 08:48:46 INFO - 'buildbot_json_path': 'buildprops.json', 08:48:46 INFO - 'config_files': ('talos/mac_config.py',), 08:48:46 INFO - 'default_actions': ('clobber', 08:48:46 INFO - 'read-buildbot-config', 08:48:46 INFO - 'download-and-extract', 08:48:46 INFO - 'populate-webroot', 08:48:46 INFO - 'create-virtualenv', 08:48:46 INFO - 'install', 08:48:46 INFO - 'run-tests'), 08:48:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:48:46 INFO - 'download_minidump_stackwalk': True, 08:48:46 INFO - 'download_symbols': 'ondemand', 08:48:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:48:46 INFO - 'tooltool.py': '/tools/tooltool.py', 08:48:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:48:46 INFO - '/tools/misc-python/virtualenv.py')}, 08:48:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:48:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:48:46 INFO - 'installer_path': 'installer.exe', 08:48:46 INFO - 'log_level': 'info', 08:48:46 INFO - 'log_name': 'talos', 08:48:46 INFO - 'log_to_console': True, 08:48:46 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:48:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:48:46 INFO - 'opt_config_files': (), 08:48:46 INFO - 'pip_index': False, 08:48:46 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:46 INFO - 'cmd': ('bash', 08:48:46 INFO - '-c', 08:48:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:46 INFO - 'enabled': True, 08:48:46 INFO - 'halt_on_failure': False, 08:48:46 INFO - 'name': 'check_screen_resolution'},), 08:48:46 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:48:46 INFO - 'cmd': ('bash', 08:48:46 INFO - '-c', 08:48:46 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:48:46 INFO - 'enabled': True, 08:48:46 INFO - 'halt_on_failure': False, 08:48:46 INFO - 'name': 'check_screen_resolution'},), 08:48:46 INFO - 'run_cmd_checks_enabled': True, 08:48:46 INFO - 'sps_profile': False, 08:48:46 INFO - 'sps_profile_interval': 0, 08:48:46 INFO - 'suite': 'dromaeojs', 08:48:46 INFO - 'system_bits': '32', 08:48:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:48:46 INFO - 'title': 't-yosemite-r7-0180', 08:48:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:48:46 INFO - 'use_talos_json': True, 08:48:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:48:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:48:46 INFO - 'work_dir': 'build'} 08:48:46 INFO - [mozharness: 2017-01-09 16:48:46.963056Z] Running clobber step. 08:48:46 INFO - Running pre-action listener: _resource_record_pre_action 08:48:46 INFO - Running main action method: clobber 08:48:46 INFO - rmtree: /builds/slave/test/build 08:48:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:48:49 INFO - Running post-action listener: _resource_record_post_action 08:48:49 INFO - [mozharness: 2017-01-09 16:48:49.374216Z] Finished clobber step (success) 08:48:49 INFO - [mozharness: 2017-01-09 16:48:49.374379Z] Running read-buildbot-config step. 08:48:49 INFO - Running pre-action listener: _resource_record_pre_action 08:48:49 INFO - Running main action method: read_buildbot_config 08:48:49 INFO - Using buildbot properties: 08:48:49 INFO - { 08:48:49 INFO - "project": "", 08:48:49 INFO - "product": "firefox", 08:48:49 INFO - "script_repo_revision": "production", 08:48:49 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:48:49 INFO - "repository": "", 08:48:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:48:49 INFO - "buildid": "20170109063808", 08:48:49 INFO - "pgo_build": "False", 08:48:49 INFO - "basedir": "/builds/slave/test", 08:48:49 INFO - "buildnumber": 57, 08:48:49 INFO - "slavename": "t-yosemite-r7-0180", 08:48:49 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 08:48:49 INFO - "platform": "yosemite_r7", 08:48:49 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:48:49 INFO - "branch": "mozilla-aurora", 08:48:49 INFO - "repo_path": "releases/mozilla-aurora", 08:48:49 INFO - "revision": "bb6a56511146b31c57a2a3c792ccd464891b0593", 08:48:49 INFO - "stage_platform": "macosx64", 08:48:49 INFO - "builduid": "a7a9b49f365d4acaa2c116a54b2d1900", 08:48:49 INFO - "slavebuilddir": "test" 08:48:49 INFO - } 08:48:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:48:49 INFO - Running post-action listener: _resource_record_post_action 08:48:49 INFO - [mozharness: 2017-01-09 16:48:49.377732Z] Finished read-buildbot-config step (success) 08:48:49 INFO - [mozharness: 2017-01-09 16:48:49.377863Z] Running download-and-extract step. 08:48:49 INFO - Running pre-action listener: _resource_record_pre_action 08:48:49 INFO - Running main action method: download_and_extract 08:48:49 INFO - mkdir: /builds/slave/test/build/tests 08:48:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:49 INFO - trying https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:48:50 INFO - Downloaded 1199 bytes. 08:48:50 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:48:50 INFO - Using the following test package requirements: 08:48:50 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:48:50 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:48:50 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:48:50 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'jsshell-mac.zip'], 08:48:50 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:48:50 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:48:50 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:48:50 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:48:50 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:48:50 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:48:50 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:48:50 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:48:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:48:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:48:51 INFO - Http code: 200 08:48:51 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 08:48:51 INFO - x-amz-cf-id: btn3lXWV8HvAkuEawQdrjQNqY1KXCArYm3LVBxPsYslOZrG3o5UHrw== 08:48:51 INFO - x-amz-version-id: NEu8X23VXLeR.nPTViJJCWOk2LfHj.EO 08:48:51 INFO - Expected file size: 16270978 08:48:51 INFO - Obtained file size: 16270978 08:48:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:48:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:48:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:58 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:48:58 INFO - Http code: 200 08:48:58 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 08:48:58 INFO - x-amz-cf-id: lyYVJNeus9JTV3tB84pW-IHZg3Owu8HqnTor3YmYPW5ZYhSJdoiZHg== 08:48:58 INFO - x-amz-version-id: NEu8X23VXLeR.nPTViJJCWOk2LfHj.EO 08:48:59 INFO - Expected file size: 16270978 08:48:59 INFO - Obtained file size: 16270978 08:49:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:49:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:49:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:49:05 INFO - Http code: 200 08:49:05 INFO - via: 1.1 c54d7f08e2f3dab1918454910cc8aad0.cloudfront.net (CloudFront) 08:49:05 INFO - x-amz-cf-id: 5UFuQagF-KqsOz8lbZNpw5kFuym5Ai8mg1AJh1yMWkNlIyM4e-fITg== 08:49:05 INFO - x-amz-version-id: HNzXtXxd.nDsejlPuDYaAqmDiAN.zIkZ 08:49:06 INFO - Expected file size: 14409038 08:49:06 INFO - Obtained file size: 14409038 08:49:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:06 INFO - trying https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:06 INFO - Downloading https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:49:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:49:09 INFO - Downloaded 109877319 bytes. 08:49:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:09 INFO - mkdir: /builds/slave/test/properties 08:49:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:49:09 INFO - Writing to file /builds/slave/test/properties/build_url 08:49:09 INFO - Contents: 08:49:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:49:10 INFO - Running post-action listener: _resource_record_post_action 08:49:10 INFO - Running post-action listener: set_extra_try_arguments 08:49:10 INFO - [mozharness: 2017-01-09 16:49:10.429458Z] Finished download-and-extract step (success) 08:49:10 INFO - [mozharness: 2017-01-09 16:49:10.429581Z] Running populate-webroot step. 08:49:10 INFO - Running pre-action listener: _resource_record_pre_action 08:49:10 INFO - Running main action method: populate_webroot 08:49:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:49:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:49:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:49:10 INFO - '--tppagecycles', 08:49:10 INFO - '7'], 08:49:10 INFO - 'tests': ['tsvgm']}}, 08:49:10 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:49:10 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:49:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:49:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:49:10 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:10 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:49:10 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['damp', 'tps']}, 08:49:10 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'tests': ['damp', 'tps']}, 08:49:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['dromaeo_dom']}, 08:49:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:49:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['basic_compositor_video']}, 08:49:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:49:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'other-e10s': {'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['a11yr', 08:49:10 INFO - 'ts_paint', 08:49:10 INFO - 'tpaint', 08:49:10 INFO - 'sessionrestore', 08:49:10 INFO - 'sessionrestore_no_auto_restore', 08:49:10 INFO - 'tabpaint']}, 08:49:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['tsvgx', 08:49:10 INFO - 'tsvgr_opacity', 08:49:10 INFO - 'tart', 08:49:10 INFO - 'tscrollx', 08:49:10 INFO - 'cart']}, 08:49:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:49:10 INFO - 'tsvgr_opacity', 08:49:10 INFO - 'tart', 08:49:10 INFO - 'tscrollx', 08:49:10 INFO - 'cart']}, 08:49:10 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'talos_options': ['--disable-e10s'], 08:49:10 INFO - 'tests': ['tp5o']}, 08:49:10 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'tests': ['tp5o']}, 08:49:10 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'talos_options': ['--disable-e10s', 08:49:10 INFO - '--xperf_path', 08:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:10 INFO - 'tests': ['tp5n']}, 08:49:10 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:49:10 INFO - 'talos_options': ['--xperf_path', 08:49:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:49:10 INFO - 'tests': ['tp5n']}}, 08:49:10 INFO - 'talos.zip': {'path': '', 08:49:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:49:10 INFO - Running post-action listener: _resource_record_post_action 08:49:10 INFO - [mozharness: 2017-01-09 16:49:10.448455Z] Finished populate-webroot step (success) 08:49:10 INFO - [mozharness: 2017-01-09 16:49:10.448566Z] Running create-virtualenv step. 08:49:10 INFO - Running pre-action listener: _resource_record_pre_action 08:49:10 INFO - Running main action method: create_virtualenv 08:49:10 INFO - Creating virtualenv /builds/slave/test/build/venv 08:49:10 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:49:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:49:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:49:10 INFO - Using real prefix '/tools/python27' 08:49:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:49:11 INFO - Installing distribute.............................................................................................................................................................................................done. 08:49:14 INFO - Installing pip.................done. 08:49:14 INFO - Return code: 0 08:49:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:49:14 INFO - Reading from file tmpfile_stdout 08:49:14 INFO - Output received: 08:49:14 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:49:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:14 INFO - 'HOME': '/Users/cltbld', 08:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:14 INFO - 'LOGNAME': 'cltbld', 08:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:14 INFO - 'MOZ_NO_REMOTE': '1', 08:49:14 INFO - 'NO_EM_RESTART': '1', 08:49:14 INFO - 'PAGER': '/bin/cat', 08:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:14 INFO - 'PWD': '/builds/slave/test', 08:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:14 INFO - 'SHELL': '/bin/bash', 08:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:14 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:14 INFO - 'USER': 'cltbld', 08:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:14 INFO - 'XPC_FLAGS': '0x0', 08:49:14 INFO - 'XPC_SERVICE_NAME': '0', 08:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:14 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:49:14 INFO - Cleaning up... 08:49:14 INFO - Return code: 0 08:49:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:49:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:49:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:14 INFO - 'HOME': '/Users/cltbld', 08:49:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:14 INFO - 'LOGNAME': 'cltbld', 08:49:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:14 INFO - 'MOZ_NO_REMOTE': '1', 08:49:14 INFO - 'NO_EM_RESTART': '1', 08:49:14 INFO - 'PAGER': '/bin/cat', 08:49:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:14 INFO - 'PWD': '/builds/slave/test', 08:49:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:14 INFO - 'SHELL': '/bin/bash', 08:49:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:14 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:14 INFO - 'USER': 'cltbld', 08:49:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:14 INFO - 'XPC_FLAGS': '0x0', 08:49:14 INFO - 'XPC_SERVICE_NAME': '0', 08:49:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:14 INFO - Downloading/unpacking psutil>=3.1.1 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:49:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:19 INFO - Installing collected packages: psutil 08:49:19 INFO - Running setup.py install for psutil 08:49:19 INFO - building 'psutil._psutil_osx' extension 08:49: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 08:49: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 08:49: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 08:49: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 08:49:19 INFO - building 'psutil._psutil_posix' extension 08:49: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 08:49:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:49:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:49:19 INFO - ^ 08:49:20 INFO - 1 warning generated. 08:49:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:49:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:49:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:49:20 INFO - Successfully installed psutil 08:49:20 INFO - Cleaning up... 08:49:20 INFO - Return code: 0 08:49:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49: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 08:49: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 08:49:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:20 INFO - 'HOME': '/Users/cltbld', 08:49:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:20 INFO - 'LOGNAME': 'cltbld', 08:49:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:20 INFO - 'MOZ_NO_REMOTE': '1', 08:49:20 INFO - 'NO_EM_RESTART': '1', 08:49:20 INFO - 'PAGER': '/bin/cat', 08:49:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:20 INFO - 'PWD': '/builds/slave/test', 08:49:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:20 INFO - 'SHELL': '/bin/bash', 08:49:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:20 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:20 INFO - 'USER': 'cltbld', 08:49:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:20 INFO - 'XPC_FLAGS': '0x0', 08:49:20 INFO - 'XPC_SERVICE_NAME': '0', 08:49:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:49:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:49:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:49:23 INFO - Installing collected packages: mozsystemmonitor 08:49:23 INFO - Running setup.py install for mozsystemmonitor 08:49:23 INFO - Successfully installed mozsystemmonitor 08:49:23 INFO - Cleaning up... 08:49:23 INFO - Return code: 0 08:49:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49: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 08:49: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 08:49:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:23 INFO - 'HOME': '/Users/cltbld', 08:49:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:23 INFO - 'LOGNAME': 'cltbld', 08:49:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:23 INFO - 'MOZ_NO_REMOTE': '1', 08:49:23 INFO - 'NO_EM_RESTART': '1', 08:49:23 INFO - 'PAGER': '/bin/cat', 08:49:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:23 INFO - 'PWD': '/builds/slave/test', 08:49:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:23 INFO - 'SHELL': '/bin/bash', 08:49:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:23 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:23 INFO - 'USER': 'cltbld', 08:49:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:23 INFO - 'XPC_FLAGS': '0x0', 08:49:23 INFO - 'XPC_SERVICE_NAME': '0', 08:49:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:23 INFO - Downloading/unpacking jsonschema==2.5.1 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:49:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:26 INFO - Downloading functools32-3.2.3-2.tar.gz 08:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:49:26 INFO - warning: no files found matching '*.txt' 08:49:26 INFO - no previously-included directories found matching 'build' 08:49:26 INFO - no previously-included directories found matching 'dist' 08:49:26 INFO - no previously-included directories found matching '.git*' 08:49:26 INFO - Installing collected packages: jsonschema, functools32 08:49:27 INFO - Running setup.py install for functools32 08:49:27 INFO - warning: no files found matching '*.txt' 08:49:27 INFO - no previously-included directories found matching 'build' 08:49:27 INFO - no previously-included directories found matching 'dist' 08:49:27 INFO - no previously-included directories found matching '.git*' 08:49:27 INFO - Successfully installed jsonschema functools32 08:49:27 INFO - Cleaning up... 08:49:27 INFO - Return code: 0 08:49:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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', '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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:49: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 functools32==3.2.3-2 08:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:27 INFO - 'HOME': '/Users/cltbld', 08:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:27 INFO - 'LOGNAME': 'cltbld', 08:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:27 INFO - 'MOZ_NO_REMOTE': '1', 08:49:27 INFO - 'NO_EM_RESTART': '1', 08:49:27 INFO - 'PAGER': '/bin/cat', 08:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:27 INFO - 'PWD': '/builds/slave/test', 08:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:27 INFO - 'SHELL': '/bin/bash', 08:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:27 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:27 INFO - 'USER': 'cltbld', 08:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:27 INFO - 'XPC_FLAGS': '0x0', 08:49:27 INFO - 'XPC_SERVICE_NAME': '0', 08:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:49:27 INFO - Cleaning up... 08:49:27 INFO - Return code: 0 08:49:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49: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 08:49: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 08:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:27 INFO - 'HOME': '/Users/cltbld', 08:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:27 INFO - 'LOGNAME': 'cltbld', 08:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:27 INFO - 'MOZ_NO_REMOTE': '1', 08:49:27 INFO - 'NO_EM_RESTART': '1', 08:49:27 INFO - 'PAGER': '/bin/cat', 08:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:27 INFO - 'PWD': '/builds/slave/test', 08:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:27 INFO - 'SHELL': '/bin/bash', 08:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:27 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:27 INFO - 'USER': 'cltbld', 08:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:27 INFO - 'XPC_FLAGS': '0x0', 08:49:27 INFO - 'XPC_SERVICE_NAME': '0', 08:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:27 INFO - Downloading/unpacking blobuploader==1.2.4 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:30 INFO - Downloading blobuploader-1.2.4.tar.gz 08:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:49:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49: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 08:49:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:49:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:31 INFO - Downloading docopt-0.6.1.tar.gz 08:49:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:49:31 INFO - Installing collected packages: blobuploader, requests, docopt 08:49:31 INFO - Running setup.py install for blobuploader 08:49:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:49:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:49:31 INFO - Running setup.py install for requests 08:49:32 INFO - Running setup.py install for docopt 08:49:32 INFO - Successfully installed blobuploader requests docopt 08:49:32 INFO - Cleaning up... 08:49:32 INFO - Return code: 0 08:49:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:32 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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:32 INFO - 'HOME': '/Users/cltbld', 08:49:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:32 INFO - 'LOGNAME': 'cltbld', 08:49:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:32 INFO - 'MOZ_NO_REMOTE': '1', 08:49:32 INFO - 'NO_EM_RESTART': '1', 08:49:32 INFO - 'PAGER': '/bin/cat', 08:49:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:32 INFO - 'PWD': '/builds/slave/test', 08:49:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:32 INFO - 'SHELL': '/bin/bash', 08:49:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:32 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:32 INFO - 'USER': 'cltbld', 08:49:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:32 INFO - 'XPC_FLAGS': '0x0', 08:49:32 INFO - 'XPC_SERVICE_NAME': '0', 08:49:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:32 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-CC25lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:32 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9UKHxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:32 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-C0PjiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-5S3S1r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-lwinLf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-a6o09C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-qkc8HP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Bge7Po-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-TZzark-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-yogqXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-SYDArh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-d9wE0j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ER8JZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-9HaexL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JECxna-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-mtmO5m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-pM0lF7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:49:34 INFO - Running setup.py install for manifestparser 08:49:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:49:34 INFO - Running setup.py install for mozcrash 08:49:34 INFO - Running setup.py install for mozdebug 08:49:34 INFO - Running setup.py install for mozdevice 08:49:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Running setup.py install for mozfile 08:49:35 INFO - Running setup.py install for mozhttpd 08:49:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Running setup.py install for mozinfo 08:49:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Running setup.py install for mozInstall 08:49:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:49:35 INFO - Running setup.py install for mozleak 08:49:35 INFO - Running setup.py install for mozlog 08:49:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Running setup.py install for moznetwork 08:49:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Running setup.py install for mozprocess 08:49:36 INFO - Running setup.py install for mozprofile 08:49:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Running setup.py install for mozrunner 08:49:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:49:36 INFO - Running setup.py install for mozscreenshot 08:49:36 INFO - Running setup.py install for moztest 08:49:36 INFO - Running setup.py install for mozversion 08:49:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:49:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:49:37 INFO - Cleaning up... 08:49:37 INFO - Return code: 0 08:49:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:49:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:37 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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:49:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:37 INFO - 'HOME': '/Users/cltbld', 08:49:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:37 INFO - 'LOGNAME': 'cltbld', 08:49:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:37 INFO - 'MOZ_NO_REMOTE': '1', 08:49:37 INFO - 'NO_EM_RESTART': '1', 08:49:37 INFO - 'PAGER': '/bin/cat', 08:49:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:37 INFO - 'PWD': '/builds/slave/test', 08:49:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:37 INFO - 'SHELL': '/bin/bash', 08:49:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:37 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:37 INFO - 'USER': 'cltbld', 08:49:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:37 INFO - 'XPC_FLAGS': '0x0', 08:49:37 INFO - 'XPC_SERVICE_NAME': '0', 08:49:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-oOz7bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:49: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)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-D73Cs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:49: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)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-H8X3Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:49: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)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-zdPVbG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:49: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)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Kb6d8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:49: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)) 08:49:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:49:37 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VeHgSU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-MnLVXc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-S8LrFq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-kWFPsv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:49:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-caE2dP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-RbthUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JBfIyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-03Nxvu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-uaxZWt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-dCrUcF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:49: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)) 08:49:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:49:38 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-6ss78v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:49:39 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VhI1gE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:49:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:49:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:49:41 INFO - Downloading blessings-1.6.tar.gz 08:49:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:49:41 INFO - Installing collected packages: blessings 08:49:41 INFO - Running setup.py install for blessings 08:49:42 INFO - Successfully installed blessings 08:49:42 INFO - Cleaning up... 08:49:42 INFO - Return code: 0 08:49:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:49:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:42 INFO - Reading from file tmpfile_stdout 08:49:42 INFO - Current package versions: 08:49:42 INFO - blessings == 1.6 08:49:42 INFO - blobuploader == 1.2.4 08:49:42 INFO - docopt == 0.6.1 08:49:42 INFO - functools32 == 3.2.3-2 08:49:42 INFO - jsonschema == 2.5.1 08:49:42 INFO - manifestparser == 1.1 08:49:42 INFO - mozInstall == 1.12 08:49:42 INFO - mozcrash == 1.0 08:49:42 INFO - mozdebug == 0.1 08:49:42 INFO - mozdevice == 0.48 08:49:42 INFO - mozfile == 1.2 08:49:42 INFO - mozhttpd == 0.7 08:49:42 INFO - mozinfo == 0.9 08:49:42 INFO - mozleak == 0.1 08:49:42 INFO - mozlog == 3.4 08:49:42 INFO - moznetwork == 0.27 08:49:42 INFO - mozprocess == 0.23 08:49:42 INFO - mozprofile == 0.28 08:49:42 INFO - mozrunner == 6.13 08:49:42 INFO - mozscreenshot == 0.1 08:49:42 INFO - mozsystemmonitor == 0.3 08:49:42 INFO - moztest == 0.8 08:49:42 INFO - mozversion == 1.4 08:49:42 INFO - psutil == 3.1.1 08:49:42 INFO - requests == 1.2.3 08:49:42 INFO - wsgiref == 0.1.2 08:49:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49:42 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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:49:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:42 INFO - 'HOME': '/Users/cltbld', 08:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:42 INFO - 'LOGNAME': 'cltbld', 08:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:42 INFO - 'MOZ_NO_REMOTE': '1', 08:49:42 INFO - 'NO_EM_RESTART': '1', 08:49:42 INFO - 'PAGER': '/bin/cat', 08:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:42 INFO - 'PWD': '/builds/slave/test', 08:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:42 INFO - 'SHELL': '/bin/bash', 08:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:42 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:42 INFO - 'USER': 'cltbld', 08:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:42 INFO - 'XPC_FLAGS': '0x0', 08:49:42 INFO - 'XPC_SERVICE_NAME': '0', 08:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49: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)) 08:49:42 INFO - Cleaning up... 08:49:42 INFO - Return code: 0 08:49:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:49: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')]} 08:49:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:49:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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')]} 08:49:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:49:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:49: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 0x101138490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010ae570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff792587680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, '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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:49: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 08:49: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 08:49:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:49:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:42 INFO - 'HOME': '/Users/cltbld', 08:49:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:42 INFO - 'LOGNAME': 'cltbld', 08:49:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:42 INFO - 'MOZ_NO_REMOTE': '1', 08:49:42 INFO - 'NO_EM_RESTART': '1', 08:49:42 INFO - 'PAGER': '/bin/cat', 08:49:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:42 INFO - 'PWD': '/builds/slave/test', 08:49:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:42 INFO - 'SHELL': '/bin/bash', 08:49:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:49:42 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:49:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:42 INFO - 'USER': 'cltbld', 08:49:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:42 INFO - 'XPC_FLAGS': '0x0', 08:49:42 INFO - 'XPC_SERVICE_NAME': '0', 08:49:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:49:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:49:42 INFO - Cleaning up... 08:49:42 INFO - Return code: 0 08:49:42 INFO - Running post-action listener: _resource_record_post_action 08:49:42 INFO - Running post-action listener: _start_resource_monitoring 08:49:42 INFO - Starting resource monitoring. 08:49:43 INFO - [mozharness: 2017-01-09 16:49:43.000938Z] Finished create-virtualenv step (success) 08:49:43 INFO - [mozharness: 2017-01-09 16:49:43.001920Z] Running install step. 08:49:43 INFO - Running pre-action listener: _resource_record_pre_action 08:49:43 INFO - Running main action method: install 08:49:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:49:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:49:43 INFO - Reading from file tmpfile_stdout 08:49:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:49:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:49:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:49:43 INFO - Reading from file tmpfile_stdout 08:49:43 INFO - Output received: 08:49:43 INFO - Usage: mozinstall [options] installer 08:49:43 INFO - Options: 08:49:43 INFO - -h, --help show this help message and exit 08:49:43 INFO - -d DEST, --destination=DEST 08:49:43 INFO - Directory to install application into. [default: 08:49:43 INFO - "/builds/slave/test"] 08:49:43 INFO - --app=APP Application being installed. [default: firefox] 08:49:43 INFO - mkdir: /builds/slave/test/build/application 08:49:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:49:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:50:14 INFO - Reading from file tmpfile_stdout 08:50:14 INFO - Output received: 08:50:14 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:50:14 INFO - Running post-action listener: _resource_record_post_action 08:50:14 INFO - [mozharness: 2017-01-09 16:50:14.984606Z] Finished install step (success) 08:50:14 INFO - [mozharness: 2017-01-09 16:50:14.984753Z] Running run-tests step. 08:50:14 INFO - Running pre-action listener: _resource_record_pre_action 08:50:14 INFO - Running main action method: run_tests 08:50:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:50:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:50:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:50:15 INFO - 2017-01-09 08:50:14.999 screenresolution[1649:8740] starting screenresolution argv=screenresolution get 08:50:15 INFO - 2017-01-09 08:50:15.001 screenresolution[1649:8740] Display 0: 1600x1200x32@60 08:50:15 INFO - 2017-01-09 08:50:15.007 screenresolution[1650:8742] starting screenresolution argv=screenresolution list 08:50:15 INFO - Available Modes on Display 0 08:50:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:50:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:50:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:50:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:50:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:50:15 INFO - Intel Iris: 08:50:15 INFO - Chipset Model: Intel Iris 08:50:15 INFO - Type: GPU 08:50:15 INFO - Bus: Built-In 08:50:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:50:15 INFO - Vendor: Intel (0x8086) 08:50:15 INFO - Device ID: 0x0a2e 08:50:15 INFO - Revision ID: 0x0009 08:50:15 INFO - Displays: 08:50:15 INFO - Display: 08:50:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:50:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:50:15 INFO - Display Serial Number: 1600x1200 60 08:50:15 INFO - Main Display: Yes 08:50:15 INFO - Mirror: Off 08:50:15 INFO - Online: Yes 08:50:15 INFO - Rotation: Supported 08:50:15 INFO - Return code: 0 08:50:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:50:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:50:15 INFO - Python 2.7.3 08:50:15 INFO - Return code: 0 08:50:15 INFO - grabbing minidump binary from tooltool 08:50: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')]} 08:50: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 0x100f4cd30>, '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 0x100db2db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f44b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:50: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 08:50: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 08:50: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 08:50:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:50:15 INFO - Return code: 0 08:50:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:50:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:50:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:50:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:50:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:50: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/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 08:50: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/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 08:50:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gk74QxLuWb/Render', 08:50:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:15 INFO - 'HOME': '/Users/cltbld', 08:50:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:15 INFO - 'LOGNAME': 'cltbld', 08:50:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:50:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:50:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:15 INFO - 'MOZ_NO_REMOTE': '1', 08:50:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:50:15 INFO - 'NO_EM_RESTART': '1', 08:50:15 INFO - 'PAGER': '/bin/cat', 08:50:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:15 INFO - 'PWD': '/builds/slave/test', 08:50:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:50:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:15 INFO - 'SHELL': '/bin/bash', 08:50:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gb5NPLI9Ix/Listeners', 08:50:15 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 08:50:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:15 INFO - 'USER': 'cltbld', 08:50:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:15 INFO - 'XPC_FLAGS': '0x0', 08:50:15 INFO - 'XPC_SERVICE_NAME': '0', 08:50:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50: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/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 08:50:15 INFO - mozversion application_buildid: 20170109063808 08:50:15 INFO - mozversion application_changeset: bb6a56511146b31c57a2a3c792ccd464891b0593 08:50:15 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:50:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:50:15 INFO - mozversion application_name: Firefox 08:50:15 INFO - mozversion application_remotingname: firefox-dev 08:50:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:15 INFO - mozversion application_vendor: Mozilla 08:50:15 INFO - mozversion application_version: 52.0a2 08:50:15 INFO - mozversion platform_buildid: 20170109063808 08:50:15 INFO - mozversion platform_changeset: bb6a56511146b31c57a2a3c792ccd464891b0593 08:50:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:50:15 INFO - mozversion platform_version: 52.0a2 08:50:15 INFO - using testdate: 1483980615 08:50:15 INFO - actual date: 1483980615 08:50:15 INFO - starting webserver on 'localhost:49243' 08:50:15 INFO - SUITE-START | Running 2 tests 08:50:15 INFO - TEST-START | dromaeo_css 08:50:15 INFO - operating with platform_type : mac_ 08:50:15 INFO - Initialising browser for dromaeo_css test... 08:50:15 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpEmWGyZ/profile http://localhost:49243/getInfo.html) 08:50:18 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 08:50:18 INFO - PROCESS | 1660 | colorDepth:24 08:50:18 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 08:50:18 INFO - PROCESS | 1660 | __metrics 08:50:18 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 08:50:18 INFO - PROCESS | 1660 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:50:19 INFO - TEST-INFO | 1660: exit 0 08:50:19 INFO - Browser initialized. 08:50:19 INFO - Running cycle 1/1 for dromaeo_css test... 08:50:19 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpEmWGyZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:50:31 INFO - PROCESS | 1663 | RSS: Main: 222994432 08:50:31 INFO - PROCESS | 1663 | 08:53:36 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49243/tests/dromaeo/cssquery-ext.html) 08:53:37 INFO - PROCESS | 1663 | RSS: Main: 344645632 08:53:37 INFO - PROCESS | 1663 | 08:56:40 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-ext.html (next: http://localhost:49243/tests/dromaeo/cssquery-jquery.html) 08:56:41 INFO - PROCESS | 1663 | RSS: Main: 350253056 08:56:41 INFO - PROCESS | 1663 | 08:59:43 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49243/tests/dromaeo/cssquery-mootools.html) 08:59:44 INFO - PROCESS | 1663 | RSS: Main: 318922752 08:59:44 INFO - PROCESS | 1663 | 09:02:46 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49243/tests/dromaeo/cssquery-prototype.html) 09:02:46 INFO - PROCESS | 1663 | RSS: Main: 326959104 09:02:46 INFO - PROCESS | 1663 | 09:05:50 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49243/tests/dromaeo/cssquery-yui.html) 09:05:51 INFO - PROCESS | 1663 | RSS: Main: 311795712 09:05:51 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49243/tests/dromaeo/cssquery-yui.html (next: http://localhost:49243/tests/dromaeo/cssquery-dojo.html) 09:08:54 INFO - PROCESS | 1663 | RSS: Main: 356040704 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | __start_tp_report 09:08:54 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 09:08:54 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 09:08:54 INFO - PROCESS | 1663 | |i|pagename|runs| 09:08:54 INFO - PROCESS | 1663 | |0;dojo.html;3292.226326813199;3311.9503207451908;3317.137544238498;3342.064222017836;3382.036119705883;5571.91642125368;5646.378898321192;5662.235598194243;5673.602847800656;5696.971515142423;5611.831645050655;5682.744276507557;5705.343885453174;5708.914366284509;5711.143328500727;5144.845654630367;5159.2003239497935;5175.974120129394;5211.765470553815;5240.737963101829;5093.286939828427;5130.179171332587;5138.845834624967;5260.079486089919;5291.365036195662;5071.873203169914;5114;5176.792928282883;5191.922121168185;5204.219367094928;5184.766685499143;5246.213068039787;5279.52484276417;5296;5363.892722145535;5045.672031317952;5113.565346945514;5186.1442861927735;5198.844034678966;5206.505381988705;5482.0406428875185;5503.11950087988;5538.252335934638;5550.028744969654;5628.802991895264;6080.878382432371;6128.050152226412;6129.938700612981;6176.197094377747;6187.628742275433;6196.2874269459135;6204;6204.81385558434;6208.627482351073;6216.067589861512;5615.438456154393;5667.489925906688;5686;5692.288463942007;5741.397153298927;6176.505879529627;6197.876042479125;6198.287196972406;6203.4416902479;6214.844628884314;6072.757089716361;6085.66528840918;6094.8171554853425;6107.755689772448;6110.419510146529;6069.544784141119;6071.605345652519;6144.631322120687;6163.106349579286;6189.071639254148;4938.308636790852;6117.583823141483;6142.140100385949;6161.568690191733;6181.505479561624;6130.509559235251;6171.47542458896;6179.567430279927;6181.81454556364;6199.411055949678;4222.873313800591;4866.513348665105;4914.606831453475;4928.7289199093675;4962.801487940515;4314.9352759708;4342.392065110887;4349.695521313478;4380.846670366522;4396.824127034947;5647.887042259132;5682.062459694104;5697.230873832063;5702.030654788696;5724.341700704473;5472.2338872557875;5872.647641141545;6229;6235.345288744707;6250.4374606285655;5537.252470916455;5618;5618.69097199658;5621.269234999506;5669.461401166883;5327.067763141512;5344.706041167773;5350.6254562180275;5351.331083614548;5356.571474282048;6269.12232287471;6269.435750782452;6275.6548389839;6278.309385967448;6282.811515654629;6040.157916539733;6144.279601711528;6205.317415084427;6208.875822483616;6240.313565507699;6263.279722831932;6285;6286;6288.6855657217875;6296.716647750771;5943.108533719977;6032.974394352883;6201.534884883562;6203.31763506005;6205.689715514297;6003.309619393651;6007.579469437097;6031.4270144336215;6112.5946701277735;6135.385782596045;4528.3660287559105;5785.305763308262;5874.559408044328;5882.794102206402;5914.526837853047;5700.629459085146;5867.823965281048;5878.617889837147;5884.558658100746;5885.73514191871;5637.46444087811;5713.028785106459;5784.2769653793275;5822.650640961556;5833.241678581757;6037.939620603738;6058.939410605837;6060.818175454743;6061;6068.575199735976;5854.268216472941;5858.062709966555;5861.9413805861395;5874.471297583238;5910.408959104056 09:08:54 INFO - PROCESS | 1663 | |1;ext.html;499.8825276060126;515.4124298299938;524.3236225269403;535.2159086937636;550.0786183143234;29279.56080658789;29910.70089299109;30917.536236956465;30948.84525577369;30957.535636965462;39169.21661566774;40101.598984010154;40135.799321003426;41969;41978.95052623686;7351.816204594875;7402.370798482136;7436.739714110007;7437.776866693981;7442.06974128234;9162.633494660238;9254.67608633698;9255.722328330161;9257.490838003907;9261.166495015448;7829.765107046797;9209.401388909733;9272.721818345426;9277.675281365153;9292;9195.95402022989;9267.25861931044;9270.443773373552;9274.536273186348;9274.933382660945;9107;9172.770680733034;9176.495292758895;9182.17360437564;9188.081191880825;37818.62181378206;38277.234455311016;38317.233655327014;38364.46542138311;38365;3294.5717056782705;3296.0770984124483;3362.9831850840833;3439.4840773883866;3858.2669292834553;28401.715982840113;28731.712682873112;29056.709432905613;29299;30158.698413016027;2984.522476403765;3097.225693576606;3098.086064610945;3105.7825952183357;3139.309351942569;457667;460008;460426;460550;460610.9999999966;58773.706131469924;59375.406245937855;59475.70262148661;59511.70244148751;59519;33903.49144762831;34663.826680866434;34739.957801266006;61552.38447615556;61822.69088654528;15473.303701333241;15738;15751.763723544156;15799.526014219591;15802;11982.940085299517;12084.57703980374;12095.092868034933;12100.818487722516;12118.272903625812;18502.537436564195;18512.9817860019;18537.44387668372;18553.44339669812;18573.071346432625;7447.925520744723;7553.584552849646;7561.584112873735;7634.580098094658;7658.425618078666;810.9229623185788;910.5765818894246;939.1547607153616;994.3536701144315;1016.0905989139826;28065.579016314772;28117.71882281151;29083.563746543394;29551.965681201054;29719.702802971693;880.8326417980692;920.30516959695;1008.2488546033105;1014.264658122855;1039.3711804358254;14056.789148162785;14408.207548584718;14690.632734181518;14773.33519991603;14809.481668141563;683787;775875;778623;973083;975054;53610.463895361325;54151.18773218405;54197;54218.18672719912;54299;1238.5293588436336;1282.6152154353656;1285.9807102893465;1330.9201447913156;1368.8768010878869;24701.135460258804;27678;29469.410611788073;30097.849510752305;30117.69882301149;47156.29265561088;47693;47759.04481910412;47768.522314777794;47775;573772;579616;580351;580906;580969;24887.62668560009;25845.836937337488;26120.8693956529;26137.869310653325;26148.215553533424;42692.78653606712;42708;42855.785721071195;43204.78397607992;43326.91682707958;36077.639223607424;36290;36454.635453645125;36661.450078248316;37300.880973570835;36267.09332266715;36408;36758.26483470369;36898.63101368952;37315.81342093272;34807;35215.64784352226;35585.46621800623;35597.82201088978;35962.64037359593;30489.390212196075;32212.83893580517;32265.51601726023;32276.193095171868;32319.676803231665;20334;24184.516309673356;24243.51512969766;24845.627315590642;24886.875565622056 09:08:54 INFO - PROCESS | 1663 | |2;jquery.html;357.71382893685046;359.6439524870377;368.35537808834533;386.95936926622693;405.7099174090525;14633;14844.92577537112;15495.225238738074;19307.806921930776;20164.991750412457;18320.725189122142;18692.924799440167;18807.43577692668;19136;19421.572303462068;5383.327084114486;5672.886542269152;5677.318721753375;5679.772809087632;5688.800891968782;4694.342792009122;5599.860003499904;6431.967840160793;6480.51396145291;6555.606663600175;6130.203073600425;6439.5170362223025;6457.6771161441975;6460.030995350711;6467.45026672731;6253.155823963775;6292.150559674431;6332.366763323676;6336.96831515844;6338.714757835908;5356.491133342326;5496.285482887239;5506.339239291271;5612.07400778871;5685.317761868561;24023.519529609486;24170.758292417027;24288.63567046496;24404.511909761706;24454.633180502307;2023.989880050605;2342.3792694935687;2342.8360014798973;2649.6555447791666;2977.136630377188;8388.538630375328;9731.902680973171;9832.950835245849;10134.949325253401;14672.779908301383;1815.664102141108;1861.6928206845855;1878.4176905159309;1888.6404558855422;1924.6920492721238;115674;118168;123265.38367308196;124735;125668.11497827631;11091.334519928669;11350.602728904449;11465.426728663533;11468.942655286837;11645.184837061324;14601.488947886774;14608.56174314751;14621.78067328991;14625.707485850437;14651.92674036623;8038.356931445471;8227.629756660965;8303.211194936472;8394.286485648789;8406.78983025417;8079.838403231903;8120.75637730869;8152.103268640445;8266.669333226724;8272.793180170544;3879.6615167767077;4342.32693932441;4412.360207769855;4457.064016556495;4465.888352791206;1892.211859792483;1923.3843571400091;1926.8747531410413;1930.2279088364767;1954.990225048894;790.328221012135;797.8922845415794;855.5422848775875;908.9912389989157;989.9010098990043;16612;18898.81101188998;19689.046643003665;19908.70136947947;19956;820.7127505373072;916.1800188830958;919.4896832258028;939.4598106089027;965.2953344058876;9868.457234852009;10432.217583681255;10473.423961682181;10496.580136794442;10579.930657753672;596825.0158749266;598520;604607.9769601212;608253;614925.8507414958;92047;125932.37033814772;127255.58282943272;129374;132658;917.4220241247971;929.2983797233181;940.47333493244;941.6892425499568;947.7298969793576;16950;17379.47861564155;17854.28582856723;17859.553511162325;18100.366487172887;53220;53235.46764532305;54279.45720542744;54619;55321.72339138279;71113.22216944411;73938;74258.51482970202;76006;77037.61481192558;6616.636085015371;7773.455858089879;7802.921970780219;8023.598820058857;8087.676492940216;9639.518024098626;10127.7468063299;11989.700257493632;12060.879391205975;12406.937965310115;10115.544800483849;10247.436390998268;10333.793324133625;10568.313059651406;10597;9418.293627978066;9690.95154524223;10236.641717539851;10272.743181420525;10663.360198388122;10521.842172367573;10674.252802303765;10779.622713205;11005.724856878642;11019.504122314354;7660.616969151632;7813.296803287732;7847.2152784721075;8002.359811214972;8055.154208808225;9918.553665084944;10399.792004159723;10511.054005139575;10514.737131571772;10737 09:08:54 INFO - PROCESS | 1663 | |3;mootools.html;1192.379962419542;1306.2358520265643;1450.4343306110618;1558.9220538973047;1828.5463056869796;7462.141853686828;7819.491733037348;8025.277725004741;8277.461964972288;8300.626471808768;8012.839743205132;8100.635471403786;8202.794930126749;8203.384746144047;8216.876746848788;2035.8065983731522;2269.2965180793926;2287.736910255317;2288.9542209155807;2293.6444851048113;2158.201465457783;2198.428408613764;2208.8012078912893;2215.2246713650175;2218.3234113595345;2211.8672879627193;2237.071615279657;2275.954480910381;2305.561943230789;2307.4116100394376;2245.73051233853;2262.751097379295;2276.8064714499287;2287.8970446329954;2288.725352957639;2287.3824067501682;2306.1006207579057;2306.7577904319974;2310.9306720798236;2315.0855412112123;8038.477498962548;8039.236272554098;8188.14024527428;8204.974378202725;8343.791405214857;5086;5201.245819356172;5211.114110601206;5212.635115541913;5236.947630523684;3389.406853800575;3517.982410087933;3539.3275277697153;3595.2449985503076;3606.6934310583633;1039.449091981251;1045.6862941117633;1074.1943542343242;1077.3643550305278;1079.3901445683243;10722.195835312305;10762.192835537287;10813.97267259617;10822.945885270523;10823.945880270627;4281.529031806499;4286.249906266391;4423.1153769246275;4449.755263460476;4466.6203372713035;8453.408261421642;8463.66145354179;8569.657213711507;8587.184217499327;8614.396992210484;5051.1413059779925;5051.898962020739;5055.16589762685;5055.570276526537;5058.215976523645;3835.5780864104927;4248.532661407243;4661.930071048937;4701.694389864716;4750.453697824794;3599.784012959231;3602.9819850900576;3670.853165873335;3694.242680250542;3698.4082546792383;1135.8920902514249;1140.988590114105;1144.553624086607;1155.1971379891063;1156.0809156720427;607.9635221886701;612.595686846688;753.6834529497625;766.1840140250615;889.4440974391006;5139.152039913372;5307.124324486416;5386.407495175527;5479.150731636602;5553.50018498329;848.6223630484499;853.2619284319019;867.0679020053342;879.9780005499786;949.9192568631605;3512;3628.2924829657964;3667.3032123896373;3669.449582562637;3748.4189950557707;8563.743087707255;10052.597896084075;10156;10171.949140254252;10173.186145108373;10116.544755485873;10130.290879638502;10130.645427410005;10137.847932281022;10140.949295253477;7634.160242373335;7801.4929029612895;7803;7805.94619726341;7811.101723301779;4858.149823780754;5023.497650234947;5067.746612669278;5196.870078248074;5253.921191182212;8199.467034642728;8274.793130171794;8306.584670766559;8358.498490090615;8419.70531031411;8038.517688938682;8284.378671599534;8382.994040715153;8509.978802543734;8536.359773017173;6627.304133065959;6717.664116794043;6742.426893713893;6784.185897692309;6804.285550017177;7745.612719364122;7844.21557844211;7849.136594974662;7857.607119643881;7857.685692572461;6907.34380233877;7047.295270472911;7101.609411482418;7127.287271272831;7139.428845692229;6933.618650974245;6980.965095174694;7010.298970102949;7014.228434872262;7045.682944267418;6473.546851720334;6541.509386796104;6607.933920660732;6610.3720146586;6610.933890661224;3893.7858417787293;4593.517680643485;4622.006268652121;4635.559621836055;4760.9285860711425;4572.4055872736335;4694.2958556216845;4709.858704238878;4715.127701375257;4822.254421309643 09:08:54 INFO - PROCESS | 1663 | |4;prototype.html;301.11172042474703;305.0862666314389;310.7436364998876;316.6311247396783;317.4729948285846;4491.169133710266;5182.870428239286;5215.217717342401;5245.790168393264;5272.947270527294;4352.434183556141;4517.164324599951;4627.8148874045;4812.28106120042;5062.468440813716;3726.4037753959374;3772.3964165733487;3965.6827453803626;3977.5425826030087;4050.5393896734663;3303.6035675718826;4000.8399664013405;4009.3184158693093;4011.7191796574252;4019.577944315834;3227.887023954162;3232.4020056289546;3467.4972129041275;3982.402639604068;3982.800859957005;3267.39553182662;3443.7946718648273;3682.4660424238605;3965.0373065332556;3986.166363472806;3423.2126610879386;3441.9483707744403;3442.7762195457467;3446.362422951762;3466.8093254870973;4544.136903869397;5002.923202230639;5005.799768009275;5065;5080.288759573663;4064.6748260139416;4234.343676730112;4438.578335058164;4547.8635640930825;4558.680892337537;5420.620556561043;5472.480114389166;5516.475934786189;5532.917006244909;5534.418886016951;3776.7356285060055;3777.395616701315;3781.6764132553385;3786.7917264550438;3798.3352913240074;4624.560666736655;5278.445763194848;5711.428857114297;5753.382211093942;5779.624324418899;3555.928881422357;3786.6024067473068;3796.563395209531;3800.410936304877;3815.809209539512;3252.609686837547;3572.99956012317;3754.8597299539;3892.357760969465;3930.449737036817;3435.845386957593;3844.8077596120083;4213.408753616503;4538.682292239511;4545.886352841206;4800.207965685692;5471.537807986422;5488.396276409591;5497.835064947978;5544.16837474374;1017.7353887989176;1240.7146356338092;1250.87491250876;1251.7371352015996;1254.1158483269455;812.4069429316633;814.4665244265016;833.4374297349265;866.6360128745941;868.6047848229041;4816.903661926742;4824.49342819006;4824.951750482521;4865.5864251538305;4868.977514721877;5264.710440925785;5387.353517577916;5394.78420863169;5405.405405405402;5433.972830135824;4003.5796241394232;4459.353393757887;4469.4580369772375;4586.528135068348;4848.878778030577;2725.1196674294674;2728.877200525981;2743.190758726181;2755.4626847764894;2762.941608501679;4324.2054547362495;4880.633952453581;5364.651297665718;5449.528627270694;5514.476124768141;4774.570288674036;5031;5297.549708274762;5306;5330.680159190461;4890.315355850184;5189.676632458699;5406.432324605859;5448.482394172547;5485.803360429275;4471.792615993599;4877;4926.605871530197;5172.301739265151;5290.338707661543;4764.690295130806;5068.746562671778;5170.353705786777;5245.7114858683135;5252.448492908343;4774.069056533943;5061.722224998692;5109.795608175631;5110.696772323034;5467.617266791445;3194.8977602727023;3254.1864533866533;3514.3674138655288;3603.6396360363756;3627.202015556679;4436.689431739747;4758.2386818108935;4796.2349555598685;4801.975990120027;4953.380827396575;4565.927007153383;4691.648126390466;4794.304825800309;4868.9756551217015;5009.7244651544515;4681.752758675922;5028.572571331478;5073.695578265316;5103.045140016368;5104.967076009911;3973.8609148679657;4535.501094879494;4741.620670346433;4977.303177555073;5281.943611277837;4922.606191504743;5301.5493683037375;5320.680759154463;5331.093714068696;5484.314460692413;4338.915271182204;5174.663646862942;5368.758405871667;5377.848860455849;5448.782048718165 09:08:54 INFO - PROCESS | 1663 | |5;yui.html;632.7532262417657;691.3397705191543;769.5844244108182;777.0208247535219;778.7079845058099;14111.223882686449;14540.49108281209;15623.21883905803;16082.678346433064;16328.34686612537;14623.853761462382;14888.925555372234;14980.400783968627;15120;15128.924355378234;800.9239122283388;823.5223570329194;824.1634740738126;824.5135370131621;825.471698113208;642.0337392224695;669.6283562622742;671.684308375063;769.9422543309246;819.7827575692446;862.965481380744;876.2289185516736;878.9736307910773;879.7316818370394;880.067128843428;863.4689665855517;863.8661007543809;865.1262225152578;867.4665080975218;869.4479005831342;501.8444282272471;520.9817656382008;597.9312379076418;624.0452108274347;644.8355669304344;14211.715765684628;14255.14469131845;14269.072510286902;14281.857181428157;14283.642908927257;12450.190737602026;12576.182548134342;12655.810113108495;12749.490020399173;12895.098602814292;11148.832767508495;11299.322040677585;11442.199046066778;11574.826377604342;11656.358900260482;3533.664301891342;3599.1002249437643;3613;3786.6024067473068;3787.507624008889;78313.04217394553;86324.56837715834;91055.08944910599;91828;91952.54023729905;10081.546330415149;10185.236107291983;10531.315464494783;10532.262741608014;10724.889098163521;19840.007999599962;19989.600207995758;20023.499412514804;20070.09684564198;20312.69530957037;7591.696332146652;7637.885431718529;7648.694052237848;7856.5678887661725;7889.605519723991;4160.729552579072;4168.8332466700995;4392.912141757147;4399.38408622793;4423.358613001097;3379.172102834821;3487.215376540309;3488.2674638326234;3495.143689796016;3497.7026952708798;646.7930262316014;647.4626060369976;652.8106849013827;667.372669690499;675.4056430341268;549.9065158922992;563.4956713741178;571.3372487913999;601.8194541637491;602.5390576209204;8777.385583009129;8884.73345799627;8965.596548155218;9041.925745246648;9198.172164505228;575.2119596153296;602.1569802276847;620.5004970998299;650.5218664281749;670.580886945659;4045.696572757055;4369.541198174146;4421.226285400042;4446.888827779267;4526.864194074183;89423.76440589037;116527;116542.41728791302;116549;127248;77139.61430192813;101857.98142018485;102113;105121.32075377325;108058;91859.54070229606;91878;92264.53867730619;93426.53286733659;95493.5225323869;8608.526531040852;8619.82760344802;8632.223099921037;8769.605367758339;8875.822483550422;55918;57012.71493642505;57510;57726.422735773784;57876;67122;78577;80250;83074.58462707647;83471;10831.512581933674;10869.891301086887;10917.508712107816;10974.341539507655;11468.197226194086;9938;11131.499082541142;11375.43122843871;11662.416879155839;11847.348395838311;11507.194496385173;11590.536378544764;11608.477618507357;11661.416929153338;11854.288742675468;10687.679369618923;12160.391980400767;12898.226106433644;12907.806382904077;13378.063535552807;12184.451699673358;12521.937390313355;12618.242905425705;12728.87271127314;12924.741505169655;9259;10632.84050739255;10860.945695271474;11018.173636977099;11384.658460246206;10675.89324106749;11103.444827758742;11120.555177792798;11152.330860148417;11252.26860254081 09:08:54 INFO - PROCESS | 1663 | __end_tp_report 09:08:54 INFO - PROCESS | 1663 | __start_cc_report 09:08:54 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1562 09:08:54 INFO - PROCESS | 1663 | __end_cc_report 09:08:54 INFO - PROCESS | 1663 | __startTimestamp1483981734307__endTimestamp 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | ------- Summary: start ------- 09:08:54 INFO - PROCESS | 1663 | Number of tests: 6 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5651.66 Median:5785.31 stddev:634.80 (11.0%) stddev-sans-first:609.17 09:08:54 INFO - PROCESS | 1663 | Values: 3292.2 3312.0 3317.1 3342.1 3382.0 5571.9 5646.4 5662.2 5673.6 5697.0 5611.8 5682.7 5705.3 5708.9 5711.1 5144.8 5159.2 5176.0 5211.8 5240.7 5093.3 5130.2 5138.8 5260.1 5291.4 5071.9 5114.0 5176.8 5191.9 5204.2 5184.8 5246.2 5279.5 5296.0 5363.9 5045.7 5113.6 5186.1 5198.8 5206.5 5482.0 5503.1 5538.3 5550.0 5628.8 6080.9 6128.1 6129.9 6176.2 6187.6 6196.3 6204.0 6204.8 6208.6 6216.1 5615.4 5667.5 5686.0 5692.3 5741.4 6176.5 6197.9 6198.3 6203.4 6214.8 6072.8 6085.7 6094.8 6107.8 6110.4 6069.5 6071.6 6144.6 6163.1 6189.1 4938.3 6117.6 6142.1 6161.6 6181.5 6130.5 6171.5 6179.6 6181.8 6199.4 4222.9 4866.5 4914.6 4928.7 4962.8 4314.9 4342.4 4349.7 4380.8 4396.8 5647.9 5682.1 5697.2 5702.0 5724.3 5472.2 5872.6 6229.0 6235.3 6250.4 5537.3 5618.0 5618.7 5621.3 5669.5 5327.1 5344.7 5350.6 5351.3 5356.6 6269.1 6269.4 6275.7 6278.3 6282.8 6040.2 6144.3 6205.3 6208.9 6240.3 6263.3 6285.0 6286.0 6288.7 6296.7 5943.1 6033.0 6201.5 6203.3 6205.7 6003.3 6007.6 6031.4 6112.6 6135.4 4528.4 5785.3 5874.6 5882.8 5914.5 5700.6 5867.8 5878.6 5884.6 5885.7 5637.5 5713.0 5784.3 5822.7 5833.2 6037.9 6058.9 6060.8 6061.0 6068.6 5854.3 5858.1 5861.9 5874.5 5910.4 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:73174.61 Median:37567.22 stddev:174948.04 (465.7%) stddev-sans-first:175353.72 09:08:54 INFO - PROCESS | 1663 | Values: 499.9 515.4 524.3 535.2 550.1 29279.6 29910.7 30917.5 30948.8 30957.5 39169.2 40101.6 40135.8 41969.0 41979.0 7351.8 7402.4 7436.7 7437.8 7442.1 9162.6 9254.7 9255.7 9257.5 9261.2 7829.8 9209.4 9272.7 9277.7 9292.0 9196.0 9267.3 9270.4 9274.5 9274.9 9107.0 9172.8 9176.5 9182.2 9188.1 37818.6 38277.2 38317.2 38364.5 38365.0 3294.6 3296.1 3363.0 3439.5 3858.3 28401.7 28731.7 29056.7 29299.0 30158.7 2984.5 3097.2 3098.1 3105.8 3139.3 457667.0 460008.0 460426.0 460550.0 460611.0 58773.7 59375.4 59475.7 59511.7 59519.0 33903.5 34663.8 34740.0 61552.4 61822.7 15473.3 15738.0 15751.8 15799.5 15802.0 11982.9 12084.6 12095.1 12100.8 12118.3 18502.5 18513.0 18537.4 18553.4 18573.1 7447.9 7553.6 7561.6 7634.6 7658.4 810.9 910.6 939.2 994.4 1016.1 28065.6 28117.7 29083.6 29552.0 29719.7 880.8 920.3 1008.2 1014.3 1039.4 14056.8 14408.2 14690.6 14773.3 14809.5 683787.0 775875.0 778623.0 973083.0 975054.0 53610.5 54151.2 54197.0 54218.2 54299.0 1238.5 1282.6 1286.0 1330.9 1368.9 24701.1 27678.0 29469.4 30097.8 30117.7 47156.3 47693.0 47759.0 47768.5 47775.0 573772.0 579616.0 580351.0 580906.0 580969.0 24887.6 25845.8 26120.9 26137.9 26148.2 42692.8 42708.0 42855.8 43204.8 43326.9 36077.6 36290.0 36454.6 36661.5 37300.9 36267.1 36408.0 36758.3 36898.6 37315.8 34807.0 35215.6 35585.5 35597.8 35962.6 30489.4 32212.8 32265.5 32276.2 32319.7 20334.0 24184.5 24243.5 24845.6 24886.9 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:34671.28 Median:2142.69 stddev:100845.87 (4706.5%) stddev-sans-first:101095.85 09:08:54 INFO - PROCESS | 1663 | Values: 357.7 359.6 368.4 387.0 405.7 14633.0 14844.9 15495.2 19307.8 20165.0 18320.7 18692.9 18807.4 19136.0 19421.6 5383.3 5672.9 5677.3 5679.8 5688.8 4694.3 5599.9 6432.0 6480.5 6555.6 6130.2 6439.5 6457.7 6460.0 6467.5 6253.2 6292.2 6332.4 6337.0 6338.7 5356.5 5496.3 5506.3 5612.1 5685.3 24023.5 24170.8 24288.6 24404.5 24454.6 2024.0 2342.4 2342.8 2649.7 2977.1 8388.5 9731.9 9833.0 10134.9 14672.8 1815.7 1861.7 1878.4 1888.6 1924.7 115674.0 118168.0 123265.4 124735.0 125668.1 11091.3 11350.6 11465.4 11468.9 11645.2 14601.5 14608.6 14621.8 14625.7 14651.9 8038.4 8227.6 8303.2 8394.3 8406.8 8079.8 8120.8 8152.1 8266.7 8272.8 3879.7 4342.3 4412.4 4457.1 4465.9 1892.2 1923.4 1926.9 1930.2 1955.0 790.3 797.9 855.5 909.0 989.9 16612.0 18898.8 19689.0 19908.7 19956.0 820.7 916.2 919.5 939.5 965.3 9868.5 10432.2 10473.4 10496.6 10579.9 596825.0 598520.0 604608.0 608253.0 614925.9 92047.0 125932.4 127255.6 129374.0 132658.0 917.4 929.3 940.5 941.7 947.7 16950.0 17379.5 17854.3 17859.6 18100.4 53220.0 53235.5 54279.5 54619.0 55321.7 71113.2 73938.0 74258.5 76006.0 77037.6 6616.6 7773.5 7802.9 8023.6 8087.7 9639.5 10127.7 11989.7 12060.9 12406.9 10115.5 10247.4 10333.8 10568.3 10597.0 9418.3 9691.0 10236.6 10272.7 10663.4 10521.8 10674.3 10779.6 11005.7 11019.5 7660.6 7813.3 7847.2 8002.4 8055.2 9918.6 10399.8 10511.1 10514.7 10737.0 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5258.66 Median:5037.32 stddev:2881.72 (57.2%) stddev-sans-first:2873.59 09:08:54 INFO - PROCESS | 1663 | Values: 1192.4 1306.2 1450.4 1558.9 1828.5 7462.1 7819.5 8025.3 8277.5 8300.6 8012.8 8100.6 8202.8 8203.4 8216.9 2035.8 2269.3 2287.7 2289.0 2293.6 2158.2 2198.4 2208.8 2215.2 2218.3 2211.9 2237.1 2276.0 2305.6 2307.4 2245.7 2262.8 2276.8 2287.9 2288.7 2287.4 2306.1 2306.8 2310.9 2315.1 8038.5 8039.2 8188.1 8205.0 8343.8 5086.0 5201.2 5211.1 5212.6 5236.9 3389.4 3518.0 3539.3 3595.2 3606.7 1039.4 1045.7 1074.2 1077.4 1079.4 10722.2 10762.2 10814.0 10822.9 10823.9 4281.5 4286.2 4423.1 4449.8 4466.6 8453.4 8463.7 8569.7 8587.2 8614.4 5051.1 5051.9 5055.2 5055.6 5058.2 3835.6 4248.5 4661.9 4701.7 4750.5 3599.8 3603.0 3670.9 3694.2 3698.4 1135.9 1141.0 1144.6 1155.2 1156.1 608.0 612.6 753.7 766.2 889.4 5139.2 5307.1 5386.4 5479.2 5553.5 848.6 853.3 867.1 880.0 949.9 3512.0 3628.3 3667.3 3669.4 3748.4 8563.7 10052.6 10156.0 10171.9 10173.2 10116.5 10130.3 10130.6 10137.8 10140.9 7634.2 7801.5 7803.0 7805.9 7811.1 4858.1 5023.5 5067.7 5196.9 5253.9 8199.5 8274.8 8306.6 8358.5 8419.7 8038.5 8284.4 8383.0 8510.0 8536.4 6627.3 6717.7 6742.4 6784.2 6804.3 7745.6 7844.2 7849.1 7857.6 7857.7 6907.3 7047.3 7101.6 7127.3 7139.4 6933.6 6981.0 7010.3 7014.2 7045.7 6473.5 6541.5 6607.9 6610.4 6610.9 3893.8 4593.5 4622.0 4635.6 4760.9 4572.4 4694.3 4709.9 4715.1 4822.3 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:4241.87 Median:4761.46 stddev:1282.47 (26.9%) stddev-sans-first:1251.49 09:08:54 INFO - PROCESS | 1663 | Values: 301.1 305.1 310.7 316.6 317.5 4491.2 5182.9 5215.2 5245.8 5272.9 4352.4 4517.2 4627.8 4812.3 5062.5 3726.4 3772.4 3965.7 3977.5 4050.5 3303.6 4000.8 4009.3 4011.7 4019.6 3227.9 3232.4 3467.5 3982.4 3982.8 3267.4 3443.8 3682.5 3965.0 3986.2 3423.2 3441.9 3442.8 3446.4 3466.8 4544.1 5002.9 5005.8 5065.0 5080.3 4064.7 4234.3 4438.6 4547.9 4558.7 5420.6 5472.5 5516.5 5532.9 5534.4 3776.7 3777.4 3781.7 3786.8 3798.3 4624.6 5278.4 5711.4 5753.4 5779.6 3555.9 3786.6 3796.6 3800.4 3815.8 3252.6 3573.0 3754.9 3892.4 3930.4 3435.8 3844.8 4213.4 4538.7 4545.9 4800.2 5471.5 5488.4 5497.8 5544.2 1017.7 1240.7 1250.9 1251.7 1254.1 812.4 814.5 833.4 866.6 868.6 4816.9 4824.5 4825.0 4865.6 4869.0 5264.7 5387.4 5394.8 5405.4 5434.0 4003.6 4459.4 4469.5 4586.5 4848.9 2725.1 2728.9 2743.2 2755.5 2762.9 4324.2 4880.6 5364.7 5449.5 5514.5 4774.6 5031.0 5297.5 5306.0 5330.7 4890.3 5189.7 5406.4 5448.5 5485.8 4471.8 4877.0 4926.6 5172.3 5290.3 4764.7 5068.7 5170.4 5245.7 5252.4 4774.1 5061.7 5109.8 5110.7 5467.6 3194.9 3254.2 3514.4 3603.6 3627.2 4436.7 4758.2 4796.2 4802.0 4953.4 4565.9 4691.6 4794.3 4869.0 5009.7 4681.8 5028.6 5073.7 5103.0 5105.0 3973.9 4535.5 4741.6 4977.3 5281.9 4922.6 5301.5 5320.7 5331.1 5484.3 4338.9 5174.7 5368.8 5377.8 5448.8 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:20999.60 Median:4396.15 stddev:31516.13 (716.9%) stddev-sans-first:31567.44 09:08:54 INFO - PROCESS | 1663 | Values: 632.8 691.3 769.6 777.0 778.7 14111.2 14540.5 15623.2 16082.7 16328.3 14623.9 14888.9 14980.4 15120.0 15128.9 800.9 823.5 824.2 824.5 825.5 642.0 669.6 671.7 769.9 819.8 863.0 876.2 879.0 879.7 880.1 863.5 863.9 865.1 867.5 869.4 501.8 521.0 597.9 624.0 644.8 14211.7 14255.1 14269.1 14281.9 14283.6 12450.2 12576.2 12655.8 12749.5 12895.1 11148.8 11299.3 11442.2 11574.8 11656.4 3533.7 3599.1 3613.0 3786.6 3787.5 78313.0 86324.6 91055.1 91828.0 91952.5 10081.5 10185.2 10531.3 10532.3 10724.9 19840.0 19989.6 20023.5 20070.1 20312.7 7591.7 7637.9 7648.7 7856.6 7889.6 4160.7 4168.8 4392.9 4399.4 4423.4 3379.2 3487.2 3488.3 3495.1 3497.7 646.8 647.5 652.8 667.4 675.4 549.9 563.5 571.3 601.8 602.5 8777.4 8884.7 8965.6 9041.9 9198.2 575.2 602.2 620.5 650.5 670.6 4045.7 4369.5 4421.2 4446.9 4526.9 89423.8 116527.0 116542.4 116549.0 127248.0 77139.6 101858.0 102113.0 105121.3 108058.0 91859.5 91878.0 92264.5 93426.5 95493.5 8608.5 8619.8 8632.2 8769.6 8875.8 55918.0 57012.7 57510.0 57726.4 57876.0 67122.0 78577.0 80250.0 83074.6 83471.0 10831.5 10869.9 10917.5 10974.3 11468.2 9938.0 11131.5 11375.4 11662.4 11847.3 11507.2 11590.5 11608.5 11661.4 11854.3 10687.7 12160.4 12898.2 12907.8 13378.1 12184.5 12521.9 12618.2 12728.9 12924.7 9259.0 10632.8 10860.9 11018.2 11384.7 10675.9 11103.4 11120.6 11152.3 11252.3 09:08:54 INFO - PROCESS | 1663 | -------- Summary: end -------- 09:08:54 INFO - PROCESS | 1663 | 09:08:54 INFO - TEST-INFO | 1663: exit 0 09:08:54 INFO - TEST-OK | dromaeo_css | took 1119350ms 09:08:54 INFO - TEST-START | kraken 09:08:54 INFO - operating with platform_type : mac_ 09:08:54 INFO - Initialising browser for kraken test... 09:08:54 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpc8p7YD/profile http://localhost:49243/getInfo.html) 09:08:57 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 09:08:57 INFO - PROCESS | 1670 | colorDepth:24 09:08:57 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 09:08:57 INFO - PROCESS | 1670 | __metrics 09:08:57 INFO - PROCESS | 1670 | 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:08:57 INFO - PROCESS | 1670 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:58 INFO - TEST-INFO | 1670: exit 0 09:08:58 INFO - Browser initialized. 09:08:58 INFO - Running cycle 1/1 for kraken test... 09:08:58 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpc8p7YD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:09:11 INFO - PROCESS | 1671 | RSS: Main: 222834688 09:09:11 INFO - PROCESS | 1671 | 09:09:14 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection) 09:09:14 INFO - PROCESS | 1671 | RSS: Main: 272556032 09:09:14 INFO - PROCESS | 1671 | 09:09:17 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-dft) 09:09:18 INFO - PROCESS | 1671 | RSS: Main: 284262400 09:09:18 INFO - PROCESS | 1671 | 09:09:21 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-fft) 09:09:21 INFO - PROCESS | 1671 | RSS: Main: 380784640 09:09:21 INFO - PROCESS | 1671 | 09:09:24 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator) 09:09:24 INFO - PROCESS | 1671 | RSS: Main: 282361856 09:09:24 INFO - PROCESS | 1671 | 09:09:26 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:09:27 INFO - PROCESS | 1671 | RSS: Main: 283484160 09:09:27 INFO - PROCESS | 1671 | 09:09:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom) 09:09:30 INFO - PROCESS | 1671 | RSS: Main: 325804032 09:09:30 INFO - PROCESS | 1671 | 09:09:33 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate) 09:09:34 INFO - PROCESS | 1671 | RSS: Main: 327331840 09:09:34 INFO - PROCESS | 1671 | 09:09:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial) 09:09:37 INFO - PROCESS | 1671 | RSS: Main: 324063232 09:09:37 INFO - PROCESS | 1671 | 09:09:38 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:09:39 INFO - PROCESS | 1671 | RSS: Main: 299393024 09:09:39 INFO - PROCESS | 1671 | 09:09:41 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:09:42 INFO - PROCESS | 1671 | RSS: Main: 302112768 09:09:42 INFO - PROCESS | 1671 | 09:09:44 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:09:45 INFO - PROCESS | 1671 | RSS: Main: 304852992 09:09:45 INFO - PROCESS | 1671 | 09:09:47 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:09:48 INFO - PROCESS | 1671 | RSS: Main: 321949696 09:09:48 INFO - PROCESS | 1671 | 09:09:51 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:09:52 INFO - PROCESS | 1671 | RSS: Main: 315187200 09:09:52 INFO - PROCESS | 1671 | 09:09:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49243/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49243/tests/kraken/driver.html?testName=ai-astar) 09:09:54 INFO - PROCESS | 1671 | RSS: Main: 359104512 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | __start_tp_report 09:09:54 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 09:09:54 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 09:09:54 INFO - PROCESS | 1671 | |i|pagename|runs| 09:09:54 INFO - PROCESS | 1671 | |0;ai-astar;116;114;113;113;114;114;117;161;168;112 09:09:54 INFO - PROCESS | 1671 | |1;audio-beat-detection;139;114;142;162;110;113;138;109;141;111 09:09:54 INFO - PROCESS | 1671 | |2;audio-dft;182;183;185;182;181;181;182;183;185;184 09:09:54 INFO - PROCESS | 1671 | |3;audio-fft;81;61;59;89;62;60;71;84;110;77 09:09:54 INFO - PROCESS | 1671 | |4;audio-oscillator;88;71;95;70;68;69;89;67;69;91 09:09:54 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;87;85;87;84;86;83;88;84;86;83 09:09:54 INFO - PROCESS | 1671 | |6;imaging-darkroom;93;94;93;95;93;94;93;94;93;94 09:09:54 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;81;81;82;83;84;83;83;83;82 09:09:54 INFO - PROCESS | 1671 | |8;json-parse-financial;57;56;54;54;56;56;57;57;57;57 09:09:54 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;67;84;73;76;71;66;74;100;103;102 09:09:54 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;82;83;82;83;83;82;89;82;98;101 09:09:54 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;141;140;141;138;141;156;132;148;218;162 09:09:54 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;165;165;168;166;167;169;164;165;164;168 09:09:54 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;67;67;67;68;68;68;67;69;68;66 09:09:54 INFO - PROCESS | 1671 | __end_tp_report 09:09:54 INFO - PROCESS | 1671 | __start_cc_report 09:09:54 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,1990 09:09:54 INFO - PROCESS | 1671 | __end_cc_report 09:09:54 INFO - PROCESS | 1671 | __startTimestamp1483981794111__endTimestamp 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | ------- Summary: start ------- 09:09:54 INFO - PROCESS | 1671 | Number of tests: 14 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:124.20 Median:115.00 stddev:21.35 (18.6%) stddev-sans-first:22.44 09:09:54 INFO - PROCESS | 1671 | Values: 116.0 114.0 113.0 113.0 114.0 114.0 117.0 161.0 168.0 112.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:127.90 Median:138.50 stddev:18.67 (13.5%) stddev-sans-first:19.36 09:09:54 INFO - PROCESS | 1671 | Values: 139.0 114.0 142.0 162.0 110.0 113.0 138.0 109.0 141.0 111.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.48 (0.8%) stddev-sans-first:1.54 09:09:54 INFO - PROCESS | 1671 | Values: 182.0 183.0 185.0 182.0 181.0 181.0 182.0 183.0 185.0 184.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:75.40 Median:74.00 stddev:16.34 (22.1%) stddev-sans-first:17.20 09:09:54 INFO - PROCESS | 1671 | Values: 81.0 61.0 59.0 89.0 62.0 60.0 71.0 84.0 110.0 77.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.50 stddev:11.42 (14.4%) stddev-sans-first:11.49 09:09:54 INFO - PROCESS | 1671 | Values: 88.0 71.0 95.0 70.0 68.0 69.0 89.0 67.0 69.0 91.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.77 (2.1%) stddev-sans-first:1.76 09:09:54 INFO - PROCESS | 1671 | Values: 87.0 85.0 87.0 84.0 86.0 83.0 88.0 84.0 86.0 83.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 09:09:54 INFO - PROCESS | 1671 | Values: 93.0 94.0 93.0 95.0 93.0 94.0 93.0 94.0 93.0 94.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:82.70 Median:83.00 stddev:1.25 (1.5%) stddev-sans-first:1.01 09:09:54 INFO - PROCESS | 1671 | Values: 85.0 81.0 81.0 82.0 83.0 84.0 83.0 83.0 83.0 82.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.00 stddev:1.20 (2.1%) stddev-sans-first:1.22 09:09:54 INFO - PROCESS | 1671 | Values: 57.0 56.0 54.0 54.0 56.0 56.0 57.0 57.0 57.0 57.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:81.60 Median:72.00 stddev:14.72 (20.4%) stddev-sans-first:14.64 09:09:54 INFO - PROCESS | 1671 | Values: 67.0 84.0 73.0 76.0 71.0 66.0 74.0 100.0 103.0 102.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:86.50 Median:83.00 stddev:7.20 (8.7%) stddev-sans-first:7.45 09:09:54 INFO - PROCESS | 1671 | Values: 82.0 83.0 82.0 83.0 83.0 82.0 89.0 82.0 98.0 101.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:151.70 Median:144.50 stddev:24.92 (17.2%) stddev-sans-first:26.13 09:09:54 INFO - PROCESS | 1671 | Values: 141.0 140.0 141.0 138.0 141.0 156.0 132.0 148.0 218.0 162.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.10 Median:166.50 stddev:1.79 (1.1%) stddev-sans-first:1.86 09:09:54 INFO - PROCESS | 1671 | Values: 165.0 165.0 168.0 166.0 167.0 169.0 164.0 165.0 164.0 168.0 09:09:54 INFO - PROCESS | 1671 | 09:09:54 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.85 (1.2%) stddev-sans-first:0.88 09:09:54 INFO - PROCESS | 1671 | Values: 67.0 67.0 67.0 68.0 68.0 68.0 67.0 69.0 68.0 66.0 09:09:54 INFO - PROCESS | 1671 | -------- Summary: end -------- 09:09:54 INFO - PROCESS | 1671 | 09:09:55 INFO - TEST-INFO | 1671: exit 0 09:09:55 INFO - TEST-OK | kraken | took 60353ms 09:09:55 INFO - SUITE-END | took 1179s 09:09:55 INFO - Completed test suite (00:19:40) 09:09:55 INFO - javascript benchmark 09:09:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3292.226326813199, 3311.9503207451908, 3317.137544238498, 3342.064222017836, 3382.036119705883, 5571.91642125368, 5646.378898321192, 5662.235598194243, 5673.602847800656, 5696.971515142423, 5611.831645050655, 5682.744276507557, 5705.343885453174, 5708.914366284509, 5711.143328500727, 5144.845654630367, 5159.2003239497935, 5175.974120129394, 5211.765470553815, 5240.737963101829, 5093.286939828427, 5130.179171332587, 5138.845834624967, 5260.079486089919, 5291.365036195662, 5071.873203169914, 5114.0, 5176.792928282883, 5191.922121168185, 5204.219367094928, 5184.766685499143, 5246.213068039787, 5279.52484276417, 5296.0, 5363.892722145535, 5045.672031317952, 5113.565346945514, 5186.1442861927735, 5198.844034678966, 5206.505381988705, 5482.0406428875185, 5503.11950087988, 5538.252335934638, 5550.028744969654, 5628.802991895264, 6080.878382432371, 6128.050152226412, 6129.938700612981, 6176.197094377747, 6187.628742275433, 6196.2874269459135, 6204.0, 6204.81385558434, 6208.627482351073, 6216.067589861512, 5615.438456154393, 5667.489925906688, 5686.0, 5692.288463942007, 5741.397153298927, 6176.505879529627, 6197.876042479125, 6198.287196972406, 6203.4416902479, 6214.844628884314, 6072.757089716361, 6085.66528840918, 6094.8171554853425, 6107.755689772448, 6110.419510146529, 6069.544784141119, 6071.605345652519, 6144.631322120687, 6163.106349579286, 6189.071639254148, 4938.308636790852, 6117.583823141483, 6142.140100385949, 6161.568690191733, 6181.505479561624, 6130.509559235251, 6171.47542458896, 6179.567430279927, 6181.81454556364, 6199.411055949678, 4222.873313800591, 4866.513348665105, 4914.606831453475, 4928.7289199093675, 4962.801487940515, 4314.9352759708, 4342.392065110887, 4349.695521313478, 4380.846670366522, 4396.824127034947, 5647.887042259132, 5682.062459694104, 5697.230873832063, 5702.030654788696, 5724.341700704473, 5472.2338872557875, 5872.647641141545, 6229.0, 6235.345288744707, 6250.4374606285655, 5537.252470916455, 5618.0, 5618.69097199658, 5621.269234999506, 5669.461401166883, 5327.067763141512, 5344.706041167773, 5350.6254562180275, 5351.331083614548, 5356.571474282048, 6269.12232287471, 6269.435750782452, 6275.6548389839, 6278.309385967448, 6282.811515654629, 6040.157916539733, 6144.279601711528, 6205.317415084427, 6208.875822483616, 6240.313565507699, 6263.279722831932, 6285.0, 6286.0, 6288.6855657217875, 6296.716647750771, 5943.108533719977, 6032.974394352883, 6201.534884883562, 6203.31763506005, 6205.689715514297, 6003.309619393651, 6007.579469437097, 6031.4270144336215, 6112.5946701277735, 6135.385782596045, 4528.3660287559105, 5785.305763308262, 5874.559408044328, 5882.794102206402, 5914.526837853047, 5700.629459085146, 5867.823965281048, 5878.617889837147, 5884.558658100746, 5885.73514191871, 5637.46444087811, 5713.028785106459, 5784.2769653793275, 5822.650640961556, 5833.241678581757, 6037.939620603738, 6058.939410605837, 6060.818175454743, 6061.0, 6068.575199735976, 5854.268216472941, 5858.062709966555, 5861.9413805861395, 5874.471297583238, 5910.408959104056], "value": 5611.872909902304, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.8825276060126, 515.4124298299938, 524.3236225269403, 535.2159086937636, 550.0786183143234, 29279.56080658789, 29910.70089299109, 30917.536236956465, 30948.84525577369, 30957.535636965462, 39169.21661566774, 40101.598984010154, 40135.799321003426, 41969.0, 41978.95052623686, 7351.816204594875, 7402.370798482136, 7436.739714110007, 7437.776866693981, 7442.06974128234, 9162.633494660238, 9254.67608633698, 9255.722328330161, 9257.490838003907, 9261.166495015448, 7829.765107046797, 9209.401388909733, 9272.721818345426, 9277.675281365153, 9292.0, 9195.95402022989, 9267.25861931044, 9270.443773373552, 9274.536273186348, 9274.933382660945, 9107.0, 9172.770680733034, 9176.495292758895, 9182.17360437564, 9188.081191880825, 37818.62181378206, 38277.234455311016, 38317.233655327014, 38364.46542138311, 38365.0, 3294.5717056782705, 3296.0770984124483, 3362.9831850840833, 3439.4840773883866, 3858.2669292834553, 28401.715982840113, 28731.712682873112, 29056.709432905613, 29299.0, 30158.698413016027, 2984.522476403765, 3097.225693576606, 3098.086064610945, 3105.7825952183357, 3139.309351942569, 457667.0, 460008.0, 460426.0, 460550.0, 460610.9999999966, 58773.706131469924, 59375.406245937855, 59475.70262148661, 59511.70244148751, 59519.0, 33903.49144762831, 34663.826680866434, 34739.957801266006, 61552.38447615556, 61822.69088654528, 15473.303701333241, 15738.0, 15751.763723544156, 15799.526014219591, 15802.0, 11982.940085299517, 12084.57703980374, 12095.092868034933, 12100.818487722516, 12118.272903625812, 18502.537436564195, 18512.9817860019, 18537.44387668372, 18553.44339669812, 18573.071346432625, 7447.925520744723, 7553.584552849646, 7561.584112873735, 7634.580098094658, 7658.425618078666, 810.9229623185788, 910.5765818894246, 939.1547607153616, 994.3536701144315, 1016.0905989139826, 28065.579016314772, 28117.71882281151, 29083.563746543394, 29551.965681201054, 29719.702802971693, 880.8326417980692, 920.30516959695, 1008.2488546033105, 1014.264658122855, 1039.3711804358254, 14056.789148162785, 14408.207548584718, 14690.632734181518, 14773.33519991603, 14809.481668141563, 683787.0, 775875.0, 778623.0, 973083.0, 975054.0, 53610.463895361325, 54151.18773218405, 54197.0, 54218.18672719912, 54299.0, 1238.5293588436336, 1282.6152154353656, 1285.9807102893465, 1330.9201447913156, 1368.8768010878869, 24701.135460258804, 27678.0, 29469.410611788073, 30097.849510752305, 30117.69882301149, 47156.29265561088, 47693.0, 47759.04481910412, 47768.522314777794, 47775.0, 573772.0, 579616.0, 580351.0, 580906.0, 580969.0, 24887.62668560009, 25845.836937337488, 26120.8693956529, 26137.869310653325, 26148.215553533424, 42692.78653606712, 42708.0, 42855.785721071195, 43204.78397607992, 43326.91682707958, 36077.639223607424, 36290.0, 36454.635453645125, 36661.450078248316, 37300.880973570835, 36267.09332266715, 36408.0, 36758.26483470369, 36898.63101368952, 37315.81342093272, 34807.0, 35215.64784352226, 35585.46621800623, 35597.82201088978, 35962.64037359593, 30489.390212196075, 32212.83893580517, 32265.51601726023, 32276.193095171868, 32319.676803231665, 20334.0, 24184.516309673356, 24243.51512969766, 24845.627315590642, 24886.875565622056], "value": 18922.73061846668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.71382893685046, 359.6439524870377, 368.35537808834533, 386.95936926622693, 405.7099174090525, 14633.0, 14844.92577537112, 15495.225238738074, 19307.806921930776, 20164.991750412457, 18320.725189122142, 18692.924799440167, 18807.43577692668, 19136.0, 19421.572303462068, 5383.327084114486, 5672.886542269152, 5677.318721753375, 5679.772809087632, 5688.800891968782, 4694.342792009122, 5599.860003499904, 6431.967840160793, 6480.51396145291, 6555.606663600175, 6130.203073600425, 6439.5170362223025, 6457.6771161441975, 6460.030995350711, 6467.45026672731, 6253.155823963775, 6292.150559674431, 6332.366763323676, 6336.96831515844, 6338.714757835908, 5356.491133342326, 5496.285482887239, 5506.339239291271, 5612.07400778871, 5685.317761868561, 24023.519529609486, 24170.758292417027, 24288.63567046496, 24404.511909761706, 24454.633180502307, 2023.989880050605, 2342.3792694935687, 2342.8360014798973, 2649.6555447791666, 2977.136630377188, 8388.538630375328, 9731.902680973171, 9832.950835245849, 10134.949325253401, 14672.779908301383, 1815.664102141108, 1861.6928206845855, 1878.4176905159309, 1888.6404558855422, 1924.6920492721238, 115674.0, 118168.0, 123265.38367308196, 124735.0, 125668.11497827631, 11091.334519928669, 11350.602728904449, 11465.426728663533, 11468.942655286837, 11645.184837061324, 14601.488947886774, 14608.56174314751, 14621.78067328991, 14625.707485850437, 14651.92674036623, 8038.356931445471, 8227.629756660965, 8303.211194936472, 8394.286485648789, 8406.78983025417, 8079.838403231903, 8120.75637730869, 8152.103268640445, 8266.669333226724, 8272.793180170544, 3879.6615167767077, 4342.32693932441, 4412.360207769855, 4457.064016556495, 4465.888352791206, 1892.211859792483, 1923.3843571400091, 1926.8747531410413, 1930.2279088364767, 1954.990225048894, 790.328221012135, 797.8922845415794, 855.5422848775875, 908.9912389989157, 989.9010098990043, 16612.0, 18898.81101188998, 19689.046643003665, 19908.70136947947, 19956.0, 820.7127505373072, 916.1800188830958, 919.4896832258028, 939.4598106089027, 965.2953344058876, 9868.457234852009, 10432.217583681255, 10473.423961682181, 10496.580136794442, 10579.930657753672, 596825.0158749266, 598520.0, 604607.9769601212, 608253.0, 614925.8507414958, 92047.0, 125932.37033814772, 127255.58282943272, 129374.0, 132658.0, 917.4220241247971, 929.2983797233181, 940.47333493244, 941.6892425499568, 947.7298969793576, 16950.0, 17379.47861564155, 17854.28582856723, 17859.553511162325, 18100.366487172887, 53220.0, 53235.46764532305, 54279.45720542744, 54619.0, 55321.72339138279, 71113.22216944411, 73938.0, 74258.51482970202, 76006.0, 77037.61481192558, 6616.636085015371, 7773.455858089879, 7802.921970780219, 8023.598820058857, 8087.676492940216, 9639.518024098626, 10127.7468063299, 11989.700257493632, 12060.879391205975, 12406.937965310115, 10115.544800483849, 10247.436390998268, 10333.793324133625, 10568.313059651406, 10597.0, 9418.293627978066, 9690.95154524223, 10236.641717539851, 10272.743181420525, 10663.360198388122, 10521.842172367573, 10674.252802303765, 10779.622713205, 11005.724856878642, 11019.504122314354, 7660.616969151632, 7813.296803287732, 7847.2152784721075, 8002.359811214972, 8055.154208808225, 9918.553665084944, 10399.792004159723, 10511.054005139575, 10514.737131571772, 10737.0], "value": 9300.827835395807, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1192.379962419542, 1306.2358520265643, 1450.4343306110618, 1558.9220538973047, 1828.5463056869796, 7462.141853686828, 7819.491733037348, 8025.277725004741, 8277.461964972288, 8300.626471808768, 8012.839743205132, 8100.635471403786, 8202.794930126749, 8203.384746144047, 8216.876746848788, 2035.8065983731522, 2269.2965180793926, 2287.736910255317, 2288.9542209155807, 2293.6444851048113, 2158.201465457783, 2198.428408613764, 2208.8012078912893, 2215.2246713650175, 2218.3234113595345, 2211.8672879627193, 2237.071615279657, 2275.954480910381, 2305.561943230789, 2307.4116100394376, 2245.73051233853, 2262.751097379295, 2276.8064714499287, 2287.8970446329954, 2288.725352957639, 2287.3824067501682, 2306.1006207579057, 2306.7577904319974, 2310.9306720798236, 2315.0855412112123, 8038.477498962548, 8039.236272554098, 8188.14024527428, 8204.974378202725, 8343.791405214857, 5086.0, 5201.245819356172, 5211.114110601206, 5212.635115541913, 5236.947630523684, 3389.406853800575, 3517.982410087933, 3539.3275277697153, 3595.2449985503076, 3606.6934310583633, 1039.449091981251, 1045.6862941117633, 1074.1943542343242, 1077.3643550305278, 1079.3901445683243, 10722.195835312305, 10762.192835537287, 10813.97267259617, 10822.945885270523, 10823.945880270627, 4281.529031806499, 4286.249906266391, 4423.1153769246275, 4449.755263460476, 4466.6203372713035, 8453.408261421642, 8463.66145354179, 8569.657213711507, 8587.184217499327, 8614.396992210484, 5051.1413059779925, 5051.898962020739, 5055.16589762685, 5055.570276526537, 5058.215976523645, 3835.5780864104927, 4248.532661407243, 4661.930071048937, 4701.694389864716, 4750.453697824794, 3599.784012959231, 3602.9819850900576, 3670.853165873335, 3694.242680250542, 3698.4082546792383, 1135.8920902514249, 1140.988590114105, 1144.553624086607, 1155.1971379891063, 1156.0809156720427, 607.9635221886701, 612.595686846688, 753.6834529497625, 766.1840140250615, 889.4440974391006, 5139.152039913372, 5307.124324486416, 5386.407495175527, 5479.150731636602, 5553.50018498329, 848.6223630484499, 853.2619284319019, 867.0679020053342, 879.9780005499786, 949.9192568631605, 3512.0, 3628.2924829657964, 3667.3032123896373, 3669.449582562637, 3748.4189950557707, 8563.743087707255, 10052.597896084075, 10156.0, 10171.949140254252, 10173.186145108373, 10116.544755485873, 10130.290879638502, 10130.645427410005, 10137.847932281022, 10140.949295253477, 7634.160242373335, 7801.4929029612895, 7803.0, 7805.94619726341, 7811.101723301779, 4858.149823780754, 5023.497650234947, 5067.746612669278, 5196.870078248074, 5253.921191182212, 8199.467034642728, 8274.793130171794, 8306.584670766559, 8358.498490090615, 8419.70531031411, 8038.517688938682, 8284.378671599534, 8382.994040715153, 8509.978802543734, 8536.359773017173, 6627.304133065959, 6717.664116794043, 6742.426893713893, 6784.185897692309, 6804.285550017177, 7745.612719364122, 7844.21557844211, 7849.136594974662, 7857.607119643881, 7857.685692572461, 6907.34380233877, 7047.295270472911, 7101.609411482418, 7127.287271272831, 7139.428845692229, 6933.618650974245, 6980.965095174694, 7010.298970102949, 7014.228434872262, 7045.682944267418, 6473.546851720334, 6541.509386796104, 6607.933920660732, 6610.3720146586, 6610.933890661224, 3893.7858417787293, 4593.517680643485, 4622.006268652121, 4635.559621836055, 4760.9285860711425, 4572.4055872736335, 4694.2958556216845, 4709.858704238878, 4715.127701375257, 4822.254421309643], "value": 4249.391200552115, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.11172042474703, 305.0862666314389, 310.7436364998876, 316.6311247396783, 317.4729948285846, 4491.169133710266, 5182.870428239286, 5215.217717342401, 5245.790168393264, 5272.947270527294, 4352.434183556141, 4517.164324599951, 4627.8148874045, 4812.28106120042, 5062.468440813716, 3726.4037753959374, 3772.3964165733487, 3965.6827453803626, 3977.5425826030087, 4050.5393896734663, 3303.6035675718826, 4000.8399664013405, 4009.3184158693093, 4011.7191796574252, 4019.577944315834, 3227.887023954162, 3232.4020056289546, 3467.4972129041275, 3982.402639604068, 3982.800859957005, 3267.39553182662, 3443.7946718648273, 3682.4660424238605, 3965.0373065332556, 3986.166363472806, 3423.2126610879386, 3441.9483707744403, 3442.7762195457467, 3446.362422951762, 3466.8093254870973, 4544.136903869397, 5002.923202230639, 5005.799768009275, 5065.0, 5080.288759573663, 4064.6748260139416, 4234.343676730112, 4438.578335058164, 4547.8635640930825, 4558.680892337537, 5420.620556561043, 5472.480114389166, 5516.475934786189, 5532.917006244909, 5534.418886016951, 3776.7356285060055, 3777.395616701315, 3781.6764132553385, 3786.7917264550438, 3798.3352913240074, 4624.560666736655, 5278.445763194848, 5711.428857114297, 5753.382211093942, 5779.624324418899, 3555.928881422357, 3786.6024067473068, 3796.563395209531, 3800.410936304877, 3815.809209539512, 3252.609686837547, 3572.99956012317, 3754.8597299539, 3892.357760969465, 3930.449737036817, 3435.845386957593, 3844.8077596120083, 4213.408753616503, 4538.682292239511, 4545.886352841206, 4800.207965685692, 5471.537807986422, 5488.396276409591, 5497.835064947978, 5544.16837474374, 1017.7353887989176, 1240.7146356338092, 1250.87491250876, 1251.7371352015996, 1254.1158483269455, 812.4069429316633, 814.4665244265016, 833.4374297349265, 866.6360128745941, 868.6047848229041, 4816.903661926742, 4824.49342819006, 4824.951750482521, 4865.5864251538305, 4868.977514721877, 5264.710440925785, 5387.353517577916, 5394.78420863169, 5405.405405405402, 5433.972830135824, 4003.5796241394232, 4459.353393757887, 4469.4580369772375, 4586.528135068348, 4848.878778030577, 2725.1196674294674, 2728.877200525981, 2743.190758726181, 2755.4626847764894, 2762.941608501679, 4324.2054547362495, 4880.633952453581, 5364.651297665718, 5449.528627270694, 5514.476124768141, 4774.570288674036, 5031.0, 5297.549708274762, 5306.0, 5330.680159190461, 4890.315355850184, 5189.676632458699, 5406.432324605859, 5448.482394172547, 5485.803360429275, 4471.792615993599, 4877.0, 4926.605871530197, 5172.301739265151, 5290.338707661543, 4764.690295130806, 5068.746562671778, 5170.353705786777, 5245.7114858683135, 5252.448492908343, 4774.069056533943, 5061.722224998692, 5109.795608175631, 5110.696772323034, 5467.617266791445, 3194.8977602727023, 3254.1864533866533, 3514.3674138655288, 3603.6396360363756, 3627.202015556679, 4436.689431739747, 4758.2386818108935, 4796.2349555598685, 4801.975990120027, 4953.380827396575, 4565.927007153383, 4691.648126390466, 4794.304825800309, 4868.9756551217015, 5009.7244651544515, 4681.752758675922, 5028.572571331478, 5073.695578265316, 5103.045140016368, 5104.967076009911, 3973.8609148679657, 4535.501094879494, 4741.620670346433, 4977.303177555073, 5281.943611277837, 4922.606191504743, 5301.5493683037375, 5320.680759154463, 5331.093714068696, 5484.314460692413, 4338.915271182204, 5174.663646862942, 5368.758405871667, 5377.848860455849, 5448.782048718165], "value": 3838.930971260389, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [632.7532262417657, 691.3397705191543, 769.5844244108182, 777.0208247535219, 778.7079845058099, 14111.223882686449, 14540.49108281209, 15623.21883905803, 16082.678346433064, 16328.34686612537, 14623.853761462382, 14888.925555372234, 14980.400783968627, 15120.0, 15128.924355378234, 800.9239122283388, 823.5223570329194, 824.1634740738126, 824.5135370131621, 825.471698113208, 642.0337392224695, 669.6283562622742, 671.684308375063, 769.9422543309246, 819.7827575692446, 862.965481380744, 876.2289185516736, 878.9736307910773, 879.7316818370394, 880.067128843428, 863.4689665855517, 863.8661007543809, 865.1262225152578, 867.4665080975218, 869.4479005831342, 501.8444282272471, 520.9817656382008, 597.9312379076418, 624.0452108274347, 644.8355669304344, 14211.715765684628, 14255.14469131845, 14269.072510286902, 14281.857181428157, 14283.642908927257, 12450.190737602026, 12576.182548134342, 12655.810113108495, 12749.490020399173, 12895.098602814292, 11148.832767508495, 11299.322040677585, 11442.199046066778, 11574.826377604342, 11656.358900260482, 3533.664301891342, 3599.1002249437643, 3613.0, 3786.6024067473068, 3787.507624008889, 78313.04217394553, 86324.56837715834, 91055.08944910599, 91828.0, 91952.54023729905, 10081.546330415149, 10185.236107291983, 10531.315464494783, 10532.262741608014, 10724.889098163521, 19840.007999599962, 19989.600207995758, 20023.499412514804, 20070.09684564198, 20312.69530957037, 7591.696332146652, 7637.885431718529, 7648.694052237848, 7856.5678887661725, 7889.605519723991, 4160.729552579072, 4168.8332466700995, 4392.912141757147, 4399.38408622793, 4423.358613001097, 3379.172102834821, 3487.215376540309, 3488.2674638326234, 3495.143689796016, 3497.7026952708798, 646.7930262316014, 647.4626060369976, 652.8106849013827, 667.372669690499, 675.4056430341268, 549.9065158922992, 563.4956713741178, 571.3372487913999, 601.8194541637491, 602.5390576209204, 8777.385583009129, 8884.73345799627, 8965.596548155218, 9041.925745246648, 9198.172164505228, 575.2119596153296, 602.1569802276847, 620.5004970998299, 650.5218664281749, 670.580886945659, 4045.696572757055, 4369.541198174146, 4421.226285400042, 4446.888827779267, 4526.864194074183, 89423.76440589037, 116527.0, 116542.41728791302, 116549.0, 127248.0, 77139.61430192813, 101857.98142018485, 102113.0, 105121.32075377325, 108058.0, 91859.54070229606, 91878.0, 92264.53867730619, 93426.53286733659, 95493.5225323869, 8608.526531040852, 8619.82760344802, 8632.223099921037, 8769.605367758339, 8875.822483550422, 55918.0, 57012.71493642505, 57510.0, 57726.422735773784, 57876.0, 67122.0, 78577.0, 80250.0, 83074.58462707647, 83471.0, 10831.512581933674, 10869.891301086887, 10917.508712107816, 10974.341539507655, 11468.197226194086, 9938.0, 11131.499082541142, 11375.43122843871, 11662.416879155839, 11847.348395838311, 11507.194496385173, 11590.536378544764, 11608.477618507357, 11661.416929153338, 11854.288742675468, 10687.679369618923, 12160.391980400767, 12898.226106433644, 12907.806382904077, 13378.063535552807, 12184.451699673358, 12521.937390313355, 12618.242905425705, 12728.87271127314, 12924.741505169655, 9259.0, 10632.84050739255, 10860.945695271474, 11018.173636977099, 11384.658460246206, 10675.89324106749, 11103.444827758742, 11120.555177792798, 11152.330860148417, 11252.26860254081], "value": 7189.087475878112, "unit": "score"}], "name": "dromaeo_css", "value": 6981.985276987699, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 114.0, 113.0, 113.0, 114.0, 114.0, 117.0, 161.0, 168.0, 112.0], "value": 124.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 114.0, 142.0, 162.0, 110.0, 113.0, 138.0, 109.0, 141.0, 111.0], "value": 127.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 183.0, 185.0, 182.0, 181.0, 181.0, 182.0, 183.0, 185.0, 184.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 59.0, 89.0, 62.0, 60.0, 71.0, 84.0, 110.0, 77.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 95.0, 70.0, 68.0, 69.0, 89.0, 67.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 86.0, 83.0, 88.0, 84.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 93.0, 95.0, 93.0, 94.0, 93.0, 94.0, 93.0, 94.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 81.0, 81.0, 82.0, 83.0, 84.0, 83.0, 83.0, 83.0, 82.0], "value": 82.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 56.0, 54.0, 54.0, 56.0, 56.0, 57.0, 57.0, 57.0, 57.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 84.0, 73.0, 76.0, 71.0, 66.0, 74.0, 100.0, 103.0, 102.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 83.0, 82.0, 83.0, 83.0, 82.0, 89.0, 82.0, 98.0, 101.0], "value": 86.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 141.0, 138.0, 141.0, 156.0, 132.0, 148.0, 218.0, 162.0], "value": 151.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 165.0, 168.0, 166.0, 167.0, 169.0, 164.0, 165.0, 164.0, 168.0], "value": 166.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 68.0, 68.0, 67.0, 69.0, 68.0, 66.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1459.1000000000001, "alertThreshold": 2.0}]} 09:09:55 INFO - Return code: 0 09:09:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:09:55 INFO - # TBPL SUCCESS # 09:09:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:09:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:09:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:09:55 INFO - 2017-01-09 09:09:55.945 screenresolution[1675:11067] starting screenresolution argv=screenresolution get 09:09:55 INFO - 2017-01-09 09:09:55.948 screenresolution[1675:11067] Display 0: 1600x1200x32@60 09:09:55 INFO - 2017-01-09 09:09:55.954 screenresolution[1676:11070] starting screenresolution argv=screenresolution list 09:09:55 INFO - Available Modes on Display 0 09:09:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:09:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:09:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:09:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:09:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:09:56 INFO - Intel Iris: 09:09:56 INFO - Chipset Model: Intel Iris 09:09:56 INFO - Type: GPU 09:09:56 INFO - Bus: Built-In 09:09:56 INFO - VRAM (Dynamic, Max): 1536 MB 09:09:56 INFO - Vendor: Intel (0x8086) 09:09:56 INFO - Device ID: 0x0a2e 09:09:56 INFO - Revision ID: 0x0009 09:09:56 INFO - Displays: 09:09:56 INFO - Display: 09:09:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:09:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:09:56 INFO - Display Serial Number: 1600x1200 60 09:09:56 INFO - Main Display: Yes 09:09:56 INFO - Mirror: Off 09:09:56 INFO - Online: Yes 09:09:56 INFO - Rotation: Supported 09:09:56 INFO - Return code: 0 09:09:56 INFO - Running post-action listener: _resource_record_post_action 09:09:56 INFO - [mozharness: 2017-01-09 17:09:56.187912Z] Finished run-tests step (success) 09:09:56 INFO - Running post-run listener: _resource_record_post_run 09:09:56 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 33377280; Write bytes: 660814336; Read time: 313; Write time: 2588 09:09:56 INFO - TinderboxPrint: CPU usage
53.4% 09:09:56 INFO - TinderboxPrint: I/O read bytes / time
33,377,280 / 313 09:09:56 INFO - TinderboxPrint: I/O write bytes / time
660,814,336 / 2,588 09:09:56 INFO - TinderboxPrint: CPU idle
2,258.4 (46.6%) 09:09:56 INFO - TinderboxPrint: CPU system
969.1 (20.0%) 09:09:56 INFO - TinderboxPrint: CPU user
1,622.2 (33.4%) 09:09:56 INFO - TinderboxPrint: Swap in / out
379,297,792 / 0 09:09:56 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 334658048; Write bytes: 333629440; Read time: 26179; Write time: 679 09:09:56 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 32443392; Write bytes: 309481984; Read time: 299; Write time: 1857 09:09:56 INFO - Running post-run listener: _upload_blobber_files 09:09:56 INFO - Blob upload gear active. 09:09:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:09:56 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:09:56 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:09:56 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:09:57 INFO - (blobuploader) - INFO - Open directory for files ... 09:09:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:09:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:09:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:58 INFO - (blobuploader) - INFO - Done attempting. 09:09:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:09:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:09:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:09:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:09:59 INFO - (blobuploader) - INFO - Done attempting. 09:09:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:09:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:09:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:10:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:10:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:10:01 INFO - (blobuploader) - INFO - Done attempting. 09:10:01 INFO - (blobuploader) - INFO - Iteration through files over. 09:10:01 INFO - Return code: 0 09:10:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:10:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:10:01 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69a818ab059f89f651836ceb1d5c3365bec7daad54f0869e09c8047ca5318d71ba7650ffb8a987b00d448f2c6ceff8c98f91bd982a9b5f569d4187300e0d17e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf952e71e27e54e4112c85fa94df05e77edddc5fd30aa5854e2aed1e8bffda6437ac625dd2fd0403fe1067ebe4ba22584923f941f519ca9a20ca5ab94d8361f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/290afc35fb18fcf3dff3c49aeec76e7b2f55a416bcbe1c17c1067ba14334203da3a6aa05767882cfd0c57c48dc8fa4fde86750402fbd09781d14f745a1f567ae"} 09:10:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:10:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:10:01 INFO - Contents: 09:10:01 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/69a818ab059f89f651836ceb1d5c3365bec7daad54f0869e09c8047ca5318d71ba7650ffb8a987b00d448f2c6ceff8c98f91bd982a9b5f569d4187300e0d17e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf952e71e27e54e4112c85fa94df05e77edddc5fd30aa5854e2aed1e8bffda6437ac625dd2fd0403fe1067ebe4ba22584923f941f519ca9a20ca5ab94d8361f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/290afc35fb18fcf3dff3c49aeec76e7b2f55a416bcbe1c17c1067ba14334203da3a6aa05767882cfd0c57c48dc8fa4fde86750402fbd09781d14f745a1f567ae"} 09:10:01 INFO - Running post-run listener: copy_logs_to_upload_dir 09:10:01 INFO - Copying logs to upload dir... 09:10:01 INFO - mkdir: /builds/slave/test/build/upload/logs 09:10:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.784478 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-09 09:10:01.589233) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.592570) ========= 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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/69a818ab059f89f651836ceb1d5c3365bec7daad54f0869e09c8047ca5318d71ba7650ffb8a987b00d448f2c6ceff8c98f91bd982a9b5f569d4187300e0d17e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf952e71e27e54e4112c85fa94df05e77edddc5fd30aa5854e2aed1e8bffda6437ac625dd2fd0403fe1067ebe4ba22584923f941f519ca9a20ca5ab94d8361f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/290afc35fb18fcf3dff3c49aeec76e7b2f55a416bcbe1c17c1067ba14334203da3a6aa05767882cfd0c57c48dc8fa4fde86750402fbd09781d14f745a1f567ae"} build_url:https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011339 build_url: 'https://queue.taskcluster.net/v1/task/Q33rbwRTQLGLLz7lDNWMtA/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/69a818ab059f89f651836ceb1d5c3365bec7daad54f0869e09c8047ca5318d71ba7650ffb8a987b00d448f2c6ceff8c98f91bd982a9b5f569d4187300e0d17e8", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cf952e71e27e54e4112c85fa94df05e77edddc5fd30aa5854e2aed1e8bffda6437ac625dd2fd0403fe1067ebe4ba22584923f941f519ca9a20ca5ab94d8361f6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/290afc35fb18fcf3dff3c49aeec76e7b2f55a416bcbe1c17c1067ba14334203da3a6aa05767882cfd0c57c48dc8fa4fde86750402fbd09781d14f745a1f567ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.628239) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.628632) ========= 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.Gk74QxLuWb/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.gb5NPLI9Ix/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005229 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-09 09:10:01.678811) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:10:01.679342) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-09 09:10:01.679835) ========= ========= Total master_lag: 0.26 =========