builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0007 starttime: 1484761654.21 results: success (0) buildid: 20170118070027 builduid: 436fa47a262a452092f35e89ab1634db revision: 27643a39e551670484fe7dd5836aaa01d66b9a7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.211633) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.212101) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.212429) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.239582) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.240022) ========= 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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-18 09:47:34-- 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% 27.9M=0s 2017-01-18 09:47:34 (27.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.130249 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.394645) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.394957) ========= 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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.028733 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:34.445105) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 09:47:34.445465) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 27643a39e551670484fe7dd5836aaa01d66b9a7b --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 27643a39e551670484fe7dd5836aaa01d66b9a7b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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-18 09:47:34,539 truncating revision to first 12 chars 2017-01-18 09:47:34,539 Setting DEBUG logging. 2017-01-18 09:47:34,539 attempt 1/10 2017-01-18 09:47:34,539 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/27643a39e551?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-18 09:47:35,611 unpacking tar archive at: mozilla-aurora-27643a39e551/testing/mozharness/ program finished with exit code 0 elapsedTime=1.336205 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-18 09:47:35.805186) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:35.805521) ========= script_repo_revision: 27643a39e551670484fe7dd5836aaa01d66b9a7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:35.805909) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:35.806234) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:35.825814) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-18 09:47:35.826136) ========= /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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:47:36 INFO - MultiFileLogger online at 20170118 09:47:36 in /builds/slave/test 09:47:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:47:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:47:36 INFO - {'append_to_log': False, 09:47:36 INFO - 'base_work_dir': '/builds/slave/test', 09:47:36 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:47:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:47:36 INFO - 'branch': 'Mozilla-Aurora', 09:47:36 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:36 INFO - 'config_files': ('talos/mac_config.py',), 09:47:36 INFO - 'default_actions': ('clobber', 09:47:36 INFO - 'read-buildbot-config', 09:47:36 INFO - 'download-and-extract', 09:47:36 INFO - 'populate-webroot', 09:47:36 INFO - 'create-virtualenv', 09:47:36 INFO - 'install', 09:47:36 INFO - 'run-tests'), 09:47:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:36 INFO - 'download_minidump_stackwalk': True, 09:47:36 INFO - 'download_symbols': 'ondemand', 09:47:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:36 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:36 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:36 INFO - 'installer_path': 'installer.exe', 09:47:36 INFO - 'log_level': 'info', 09:47:36 INFO - 'log_name': 'talos', 09:47:36 INFO - 'log_to_console': True, 09:47:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:47:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:47:36 INFO - 'opt_config_files': (), 09:47:36 INFO - 'pip_index': False, 09:47:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:36 INFO - 'cmd': ('bash', 09:47:36 INFO - '-c', 09:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:36 INFO - 'enabled': True, 09:47:36 INFO - 'halt_on_failure': False, 09:47:36 INFO - 'name': 'check_screen_resolution'},), 09:47:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:36 INFO - 'cmd': ('bash', 09:47:36 INFO - '-c', 09:47:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:36 INFO - 'enabled': True, 09:47:36 INFO - 'halt_on_failure': False, 09:47:36 INFO - 'name': 'check_screen_resolution'},), 09:47:36 INFO - 'run_cmd_checks_enabled': True, 09:47:36 INFO - 'sps_profile': False, 09:47:36 INFO - 'sps_profile_interval': 0, 09:47:36 INFO - 'suite': 'dromaeojs', 09:47:36 INFO - 'system_bits': '32', 09:47:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:36 INFO - 'title': 't-yosemite-r7-0007', 09:47:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:36 INFO - 'use_talos_json': True, 09:47:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:47:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:36 INFO - 'work_dir': 'build'} 09:47:36 INFO - [mozharness: 2017-01-18 17:47:36.033355Z] Running clobber step. 09:47:36 INFO - Running pre-action listener: _resource_record_pre_action 09:47:36 INFO - Running main action method: clobber 09:47:36 INFO - rmtree: /builds/slave/test/build 09:47:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:47:37 INFO - Running post-action listener: _resource_record_post_action 09:47:37 INFO - [mozharness: 2017-01-18 17:47:37.481703Z] Finished clobber step (success) 09:47:37 INFO - [mozharness: 2017-01-18 17:47:37.481832Z] Running read-buildbot-config step. 09:47:37 INFO - Running pre-action listener: _resource_record_pre_action 09:47:37 INFO - Running main action method: read_buildbot_config 09:47:37 INFO - Using buildbot properties: 09:47:37 INFO - { 09:47:37 INFO - "project": "", 09:47:37 INFO - "product": "firefox", 09:47:37 INFO - "script_repo_revision": "production", 09:47:37 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:47:37 INFO - "repository": "", 09:47:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:47:37 INFO - "buildid": "20170118070027", 09:47:37 INFO - "pgo_build": "False", 09:47:37 INFO - "basedir": "/builds/slave/test", 09:47:37 INFO - "buildnumber": 36, 09:47:37 INFO - "slavename": "t-yosemite-r7-0007", 09:47:37 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 09:47:37 INFO - "platform": "yosemite_r7", 09:47:37 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:47:37 INFO - "branch": "mozilla-aurora", 09:47:37 INFO - "repo_path": "releases/mozilla-aurora", 09:47:37 INFO - "revision": "27643a39e551670484fe7dd5836aaa01d66b9a7b", 09:47:37 INFO - "stage_platform": "macosx64", 09:47:37 INFO - "builduid": "436fa47a262a452092f35e89ab1634db", 09:47:37 INFO - "slavebuilddir": "test" 09:47:37 INFO - } 09:47:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:47:37 INFO - Running post-action listener: _resource_record_post_action 09:47:37 INFO - [mozharness: 2017-01-18 17:47:37.485006Z] Finished read-buildbot-config step (success) 09:47:37 INFO - [mozharness: 2017-01-18 17:47:37.485130Z] Running download-and-extract step. 09:47:37 INFO - Running pre-action listener: _resource_record_pre_action 09:47:37 INFO - Running main action method: download_and_extract 09:47:37 INFO - mkdir: /builds/slave/test/build/tests 09:47: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')]} 09:47:37 INFO - trying https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:37 INFO - Downloading https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:47:38 INFO - Downloaded 1199 bytes. 09:47:38 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:38 INFO - Using the following test package requirements: 09:47:38 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:38 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:47:38 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:47:38 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'jsshell-mac.zip'], 09:47:38 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:47:38 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:38 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:47:38 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:47:38 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:47:38 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:38 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:47:38 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:47:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:47:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:47:38 INFO - Fetch https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:47:39 INFO - Http code: 200 09:47:39 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 09:47:39 INFO - x-amz-cf-id: PI7DwGSvze3obJdA3zZSjG9PcbSI9-ZplPVSGjNmIELc5LsZlvybZw== 09:47:39 INFO - x-amz-version-id: 7mkAvysHwHLRpUONwQpNb5vFrjcsgGa6 09:47:39 INFO - Expected file size: 16276157 09:47:39 INFO - Obtained file size: 16276157 09:47:46 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:47:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:47:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:47:46 INFO - Fetch https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:47:46 INFO - Http code: 200 09:47:46 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 09:47:46 INFO - x-amz-cf-id: HwkL8c1unP1mNuG0ZdbnoiV-ULqL776gfwcCJaekb1xnPXYuvtCuPw== 09:47:46 INFO - x-amz-version-id: 7mkAvysHwHLRpUONwQpNb5vFrjcsgGa6 09:47:47 INFO - Expected file size: 16276157 09:47:47 INFO - Obtained file size: 16276157 09:47:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:47:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:47:53 INFO - Fetch https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:47:54 INFO - Http code: 200 09:47:54 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 09:47:54 INFO - x-amz-cf-id: cJMtrkoVQ-tDmVcV8rC4INNAB9cB2FeLmBHYVpoG97UDzOHlLOOrgQ== 09:47:54 INFO - x-amz-version-id: L1S054.rNu69y7luN94DHfFFHuQO4yFw 09:47:55 INFO - Expected file size: 14409038 09:47:55 INFO - Obtained file size: 14409038 09:47:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:55 INFO - trying https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:47:55 INFO - Downloading https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:47:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:47:58 INFO - Downloaded 110008246 bytes. 09:47:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:47:58 INFO - mkdir: /builds/slave/test/properties 09:47:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:47:58 INFO - Writing to file /builds/slave/test/properties/build_url 09:47:58 INFO - Contents: 09:47:58 INFO - build_url:https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:47:58 INFO - Running post-action listener: _resource_record_post_action 09:47:58 INFO - Running post-action listener: set_extra_try_arguments 09:47:58 INFO - [mozharness: 2017-01-18 17:47:58.978705Z] Finished download-and-extract step (success) 09:47:58 INFO - [mozharness: 2017-01-18 17:47:58.978832Z] Running populate-webroot step. 09:47:58 INFO - Running pre-action listener: _resource_record_pre_action 09:47:58 INFO - Running main action method: populate_webroot 09:47:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:47:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:47:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:47:58 INFO - '--tppagecycles', 09:47:58 INFO - '7'], 09:47:58 INFO - 'tests': ['tsvgm']}}, 09:47:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:47:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:47:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:47:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:47:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:47:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['damp', 'tps']}, 09:47:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'tests': ['damp', 'tps']}, 09:47:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['dromaeo_dom']}, 09:47:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:47:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['basic_compositor_video']}, 09:47:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:47:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'other-e10s': {'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['a11yr', 09:47:58 INFO - 'ts_paint', 09:47:58 INFO - 'tpaint', 09:47:58 INFO - 'sessionrestore', 09:47:58 INFO - 'sessionrestore_no_auto_restore', 09:47:58 INFO - 'tabpaint']}, 09:47:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['tsvgx', 09:47:58 INFO - 'tsvgr_opacity', 09:47:58 INFO - 'tart', 09:47:58 INFO - 'tscrollx', 09:47:58 INFO - 'cart']}, 09:47:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:47:58 INFO - 'tsvgr_opacity', 09:47:58 INFO - 'tart', 09:47:58 INFO - 'tscrollx', 09:47:58 INFO - 'cart']}, 09:47:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'talos_options': ['--disable-e10s'], 09:47:58 INFO - 'tests': ['tp5o']}, 09:47:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'tests': ['tp5o']}, 09:47:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'talos_options': ['--disable-e10s', 09:47:58 INFO - '--xperf_path', 09:47:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:58 INFO - 'tests': ['tp5n']}, 09:47:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:47:58 INFO - 'talos_options': ['--xperf_path', 09:47:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:47:58 INFO - 'tests': ['tp5n']}}, 09:47:58 INFO - 'talos.zip': {'path': '', 09:47:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:47:58 INFO - Running post-action listener: _resource_record_post_action 09:47:58 INFO - [mozharness: 2017-01-18 17:47:58.997378Z] Finished populate-webroot step (success) 09:47:58 INFO - [mozharness: 2017-01-18 17:47:58.997516Z] Running create-virtualenv step. 09:47:58 INFO - Running pre-action listener: _resource_record_pre_action 09:47:58 INFO - Running main action method: create_virtualenv 09:47:58 INFO - Creating virtualenv /builds/slave/test/build/venv 09:47:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:47:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:47:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:47:59 INFO - Using real prefix '/tools/python27' 09:47:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:47:59 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:02 INFO - Installing pip.................done. 09:48:02 INFO - Return code: 0 09:48:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:03 INFO - Reading from file tmpfile_stdout 09:48:03 INFO - Output received: 09:48:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:48:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:03 INFO - 'HOME': '/Users/cltbld', 09:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:03 INFO - 'LOGNAME': 'cltbld', 09:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:03 INFO - 'MOZ_NO_REMOTE': '1', 09:48:03 INFO - 'NO_EM_RESTART': '1', 09:48:03 INFO - 'PAGER': '/bin/cat', 09:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:03 INFO - 'PWD': '/builds/slave/test', 09:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:03 INFO - 'SHELL': '/bin/bash', 09:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:03 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:03 INFO - 'USER': 'cltbld', 09:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:03 INFO - 'XPC_FLAGS': '0x0', 09:48:03 INFO - 'XPC_SERVICE_NAME': '0', 09:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:48:03 INFO - Cleaning up... 09:48:03 INFO - Return code: 0 09:48:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:48:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:03 INFO - 'HOME': '/Users/cltbld', 09:48:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:03 INFO - 'LOGNAME': 'cltbld', 09:48:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:03 INFO - 'MOZ_NO_REMOTE': '1', 09:48:03 INFO - 'NO_EM_RESTART': '1', 09:48:03 INFO - 'PAGER': '/bin/cat', 09:48:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:03 INFO - 'PWD': '/builds/slave/test', 09:48:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:03 INFO - 'SHELL': '/bin/bash', 09:48:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:03 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:03 INFO - 'USER': 'cltbld', 09:48:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:03 INFO - 'XPC_FLAGS': '0x0', 09:48:03 INFO - 'XPC_SERVICE_NAME': '0', 09:48:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:03 INFO - Downloading/unpacking psutil>=3.1.1 09:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:07 INFO - Installing collected packages: psutil 09:48:07 INFO - Running setup.py install for psutil 09:48:07 INFO - building 'psutil._psutil_osx' extension 09:48:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:48:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:48:07 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:48:07 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:48:08 INFO - building 'psutil._psutil_posix' extension 09:48:08 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:48:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:08 INFO - ^ 09:48:08 INFO - 1 warning generated. 09:48:08 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:48:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:08 INFO - Successfully installed psutil 09:48:08 INFO - Cleaning up... 09:48:08 INFO - Return code: 0 09:48:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:08 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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:48:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:08 INFO - 'HOME': '/Users/cltbld', 09:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:08 INFO - 'LOGNAME': 'cltbld', 09:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:08 INFO - 'MOZ_NO_REMOTE': '1', 09:48:08 INFO - 'NO_EM_RESTART': '1', 09:48:08 INFO - 'PAGER': '/bin/cat', 09:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:08 INFO - 'PWD': '/builds/slave/test', 09:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:08 INFO - 'SHELL': '/bin/bash', 09:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:08 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:08 INFO - 'USER': 'cltbld', 09:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:08 INFO - 'XPC_FLAGS': '0x0', 09:48:08 INFO - 'XPC_SERVICE_NAME': '0', 09:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:48:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:48:11 INFO - Installing collected packages: mozsystemmonitor 09:48:11 INFO - Running setup.py install for mozsystemmonitor 09:48:11 INFO - Successfully installed mozsystemmonitor 09:48:11 INFO - Cleaning up... 09:48:11 INFO - Return code: 0 09:48:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:48:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:11 INFO - 'HOME': '/Users/cltbld', 09:48:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:11 INFO - 'LOGNAME': 'cltbld', 09:48:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:11 INFO - 'MOZ_NO_REMOTE': '1', 09:48:11 INFO - 'NO_EM_RESTART': '1', 09:48:11 INFO - 'PAGER': '/bin/cat', 09:48:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:11 INFO - 'PWD': '/builds/slave/test', 09:48:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:11 INFO - 'SHELL': '/bin/bash', 09:48:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:11 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:11 INFO - 'USER': 'cltbld', 09:48:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:11 INFO - 'XPC_FLAGS': '0x0', 09:48:11 INFO - 'XPC_SERVICE_NAME': '0', 09:48:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:11 INFO - Downloading/unpacking jsonschema==2.5.1 09:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:48:14 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48: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 09:48:14 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:15 INFO - warning: no files found matching '*.txt' 09:48:15 INFO - no previously-included directories found matching 'build' 09:48:15 INFO - no previously-included directories found matching 'dist' 09:48:15 INFO - no previously-included directories found matching '.git*' 09:48:15 INFO - Installing collected packages: jsonschema, functools32 09:48:15 INFO - Running setup.py install for functools32 09:48:15 INFO - warning: no files found matching '*.txt' 09:48:15 INFO - no previously-included directories found matching 'build' 09:48:15 INFO - no previously-included directories found matching 'dist' 09:48:15 INFO - no previously-included directories found matching '.git*' 09:48:15 INFO - Successfully installed jsonschema functools32 09:48:15 INFO - Cleaning up... 09:48:15 INFO - Return code: 0 09:48:15 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:15 INFO - 'HOME': '/Users/cltbld', 09:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:15 INFO - 'LOGNAME': 'cltbld', 09:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:15 INFO - 'MOZ_NO_REMOTE': '1', 09:48:15 INFO - 'NO_EM_RESTART': '1', 09:48:15 INFO - 'PAGER': '/bin/cat', 09:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:15 INFO - 'PWD': '/builds/slave/test', 09:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:15 INFO - 'SHELL': '/bin/bash', 09:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:15 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:15 INFO - 'USER': 'cltbld', 09:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:15 INFO - 'XPC_FLAGS': '0x0', 09:48:15 INFO - 'XPC_SERVICE_NAME': '0', 09:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:15 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:15 INFO - Cleaning up... 09:48:15 INFO - Return code: 0 09:48:15 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:48:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:48:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:15 INFO - 'HOME': '/Users/cltbld', 09:48:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:15 INFO - 'LOGNAME': 'cltbld', 09:48:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:15 INFO - 'MOZ_NO_REMOTE': '1', 09:48:15 INFO - 'NO_EM_RESTART': '1', 09:48:15 INFO - 'PAGER': '/bin/cat', 09:48:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:15 INFO - 'PWD': '/builds/slave/test', 09:48:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:15 INFO - 'SHELL': '/bin/bash', 09:48:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:15 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:15 INFO - 'USER': 'cltbld', 09:48:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:15 INFO - 'XPC_FLAGS': '0x0', 09:48:15 INFO - 'XPC_SERVICE_NAME': '0', 09:48:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:15 INFO - Downloading/unpacking blobuploader==1.2.4 09:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:18 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:18 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:19 INFO - Downloading docopt-0.6.1.tar.gz 09:48:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:19 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:19 INFO - Running setup.py install for blobuploader 09:48:19 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:19 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:19 INFO - Running setup.py install for requests 09:48:20 INFO - Running setup.py install for docopt 09:48:20 INFO - Successfully installed blobuploader requests docopt 09:48:20 INFO - Cleaning up... 09:48:20 INFO - Return code: 0 09:48:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:20 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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:48:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:20 INFO - 'HOME': '/Users/cltbld', 09:48:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:20 INFO - 'LOGNAME': 'cltbld', 09:48:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:20 INFO - 'MOZ_NO_REMOTE': '1', 09:48:20 INFO - 'NO_EM_RESTART': '1', 09:48:20 INFO - 'PAGER': '/bin/cat', 09:48:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:20 INFO - 'PWD': '/builds/slave/test', 09:48:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:20 INFO - 'SHELL': '/bin/bash', 09:48:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:20 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:20 INFO - 'USER': 'cltbld', 09:48:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:20 INFO - 'XPC_FLAGS': '0x0', 09:48:20 INFO - 'XPC_SERVICE_NAME': '0', 09:48:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-KyNgnO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-GlDFJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Tu9v_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Fs_yhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-6WBLba-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-9ABMCA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-e42Zsc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-JEJx8U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-EWRTcs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:21 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-4Ddbm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-tmIUGY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-E5kzPG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-D1Zt_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-IPKR1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-X64cuw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-0pSjGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:22 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-PUcNSP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:22 INFO - Running setup.py install for manifestparser 09:48:22 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:22 INFO - Running setup.py install for mozcrash 09:48:23 INFO - Running setup.py install for mozdebug 09:48:23 INFO - Running setup.py install for mozdevice 09:48:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Running setup.py install for mozfile 09:48:23 INFO - Running setup.py install for mozhttpd 09:48:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Running setup.py install for mozinfo 09:48:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Running setup.py install for mozInstall 09:48:23 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:23 INFO - Running setup.py install for mozleak 09:48:24 INFO - Running setup.py install for mozlog 09:48:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Running setup.py install for moznetwork 09:48:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Running setup.py install for mozprocess 09:48:24 INFO - Running setup.py install for mozprofile 09:48:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Running setup.py install for mozrunner 09:48:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:24 INFO - Running setup.py install for mozscreenshot 09:48:25 INFO - Running setup.py install for moztest 09:48:25 INFO - Running setup.py install for mozversion 09:48:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:25 INFO - Cleaning up... 09:48:25 INFO - Return code: 0 09:48:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:25 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 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:48:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:25 INFO - 'HOME': '/Users/cltbld', 09:48:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:25 INFO - 'LOGNAME': 'cltbld', 09:48:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:25 INFO - 'MOZ_NO_REMOTE': '1', 09:48:25 INFO - 'NO_EM_RESTART': '1', 09:48:25 INFO - 'PAGER': '/bin/cat', 09:48:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:25 INFO - 'PWD': '/builds/slave/test', 09:48:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:25 INFO - 'SHELL': '/bin/bash', 09:48:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:25 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:25 INFO - 'USER': 'cltbld', 09:48:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:25 INFO - 'XPC_FLAGS': '0x0', 09:48:25 INFO - 'XPC_SERVICE_NAME': '0', 09:48:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-vOhbuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Z1YLVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:25 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-I7wUQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-mgRu6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-r08asq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-zzx1Dz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-D7iate-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-USnGAd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-rLkhUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-P8n9rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-Ljx5oG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-HgIdmZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:26 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-dn5uKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:27 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-oUOcZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:27 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-9Ky42L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:27 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-SXeBeO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:27 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)) 09:48:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:27 INFO - Running setup.py (path:/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/pip-F0aLud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:48:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:48:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - Downloading blessings-1.6.tar.gz 09:48:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:30 INFO - Installing collected packages: blessings 09:48:30 INFO - Running setup.py install for blessings 09:48:30 INFO - Successfully installed blessings 09:48:30 INFO - Cleaning up... 09:48:30 INFO - Return code: 0 09:48:30 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:30 INFO - Reading from file tmpfile_stdout 09:48:30 INFO - Current package versions: 09:48:30 INFO - blessings == 1.6 09:48:30 INFO - blobuploader == 1.2.4 09:48:30 INFO - docopt == 0.6.1 09:48:30 INFO - functools32 == 3.2.3-2 09:48:30 INFO - jsonschema == 2.5.1 09:48:30 INFO - manifestparser == 1.1 09:48:30 INFO - mozInstall == 1.12 09:48:30 INFO - mozcrash == 1.0 09:48:30 INFO - mozdebug == 0.1 09:48:30 INFO - mozdevice == 0.48 09:48:30 INFO - mozfile == 1.2 09:48:30 INFO - mozhttpd == 0.7 09:48:30 INFO - mozinfo == 0.9 09:48:30 INFO - mozleak == 0.1 09:48:30 INFO - mozlog == 3.4 09:48:30 INFO - moznetwork == 0.27 09:48:30 INFO - mozprocess == 0.23 09:48:30 INFO - mozprofile == 0.28 09:48:30 INFO - mozrunner == 6.13 09:48:30 INFO - mozscreenshot == 0.1 09:48:30 INFO - mozsystemmonitor == 0.3 09:48:30 INFO - moztest == 0.8 09:48:30 INFO - mozversion == 1.4 09:48:30 INFO - psutil == 3.1.1 09:48:30 INFO - requests == 1.2.3 09:48:30 INFO - wsgiref == 0.1.2 09:48:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:48:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:31 INFO - 'HOME': '/Users/cltbld', 09:48:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:31 INFO - 'LOGNAME': 'cltbld', 09:48:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:31 INFO - 'MOZ_NO_REMOTE': '1', 09:48:31 INFO - 'NO_EM_RESTART': '1', 09:48:31 INFO - 'PAGER': '/bin/cat', 09:48:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:31 INFO - 'PWD': '/builds/slave/test', 09:48:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:31 INFO - 'SHELL': '/bin/bash', 09:48:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:31 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:31 INFO - 'USER': 'cltbld', 09:48:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:31 INFO - 'XPC_FLAGS': '0x0', 09:48:31 INFO - 'XPC_SERVICE_NAME': '0', 09:48:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:48:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:48:31 INFO - Cleaning up... 09:48:31 INFO - Return code: 0 09:48:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10295d490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028d3570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd3bd5e100>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, '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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:32 INFO - 'HOME': '/Users/cltbld', 09:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:32 INFO - 'LOGNAME': 'cltbld', 09:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:32 INFO - 'MOZ_NO_REMOTE': '1', 09:48:32 INFO - 'NO_EM_RESTART': '1', 09:48:32 INFO - 'PAGER': '/bin/cat', 09:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:32 INFO - 'PWD': '/builds/slave/test', 09:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:32 INFO - 'SHELL': '/bin/bash', 09:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:48:32 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:32 INFO - 'USER': 'cltbld', 09:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:32 INFO - 'XPC_FLAGS': '0x0', 09:48:32 INFO - 'XPC_SERVICE_NAME': '0', 09:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:48:32 INFO - Cleaning up... 09:48:32 INFO - Return code: 0 09:48:32 INFO - Running post-action listener: _resource_record_post_action 09:48:32 INFO - Running post-action listener: _start_resource_monitoring 09:48:32 INFO - Starting resource monitoring. 09:48:32 INFO - [mozharness: 2017-01-18 17:48:32.414643Z] Finished create-virtualenv step (success) 09:48:32 INFO - [mozharness: 2017-01-18 17:48:32.415634Z] Running install step. 09:48:32 INFO - Running pre-action listener: _resource_record_pre_action 09:48:32 INFO - Running main action method: install 09:48:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:32 INFO - Reading from file tmpfile_stdout 09:48:32 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:32 INFO - Reading from file tmpfile_stdout 09:48:32 INFO - Output received: 09:48:32 INFO - Usage: mozinstall [options] installer 09:48:32 INFO - Options: 09:48:32 INFO - -h, --help show this help message and exit 09:48:32 INFO - -d DEST, --destination=DEST 09:48:32 INFO - Directory to install application into. [default: 09:48:32 INFO - "/builds/slave/test"] 09:48:32 INFO - --app=APP Application being installed. [default: firefox] 09:48:32 INFO - mkdir: /builds/slave/test/build/application 09:48:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:49:04 INFO - Reading from file tmpfile_stdout 09:49:04 INFO - Output received: 09:49:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:49:04 INFO - Running post-action listener: _resource_record_post_action 09:49:04 INFO - [mozharness: 2017-01-18 17:49:04.954503Z] Finished install step (success) 09:49:04 INFO - [mozharness: 2017-01-18 17:49:04.954670Z] Running run-tests step. 09:49:04 INFO - Running pre-action listener: _resource_record_pre_action 09:49:04 INFO - Running main action method: run_tests 09:49:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:04 INFO - 2017-01-18 09:49:04.969 screenresolution[1626:7458] starting screenresolution argv=screenresolution get 09:49:04 INFO - 2017-01-18 09:49:04.972 screenresolution[1626:7458] Display 0: 1600x1200x32@60 09:49:04 INFO - 2017-01-18 09:49:04.978 screenresolution[1627:7460] starting screenresolution argv=screenresolution list 09:49:04 INFO - Available Modes on Display 0 09:49:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:05 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:05 INFO - Intel Iris: 09:49:05 INFO - Chipset Model: Intel Iris 09:49:05 INFO - Type: GPU 09:49:05 INFO - Bus: Built-In 09:49:05 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:05 INFO - Vendor: Intel (0x8086) 09:49:05 INFO - Device ID: 0x0a2e 09:49:05 INFO - Revision ID: 0x0009 09:49:05 INFO - Displays: 09:49:05 INFO - Display: 09:49:05 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:05 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:05 INFO - Display Serial Number: 1600x1200 60 09:49:05 INFO - Main Display: Yes 09:49:05 INFO - Mirror: Off 09:49:05 INFO - Online: Yes 09:49:05 INFO - Rotation: Supported 09:49:05 INFO - Return code: 0 09:49:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:05 INFO - Python 2.7.3 09:49:05 INFO - Return code: 0 09:49:05 INFO - grabbing minidump binary from tooltool 09:49:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:05 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 0x102771d30>, '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 0x1025d7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102769b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:05 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:49:05 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:49:05 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:49:05 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:05 INFO - Return code: 0 09:49:05 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:05 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:05 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:05 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:05 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:05 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/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:49:05 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/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0007 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:49:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WFbA4rt7OY/Render', 09:49:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:05 INFO - 'HOME': '/Users/cltbld', 09:49:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:05 INFO - 'LOGNAME': 'cltbld', 09:49:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:05 INFO - 'MOZ_NO_REMOTE': '1', 09:49:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:05 INFO - 'NO_EM_RESTART': '1', 09:49:05 INFO - 'PAGER': '/bin/cat', 09:49:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:05 INFO - 'PWD': '/builds/slave/test', 09:49:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:05 INFO - 'SHELL': '/bin/bash', 09:49:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XJqsXAvQqb/Listeners', 09:49:05 INFO - 'TMPDIR': '/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/', 09:49:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:05 INFO - 'USER': 'cltbld', 09:49:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:05 INFO - 'XPC_FLAGS': '0x0', 09:49:05 INFO - 'XPC_SERVICE_NAME': '0', 09:49:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:05 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/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0007', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:49:05 INFO - mozversion application_buildid: 20170118070027 09:49:05 INFO - mozversion application_changeset: 27643a39e551670484fe7dd5836aaa01d66b9a7b 09:49:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:49:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:05 INFO - mozversion application_name: Firefox 09:49:05 INFO - mozversion application_remotingname: firefox-dev 09:49:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:05 INFO - mozversion application_vendor: Mozilla 09:49:05 INFO - mozversion application_version: 52.0a2 09:49:05 INFO - mozversion platform_buildid: 20170118070027 09:49:05 INFO - mozversion platform_changeset: 27643a39e551670484fe7dd5836aaa01d66b9a7b 09:49:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:05 INFO - mozversion platform_version: 52.0a2 09:49:05 INFO - using testdate: 1484761745 09:49:05 INFO - actual date: 1484761745 09:49:05 INFO - starting webserver on 'localhost:49242' 09:49:05 INFO - SUITE-START | Running 2 tests 09:49:05 INFO - TEST-START | dromaeo_css 09:49:05 INFO - operating with platform_type : mac_ 09:49:05 INFO - Initialising browser for dromaeo_css test... 09:49:05 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpXpxTr3/profile http://localhost:49242/getInfo.html) 09:49:08 INFO - PROCESS | 1637 | __metrics Screen width/height:1600/1200 09:49:08 INFO - PROCESS | 1637 | colorDepth:24 09:49:08 INFO - PROCESS | 1637 | Browser inner width/height: 1024/689 09:49:08 INFO - PROCESS | 1637 | __metrics 09:49:08 INFO - PROCESS | 1637 | 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:49:08 INFO - PROCESS | 1637 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:49:09 INFO - TEST-INFO | 1637: exit 0 09:49:09 INFO - Browser initialized. 09:49:09 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:09 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpXpxTr3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:21 INFO - PROCESS | 1639 | RSS: Main: 222101504 09:49:21 INFO - PROCESS | 1639 | 09:52:25 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 09:52:27 INFO - PROCESS | 1639 | RSS: Main: 322445312 09:52:27 INFO - PROCESS | 1639 | 09:55:30 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 09:55:30 INFO - PROCESS | 1639 | RSS: Main: 346402816 09:55:30 INFO - PROCESS | 1639 | 09:58:32 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 09:58:33 INFO - PROCESS | 1639 | RSS: Main: 322506752 09:58:33 INFO - PROCESS | 1639 | 10:01:35 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 10:01:36 INFO - PROCESS | 1639 | RSS: Main: 325988352 10:01:36 INFO - PROCESS | 1639 | 10:04:39 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 10:04:40 INFO - PROCESS | 1639 | RSS: Main: 319561728 10:04:40 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 10:07:43 INFO - PROCESS | 1639 | RSS: Main: 372514816 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | __start_tp_report 10:07:43 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 10:07:43 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 10:07:43 INFO - PROCESS | 1639 | |i|pagename|runs| 10:07:43 INFO - PROCESS | 1639 | |0;dojo.html;3320.4189266878298;3328.1845947742804;3340.2985373071656;3348.3303339332124;3414.487826825976;5621.606487545873;5662.122371032491;5777.22007528984;5781.13283007549;5783.884322313551;5563.582731295159;5600.2719646446;5603.887922241563;5625.156226566017;5628.746706398211;5130.6152038597065;5134.25553294773;5135.845924622248;5157.097507936115;5196.558292545138;5174.689518628876;5185.870353241162;5198.974005129969;5234.214867769827;5266.973665131669;5241.764120614582;5254.710990895499;5277.3139491866;5279.340082489689;5290.497402746733;4375.409319741826;5272.367315922075;5297.761600727977;5346.7861285548925;5393.568514518868;5111.105556527593;5191.844244672666;5268.078086334915;5270.235815806686;5291.497307755757;5620.353659329147;5641.294838145232;5673.8581535461535;5687.459691329317;5694.003549378884;6144.109104179869;6204.379562043805;6243.937560624381;6276.466500347476;6281.7173227204885;6274.905876411857;6321.24145102586;6345.968270158666;6348.238211414614;6355.904661430082;5660.462256085665;5721.885562288731;5735.369109397963;5855.033919403336;5856.092305692625;6239.188905442264;6244.250689917239;6263.561550691408;6265.65538895356;6288.748450062038;5495.148252020943;6150.7847225346895;6162.938370616326;6173.59871608344;6182.876342473215;4796.37647105874;6162.26052873649;6208.441240288395;6213.720382582795;6220.191375121205;6206.441420272197;6209.6895155242055;6227.906581401283;6235.563510554309;6236.03341482071;6169.876602467925;6199.9770037943235;6257.56197066201;6262.624242545371;6289.213848268966;4836.250381190921;4849.539293767087;4864.805407783649;4904.485029071896;4944.1100601891285;4363.912721745547;4409.40473036136;4414.580614841585;4431.290993441034;4487.169873573399;5711.600187986801;5727.541796656257;5730.312362516442;5739.110437882135;5746.971265143731;6083.391660833881;6285.057241413733;6302.149209856702;6312.147860038836;6323.683815809191;5622.690752008597;5669.829905102853;5679.716014199274;5706.885862282814;5716.056850619684;4975.154223781966;5088.033273678064;5382.892342153135;5423.322084739408;5452.372977107683;6273.184486016971;6278.623282603058;6283.277423096402;6286.779962701284;6290.874182516415;6055.818325450244;6099.664518451527;6104.053871649901;6179.196704428576;6228.626282423069;6211.254649442096;6304.810855674337;6306.306306306211;6309.526785491014;6312.589681670676;6200.038993955944;6203.193584833943;6209.782657606962;6222.751089956351;6225.408586184486;6130.172426722335;6149.538784591084;6195.411435913581;6255.78104766331;6295.937040629535;6076.696165191811;6139.877202455837;6142.375998160927;6152.200213972155;6175.9073613894925;6110.710646019097;6118.20463339763;6121.392004143408;6121.645528617276;6130.551011451207;5796.478316951495;5854.4730974212525;5956.404359564009;5987.64074155552;6020.668863212565;6083.695815209133;6087.665178415053;6114.6942652868065;6150.292716337678;6154.446099850856;5958.791442299672;6004.459598635969;6016.8796624068145;6041.154238406539;6049.243844519435 10:07:43 INFO - PROCESS | 1639 | |1;ext.html;471.86080106368615;476.2736826339831;494.6265569495031;500.28709089547385;517.363642719455;29333.506651934586;32001.71993120278;32014.839925800396;32029.35941281173;32107;32086.67913320866;40857.18285634286;42331.153376932445;42412.787936060355;44085.559144408544;9393.201577865906;9545.856812147824;9547.18848897841;9551.235901127928;9556.856647150298;9357.719268421924;9365.110314520143;9465.006174351698;9469.147776700096;9477.857832132524;7889.921100789004;9485.241180705527;9513.71458856232;9521.000294969032;9553.904460955371;8690.739277821676;8813.633886747532;9359.95320023399;9429.15137637609;9431.198348140417;9397.248220142372;9493.240540756722;9495.14543691071;9506.239500839916;9531.428114313163;39064.218715625815;39300.21399572021;39426.80286598549;39476.81569552918;39609.60390396088;3358.1604598850286;3372.224388390684;3661.2311414602964;3822.73240873139;3899.78551179685;28687.71312286892;29016.9370015235;29317.706822931923;29468.26329341762;30368;3143.764217683683;3162.8418579071185;3190.8245046522434;3210.550522926792;3211.486162214058;469879;472628;476337;478176;478643;60684.482887927275;61007.38992610106;61099.694501528094;61121.08318375228;61233.38766612277;34516;35491;64084;64124.67937660375;64437;15270.236488175547;15768.89617726771;15840.76238856418;15844.920775396049;15855.365785368687;11675.76648467026;11687.532498700128;11723;11732.296062236293;11734.237274577124;19133.23467061302;19194.13626386816;19199.040047997823;19213.615727685366;19223.807761922202;6343.27052388972;6376.521760867953;7397.075365579302;7566.767803497982;7674.232576742281;807.6204184033612;888.5956889615339;939.7556635274877;961.8797650293714;968.4721826604376;26990.730092699214;29350.853245734063;30178.69821301803;30455.847720761696;32337.676623233467;848.4442690038035;883.9823203536022;941.990580094204;1006.7483129217695;1023.3757407981126;11023.393713345842;14929.253537323308;15869.285882135331;15870;16356.836431635531;682453;684239;685361;687932;699970;48006.75996619995;57520.42479575234;57938.710306449044;58104.70947645235;58226.41773582295;1080.4705694209624;1191.2673705671123;1229.6065259116997;1231.8090695942144;1317.1636011132807;29040.273993150342;30352;30674.846625767623;31197.376052479278;31366.843165784026;50362.2445663308;50942.490575093776;51082.489175107774;51091;51117;564219;568782;570784;572202.1389893191;573454;21228.7877121233;26268.60597091007;27143;27159;27852.16443506698;45804.08391832078;45947.77026114848;46216.768916155204;46472;46521.06957860891;37315.81342093272;37615.05962350963;38472.80763596164;39631.81104566868;39804.40293395543;37831.43252851265;38117;38180.427293590066;39350;39404.60595394124;36825.63174368329;37101.628983709816;37596.2480750378;38174;38195.42706859455;28372.716272837006;32991.83504082464;33318;33500;34636.82681586677;21003;25483.872580636977;25514.872425637754;25713.357166070997;25962.610560841982 10:07:43 INFO - PROCESS | 1639 | |2;jquery.html;306.30774449743575;367.9043448703337;368.69582594359645;378.1944458303813;395.57475713607863;14552.34514446851;14761.926190369046;15433.614159646015;18026.5493362666;19939.501512462197;18716;19094.90452547739;19575.021248937566;19769.703454448158;19796.30712925048;5478.369987451434;5795.507381872546;5798.62308949919;5810.56420768442;5831.241938548004;4588.977055114719;5544.390117087117;5634.154876768476;5715.057015592422;5728.770849166029;5649.774009039634;5663.575231857607;5670.461306175906;5675.971620141914;5679.488846003859;4832.275158726184;5423.484768946963;5662.207290979266;5699.487046165824;5720.227769251181;5007.6244281678655;5021.146405111103;5022.296878437021;5024.648274620778;5037.722925239111;18267.543311417186;22353.441163970867;23602.881985590135;24842.00631974719;24867.50264994708;2189.627763280246;2236.8993395297252;2242.5066485373354;2293.8050265727265;2337.0885354711504;13921.095128816594;15072.623184420478;15318.463853765175;15609.375624974988;15862.841371586252;1736;1779.4483710049797;1823.6717390869637;1837.9724304135448;1889.3104017033684;101580;104658;104977.47511262471;105215.4739226299;105281;8626.870596941051;10370.481475926174;10390.688279351632;10425;10784.191185661106;13517.59447216585;13547.25490098054;13548.254845983369;13561.525346612822;13584.728305434033;6376.776812811529;7905.328047115944;7958.045034595885;8073.6366863491285;8084.514929104273;6291.811245662638;7731.845363092707;7785.766427007199;7807.492512986638;7885.448018638755;6370.8088757337355;6495;6514.283428822826;6533.705983230766;6643.169603799525;1772.7340898865273;1788.2578729826976;1791.4715159028117;1793.2199493220492;1794.3809385761892;738.3207449146798;765.9731909383145;897.6274845938863;940.9200217981411;966.3573723473952;20716;21217.372197891855;21746.25723964593;21859.562808744053;21876.45308867291;931.2131249094505;993.2203220471895;1007.4811472091878;1072.3137192197;1074.908632766208;10523.315984460985;11240.269382490113;11443.141764367705;11456.885431145749;11488.77022459546;602056;607758.9612051911;610751;622148;624299;110043;111757;113646;113820.29269560963;119577;902.9142231487998;974.1378879691382;994.7065615643403;1000.364768372089;1006.0291818395137;15431.84568154304;18549.072546372896;18877.71683424777;19047.333343332917;19107.713384299524;51287.97424051573;53265;55517.89303176566;55734.16398753941;55787.88424231412;82087.58956205181;82445;82584.58707706664;85498.57250713707;85853.57073214593;6201.844953876189;7499.325060744559;7566.659500322608;7698.884516732365;7769.961150194213;8515.361347898808;11994.700132496757;12483.188592741442;12523.248605083723;12745.61763147107;10920.78158436811;10931.617393391192;10991.285566438157;11012.559497620005;11106.777864442827;10566.41884696349;11053.778924421627;11245.887541124484;11406.48670809799;11666.824997624874;11094.667159985212;11431.742508323912;11638.88361116378;11749.00133488663;11845.289282643069;8034.919650803417;8299.709510167115;8329.6668133274;8370.204830541088;8443.95778021106;10532.420716860646;10829.509212880925;11108.389038602954;11305.773884522428;11541.192116552096 10:07:43 INFO - PROCESS | 1639 | |3;mootools.html;1294.6051454306444;1382.2881216173673;1441.8125643666322;1481.1039321210667;1716.9227384767685;6799.966000169999;7393.112826460825;7797.142314345418;8370.916290837089;8384.664613415456;8192;8290.502569845814;8291.502509849413;8293.129221431738;8294.046184688763;2269.818414526842;2279.168103642171;2313.6529520571967;2317.6986991691056;2320.211128216406;2298.643710224918;2336.7195936487647;2356.1988923765916;2357.882105894707;2360.492494113765;2300.7124109486313;2301.1255722825385;2330.883455827202;2337.0885354711677;2340.918067867625;2174.6629272462715;2207.907085992428;2225.3880182949656;2238.1271304191378;2238.50752834377;2304.308707387777;2336.953260934789;2337.2871274261342;2339.508703172336;2339.7309309429465;7879.842403151963;8177.5502347370875;8300.833983320299;8377.204165604318;8396.664133434655;5035.269885866529;5184.015037142968;5184.740762961875;5186.5850731941355;5198.324217851655;3055.511118221074;3264.2329052672603;3361.6638336166434;3384.815314639881;3461.809600471973;947.8483442649213;1001.9899801001969;1010.8635334229932;1024.55943944104;1024.733569271987;10707.732306692395;10764.784704305948;10868.510917008754;10879.12966962641;10895.291806032583;4249.575042495788;4362.563743625676;4455.554444555583;4456.732596044248;4468.709533880287;8596.570171491525;8607.053224145338;8612.975950651591;8617.655293788179;8617.69838055665;4991.80032798684;5061.949380506221;5064.873378165502;5073.340465739504;5082.771275292621;3997.5802540733384;4231.597998190167;4332.956670433318;4401.119776044804;4663.440387153495;2987.4025194961096;3629.4011488104165;3713.313037088147;3718.7025037996896;3724.273766615538;987.0080569028081;1135.6820090374708;1142.4116579961326;1148.6898537394857;1156.9074474042225;750.1823012915947;762.5920132728963;851.391255252485;864.8616221404545;889.835380454618;5337.519623233928;5477.45225477457;5481.917771233435;5703.258576385043;5733.455321744427;436.5525336530019;444.16496985666737;447.29997553828156;450.56070331427253;458.33999041380304;3572.499850020999;3664.871729489455;3678.7240956928335;3727.7390582659496;3782.7541209821275;9834.901650983542;10126.746831329276;10170.796584068425;10282.07461328469;10316.226283028804;10066.14437772783;10203;10246.53890574926;10255.487225638688;10266.178705703525;7605.505642133244;7816.843663126706;7901.683932642745;7992.720254791055;8032.558209298545;4657.930131048105;5358.84502343318;5475.5072043516275;5493.23094766725;5504.6421982571;9472.052794720474;9477.952610236905;9486.667966621135;9493.667721629987;9494.620215191315;8755.868661969947;9123.178913897782;9186.54067296646;9551.42691438516;9624.51877406141;6101.664408457577;6343.365663433619;6426.4216220540375;6798.490113241625;6826.761063362758;8005.279524842792;8078.83842323162;8106.47307924983;8123.309518690978;8135.633896474791;7139.500234983501;7189.928100719135;7210.9278907210255;7215.134183897966;7247.528910620793;6980.755673551402;7017.228104908354;7059.5058345915295;7070.328318809705;7105.25394833535;6694.83262918431;6700.899486507804;6718.899216511659;6772.695228714621;6804.21751498584;4555.681102322938;4601.516840731675;4666.929996049994;4706.952930470651;4763.4522029967;4639.44326680787;4776.920697328703;4832.275158726219;4835.129676658235;4950.158473059517 10:07:43 INFO - PROCESS | 1639 | |4;prototype.html;273.4135279824776;305.55084026481063;305.58745693314023;308.60190354442784;313.1638525137882;4633;5202.48832038374;5252.185911183773;5256.500632439922;5263.710495922723;4055.9797201014026;4651.7441540715245;5209.426963034063;5223.250211179259;5246.554042906348;3466.653334666526;3725.6833169180654;3750.1937083527037;3876.8643097491595;4068.8169032393466;3460.6640517650485;3854.4989151410277;3944.16020465266;3997.8001099944886;4077.9592204078026;3409.244104474257;3530.8234588270484;3939.2121575684832;3969.4244334571613;4012.4953142571535;3639.7634153780186;3804.4293355996824;3986.621270979252;3999.040230344711;4006.298897692907;3447.293304872495;3465.376232278189;3475.6350583188655;3480.617132115465;3484.9477257841154;4515.458145022619;4697.436307643105;5034.496550344973;5042.495750424965;5056.747162641853;4378.5183629800695;4501.459824821042;4545.545445455461;4550.3402006709175;4560.863174104782;5342.626016178869;5394.1908713692865;5414.35027796663;5418.539424148952;5433.592480563973;3802.543694756619;3803.2885201659365;3821.5987321331136;3825.2923209206106;3839.232153569297;5345.652532585447;5407.3781515125875;5721.542276617861;5735.827925162252;5764.798232061899;3750.062484378905;3780.2061567070673;3791.9572117667312;3796.487474191004;3799.556168655948;3459.930801383958;3836.9424458633143;3868.613138686109;3874.6319099685484;3890.8638197662945;3723.3670276053135;4428.446444194476;4442.445144199569;4497.910041799146;4510.684252102387;5259.500347466985;5390.97304513475;5428.972855135699;5480.931218412451;5493.395726470085;1234.4383305595932;1254.4543123741203;1255.2656695832857;1259.6018419554287;1264.6711854917787;740.3263030642197;810.4772421787914;845.9425717852685;856.7858035491128;867.483847110968;4473.373727678128;4489.663275254369;4894.314795928502;4927.285543596158;5076.314697515788;4685.079117561783;5605.299337582802;5630.859228519238;5645.294338207724;5653.265075540154;5177.534021938043;5182.170852663556;5183.496785932113;5186.484554990827;5187.028929007029;2437.720731116412;2718.619393284942;2794.4131732336436;2795.692473827877;2797.2866918935542;4853.611711063177;4945.332380128709;5114.590832733446;5530.364008139035;5557.3331200256225;4610.469795973506;4948.628852836052;5371.060064488777;5472.671639701631;5522.889542209134;4893.02139572087;5421.972890135446;5607.271054762855;5827.883442331215;5870.73581688825;4856.490068542752;5591.384947655836;5623.8594035149445;5648.88702225945;5662.433756624305;4921.434035085867;5233.633645644768;5505.118891181787;5555.388907220122;5565.1374037024325;5234.659747116425;5529.115341545334;5893.941060589339;5899.085641725539;5911;3701.833922314462;3817.828197731053;3819.6371344722206;3840.8655697050467;3868.6324799144036;5493.560515158858;5622.85942851432;5656.406077361818;5683.964385286493;5743.540516758733;4841.489919808111;5506.944930550643;5516.172574113915;5516.613837031369;5530.834074977757;4570.7257564546235;5481.616286859881;5499.4775496329385;5501.779928802963;5524.972375138099;4011.4383986241364;4541.364209010807;4678.274867395559;5437.809676661298;5458.781648734006;5235.691077230692;5400.648957817729;5411.675299481886;5533.197686335539;5538.584606154635;5057.418396884405;5200.739963001759;5280.630355875205;5284.365876094893;5291.656042357235 10:07:43 INFO - PROCESS | 1639 | |5;yui.html;664.9235337936138;715.8890371992339;760.2321655128319;775.2480094308519;791.0467886197133;13921.651958701037;14241.572752817407;14306.141631502118;14703.852961470382;14787.40850365984;11936;12925.28910909902;13289.867101328984;13314.201147931131;13322.733545329087;773.0877564473933;814.9266566009057;815.213319147023;816.4856140631393;818.3412353055787;651.8142329436112;677.7085853083172;678.3420082519948;790.640258682301;811.8376324735048;858.2790456016944;865.7272959017921;865.8484765166103;868.2793281576309;870.4298684361753;858.4891989266408;858.9656413743443;859.8194379180379;860.1742327365737;861.5821326656567;505.7092172001114;525.2226704477357;616.636184651058;641.1568787045021;642.2838535033404;13562.728745425036;14087.154770713687;14092.647683807885;14108.506202282973;14129.646758831008;12812.42344094518;13034.608961731163;13272.469101235938;13278.668033299246;13306.534271300457;10221.9488902555;11626;12033;12045.337506437143;12078.516859325617;4223.556526564728;4371.759553224571;4396.956030439686;4478.238699421074;4519.728816271001;90968.09031909729;94832.57751133738;95928;96016.51991740067;96260.51869740676;10233.385996840214;10301.690949271533;10304;10388.584456621802;10442.53008614599;16183.43357982465;19744.210231590863;19935.501612459804;20017.699734503993;20021.499462513264;6421.389967953037;6685.431738302202;6708.396244337935;6711.798646040626;6941.722331106699;3870.3033453978555;3871.748336358184;3882.8640997565512;3984.0438294809037;4079.4900637420324;3160.352127813793;3295.3903527846946;3312.801231926092;3331.417002024636;3331.8833840815455;586.3257254157668;627.2629660149306;627.3569591169088;633.6863252689948;635.1711017122607;465.7647887816699;553.6263022459824;607.7903123422412;613.7238242790672;633.9746410143634;7938.801529961797;8498.192671696179;8811.383203175845;8880.245179159714;9044.186023257938;594.5392320951297;640.113442881603;650.3658932540735;663.1213641924469;673.9932600673931;4178.310578754532;4244;4298.312270036779;4338.327559228324;4411.625011874026;116663;116772;118288;119841;124920.37539812243;80942.19057809464;81986;82358.58820705858;82873.75689364664;102073.97926020794;73979;79458;80390.5980470094;83194.58402707947;84462.83378393839;8961.731148065568;8970.910290897007;9426.717198484057;9429.717108486757;9442.811143777224;52796;53063;53183.93632127413;53517.66205844885;55246.89506209773;66204;68054.61808836667;68381.31618683749;70835.29164708493;73983;11950.940245299065;12062.457189426672;12158.574449894211;12198.634040978784;12510.687232819253;10624.946875265574;13561.728765424834;13887.58337249884;13893.930530347283;13990.860091398956;12234;12408.34011371057;12672.683182920133;12705.93647031796;12866.228026318451;12481.56314528987;12504.12372164632;12749.107562470537;12833.871661283267;12960;11466.885331146916;12083.939580302042;12362.876371236172;12601.68495787576;12626.55807046749;10467;10774.138068954622;10987.505562249558;11146.996770290712;11346.659600212008;10635.680929571816;10788.892111079103;11072.169587281147;11093.33439993603;11197.048250898764 10:07:43 INFO - PROCESS | 1639 | __end_tp_report 10:07:43 INFO - PROCESS | 1639 | __start_cc_report 10:07:43 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,1944 10:07:43 INFO - PROCESS | 1639 | __end_cc_report 10:07:43 INFO - PROCESS | 1639 | __startTimestamp1484762863897__endTimestamp 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | ------- Summary: start ------- 10:07:43 INFO - PROCESS | 1639 | Number of tests: 6 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5727.55 Median:6004.46 stddev:653.72 (10.9%) stddev-sans-first:627.86 10:07:43 INFO - PROCESS | 1639 | Values: 3320.4 3328.2 3340.3 3348.3 3414.5 5621.6 5662.1 5777.2 5781.1 5783.9 5563.6 5600.3 5603.9 5625.2 5628.7 5130.6 5134.3 5135.8 5157.1 5196.6 5174.7 5185.9 5199.0 5234.2 5267.0 5241.8 5254.7 5277.3 5279.3 5290.5 4375.4 5272.4 5297.8 5346.8 5393.6 5111.1 5191.8 5268.1 5270.2 5291.5 5620.4 5641.3 5673.9 5687.5 5694.0 6144.1 6204.4 6243.9 6276.5 6281.7 6274.9 6321.2 6346.0 6348.2 6355.9 5660.5 5721.9 5735.4 5855.0 5856.1 6239.2 6244.3 6263.6 6265.7 6288.7 5495.1 6150.8 6162.9 6173.6 6182.9 4796.4 6162.3 6208.4 6213.7 6220.2 6206.4 6209.7 6227.9 6235.6 6236.0 6169.9 6200.0 6257.6 6262.6 6289.2 4836.3 4849.5 4864.8 4904.5 4944.1 4363.9 4409.4 4414.6 4431.3 4487.2 5711.6 5727.5 5730.3 5739.1 5747.0 6083.4 6285.1 6302.1 6312.1 6323.7 5622.7 5669.8 5679.7 5706.9 5716.1 4975.2 5088.0 5382.9 5423.3 5452.4 6273.2 6278.6 6283.3 6286.8 6290.9 6055.8 6099.7 6104.1 6179.2 6228.6 6211.3 6304.8 6306.3 6309.5 6312.6 6200.0 6203.2 6209.8 6222.8 6225.4 6130.2 6149.5 6195.4 6255.8 6295.9 6076.7 6139.9 6142.4 6152.2 6175.9 6110.7 6118.2 6121.4 6121.6 6130.6 5796.5 5854.5 5956.4 5987.6 6020.7 6083.7 6087.7 6114.7 6150.3 6154.4 5958.8 6004.5 6016.9 6041.2 6049.2 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:70117.10 Median:39451.81 stddev:156480.86 (396.6%) stddev-sans-first:156832.46 10:07:43 INFO - PROCESS | 1639 | Values: 471.9 476.3 494.6 500.3 517.4 29333.5 32001.7 32014.8 32029.4 32107.0 32086.7 40857.2 42331.2 42412.8 44085.6 9393.2 9545.9 9547.2 9551.2 9556.9 9357.7 9365.1 9465.0 9469.1 9477.9 7889.9 9485.2 9513.7 9521.0 9553.9 8690.7 8813.6 9360.0 9429.2 9431.2 9397.2 9493.2 9495.1 9506.2 9531.4 39064.2 39300.2 39426.8 39476.8 39609.6 3358.2 3372.2 3661.2 3822.7 3899.8 28687.7 29016.9 29317.7 29468.3 30368.0 3143.8 3162.8 3190.8 3210.6 3211.5 469879.0 472628.0 476337.0 478176.0 478643.0 60684.5 61007.4 61099.7 61121.1 61233.4 34516.0 35491.0 64084.0 64124.7 64437.0 15270.2 15768.9 15840.8 15844.9 15855.4 11675.8 11687.5 11723.0 11732.3 11734.2 19133.2 19194.1 19199.0 19213.6 19223.8 6343.3 6376.5 7397.1 7566.8 7674.2 807.6 888.6 939.8 961.9 968.5 26990.7 29350.9 30178.7 30455.8 32337.7 848.4 884.0 942.0 1006.7 1023.4 11023.4 14929.3 15869.3 15870.0 16356.8 682453.0 684239.0 685361.0 687932.0 699970.0 48006.8 57520.4 57938.7 58104.7 58226.4 1080.5 1191.3 1229.6 1231.8 1317.2 29040.3 30352.0 30674.8 31197.4 31366.8 50362.2 50942.5 51082.5 51091.0 51117.0 564219.0 568782.0 570784.0 572202.1 573454.0 21228.8 26268.6 27143.0 27159.0 27852.2 45804.1 45947.8 46216.8 46472.0 46521.1 37315.8 37615.1 38472.8 39631.8 39804.4 37831.4 38117.0 38180.4 39350.0 39404.6 36825.6 37101.6 37596.2 38174.0 38195.4 28372.7 32991.8 33318.0 33500.0 34636.8 21003.0 25483.9 25514.9 25713.4 25962.6 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34672.07 Median:12295.17 stddev:101739.90 (827.5%) stddev-sans-first:101992.58 10:07:43 INFO - PROCESS | 1639 | Values: 306.3 367.9 368.7 378.2 395.6 14552.3 14761.9 15433.6 18026.5 19939.5 18716.0 19094.9 19575.0 19769.7 19796.3 5478.4 5795.5 5798.6 5810.6 5831.2 4589.0 5544.4 5634.2 5715.1 5728.8 5649.8 5663.6 5670.5 5676.0 5679.5 4832.3 5423.5 5662.2 5699.5 5720.2 5007.6 5021.1 5022.3 5024.6 5037.7 18267.5 22353.4 23602.9 24842.0 24867.5 2189.6 2236.9 2242.5 2293.8 2337.1 13921.1 15072.6 15318.5 15609.4 15862.8 1736.0 1779.4 1823.7 1838.0 1889.3 101580.0 104658.0 104977.5 105215.5 105281.0 8626.9 10370.5 10390.7 10425.0 10784.2 13517.6 13547.3 13548.3 13561.5 13584.7 6376.8 7905.3 7958.0 8073.6 8084.5 6291.8 7731.8 7785.8 7807.5 7885.4 6370.8 6495.0 6514.3 6533.7 6643.2 1772.7 1788.3 1791.5 1793.2 1794.4 738.3 766.0 897.6 940.9 966.4 20716.0 21217.4 21746.3 21859.6 21876.5 931.2 993.2 1007.5 1072.3 1074.9 10523.3 11240.3 11443.1 11456.9 11488.8 602056.0 607759.0 610751.0 622148.0 624299.0 110043.0 111757.0 113646.0 113820.3 119577.0 902.9 974.1 994.7 1000.4 1006.0 15431.8 18549.1 18877.7 19047.3 19107.7 51288.0 53265.0 55517.9 55734.2 55787.9 82087.6 82445.0 82584.6 85498.6 85853.6 6201.8 7499.3 7566.7 7698.9 7770.0 8515.4 11994.7 12483.2 12523.2 12745.6 10920.8 10931.6 10991.3 11012.6 11106.8 10566.4 11053.8 11245.9 11406.5 11666.8 11094.7 11431.7 11638.9 11749.0 11845.3 8034.9 8299.7 8329.7 8370.2 8444.0 10532.4 10829.5 11108.4 11305.8 11541.2 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5343.58 Median:4833.70 stddev:3001.07 (62.1%) stddev-sans-first:2994.06 10:07:43 INFO - PROCESS | 1639 | Values: 1294.6 1382.3 1441.8 1481.1 1716.9 6800.0 7393.1 7797.1 8370.9 8384.7 8192.0 8290.5 8291.5 8293.1 8294.0 2269.8 2279.2 2313.7 2317.7 2320.2 2298.6 2336.7 2356.2 2357.9 2360.5 2300.7 2301.1 2330.9 2337.1 2340.9 2174.7 2207.9 2225.4 2238.1 2238.5 2304.3 2337.0 2337.3 2339.5 2339.7 7879.8 8177.6 8300.8 8377.2 8396.7 5035.3 5184.0 5184.7 5186.6 5198.3 3055.5 3264.2 3361.7 3384.8 3461.8 947.8 1002.0 1010.9 1024.6 1024.7 10707.7 10764.8 10868.5 10879.1 10895.3 4249.6 4362.6 4455.6 4456.7 4468.7 8596.6 8607.1 8613.0 8617.7 8617.7 4991.8 5061.9 5064.9 5073.3 5082.8 3997.6 4231.6 4333.0 4401.1 4663.4 2987.4 3629.4 3713.3 3718.7 3724.3 987.0 1135.7 1142.4 1148.7 1156.9 750.2 762.6 851.4 864.9 889.8 5337.5 5477.5 5481.9 5703.3 5733.5 436.6 444.2 447.3 450.6 458.3 3572.5 3664.9 3678.7 3727.7 3782.8 9834.9 10126.7 10170.8 10282.1 10316.2 10066.1 10203.0 10246.5 10255.5 10266.2 7605.5 7816.8 7901.7 7992.7 8032.6 4657.9 5358.8 5475.5 5493.2 5504.6 9472.1 9478.0 9486.7 9493.7 9494.6 8755.9 9123.2 9186.5 9551.4 9624.5 6101.7 6343.4 6426.4 6798.5 6826.8 8005.3 8078.8 8106.5 8123.3 8135.6 7139.5 7189.9 7210.9 7215.1 7247.5 6980.8 7017.2 7059.5 7070.3 7105.3 6694.8 6700.9 6718.9 6772.7 6804.2 4555.7 4601.5 4666.9 4707.0 4763.5 4639.4 4776.9 4832.3 4835.1 4950.2 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4411.37 Median:4946.98 stddev:1355.47 (27.4%) stddev-sans-first:1323.21 10:07:43 INFO - PROCESS | 1639 | Values: 273.4 305.6 305.6 308.6 313.2 4633.0 5202.5 5252.2 5256.5 5263.7 4056.0 4651.7 5209.4 5223.3 5246.6 3466.7 3725.7 3750.2 3876.9 4068.8 3460.7 3854.5 3944.2 3997.8 4078.0 3409.2 3530.8 3939.2 3969.4 4012.5 3639.8 3804.4 3986.6 3999.0 4006.3 3447.3 3465.4 3475.6 3480.6 3484.9 4515.5 4697.4 5034.5 5042.5 5056.7 4378.5 4501.5 4545.5 4550.3 4560.9 5342.6 5394.2 5414.4 5418.5 5433.6 3802.5 3803.3 3821.6 3825.3 3839.2 5345.7 5407.4 5721.5 5735.8 5764.8 3750.1 3780.2 3792.0 3796.5 3799.6 3459.9 3836.9 3868.6 3874.6 3890.9 3723.4 4428.4 4442.4 4497.9 4510.7 5259.5 5391.0 5429.0 5480.9 5493.4 1234.4 1254.5 1255.3 1259.6 1264.7 740.3 810.5 845.9 856.8 867.5 4473.4 4489.7 4894.3 4927.3 5076.3 4685.1 5605.3 5630.9 5645.3 5653.3 5177.5 5182.2 5183.5 5186.5 5187.0 2437.7 2718.6 2794.4 2795.7 2797.3 4853.6 4945.3 5114.6 5530.4 5557.3 4610.5 4948.6 5371.1 5472.7 5522.9 4893.0 5422.0 5607.3 5827.9 5870.7 4856.5 5591.4 5623.9 5648.9 5662.4 4921.4 5233.6 5505.1 5555.4 5565.1 5234.7 5529.1 5893.9 5899.1 5911.0 3701.8 3817.8 3819.6 3840.9 3868.6 5493.6 5622.9 5656.4 5684.0 5743.5 4841.5 5506.9 5516.2 5516.6 5530.8 4570.7 5481.6 5499.5 5501.8 5525.0 4011.4 4541.4 4678.3 5437.8 5458.8 5235.7 5400.6 5411.7 5533.2 5538.6 5057.4 5200.7 5280.6 5284.4 5291.7 10:07:43 INFO - PROCESS | 1639 | 10:07:43 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20299.83 Median:4355.04 stddev:30220.82 (693.9%) stddev-sans-first:30269.63 10:07:43 INFO - PROCESS | 1639 | Values: 664.9 715.9 760.2 775.2 791.0 13921.7 14241.6 14306.1 14703.9 14787.4 11936.0 12925.3 13289.9 13314.2 13322.7 773.1 814.9 815.2 816.5 818.3 651.8 677.7 678.3 790.6 811.8 858.3 865.7 865.8 868.3 870.4 858.5 859.0 859.8 860.2 861.6 505.7 525.2 616.6 641.2 642.3 13562.7 14087.2 14092.6 14108.5 14129.6 12812.4 13034.6 13272.5 13278.7 13306.5 10221.9 11626.0 12033.0 12045.3 12078.5 4223.6 4371.8 4397.0 4478.2 4519.7 90968.1 94832.6 95928.0 96016.5 96260.5 10233.4 10301.7 10304.0 10388.6 10442.5 16183.4 19744.2 19935.5 20017.7 20021.5 6421.4 6685.4 6708.4 6711.8 6941.7 3870.3 3871.7 3882.9 3984.0 4079.5 3160.4 3295.4 3312.8 3331.4 3331.9 586.3 627.3 627.4 633.7 635.2 465.8 553.6 607.8 613.7 634.0 7938.8 8498.2 8811.4 8880.2 9044.2 594.5 640.1 650.4 663.1 674.0 4178.3 4244.0 4298.3 4338.3 4411.6 116663.0 116772.0 118288.0 119841.0 124920.4 80942.2 81986.0 82358.6 82873.8 102074.0 73979.0 79458.0 80390.6 83194.6 84462.8 8961.7 8970.9 9426.7 9429.7 9442.8 52796.0 53063.0 53183.9 53517.7 55246.9 66204.0 68054.6 68381.3 70835.3 73983.0 11950.9 12062.5 12158.6 12198.6 12510.7 10624.9 13561.7 13887.6 13893.9 13990.9 12234.0 12408.3 12672.7 12705.9 12866.2 12481.6 12504.1 12749.1 12833.9 12960.0 11466.9 12083.9 12362.9 12601.7 12626.6 10467.0 10774.1 10987.5 11147.0 11346.7 10635.7 10788.9 11072.2 11093.3 11197.0 10:07:43 INFO - PROCESS | 1639 | -------- Summary: end -------- 10:07:43 INFO - PROCESS | 1639 | 10:07:44 INFO - TEST-INFO | 1639: exit 0 10:07:44 INFO - TEST-OK | dromaeo_css | took 1119003ms 10:07:44 INFO - TEST-START | kraken 10:07:44 INFO - operating with platform_type : mac_ 10:07:44 INFO - Initialising browser for kraken test... 10:07:44 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpX5LSPv/profile http://localhost:49242/getInfo.html) 10:07:47 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 10:07:47 INFO - PROCESS | 1646 | colorDepth:24 10:07:47 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 10:07:47 INFO - PROCESS | 1646 | __metrics 10:07:47 INFO - PROCESS | 1646 | 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" 10:07:47 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:07:48 INFO - TEST-INFO | 1646: exit 0 10:07:48 INFO - Browser initialized. 10:07:48 INFO - Running cycle 1/1 for kraken test... 10:07:48 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/T/tmpX5LSPv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:08:00 INFO - PROCESS | 1647 | RSS: Main: 222003200 10:08:00 INFO - PROCESS | 1647 | 10:08:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:04 INFO - PROCESS | 1647 | RSS: Main: 271904768 10:08:04 INFO - PROCESS | 1647 | 10:08:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 10:08:07 INFO - PROCESS | 1647 | RSS: Main: 282968064 10:08:07 INFO - PROCESS | 1647 | 10:08:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 10:08:11 INFO - PROCESS | 1647 | RSS: Main: 379105280 10:08:11 INFO - PROCESS | 1647 | 10:08:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 10:08:14 INFO - PROCESS | 1647 | RSS: Main: 283238400 10:08:14 INFO - PROCESS | 1647 | 10:08:16 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:16 INFO - PROCESS | 1647 | RSS: Main: 284454912 10:08:16 INFO - PROCESS | 1647 | 10:08:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:20 INFO - PROCESS | 1647 | RSS: Main: 324898816 10:08:20 INFO - PROCESS | 1647 | 10:08:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:23 INFO - PROCESS | 1647 | RSS: Main: 325808128 10:08:23 INFO - PROCESS | 1647 | 10:08:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 10:08:26 INFO - PROCESS | 1647 | RSS: Main: 323948544 10:08:26 INFO - PROCESS | 1647 | 10:08:28 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:29 INFO - PROCESS | 1647 | RSS: Main: 298688512 10:08:29 INFO - PROCESS | 1647 | 10:08:31 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:31 INFO - PROCESS | 1647 | RSS: Main: 300797952 10:08:31 INFO - PROCESS | 1647 | 10:08:33 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:34 INFO - PROCESS | 1647 | RSS: Main: 302825472 10:08:34 INFO - PROCESS | 1647 | 10:08:37 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:38 INFO - PROCESS | 1647 | RSS: Main: 321953792 10:08:38 INFO - PROCESS | 1647 | 10:08:41 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:41 INFO - PROCESS | 1647 | RSS: Main: 316006400 10:08:41 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 10:08:43 INFO - PROCESS | 1647 | RSS: Main: 359559168 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | __start_tp_report 10:08:43 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 10:08:43 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 10:08:43 INFO - PROCESS | 1647 | |i|pagename|runs| 10:08:43 INFO - PROCESS | 1647 | |0;ai-astar;115;112;114;112;135;170;152;113;113;111 10:08:43 INFO - PROCESS | 1647 | |1;audio-beat-detection;150;137;139;146;111;110;112;121;173;136 10:08:43 INFO - PROCESS | 1647 | |2;audio-dft;184;184;185;185;180;182;183;190;185;183 10:08:43 INFO - PROCESS | 1647 | |3;audio-fft;81;58;49;88;78;85;82;82;88;56 10:08:43 INFO - PROCESS | 1647 | |4;audio-oscillator;87;70;94;69;69;70;88;68;69;93 10:08:43 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;91;88;92;87;92;88;91;88;91;88 10:08:43 INFO - PROCESS | 1647 | |6;imaging-darkroom;93;96;97;97;95;96;96;96;96;95 10:08:43 INFO - PROCESS | 1647 | |7;imaging-desaturate;84;81;82;83;84;84;83;84;83;84 10:08:43 INFO - PROCESS | 1647 | |8;json-parse-financial;58;52;51;53;77;86;85;88;85;87 10:08:43 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;67;76;68;76;72;70;69;70;71;70 10:08:43 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;85;85;84;92;102;99;83;83;82;84 10:08:43 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;141;140;141;140;142;145;143;142;192;130 10:08:43 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;167;175;223;223;168;168;164;164;166;166 10:08:43 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;68;67;69;68;65;71;83;87;83;85 10:08:43 INFO - PROCESS | 1647 | __end_tp_report 10:08:43 INFO - PROCESS | 1647 | __start_cc_report 10:08:43 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1944 10:08:43 INFO - PROCESS | 1647 | __end_cc_report 10:08:43 INFO - PROCESS | 1647 | __startTimestamp1484762923934__endTimestamp 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | ------- Summary: start ------- 10:08:43 INFO - PROCESS | 1647 | Number of tests: 14 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:124.70 Median:114.50 stddev:20.81 (18.2%) stddev-sans-first:21.77 10:08:43 INFO - PROCESS | 1647 | Values: 115.0 112.0 114.0 112.0 135.0 170.0 152.0 113.0 113.0 111.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:133.50 Median:138.00 stddev:20.32 (14.7%) stddev-sans-first:20.65 10:08:43 INFO - PROCESS | 1647 | Values: 150.0 137.0 139.0 146.0 111.0 110.0 112.0 121.0 173.0 136.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:184.10 Median:184.50 stddev:2.60 (1.4%) stddev-sans-first:2.76 10:08:43 INFO - PROCESS | 1647 | Values: 184.0 184.0 185.0 185.0 180.0 182.0 183.0 190.0 185.0 183.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:74.70 Median:82.00 stddev:14.55 (17.7%) stddev-sans-first:15.26 10:08:43 INFO - PROCESS | 1647 | Values: 81.0 58.0 49.0 88.0 78.0 85.0 82.0 82.0 88.0 56.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:78.50 stddev:11.22 (14.3%) stddev-sans-first:11.38 10:08:43 INFO - PROCESS | 1647 | Values: 87.0 70.0 94.0 69.0 69.0 70.0 88.0 68.0 69.0 93.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:89.60 Median:91.00 stddev:1.96 (2.1%) stddev-sans-first:2.01 10:08:43 INFO - PROCESS | 1647 | Values: 91.0 88.0 92.0 87.0 92.0 88.0 91.0 88.0 91.0 88.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:95.70 Median:96.00 stddev:1.16 (1.2%) stddev-sans-first:0.71 10:08:43 INFO - PROCESS | 1647 | Values: 93.0 96.0 97.0 97.0 95.0 96.0 96.0 96.0 96.0 95.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:83.20 Median:84.00 stddev:1.03 (1.2%) stddev-sans-first:1.05 10:08:43 INFO - PROCESS | 1647 | Values: 84.0 81.0 82.0 83.0 84.0 84.0 83.0 84.0 83.0 84.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:72.20 Median:85.00 stddev:16.46 (19.4%) stddev-sans-first:16.63 10:08:43 INFO - PROCESS | 1647 | Values: 58.0 52.0 51.0 53.0 77.0 86.0 85.0 88.0 85.0 87.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:70.50 stddev:3.03 (4.3%) stddev-sans-first:2.87 10:08:43 INFO - PROCESS | 1647 | Values: 67.0 76.0 68.0 76.0 72.0 70.0 69.0 70.0 71.0 70.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:84.50 stddev:7.22 (8.5%) stddev-sans-first:7.58 10:08:43 INFO - PROCESS | 1647 | Values: 85.0 85.0 84.0 92.0 102.0 99.0 83.0 83.0 82.0 84.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:145.60 Median:142.00 stddev:16.78 (11.8%) stddev-sans-first:17.72 10:08:43 INFO - PROCESS | 1647 | Values: 141.0 140.0 141.0 140.0 142.0 145.0 143.0 142.0 192.0 130.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.40 Median:168.00 stddev:23.71 (14.1%) stddev-sans-first:24.78 10:08:43 INFO - PROCESS | 1647 | Values: 167.0 175.0 223.0 223.0 168.0 168.0 164.0 164.0 166.0 166.0 10:08:43 INFO - PROCESS | 1647 | 10:08:43 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.60 Median:77.00 stddev:8.72 (11.3%) stddev-sans-first:8.92 10:08:43 INFO - PROCESS | 1647 | Values: 68.0 67.0 69.0 68.0 65.0 71.0 83.0 87.0 83.0 85.0 10:08:43 INFO - PROCESS | 1647 | -------- Summary: end -------- 10:08:43 INFO - PROCESS | 1647 | 10:08:45 INFO - TEST-INFO | 1647: exit 0 10:08:45 INFO - TEST-OK | kraken | took 60705ms 10:08:45 INFO - SUITE-END | took 1179s 10:08:45 INFO - Completed test suite (00:19:40) 10:08:45 INFO - javascript benchmark 10:08:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.4189266878298, 3328.1845947742804, 3340.2985373071656, 3348.3303339332124, 3414.487826825976, 5621.606487545873, 5662.122371032491, 5777.22007528984, 5781.13283007549, 5783.884322313551, 5563.582731295159, 5600.2719646446, 5603.887922241563, 5625.156226566017, 5628.746706398211, 5130.6152038597065, 5134.25553294773, 5135.845924622248, 5157.097507936115, 5196.558292545138, 5174.689518628876, 5185.870353241162, 5198.974005129969, 5234.214867769827, 5266.973665131669, 5241.764120614582, 5254.710990895499, 5277.3139491866, 5279.340082489689, 5290.497402746733, 4375.409319741826, 5272.367315922075, 5297.761600727977, 5346.7861285548925, 5393.568514518868, 5111.105556527593, 5191.844244672666, 5268.078086334915, 5270.235815806686, 5291.497307755757, 5620.353659329147, 5641.294838145232, 5673.8581535461535, 5687.459691329317, 5694.003549378884, 6144.109104179869, 6204.379562043805, 6243.937560624381, 6276.466500347476, 6281.7173227204885, 6274.905876411857, 6321.24145102586, 6345.968270158666, 6348.238211414614, 6355.904661430082, 5660.462256085665, 5721.885562288731, 5735.369109397963, 5855.033919403336, 5856.092305692625, 6239.188905442264, 6244.250689917239, 6263.561550691408, 6265.65538895356, 6288.748450062038, 5495.148252020943, 6150.7847225346895, 6162.938370616326, 6173.59871608344, 6182.876342473215, 4796.37647105874, 6162.26052873649, 6208.441240288395, 6213.720382582795, 6220.191375121205, 6206.441420272197, 6209.6895155242055, 6227.906581401283, 6235.563510554309, 6236.03341482071, 6169.876602467925, 6199.9770037943235, 6257.56197066201, 6262.624242545371, 6289.213848268966, 4836.250381190921, 4849.539293767087, 4864.805407783649, 4904.485029071896, 4944.1100601891285, 4363.912721745547, 4409.40473036136, 4414.580614841585, 4431.290993441034, 4487.169873573399, 5711.600187986801, 5727.541796656257, 5730.312362516442, 5739.110437882135, 5746.971265143731, 6083.391660833881, 6285.057241413733, 6302.149209856702, 6312.147860038836, 6323.683815809191, 5622.690752008597, 5669.829905102853, 5679.716014199274, 5706.885862282814, 5716.056850619684, 4975.154223781966, 5088.033273678064, 5382.892342153135, 5423.322084739408, 5452.372977107683, 6273.184486016971, 6278.623282603058, 6283.277423096402, 6286.779962701284, 6290.874182516415, 6055.818325450244, 6099.664518451527, 6104.053871649901, 6179.196704428576, 6228.626282423069, 6211.254649442096, 6304.810855674337, 6306.306306306211, 6309.526785491014, 6312.589681670676, 6200.038993955944, 6203.193584833943, 6209.782657606962, 6222.751089956351, 6225.408586184486, 6130.172426722335, 6149.538784591084, 6195.411435913581, 6255.78104766331, 6295.937040629535, 6076.696165191811, 6139.877202455837, 6142.375998160927, 6152.200213972155, 6175.9073613894925, 6110.710646019097, 6118.20463339763, 6121.392004143408, 6121.645528617276, 6130.551011451207, 5796.478316951495, 5854.4730974212525, 5956.404359564009, 5987.64074155552, 6020.668863212565, 6083.695815209133, 6087.665178415053, 6114.6942652868065, 6150.292716337678, 6154.446099850856, 5958.791442299672, 6004.459598635969, 6016.8796624068145, 6041.154238406539, 6049.243844519435], "value": 5685.4130323226545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.86080106368615, 476.2736826339831, 494.6265569495031, 500.28709089547385, 517.363642719455, 29333.506651934586, 32001.71993120278, 32014.839925800396, 32029.35941281173, 32107.0, 32086.67913320866, 40857.18285634286, 42331.153376932445, 42412.787936060355, 44085.559144408544, 9393.201577865906, 9545.856812147824, 9547.18848897841, 9551.235901127928, 9556.856647150298, 9357.719268421924, 9365.110314520143, 9465.006174351698, 9469.147776700096, 9477.857832132524, 7889.921100789004, 9485.241180705527, 9513.71458856232, 9521.000294969032, 9553.904460955371, 8690.739277821676, 8813.633886747532, 9359.95320023399, 9429.15137637609, 9431.198348140417, 9397.248220142372, 9493.240540756722, 9495.14543691071, 9506.239500839916, 9531.428114313163, 39064.218715625815, 39300.21399572021, 39426.80286598549, 39476.81569552918, 39609.60390396088, 3358.1604598850286, 3372.224388390684, 3661.2311414602964, 3822.73240873139, 3899.78551179685, 28687.71312286892, 29016.9370015235, 29317.706822931923, 29468.26329341762, 30368.0, 3143.764217683683, 3162.8418579071185, 3190.8245046522434, 3210.550522926792, 3211.486162214058, 469879.0, 472628.0, 476337.0, 478176.0, 478643.0, 60684.482887927275, 61007.38992610106, 61099.694501528094, 61121.08318375228, 61233.38766612277, 34516.0, 35491.0, 64084.0, 64124.67937660375, 64437.0, 15270.236488175547, 15768.89617726771, 15840.76238856418, 15844.920775396049, 15855.365785368687, 11675.76648467026, 11687.532498700128, 11723.0, 11732.296062236293, 11734.237274577124, 19133.23467061302, 19194.13626386816, 19199.040047997823, 19213.615727685366, 19223.807761922202, 6343.27052388972, 6376.521760867953, 7397.075365579302, 7566.767803497982, 7674.232576742281, 807.6204184033612, 888.5956889615339, 939.7556635274877, 961.8797650293714, 968.4721826604376, 26990.730092699214, 29350.853245734063, 30178.69821301803, 30455.847720761696, 32337.676623233467, 848.4442690038035, 883.9823203536022, 941.990580094204, 1006.7483129217695, 1023.3757407981126, 11023.393713345842, 14929.253537323308, 15869.285882135331, 15870.0, 16356.836431635531, 682453.0, 684239.0, 685361.0, 687932.0, 699970.0, 48006.75996619995, 57520.42479575234, 57938.710306449044, 58104.70947645235, 58226.41773582295, 1080.4705694209624, 1191.2673705671123, 1229.6065259116997, 1231.8090695942144, 1317.1636011132807, 29040.273993150342, 30352.0, 30674.846625767623, 31197.376052479278, 31366.843165784026, 50362.2445663308, 50942.490575093776, 51082.489175107774, 51091.0, 51117.0, 564219.0, 568782.0, 570784.0, 572202.1389893191, 573454.0, 21228.7877121233, 26268.60597091007, 27143.0, 27159.0, 27852.16443506698, 45804.08391832078, 45947.77026114848, 46216.768916155204, 46472.0, 46521.06957860891, 37315.81342093272, 37615.05962350963, 38472.80763596164, 39631.81104566868, 39804.40293395543, 37831.43252851265, 38117.0, 38180.427293590066, 39350.0, 39404.60595394124, 36825.63174368329, 37101.628983709816, 37596.2480750378, 38174.0, 38195.42706859455, 28372.716272837006, 32991.83504082464, 33318.0, 33500.0, 34636.82681586677, 21003.0, 25483.872580636977, 25514.872425637754, 25713.357166070997, 25962.610560841982], "value": 19307.21421343947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.30774449743575, 367.9043448703337, 368.69582594359645, 378.1944458303813, 395.57475713607863, 14552.34514446851, 14761.926190369046, 15433.614159646015, 18026.5493362666, 19939.501512462197, 18716.0, 19094.90452547739, 19575.021248937566, 19769.703454448158, 19796.30712925048, 5478.369987451434, 5795.507381872546, 5798.62308949919, 5810.56420768442, 5831.241938548004, 4588.977055114719, 5544.390117087117, 5634.154876768476, 5715.057015592422, 5728.770849166029, 5649.774009039634, 5663.575231857607, 5670.461306175906, 5675.971620141914, 5679.488846003859, 4832.275158726184, 5423.484768946963, 5662.207290979266, 5699.487046165824, 5720.227769251181, 5007.6244281678655, 5021.146405111103, 5022.296878437021, 5024.648274620778, 5037.722925239111, 18267.543311417186, 22353.441163970867, 23602.881985590135, 24842.00631974719, 24867.50264994708, 2189.627763280246, 2236.8993395297252, 2242.5066485373354, 2293.8050265727265, 2337.0885354711504, 13921.095128816594, 15072.623184420478, 15318.463853765175, 15609.375624974988, 15862.841371586252, 1736.0, 1779.4483710049797, 1823.6717390869637, 1837.9724304135448, 1889.3104017033684, 101580.0, 104658.0, 104977.47511262471, 105215.4739226299, 105281.0, 8626.870596941051, 10370.481475926174, 10390.688279351632, 10425.0, 10784.191185661106, 13517.59447216585, 13547.25490098054, 13548.254845983369, 13561.525346612822, 13584.728305434033, 6376.776812811529, 7905.328047115944, 7958.045034595885, 8073.6366863491285, 8084.514929104273, 6291.811245662638, 7731.845363092707, 7785.766427007199, 7807.492512986638, 7885.448018638755, 6370.8088757337355, 6495.0, 6514.283428822826, 6533.705983230766, 6643.169603799525, 1772.7340898865273, 1788.2578729826976, 1791.4715159028117, 1793.2199493220492, 1794.3809385761892, 738.3207449146798, 765.9731909383145, 897.6274845938863, 940.9200217981411, 966.3573723473952, 20716.0, 21217.372197891855, 21746.25723964593, 21859.562808744053, 21876.45308867291, 931.2131249094505, 993.2203220471895, 1007.4811472091878, 1072.3137192197, 1074.908632766208, 10523.315984460985, 11240.269382490113, 11443.141764367705, 11456.885431145749, 11488.77022459546, 602056.0, 607758.9612051911, 610751.0, 622148.0, 624299.0, 110043.0, 111757.0, 113646.0, 113820.29269560963, 119577.0, 902.9142231487998, 974.1378879691382, 994.7065615643403, 1000.364768372089, 1006.0291818395137, 15431.84568154304, 18549.072546372896, 18877.71683424777, 19047.333343332917, 19107.713384299524, 51287.97424051573, 53265.0, 55517.89303176566, 55734.16398753941, 55787.88424231412, 82087.58956205181, 82445.0, 82584.58707706664, 85498.57250713707, 85853.57073214593, 6201.844953876189, 7499.325060744559, 7566.659500322608, 7698.884516732365, 7769.961150194213, 8515.361347898808, 11994.700132496757, 12483.188592741442, 12523.248605083723, 12745.61763147107, 10920.78158436811, 10931.617393391192, 10991.285566438157, 11012.559497620005, 11106.777864442827, 10566.41884696349, 11053.778924421627, 11245.887541124484, 11406.48670809799, 11666.824997624874, 11094.667159985212, 11431.742508323912, 11638.88361116378, 11749.00133488663, 11845.289282643069, 8034.919650803417, 8299.709510167115, 8329.6668133274, 8370.204830541088, 8443.95778021106, 10532.420716860646, 10829.509212880925, 11108.389038602954, 11305.773884522428, 11541.192116552096], "value": 9387.990629804604, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1294.6051454306444, 1382.2881216173673, 1441.8125643666322, 1481.1039321210667, 1716.9227384767685, 6799.966000169999, 7393.112826460825, 7797.142314345418, 8370.916290837089, 8384.664613415456, 8192.0, 8290.502569845814, 8291.502509849413, 8293.129221431738, 8294.046184688763, 2269.818414526842, 2279.168103642171, 2313.6529520571967, 2317.6986991691056, 2320.211128216406, 2298.643710224918, 2336.7195936487647, 2356.1988923765916, 2357.882105894707, 2360.492494113765, 2300.7124109486313, 2301.1255722825385, 2330.883455827202, 2337.0885354711677, 2340.918067867625, 2174.6629272462715, 2207.907085992428, 2225.3880182949656, 2238.1271304191378, 2238.50752834377, 2304.308707387777, 2336.953260934789, 2337.2871274261342, 2339.508703172336, 2339.7309309429465, 7879.842403151963, 8177.5502347370875, 8300.833983320299, 8377.204165604318, 8396.664133434655, 5035.269885866529, 5184.015037142968, 5184.740762961875, 5186.5850731941355, 5198.324217851655, 3055.511118221074, 3264.2329052672603, 3361.6638336166434, 3384.815314639881, 3461.809600471973, 947.8483442649213, 1001.9899801001969, 1010.8635334229932, 1024.55943944104, 1024.733569271987, 10707.732306692395, 10764.784704305948, 10868.510917008754, 10879.12966962641, 10895.291806032583, 4249.575042495788, 4362.563743625676, 4455.554444555583, 4456.732596044248, 4468.709533880287, 8596.570171491525, 8607.053224145338, 8612.975950651591, 8617.655293788179, 8617.69838055665, 4991.80032798684, 5061.949380506221, 5064.873378165502, 5073.340465739504, 5082.771275292621, 3997.5802540733384, 4231.597998190167, 4332.956670433318, 4401.119776044804, 4663.440387153495, 2987.4025194961096, 3629.4011488104165, 3713.313037088147, 3718.7025037996896, 3724.273766615538, 987.0080569028081, 1135.6820090374708, 1142.4116579961326, 1148.6898537394857, 1156.9074474042225, 750.1823012915947, 762.5920132728963, 851.391255252485, 864.8616221404545, 889.835380454618, 5337.519623233928, 5477.45225477457, 5481.917771233435, 5703.258576385043, 5733.455321744427, 436.5525336530019, 444.16496985666737, 447.29997553828156, 450.56070331427253, 458.33999041380304, 3572.499850020999, 3664.871729489455, 3678.7240956928335, 3727.7390582659496, 3782.7541209821275, 9834.901650983542, 10126.746831329276, 10170.796584068425, 10282.07461328469, 10316.226283028804, 10066.14437772783, 10203.0, 10246.53890574926, 10255.487225638688, 10266.178705703525, 7605.505642133244, 7816.843663126706, 7901.683932642745, 7992.720254791055, 8032.558209298545, 4657.930131048105, 5358.84502343318, 5475.5072043516275, 5493.23094766725, 5504.6421982571, 9472.052794720474, 9477.952610236905, 9486.667966621135, 9493.667721629987, 9494.620215191315, 8755.868661969947, 9123.178913897782, 9186.54067296646, 9551.42691438516, 9624.51877406141, 6101.664408457577, 6343.365663433619, 6426.4216220540375, 6798.490113241625, 6826.761063362758, 8005.279524842792, 8078.83842323162, 8106.47307924983, 8123.309518690978, 8135.633896474791, 7139.500234983501, 7189.928100719135, 7210.9278907210255, 7215.134183897966, 7247.528910620793, 6980.755673551402, 7017.228104908354, 7059.5058345915295, 7070.328318809705, 7105.25394833535, 6694.83262918431, 6700.899486507804, 6718.899216511659, 6772.695228714621, 6804.21751498584, 4555.681102322938, 4601.516840731675, 4666.929996049994, 4706.952930470651, 4763.4522029967, 4639.44326680787, 4776.920697328703, 4832.275158726219, 4835.129676658235, 4950.158473059517], "value": 4228.521099222903, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [273.4135279824776, 305.55084026481063, 305.58745693314023, 308.60190354442784, 313.1638525137882, 4633.0, 5202.48832038374, 5252.185911183773, 5256.500632439922, 5263.710495922723, 4055.9797201014026, 4651.7441540715245, 5209.426963034063, 5223.250211179259, 5246.554042906348, 3466.653334666526, 3725.6833169180654, 3750.1937083527037, 3876.8643097491595, 4068.8169032393466, 3460.6640517650485, 3854.4989151410277, 3944.16020465266, 3997.8001099944886, 4077.9592204078026, 3409.244104474257, 3530.8234588270484, 3939.2121575684832, 3969.4244334571613, 4012.4953142571535, 3639.7634153780186, 3804.4293355996824, 3986.621270979252, 3999.040230344711, 4006.298897692907, 3447.293304872495, 3465.376232278189, 3475.6350583188655, 3480.617132115465, 3484.9477257841154, 4515.458145022619, 4697.436307643105, 5034.496550344973, 5042.495750424965, 5056.747162641853, 4378.5183629800695, 4501.459824821042, 4545.545445455461, 4550.3402006709175, 4560.863174104782, 5342.626016178869, 5394.1908713692865, 5414.35027796663, 5418.539424148952, 5433.592480563973, 3802.543694756619, 3803.2885201659365, 3821.5987321331136, 3825.2923209206106, 3839.232153569297, 5345.652532585447, 5407.3781515125875, 5721.542276617861, 5735.827925162252, 5764.798232061899, 3750.062484378905, 3780.2061567070673, 3791.9572117667312, 3796.487474191004, 3799.556168655948, 3459.930801383958, 3836.9424458633143, 3868.613138686109, 3874.6319099685484, 3890.8638197662945, 3723.3670276053135, 4428.446444194476, 4442.445144199569, 4497.910041799146, 4510.684252102387, 5259.500347466985, 5390.97304513475, 5428.972855135699, 5480.931218412451, 5493.395726470085, 1234.4383305595932, 1254.4543123741203, 1255.2656695832857, 1259.6018419554287, 1264.6711854917787, 740.3263030642197, 810.4772421787914, 845.9425717852685, 856.7858035491128, 867.483847110968, 4473.373727678128, 4489.663275254369, 4894.314795928502, 4927.285543596158, 5076.314697515788, 4685.079117561783, 5605.299337582802, 5630.859228519238, 5645.294338207724, 5653.265075540154, 5177.534021938043, 5182.170852663556, 5183.496785932113, 5186.484554990827, 5187.028929007029, 2437.720731116412, 2718.619393284942, 2794.4131732336436, 2795.692473827877, 2797.2866918935542, 4853.611711063177, 4945.332380128709, 5114.590832733446, 5530.364008139035, 5557.3331200256225, 4610.469795973506, 4948.628852836052, 5371.060064488777, 5472.671639701631, 5522.889542209134, 4893.02139572087, 5421.972890135446, 5607.271054762855, 5827.883442331215, 5870.73581688825, 4856.490068542752, 5591.384947655836, 5623.8594035149445, 5648.88702225945, 5662.433756624305, 4921.434035085867, 5233.633645644768, 5505.118891181787, 5555.388907220122, 5565.1374037024325, 5234.659747116425, 5529.115341545334, 5893.941060589339, 5899.085641725539, 5911.0, 3701.833922314462, 3817.828197731053, 3819.6371344722206, 3840.8655697050467, 3868.6324799144036, 5493.560515158858, 5622.85942851432, 5656.406077361818, 5683.964385286493, 5743.540516758733, 4841.489919808111, 5506.944930550643, 5516.172574113915, 5516.613837031369, 5530.834074977757, 4570.7257564546235, 5481.616286859881, 5499.4775496329385, 5501.779928802963, 5524.972375138099, 4011.4383986241364, 4541.364209010807, 4678.274867395559, 5437.809676661298, 5458.781648734006, 5235.691077230692, 5400.648957817729, 5411.675299481886, 5533.197686335539, 5538.584606154635, 5057.418396884405, 5200.739963001759, 5280.630355875205, 5284.365876094893, 5291.656042357235], "value": 3974.5256202284613, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [664.9235337936138, 715.8890371992339, 760.2321655128319, 775.2480094308519, 791.0467886197133, 13921.651958701037, 14241.572752817407, 14306.141631502118, 14703.852961470382, 14787.40850365984, 11936.0, 12925.28910909902, 13289.867101328984, 13314.201147931131, 13322.733545329087, 773.0877564473933, 814.9266566009057, 815.213319147023, 816.4856140631393, 818.3412353055787, 651.8142329436112, 677.7085853083172, 678.3420082519948, 790.640258682301, 811.8376324735048, 858.2790456016944, 865.7272959017921, 865.8484765166103, 868.2793281576309, 870.4298684361753, 858.4891989266408, 858.9656413743443, 859.8194379180379, 860.1742327365737, 861.5821326656567, 505.7092172001114, 525.2226704477357, 616.636184651058, 641.1568787045021, 642.2838535033404, 13562.728745425036, 14087.154770713687, 14092.647683807885, 14108.506202282973, 14129.646758831008, 12812.42344094518, 13034.608961731163, 13272.469101235938, 13278.668033299246, 13306.534271300457, 10221.9488902555, 11626.0, 12033.0, 12045.337506437143, 12078.516859325617, 4223.556526564728, 4371.759553224571, 4396.956030439686, 4478.238699421074, 4519.728816271001, 90968.09031909729, 94832.57751133738, 95928.0, 96016.51991740067, 96260.51869740676, 10233.385996840214, 10301.690949271533, 10304.0, 10388.584456621802, 10442.53008614599, 16183.43357982465, 19744.210231590863, 19935.501612459804, 20017.699734503993, 20021.499462513264, 6421.389967953037, 6685.431738302202, 6708.396244337935, 6711.798646040626, 6941.722331106699, 3870.3033453978555, 3871.748336358184, 3882.8640997565512, 3984.0438294809037, 4079.4900637420324, 3160.352127813793, 3295.3903527846946, 3312.801231926092, 3331.417002024636, 3331.8833840815455, 586.3257254157668, 627.2629660149306, 627.3569591169088, 633.6863252689948, 635.1711017122607, 465.7647887816699, 553.6263022459824, 607.7903123422412, 613.7238242790672, 633.9746410143634, 7938.801529961797, 8498.192671696179, 8811.383203175845, 8880.245179159714, 9044.186023257938, 594.5392320951297, 640.113442881603, 650.3658932540735, 663.1213641924469, 673.9932600673931, 4178.310578754532, 4244.0, 4298.312270036779, 4338.327559228324, 4411.625011874026, 116663.0, 116772.0, 118288.0, 119841.0, 124920.37539812243, 80942.19057809464, 81986.0, 82358.58820705858, 82873.75689364664, 102073.97926020794, 73979.0, 79458.0, 80390.5980470094, 83194.58402707947, 84462.83378393839, 8961.731148065568, 8970.910290897007, 9426.717198484057, 9429.717108486757, 9442.811143777224, 52796.0, 53063.0, 53183.93632127413, 53517.66205844885, 55246.89506209773, 66204.0, 68054.61808836667, 68381.31618683749, 70835.29164708493, 73983.0, 11950.940245299065, 12062.457189426672, 12158.574449894211, 12198.634040978784, 12510.687232819253, 10624.946875265574, 13561.728765424834, 13887.58337249884, 13893.930530347283, 13990.860091398956, 12234.0, 12408.34011371057, 12672.683182920133, 12705.93647031796, 12866.228026318451, 12481.56314528987, 12504.12372164632, 12749.107562470537, 12833.871661283267, 12960.0, 11466.885331146916, 12083.939580302042, 12362.876371236172, 12601.68495787576, 12626.55807046749, 10467.0, 10774.138068954622, 10987.505562249558, 11146.996770290712, 11346.659600212008, 10635.680929571816, 10788.892111079103, 11072.169587281147, 11093.33439993603, 11197.048250898764], "value": 7122.617543916408, "unit": "score"}], "name": "dromaeo_css", "value": 7055.63455668486, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 112.0, 114.0, 112.0, 135.0, 170.0, 152.0, 113.0, 113.0, 111.0], "value": 124.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 137.0, 139.0, 146.0, 111.0, 110.0, 112.0, 121.0, 173.0, 136.0], "value": 133.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 185.0, 180.0, 182.0, 183.0, 190.0, 185.0, 183.0], "value": 184.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 49.0, 88.0, 78.0, 85.0, 82.0, 82.0, 88.0, 56.0], "value": 74.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 94.0, 69.0, 69.0, 70.0, 88.0, 68.0, 69.0, 93.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 92.0, 87.0, 92.0, 88.0, 91.0, 88.0, 91.0, 88.0], "value": 89.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 97.0, 97.0, 95.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 81.0, 82.0, 83.0, 84.0, 84.0, 83.0, 84.0, 83.0, 84.0], "value": 83.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 52.0, 51.0, 53.0, 77.0, 86.0, 85.0, 88.0, 85.0, 87.0], "value": 72.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 68.0, 76.0, 72.0, 70.0, 69.0, 70.0, 71.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 92.0, 102.0, 99.0, 83.0, 83.0, 82.0, 84.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 140.0, 141.0, 140.0, 142.0, 145.0, 143.0, 142.0, 192.0, 130.0], "value": 145.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 175.0, 223.0, 223.0, 168.0, 168.0, 164.0, 164.0, 166.0, 166.0], "value": 178.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 69.0, 68.0, 65.0, 71.0, 83.0, 87.0, 83.0, 85.0], "value": 74.6, "unit": "score"}], "name": "kraken", "value": 1492.8000000000002, "alertThreshold": 2.0}]} 10:08:45 INFO - Return code: 0 10:08:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:08:45 INFO - # TBPL SUCCESS # 10:08:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:08:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:08:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:08:45 INFO - 2017-01-18 10:08:45.740 screenresolution[1651:9804] starting screenresolution argv=screenresolution get 10:08:45 INFO - 2017-01-18 10:08:45.742 screenresolution[1651:9804] Display 0: 1600x1200x32@60 10:08:45 INFO - 2017-01-18 10:08:45.748 screenresolution[1652:9807] starting screenresolution argv=screenresolution list 10:08:45 INFO - Available Modes on Display 0 10:08:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:08:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:08:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:08:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:08:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:08:45 INFO - Intel Iris: 10:08:45 INFO - Chipset Model: Intel Iris 10:08:45 INFO - Type: GPU 10:08:45 INFO - Bus: Built-In 10:08:45 INFO - VRAM (Dynamic, Max): 1536 MB 10:08:45 INFO - Vendor: Intel (0x8086) 10:08:45 INFO - Device ID: 0x0a2e 10:08:45 INFO - Revision ID: 0x0009 10:08:45 INFO - Displays: 10:08:45 INFO - Display: 10:08:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:08:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:08:45 INFO - Display Serial Number: 1600x1200 60 10:08:45 INFO - Main Display: Yes 10:08:45 INFO - Mirror: Off 10:08:45 INFO - Online: Yes 10:08:45 INFO - Rotation: Supported 10:08:45 INFO - Return code: 0 10:08:45 INFO - Running post-action listener: _resource_record_post_action 10:08:45 INFO - [mozharness: 2017-01-18 18:08:45.984635Z] Finished run-tests step (success) 10:08:45 INFO - Running post-run listener: _resource_record_post_run 10:08:46 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 39130112; Write bytes: 658838528; Read time: 380; Write time: 2024 10:08:46 INFO - TinderboxPrint: CPU usage
53.7% 10:08:46 INFO - TinderboxPrint: I/O read bytes / time
39,130,112 / 380 10:08:46 INFO - TinderboxPrint: I/O write bytes / time
658,838,528 / 2,024 10:08:46 INFO - TinderboxPrint: CPU idle
2,248.2 (46.3%) 10:08:46 INFO - TinderboxPrint: CPU system
984.3 (20.3%) 10:08:46 INFO - TinderboxPrint: CPU user
1,621.2 (33.4%) 10:08:46 INFO - TinderboxPrint: Swap in / out
388,407,296 / 0 10:08:46 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 348588544; Write bytes: 345355776; Read time: 27752; Write time: 739 10:08:46 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 31812608; Write bytes: 306576896; Read time: 292; Write time: 1258 10:08:46 INFO - Running post-run listener: _upload_blobber_files 10:08:46 INFO - Blob upload gear active. 10:08:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:08:46 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 10:08:46 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'] 10:08:46 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 10:08:46 INFO - (blobuploader) - INFO - Open directory for files ... 10:08:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:08:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:08:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:48 INFO - (blobuploader) - INFO - Done attempting. 10:08:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:08:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:08:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:49 INFO - (blobuploader) - INFO - Done attempting. 10:08:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:08:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:08:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:51 INFO - (blobuploader) - INFO - Done attempting. 10:08:51 INFO - (blobuploader) - INFO - Iteration through files over. 10:08:51 INFO - Return code: 0 10:08:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:08:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:08:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd85a3e0b2a3655f0e767f9e1afef15397d761de4d5ec3ff9ca6c24956ee580a9a2300c7371b40533fb086afb9cc3d98f2745c13e9f62826058cd5ec54ba3df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f31f65b929152362330b16d3632ea58aa4fbcdcc1fb68eb7d121ff145298f2644c121db6502ff1ce6de4be049e2943b4e23daa6e010516434f7076b592c7075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8871738ce06ca404abfe9c53b0380c1450f80bd2f22dc630b2f8b9b85e7885402465c3eece245efab158dabf8c59ed9c9a1065e69a76d283a04abea6c1107d4b"} 10:08:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:08:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:08:51 INFO - Contents: 10:08:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9cd85a3e0b2a3655f0e767f9e1afef15397d761de4d5ec3ff9ca6c24956ee580a9a2300c7371b40533fb086afb9cc3d98f2745c13e9f62826058cd5ec54ba3df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f31f65b929152362330b16d3632ea58aa4fbcdcc1fb68eb7d121ff145298f2644c121db6502ff1ce6de4be049e2943b4e23daa6e010516434f7076b592c7075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8871738ce06ca404abfe9c53b0380c1450f80bd2f22dc630b2f8b9b85e7885402465c3eece245efab158dabf8c59ed9c9a1065e69a76d283a04abea6c1107d4b"} 10:08:51 INFO - Running post-run listener: copy_logs_to_upload_dir 10:08:51 INFO - Copying logs to upload dir... 10:08:51 INFO - mkdir: /builds/slave/test/build/upload/logs 10:08:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.492858 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-18 10:08:51.357584) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:51.360769) ========= 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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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/9cd85a3e0b2a3655f0e767f9e1afef15397d761de4d5ec3ff9ca6c24956ee580a9a2300c7371b40533fb086afb9cc3d98f2745c13e9f62826058cd5ec54ba3df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f31f65b929152362330b16d3632ea58aa4fbcdcc1fb68eb7d121ff145298f2644c121db6502ff1ce6de4be049e2943b4e23daa6e010516434f7076b592c7075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8871738ce06ca404abfe9c53b0380c1450f80bd2f22dc630b2f8b9b85e7885402465c3eece245efab158dabf8c59ed9c9a1065e69a76d283a04abea6c1107d4b"} build_url:https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011153 build_url: 'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/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/9cd85a3e0b2a3655f0e767f9e1afef15397d761de4d5ec3ff9ca6c24956ee580a9a2300c7371b40533fb086afb9cc3d98f2745c13e9f62826058cd5ec54ba3df", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9f31f65b929152362330b16d3632ea58aa4fbcdcc1fb68eb7d121ff145298f2644c121db6502ff1ce6de4be049e2943b4e23daa6e010516434f7076b592c7075", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8871738ce06ca404abfe9c53b0380c1450f80bd2f22dc630b2f8b9b85e7885402465c3eece245efab158dabf8c59ed9c9a1065e69a76d283a04abea6c1107d4b"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:51.394887) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:51.395174) ========= 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.WFbA4rt7OY/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.XJqsXAvQqb/Listeners TMPDIR=/var/folders/tg/r664rfr17rsdq6gszpzv8rzm00000w/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.005201 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:51.443184) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 10:08:51.443502) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 10:08:51.443832) ========= ========= Total master_lag: 0.17 =========