builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0161 starttime: 1484761657.33 results: success (0) buildid: 20170118070027 builduid: 436fa47a262a452092f35e89ab1634db revision: 27643a39e551670484fe7dd5836aaa01d66b9a7b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.329568) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.330027) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.330297) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.356437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.356731) ========= 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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.2M=0.001s 2017-01-18 09:47:37 (17.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.348629 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.729375) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.729702) ========= 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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.028380 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.779698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:37.780044) ========= 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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:37,874 truncating revision to first 12 chars 2017-01-18 09:47:37,874 Setting DEBUG logging. 2017-01-18 09:47:37,874 attempt 1/10 2017-01-18 09:47:37,874 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:38,519 unpacking tar archive at: mozilla-aurora-27643a39e551/testing/mozharness/ program finished with exit code 0 elapsedTime=0.918904 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:38.722686) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:38.723005) ========= script_repo_revision: 27643a39e551670484fe7dd5836aaa01d66b9a7b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:38.723410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:38.723685) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-18 09:47:38.743204) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-18 09:47:38.743505) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:38 INFO - MultiFileLogger online at 20170118 09:47:38 in /builds/slave/test 09:47:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --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:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:47:38 INFO - {'append_to_log': False, 09:47:38 INFO - 'base_work_dir': '/builds/slave/test', 09:47:38 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:47:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:47:38 INFO - 'branch': 'Mozilla-Aurora', 09:47:38 INFO - 'buildbot_json_path': 'buildprops.json', 09:47:38 INFO - 'config_files': ('talos/mac_config.py',), 09:47:38 INFO - 'default_actions': ('clobber', 09:47:38 INFO - 'read-buildbot-config', 09:47:38 INFO - 'download-and-extract', 09:47:38 INFO - 'populate-webroot', 09:47:38 INFO - 'create-virtualenv', 09:47:38 INFO - 'install', 09:47:38 INFO - 'run-tests'), 09:47:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:47:38 INFO - 'download_minidump_stackwalk': True, 09:47:38 INFO - 'download_symbols': 'ondemand', 09:47:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:47:38 INFO - 'tooltool.py': '/tools/tooltool.py', 09:47:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:47:38 INFO - '/tools/misc-python/virtualenv.py')}, 09:47:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:47:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:47:38 INFO - 'installer_path': 'installer.exe', 09:47:38 INFO - 'log_level': 'info', 09:47:38 INFO - 'log_name': 'talos', 09:47:38 INFO - 'log_to_console': True, 09:47:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:47:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:47:38 INFO - 'opt_config_files': (), 09:47:38 INFO - 'pip_index': False, 09:47:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:38 INFO - 'cmd': ('bash', 09:47:38 INFO - '-c', 09:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:38 INFO - 'enabled': True, 09:47:38 INFO - 'halt_on_failure': False, 09:47:38 INFO - 'name': 'check_screen_resolution'},), 09:47:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:47:38 INFO - 'cmd': ('bash', 09:47:38 INFO - '-c', 09:47:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:47:38 INFO - 'enabled': True, 09:47:38 INFO - 'halt_on_failure': False, 09:47:38 INFO - 'name': 'check_screen_resolution'},), 09:47:38 INFO - 'run_cmd_checks_enabled': True, 09:47:38 INFO - 'sps_profile': False, 09:47:38 INFO - 'sps_profile_interval': 0, 09:47:38 INFO - 'suite': 'dromaeojs-e10s', 09:47:38 INFO - 'system_bits': '32', 09:47:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:47:38 INFO - 'title': 't-yosemite-r7-0161', 09:47:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:47:38 INFO - 'use_talos_json': True, 09:47:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:47:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:47:38 INFO - 'work_dir': 'build'} 09:47:38 INFO - [mozharness: 2017-01-18 17:47:38.945534Z] Running clobber step. 09:47:38 INFO - Running pre-action listener: _resource_record_pre_action 09:47:38 INFO - Running main action method: clobber 09:47:38 INFO - rmtree: /builds/slave/test/build 09:47:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:47:41 INFO - Running post-action listener: _resource_record_post_action 09:47:41 INFO - [mozharness: 2017-01-18 17:47:41.415980Z] Finished clobber step (success) 09:47:41 INFO - [mozharness: 2017-01-18 17:47:41.416093Z] Running read-buildbot-config step. 09:47:41 INFO - Running pre-action listener: _resource_record_pre_action 09:47:41 INFO - Running main action method: read_buildbot_config 09:47:41 INFO - Using buildbot properties: 09:47:41 INFO - { 09:47:41 INFO - "project": "", 09:47:41 INFO - "product": "firefox", 09:47:41 INFO - "script_repo_revision": "production", 09:47:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 09:47:41 INFO - "repository": "", 09:47:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 09:47:41 INFO - "buildid": "20170118070027", 09:47:41 INFO - "pgo_build": "False", 09:47:41 INFO - "basedir": "/builds/slave/test", 09:47:41 INFO - "buildnumber": 39, 09:47:41 INFO - "slavename": "t-yosemite-r7-0161", 09:47:41 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 09:47:41 INFO - "platform": "yosemite_r7", 09:47:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:47:41 INFO - "branch": "mozilla-aurora", 09:47:41 INFO - "repo_path": "releases/mozilla-aurora", 09:47:41 INFO - "revision": "27643a39e551670484fe7dd5836aaa01d66b9a7b", 09:47:41 INFO - "stage_platform": "macosx64", 09:47:41 INFO - "builduid": "436fa47a262a452092f35e89ab1634db", 09:47:41 INFO - "slavebuilddir": "test" 09:47:41 INFO - } 09:47:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:47:41 INFO - Running post-action listener: _resource_record_post_action 09:47:41 INFO - [mozharness: 2017-01-18 17:47:41.419138Z] Finished read-buildbot-config step (success) 09:47:41 INFO - [mozharness: 2017-01-18 17:47:41.419280Z] Running download-and-extract step. 09:47:41 INFO - Running pre-action listener: _resource_record_pre_action 09:47:41 INFO - Running main action method: download_and_extract 09:47:41 INFO - mkdir: /builds/slave/test/build/tests 09:47:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:47:41 INFO - trying https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:41 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:41 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:42 INFO - Downloaded 1199 bytes. 09:47:42 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:47:42 INFO - Using the following test package requirements: 09:47:42 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:42 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:47:42 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:47:42 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'jsshell-mac.zip'], 09:47:42 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:47:42 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:47:42 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:47:42 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:47:42 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:47:42 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:47:42 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:47:42 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:47:42 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:42 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:42 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:42 INFO - Http code: 200 09:47:42 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 09:47:42 INFO - x-amz-cf-id: 5Ykfka8g8B-v5zSM6ZgccvNzAIMQlMVchBETWc3D3nKgSY_mTwzybA== 09:47:42 INFO - x-amz-version-id: 7mkAvysHwHLRpUONwQpNb5vFrjcsgGa6 09:47:43 INFO - Expected file size: 16276157 09:47:43 INFO - Obtained file size: 16276157 09:47:49 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:49 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:49 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:49 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:50 INFO - Http code: 200 09:47:50 INFO - via: 1.1 49c80a47c1441dd194a8337982f1cd7e.cloudfront.net (CloudFront) 09:47:50 INFO - x-amz-cf-id: xhOWJsadrYDCSm8amr912SQpgmbWu2ZYDJSbfKIC7k-5wt4Pad8lqw== 09:47:50 INFO - x-amz-version-id: 7mkAvysHwHLRpUONwQpNb5vFrjcsgGa6 09:47:50 INFO - Expected file size: 16276157 09:47:50 INFO - Obtained file size: 16276157 09:47:56 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:56 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:56 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:58 INFO - Http code: 200 09:47:58 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 09:47:58 INFO - x-amz-cf-id: KtEX2qQX_RPMrD8iZsgV9WuIi0juE0P6r3IXxvsZo8B-x6ryLzipZg== 09:47:58 INFO - x-amz-version-id: L1S054.rNu69y7luN94DHfFFHuQO4yFw 09:47:58 INFO - Expected file size: 14409038 09:47:58 INFO - Obtained file size: 14409038 09:47:58 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:58 INFO - trying https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:47:58 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:58 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:48:01 INFO - Downloaded 110008246 bytes. 09:48:01 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:48:01 INFO - mkdir: /builds/slave/test/properties 09:48:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:48:01 INFO - Writing to file /builds/slave/test/properties/build_url 09:48:01 INFO - Contents: 09:48:01 INFO - build_url:https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:48:02 INFO - Running post-action listener: _resource_record_post_action 09:48:02 INFO - Running post-action listener: set_extra_try_arguments 09:48:02 INFO - [mozharness: 2017-01-18 17:48:02.314668Z] Finished download-and-extract step (success) 09:48:02 INFO - [mozharness: 2017-01-18 17:48:02.314793Z] Running populate-webroot step. 09:48:02 INFO - Running pre-action listener: _resource_record_pre_action 09:48:02 INFO - Running main action method: populate_webroot 09:48:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:48:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:48:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:48:02 INFO - '--tppagecycles', 09:48:02 INFO - '7'], 09:48:02 INFO - 'tests': ['tsvgm']}}, 09:48:02 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:48:02 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:48:02 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:48:02 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:48:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:48:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['damp', 'tps']}, 09:48:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'tests': ['damp', 'tps']}, 09:48:02 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['dromaeo_dom']}, 09:48:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:48:02 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['basic_compositor_video']}, 09:48:02 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:48:02 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'other-e10s': {'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['a11yr', 09:48:02 INFO - 'ts_paint', 09:48:02 INFO - 'tpaint', 09:48:02 INFO - 'sessionrestore', 09:48:02 INFO - 'sessionrestore_no_auto_restore', 09:48:02 INFO - 'tabpaint']}, 09:48:02 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['tsvgx', 09:48:02 INFO - 'tsvgr_opacity', 09:48:02 INFO - 'tart', 09:48:02 INFO - 'tscrollx', 09:48:02 INFO - 'cart']}, 09:48:02 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:48:02 INFO - 'tsvgr_opacity', 09:48:02 INFO - 'tart', 09:48:02 INFO - 'tscrollx', 09:48:02 INFO - 'cart']}, 09:48:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'talos_options': ['--disable-e10s'], 09:48:02 INFO - 'tests': ['tp5o']}, 09:48:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'tests': ['tp5o']}, 09:48:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'talos_options': ['--disable-e10s', 09:48:02 INFO - '--xperf_path', 09:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:02 INFO - 'tests': ['tp5n']}, 09:48:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:48:02 INFO - 'talos_options': ['--xperf_path', 09:48:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:48:02 INFO - 'tests': ['tp5n']}}, 09:48:02 INFO - 'talos.zip': {'path': '', 09:48:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:48:02 INFO - Running post-action listener: _resource_record_post_action 09:48:02 INFO - [mozharness: 2017-01-18 17:48:02.334230Z] Finished populate-webroot step (success) 09:48:02 INFO - [mozharness: 2017-01-18 17:48:02.334374Z] Running create-virtualenv step. 09:48:02 INFO - Running pre-action listener: _resource_record_pre_action 09:48:02 INFO - Running main action method: create_virtualenv 09:48:02 INFO - Creating virtualenv /builds/slave/test/build/venv 09:48:02 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:48:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:48:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:48:02 INFO - Using real prefix '/tools/python27' 09:48:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:48:03 INFO - Installing distribute.............................................................................................................................................................................................done. 09:48:06 INFO - Installing pip.................done. 09:48:06 INFO - Return code: 0 09:48:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:48:06 INFO - Reading from file tmpfile_stdout 09:48:06 INFO - Output received: 09:48:06 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:48:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:06 INFO - 'HOME': '/Users/cltbld', 09:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:06 INFO - 'LOGNAME': 'cltbld', 09:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:06 INFO - 'MOZ_NO_REMOTE': '1', 09:48:06 INFO - 'NO_EM_RESTART': '1', 09:48:06 INFO - 'PAGER': '/bin/cat', 09:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:06 INFO - 'PWD': '/builds/slave/test', 09:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:06 INFO - 'SHELL': '/bin/bash', 09:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:06 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:06 INFO - 'USER': 'cltbld', 09:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:06 INFO - 'XPC_FLAGS': '0x0', 09:48:06 INFO - 'XPC_SERVICE_NAME': '0', 09:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:06 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:48:06 INFO - Cleaning up... 09:48:06 INFO - Return code: 0 09:48:06 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:48:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:48:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:06 INFO - 'HOME': '/Users/cltbld', 09:48:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:06 INFO - 'LOGNAME': 'cltbld', 09:48:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:06 INFO - 'MOZ_NO_REMOTE': '1', 09:48:06 INFO - 'NO_EM_RESTART': '1', 09:48:06 INFO - 'PAGER': '/bin/cat', 09:48:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:06 INFO - 'PWD': '/builds/slave/test', 09:48:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:06 INFO - 'SHELL': '/bin/bash', 09:48:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:06 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:06 INFO - 'USER': 'cltbld', 09:48:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:06 INFO - 'XPC_FLAGS': '0x0', 09:48:06 INFO - 'XPC_SERVICE_NAME': '0', 09:48:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:06 INFO - Downloading/unpacking psutil>=3.1.1 09:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:48:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:10 INFO - Installing collected packages: psutil 09:48:10 INFO - Running setup.py install for psutil 09:48:10 INFO - building 'psutil._psutil_osx' extension 09:48:10 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:48:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:48:11 INFO - building 'psutil._psutil_posix' extension 09:48:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:48:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:48:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:48:11 INFO - ^ 09:48:11 INFO - 1 warning generated. 09:48:11 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:48:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:48:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:48:11 INFO - Successfully installed psutil 09:48:11 INFO - Cleaning up... 09:48:11 INFO - Return code: 0 09:48:11 INFO - Installing mozsystemmonitor==0.3 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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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', 'mozsystemmonitor==0.3'] 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 mozsystemmonitor==0.3 09:48:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 09:48:11 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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 mozsystemmonitor==0.3 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 mozsystemmonitor-0.3.tar.gz 09:48:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:48:14 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:14 INFO - Installing collected packages: mozsystemmonitor 09:48:14 INFO - Running setup.py install for mozsystemmonitor 09:48:14 INFO - Successfully installed mozsystemmonitor 09:48:14 INFO - Cleaning up... 09:48:14 INFO - Return code: 0 09:48:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:48:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:14 INFO - 'HOME': '/Users/cltbld', 09:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:14 INFO - 'LOGNAME': 'cltbld', 09:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:14 INFO - 'MOZ_NO_REMOTE': '1', 09:48:14 INFO - 'NO_EM_RESTART': '1', 09:48:14 INFO - 'PAGER': '/bin/cat', 09:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:14 INFO - 'PWD': '/builds/slave/test', 09:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:14 INFO - 'SHELL': '/bin/bash', 09:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:14 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:14 INFO - 'USER': 'cltbld', 09:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:14 INFO - 'XPC_FLAGS': '0x0', 09:48:14 INFO - 'XPC_SERVICE_NAME': '0', 09:48:14 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 jsonschema==2.5.1 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 jsonschema-2.5.1-py2.py3-none-any.whl 09:48:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 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:18 INFO - Downloading functools32-3.2.3-2.tar.gz 09:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:48:18 INFO - warning: no files found matching '*.txt' 09:48:18 INFO - no previously-included directories found matching 'build' 09:48:18 INFO - no previously-included directories found matching 'dist' 09:48:18 INFO - no previously-included directories found matching '.git*' 09:48:18 INFO - Installing collected packages: jsonschema, functools32 09:48:18 INFO - Running setup.py install for functools32 09:48:18 INFO - warning: no files found matching '*.txt' 09:48:18 INFO - no previously-included directories found matching 'build' 09:48:18 INFO - no previously-included directories found matching 'dist' 09:48:18 INFO - no previously-included directories found matching '.git*' 09:48:18 INFO - Successfully installed jsonschema functools32 09:48:18 INFO - Cleaning up... 09:48:18 INFO - Return code: 0 09:48:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:18 INFO - 'HOME': '/Users/cltbld', 09:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:18 INFO - 'LOGNAME': 'cltbld', 09:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:18 INFO - 'MOZ_NO_REMOTE': '1', 09:48:18 INFO - 'NO_EM_RESTART': '1', 09:48:18 INFO - 'PAGER': '/bin/cat', 09:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:18 INFO - 'PWD': '/builds/slave/test', 09:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:18 INFO - 'SHELL': '/bin/bash', 09:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:18 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:18 INFO - 'USER': 'cltbld', 09:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:18 INFO - 'XPC_FLAGS': '0x0', 09:48:18 INFO - 'XPC_SERVICE_NAME': '0', 09:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:48:19 INFO - Cleaning up... 09:48:19 INFO - Return code: 0 09:48:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:48:19 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:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:19 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:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:19 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 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:19 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:19 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:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:19 INFO - 'HOME': '/Users/cltbld', 09:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:19 INFO - 'LOGNAME': 'cltbld', 09:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:19 INFO - 'MOZ_NO_REMOTE': '1', 09:48:19 INFO - 'NO_EM_RESTART': '1', 09:48:19 INFO - 'PAGER': '/bin/cat', 09:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:19 INFO - 'PWD': '/builds/slave/test', 09:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:19 INFO - 'SHELL': '/bin/bash', 09:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:19 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:19 INFO - 'USER': 'cltbld', 09:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:19 INFO - 'XPC_FLAGS': '0x0', 09:48:19 INFO - 'XPC_SERVICE_NAME': '0', 09:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:19 INFO - Downloading/unpacking 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:21 INFO - Downloading blobuploader-1.2.4.tar.gz 09:48:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:48:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:48:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:23 INFO - Downloading docopt-0.6.1.tar.gz 09:48:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:48:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:48:23 INFO - Running setup.py install for blobuploader 09:48:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:48:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:48:23 INFO - Running setup.py install for requests 09:48:23 INFO - Running setup.py install for docopt 09:48:24 INFO - Successfully installed blobuploader requests docopt 09:48:24 INFO - Cleaning up... 09:48:24 INFO - Return code: 0 09:48:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:24 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:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:24 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:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:24 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 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:24 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:24 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:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:24 INFO - 'HOME': '/Users/cltbld', 09:48:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:24 INFO - 'LOGNAME': 'cltbld', 09:48:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:24 INFO - 'MOZ_NO_REMOTE': '1', 09:48:24 INFO - 'NO_EM_RESTART': '1', 09:48:24 INFO - 'PAGER': '/bin/cat', 09:48:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:24 INFO - 'PWD': '/builds/slave/test', 09:48:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:24 INFO - 'SHELL': '/bin/bash', 09:48:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:24 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:24 INFO - 'USER': 'cltbld', 09:48:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:24 INFO - 'XPC_FLAGS': '0x0', 09:48:24 INFO - 'XPC_SERVICE_NAME': '0', 09:48:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-EnHIPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-CVdKpY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-gB49K2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-AuzfLR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-ZqVilQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:24 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-DqXBOn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-1uD7Ho-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-eqHwS5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-vD_Dys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-s8L_VZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-0G4mte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-QtTC6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-55MSqP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-bd4lx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:25 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-DaSnu3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-PiMBYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:26 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-lX5CqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:48:26 INFO - Running setup.py install for manifestparser 09:48:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:48:26 INFO - Running setup.py install for mozcrash 09:48:26 INFO - Running setup.py install for mozdebug 09:48:26 INFO - Running setup.py install for mozdevice 09:48:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:48:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:48:26 INFO - Running setup.py install for mozfile 09:48:26 INFO - Running setup.py install for mozhttpd 09:48:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Running setup.py install for mozinfo 09:48:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Running setup.py install for mozInstall 09:48:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Running setup.py install for mozleak 09:48:27 INFO - Running setup.py install for mozlog 09:48:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Running setup.py install for moznetwork 09:48:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:48:27 INFO - Running setup.py install for mozprocess 09:48:27 INFO - Running setup.py install for mozprofile 09:48:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:48:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:48:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:48:28 INFO - Running setup.py install for mozrunner 09:48:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:48:28 INFO - Running setup.py install for mozscreenshot 09:48:28 INFO - Running setup.py install for moztest 09:48:28 INFO - Running setup.py install for mozversion 09:48:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:48:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:48:28 INFO - Cleaning up... 09:48:28 INFO - Return code: 0 09:48:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:28 INFO - 'HOME': '/Users/cltbld', 09:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:28 INFO - 'LOGNAME': 'cltbld', 09:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:28 INFO - 'MOZ_NO_REMOTE': '1', 09:48:28 INFO - 'NO_EM_RESTART': '1', 09:48:28 INFO - 'PAGER': '/bin/cat', 09:48:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:28 INFO - 'PWD': '/builds/slave/test', 09:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:28 INFO - 'SHELL': '/bin/bash', 09:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:28 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:28 INFO - 'USER': 'cltbld', 09:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:28 INFO - 'XPC_FLAGS': '0x0', 09:48:28 INFO - 'XPC_SERVICE_NAME': '0', 09:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-YOJ3Wo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:48:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-gDPcAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:48:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-0oirWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:48:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-BoOyms-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:48:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-w4kogp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:48:29 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:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-HNT5Rv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-6CK2mH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-tdaPo_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:48:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:48:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:48:29 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-Vsa3ey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-PjoQBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-97e6xX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-0h4yBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-qx2lS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-tmZ8wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-6J72Qd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:48:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-PZwxNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:48:30 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:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:48:30 INFO - Running setup.py (path:/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/pip-BEUtXt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:48:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:48:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:48:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:48:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:48:33 INFO - Downloading blessings-1.6.tar.gz 09:48:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:48:33 INFO - Installing collected packages: blessings 09:48:33 INFO - Running setup.py install for blessings 09:48:33 INFO - Successfully installed blessings 09:48:33 INFO - Cleaning up... 09:48:33 INFO - Return code: 0 09:48:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:48:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:34 INFO - Reading from file tmpfile_stdout 09:48:34 INFO - Current package versions: 09:48:34 INFO - blessings == 1.6 09:48:34 INFO - blobuploader == 1.2.4 09:48:34 INFO - docopt == 0.6.1 09:48:34 INFO - functools32 == 3.2.3-2 09:48:34 INFO - jsonschema == 2.5.1 09:48:34 INFO - manifestparser == 1.1 09:48:34 INFO - mozInstall == 1.12 09:48:34 INFO - mozcrash == 1.0 09:48:34 INFO - mozdebug == 0.1 09:48:34 INFO - mozdevice == 0.48 09:48:34 INFO - mozfile == 1.2 09:48:34 INFO - mozhttpd == 0.7 09:48:34 INFO - mozinfo == 0.9 09:48:34 INFO - mozleak == 0.1 09:48:34 INFO - mozlog == 3.4 09:48:34 INFO - moznetwork == 0.27 09:48:34 INFO - mozprocess == 0.23 09:48:34 INFO - mozprofile == 0.28 09:48:34 INFO - mozrunner == 6.13 09:48:34 INFO - mozscreenshot == 0.1 09:48:34 INFO - mozsystemmonitor == 0.3 09:48:34 INFO - moztest == 0.8 09:48:34 INFO - mozversion == 1.4 09:48:34 INFO - psutil == 3.1.1 09:48:34 INFO - requests == 1.2.3 09:48:34 INFO - wsgiref == 0.1.2 09:48:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:48:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:48:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:34 INFO - 'HOME': '/Users/cltbld', 09:48:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:34 INFO - 'LOGNAME': 'cltbld', 09:48:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:34 INFO - 'MOZ_NO_REMOTE': '1', 09:48:34 INFO - 'NO_EM_RESTART': '1', 09:48:34 INFO - 'PAGER': '/bin/cat', 09:48:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:34 INFO - 'PWD': '/builds/slave/test', 09:48:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:34 INFO - 'SHELL': '/bin/bash', 09:48:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:34 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:34 INFO - 'USER': 'cltbld', 09:48:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:34 INFO - 'XPC_FLAGS': '0x0', 09:48:34 INFO - 'XPC_SERVICE_NAME': '0', 09:48:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 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:35 INFO - Cleaning up... 09:48:35 INFO - Return code: 0 09:48:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:48:35 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:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:35 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:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:48:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:48:35 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 0x100709490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10067f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc432cad990>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, '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.Hsk37HItvW/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.VRxkfTXpXS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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:35 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:35 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:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:48:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:48:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:48:35 INFO - 'HOME': '/Users/cltbld', 09:48:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:48:35 INFO - 'LOGNAME': 'cltbld', 09:48:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:48:35 INFO - 'MOZ_NO_REMOTE': '1', 09:48:35 INFO - 'NO_EM_RESTART': '1', 09:48:35 INFO - 'PAGER': '/bin/cat', 09:48:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:48:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:48:35 INFO - 'PWD': '/builds/slave/test', 09:48:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:48:35 INFO - 'SHELL': '/bin/bash', 09:48:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:48:35 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:48:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:48:35 INFO - 'USER': 'cltbld', 09:48:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:48:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:48:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:48:35 INFO - 'XPC_FLAGS': '0x0', 09:48:35 INFO - 'XPC_SERVICE_NAME': '0', 09:48:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:48:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:48:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:48:35 INFO - Cleaning up... 09:48:35 INFO - Return code: 0 09:48:35 INFO - Running post-action listener: _resource_record_post_action 09:48:35 INFO - Running post-action listener: _start_resource_monitoring 09:48:35 INFO - Starting resource monitoring. 09:48:35 INFO - [mozharness: 2017-01-18 17:48:35.829386Z] Finished create-virtualenv step (success) 09:48:35 INFO - [mozharness: 2017-01-18 17:48:35.830370Z] Running install step. 09:48:35 INFO - Running pre-action listener: _resource_record_pre_action 09:48:35 INFO - Running main action method: install 09:48:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:48:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:48:36 INFO - Reading from file tmpfile_stdout 09:48:36 INFO - Detecting whether we're running mozinstall >=1.0... 09:48:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:48:36 INFO - Reading from file tmpfile_stdout 09:48:36 INFO - Output received: 09:48:36 INFO - Usage: mozinstall [options] installer 09:48:36 INFO - Options: 09:48:36 INFO - -h, --help show this help message and exit 09:48:36 INFO - -d DEST, --destination=DEST 09:48:36 INFO - Directory to install application into. [default: 09:48:36 INFO - "/builds/slave/test"] 09:48:36 INFO - --app=APP Application being installed. [default: firefox] 09:48:36 INFO - mkdir: /builds/slave/test/build/application 09:48:36 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:36 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:07 INFO - Reading from file tmpfile_stdout 09:49:07 INFO - Output received: 09:49:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:49:07 INFO - Running post-action listener: _resource_record_post_action 09:49:07 INFO - [mozharness: 2017-01-18 17:49:07.833886Z] Finished install step (success) 09:49:07 INFO - [mozharness: 2017-01-18 17:49:07.834034Z] Running run-tests step. 09:49:07 INFO - Running pre-action listener: _resource_record_pre_action 09:49:07 INFO - Running main action method: run_tests 09:49:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:49:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:49:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:49:07 INFO - 2017-01-18 09:49:07.848 screenresolution[1617:7391] starting screenresolution argv=screenresolution get 09:49:07 INFO - 2017-01-18 09:49:07.851 screenresolution[1617:7391] Display 0: 1600x1200x32@60 09:49:07 INFO - 2017-01-18 09:49:07.856 screenresolution[1618:7394] starting screenresolution argv=screenresolution list 09:49:07 INFO - Available Modes on Display 0 09:49:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:49:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:49:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:49:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:49:08 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:49:08 INFO - Intel Iris: 09:49:08 INFO - Chipset Model: Intel Iris 09:49:08 INFO - Type: GPU 09:49:08 INFO - Bus: Built-In 09:49:08 INFO - VRAM (Dynamic, Max): 1536 MB 09:49:08 INFO - Vendor: Intel (0x8086) 09:49:08 INFO - Device ID: 0x0a2e 09:49:08 INFO - Revision ID: 0x0009 09:49:08 INFO - Displays: 09:49:08 INFO - Display: 09:49:08 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:49:08 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:49:08 INFO - Display Serial Number: 1600x1200 60 09:49:08 INFO - Main Display: Yes 09:49:08 INFO - Mirror: Off 09:49:08 INFO - Online: Yes 09:49:08 INFO - Rotation: Supported 09:49:08 INFO - Return code: 0 09:49:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:49:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:49:08 INFO - Python 2.7.3 09:49:08 INFO - Return code: 0 09:49:08 INFO - grabbing minidump binary from tooltool 09:49: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:49:08 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 0x10051dd30>, '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 0x100383db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100515b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:49:08 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:08 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:08 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:08 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:49:08 INFO - Return code: 0 09:49:08 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:49:08 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:49:08 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:49:08 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:49:08 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:49:08 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-e10s', '--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-0161', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:49:08 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-e10s --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-0161 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:49:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hsk37HItvW/Render', 09:49:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:08 INFO - 'HOME': '/Users/cltbld', 09:49:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:08 INFO - 'LOGNAME': 'cltbld', 09:49:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:49:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:49:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:08 INFO - 'MOZ_NO_REMOTE': '1', 09:49:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:49:08 INFO - 'NO_EM_RESTART': '1', 09:49:08 INFO - 'PAGER': '/bin/cat', 09:49:08 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:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:08 INFO - 'PWD': '/builds/slave/test', 09:49:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:49:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:08 INFO - 'SHELL': '/bin/bash', 09:49:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.VRxkfTXpXS/Listeners', 09:49:08 INFO - 'TMPDIR': '/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/', 09:49:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:08 INFO - 'USER': 'cltbld', 09:49:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:08 INFO - 'XPC_FLAGS': '0x0', 09:49:08 INFO - 'XPC_SERVICE_NAME': '0', 09:49:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--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-0161', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:49:08 INFO - mozversion application_buildid: 20170118070027 09:49:08 INFO - mozversion application_changeset: 27643a39e551670484fe7dd5836aaa01d66b9a7b 09:49:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:49:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:49:08 INFO - mozversion application_name: Firefox 09:49:08 INFO - mozversion application_remotingname: firefox-dev 09:49:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:08 INFO - mozversion application_vendor: Mozilla 09:49:08 INFO - mozversion application_version: 52.0a2 09:49:08 INFO - mozversion platform_buildid: 20170118070027 09:49:08 INFO - mozversion platform_changeset: 27643a39e551670484fe7dd5836aaa01d66b9a7b 09:49:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:49:08 INFO - mozversion platform_version: 52.0a2 09:49:08 INFO - using testdate: 1484761748 09:49:08 INFO - actual date: 1484761748 09:49:08 INFO - starting webserver on 'localhost:49241' 09:49:08 INFO - SUITE-START | Running 2 tests 09:49:08 INFO - TEST-START | dromaeo_css 09:49:08 INFO - operating with platform_type : mac_ 09:49:08 INFO - Initialising browser for dromaeo_css test... 09:49:08 INFO - TEST-INFO | started process 1628 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmp7aL05C/profile http://localhost:49241/getInfo.html) 09:49:12 INFO - PROCESS | 1628 | 2017-01-18 09:49:12.049 plugin-container[1633:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a43, name = 'com.apple.tsm.portname' 09:49:12 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:12 INFO - PROCESS | 1628 | 2017-01-18 09:49:12.051 plugin-container[1633:7593] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9103, name = 'com.apple.CFPasteboardClient' 09:49:12 INFO - PROCESS | 1628 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:12 INFO - PROCESS | 1628 | 2017-01-18 09:49:12.052 plugin-container[1633:7593] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:49:12 INFO - PROCESS | 1628 | __metrics Screen width/height:1600/1200 09:49:12 INFO - PROCESS | 1628 | colorDepth:24 09:49:12 INFO - PROCESS | 1628 | Browser inner width/height: 1024/689 09:49:12 INFO - PROCESS | 1628 | __metrics 09:49:12 ERROR - PROCESS | 1628 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 09:49:12 INFO - TEST-INFO | 1628: exit 0 09:49:12 INFO - Browser initialized. 09:49:12 INFO - Running cycle 1/1 for dromaeo_css test... 09:49:12 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmp7aL05C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:49:15 INFO - PROCESS | 1635 | 2017-01-18 09:49:15.534 plugin-container[1636:7767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 09:49:15 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:15 INFO - PROCESS | 1635 | 2017-01-18 09:49:15.535 plugin-container[1636:7767] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 09:49:15 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:49:15 INFO - PROCESS | 1635 | 2017-01-18 09:49:15.535 plugin-container[1636:7767] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:49:25 INFO - PROCESS | 1635 | RSS: Main: 229208064 09:49:25 INFO - PROCESS | 1635 | 09:52:27 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 09:52:29 INFO - PROCESS | 1635 | RSS: Main: 235118592 09:52:29 INFO - PROCESS | 1635 | 09:55:31 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 09:55:32 INFO - PROCESS | 1635 | RSS: Main: 244408320 09:55:32 INFO - PROCESS | 1635 | 09:58:34 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 09:58:35 INFO - PROCESS | 1635 | RSS: Main: 247377920 09:58:35 INFO - PROCESS | 1635 | 10:01:37 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 10:01:37 INFO - PROCESS | 1635 | RSS: Main: 250748928 10:01:37 INFO - PROCESS | 1635 | 10:04:40 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 10:04:41 INFO - PROCESS | 1635 | RSS: Main: 254230528 10:04:41 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 10:07:43 INFO - PROCESS | 1635 | RSS: Main: 255479808 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | __start_tp_report 10:07:43 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 10:07:43 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 10:07:43 INFO - PROCESS | 1635 | |i|pagename|runs| 10:07:43 INFO - PROCESS | 1635 | |0;dojo.html;3430.9485357719636;3435.3129374125174;3447.9138021549475;3456.2569047654756;3459.7232221422287;5812.709364531773;5860.974329492343;5882.647041177533;5904.675242861641;5905.379935106811;5879.11813228016;5936.673482958447;5961.523078153748;5968.343482216964;5968.522518198544;5273.419923808378;5353.223782551528;5366.731663416833;5385.272988146589;5388.568914486851;4682.578567928903;5400.108982017982;5402.8919421611545;5419.430959749209;5459.344878614572;5264.157734762439;5292.629515933886;5427.83716488506;5428.077226871442;5430.67415955042;5320.414754377015;5342.637627405006;5383.946160538384;5410.242566040757;5446.455354464561;5231.398389185217;5329.25390445338;5336.626436149471;5351.063563876306;5369.892602147936;5489.39616642169;5662.121446808908;5722.313322401338;5851.0638297872565;5964.701764911781;6223.719932602999;6232.688365581703;6259.4679452246155;6267.686615669244;6297.6536290504;6315.463185629227;6318.210223722035;6323.557350985433;6335.873282534323;6339.175907132089;5969.253843269591;5972.432618901197;5977.013792724152;5991.610545314584;6004.309504406961;6145.723442445012;6209.9379006210265;6261.279952805395;6270.372962703693;6444.77443289492;5186.559142472932;6168.568200226031;6234.127222188898;6267.874642507124;6277.96861015692;5218.478152184751;6238;6286.339934306832;6308.3060863305;6313.652749098752;6113.780377411019;6135.110179431545;6166.506679465632;6274.372562743689;6275.874482510416;5387.326584176978;5649;5916.757480928967;6206.317305096436;6222.12890195373;5024.698518088926;5033.723145226974;5036.194208926628;5040.747962601855;5049.192129259301;4403.075354175659;4457.974665826878;4494.775261236925;4500.234960056799;4514.81940722374;5764.510016648548;5780.450857168562;5967.522598192134;5996.910046349309;6132.264128304632;6268.905966410507;6269.8746025079245;6304.117423560705;6310.4005119514495;6325.177726895474;5875.383084776123;5907.586468947218;5945.64326140433;5958.582899197015;6056.36408177135;5466.835994920159;5509.807156749494;5529.917051244234;5559.444055594489;5568.9443105568425;6484.092227088211;6487.3836985486305;6488.935110648833;6504.9674751625935;6507.544471887112;5633.295838020247;5856.677882716492;6147.200863887666;6234.875302493834;6282.654454004891;6378.6810659467765;6398.4561312287115;6472.15861937945;6531.150950376421;6549.508786841101;6246.812595622138;6262.68686565679;6280.1207830902795;6286.4342209201395;6296.748130074746;6134.693265336626;6182.783602573888;6269.0909818075215;6308.589941653778;6319.778807741707;5037.596992240538;6155.630662160284;6166.075088736726;6170.598911070766;6239.4696450800375;6164.753409863555;6218.347073557392;6255.593386429868;6258.092576576462;6260.436560709558;5221.7728833724805;6085.361037091042;6155.169052177898;6159.4456498915315;6174.475169610454;6087;6122.367660829107;6130.642739936431;6134.601250918676;6140.657627863787;6126.670355029888;6128.625347140905;6129.603311142587;6138.738486743586;6146.938530614815 10:07:43 INFO - PROCESS | 1635 | |1;ext.html;471.50256479414224;496.3101289208001;526.2658591265183;527.8838655495792;529.2035486592678;28300.57549136762;30802.76788928443;38742.80628596857;39754;39876.80061599688;41035;41556.79221603895;41577.37633935493;41593;41609;9385.953070234673;9534.666286679967;9543.47510886901;9558.80882382352;9565.713028609118;9482;9510.096540828645;9555;9574.425534467919;9575.23398128148;9498.620055197784;9536.046395360443;9581;9592.856107158399;9604.327697061208;9442.244620430418;9500.619975200983;9548.904510954872;9558.235341172689;9568.808623827554;7887.723929662434;8004.239597238253;8033.799155021113;9318.254539636813;9401.059894010612;30399;38982.610173898465;39482.407763883566;39620.40569391462;39915.201695965916;3151.43274210642;3330.20075181957;3345.330933813247;3538.840752166159;3595.1551385424405;26710.198694039016;29261.56107658387;29838;29959.801607935657;30705.846470767505;2630.4607555451084;2657.0833062593374;2938.9559156612668;3169.413658473167;3200.9519857202163;407127;425161;427189;428301.8584907087;430625;61506;62099.6895015522;62106;62201;62433.68783156055;63899.68050159812;64215.6789216051;64792;65237;65532.34467655358;15964.92017539905;16039.919800400923;16220.756688649679;16250.51248462548;16259.943103698222;11972;12416.379181040911;12425.31660758667;12475.251484910934;12496.81254781179;17643.56030508216;18268.90865545664;18899.81100189008;18920.43238702841;19012.334568290044;7198.676059577331;7556.697732090654;7735.375571130013;7766.728164514215;7775.844483110306;847.6694089305176;875.2341701011615;939.3135632092211;972.6595691507915;978.280963491833;29218.831246750316;29504.262393440335;29824.850875745484;29861.253468663457;30248.84875575608;962.6823148361024;992.667456402099;1019.4596863662271;1028.1055481730941;1036.264252380803;13321.800172997413;15559.29983150761;15840.603984900239;15888.92055539715;15902.60243493922;663435;666483;670975;672051;677214;55570.16644750332;55727;55743.163852542246;55800.441995579524;55946;1323.2523624152323;1348.865113488663;1362.495876525672;1368.404743936371;1372.444160115155;29283.707162928098;29623.407531849673;29774.404511910074;30155.246118846328;30458.543121853632;47886.28170577374;48503.5149648499;49113.508864910895;49163.262551061045;49357.506424935294;520066;522411;523859;524417;527484;26069.348266293495;26158.476830462903;26643;27103.322416939733;27422.314442139104;44232.55767442413;44563;44698.3295250565;44912.32631510595;46045;37062.90561908889;37428.43857342197;37536.812315938245;38479.61520384872;38670.8398748038;31675.416229188908;36408.08979775421;37599.06002349963;38606.42090368703;38871;36484.45273320955;36511.81744091262;36970.8151459241;37221.25557488781;37329.6267037337;32236.35527289488;33318.50022249717;33811;33849.830750846086;34040;23276.301710948697;25317.74682253154;25557.744422555537;25622.615660765478;26022.86988565045 10:07:43 INFO - PROCESS | 1635 | |2;jquery.html;366.1779305459244;371.6357969190192;374.8931554506966;377.39428217710577;395.2135250850806;18883.811161888378;19116.044197790106;19577.608447831033;19826;20072.69890951637;11498.25261358011;11850;11904.285742855434;11905.16663833532;11923.64229073127;5032.220005899082;5307.893842123155;5355.518003379695;5395.10980688186;5410.864728381783;3877.263319969197;4079.3473044312914;5323.733813309319;5366.731663416833;6262.499000080005;6445.162128923257;6551.901721474182;6555.737770489175;6561.245456772484;6588.143541339643;5903.675297858616;5971.701414929236;5978.521718262572;5987.910181347262;6212.44088032077;5582.218489411485;5693.77224911003;5787.594868359217;5789.04480760674;5811.244538210048;12881.355932203352;13154.408051637603;13176.802347964789;13240.602781916557;13256.204627722367;2081.6461201595766;2225.732912050564;2298.747137814839;2558.2325302409204;2642.907498237572;9498.14516693501;9571.61713531465;9668.806623867484;9994.750131246705;10112.292139550234;1719.355241784331;1814.2833580735667;1815.4099917526726;1922.0389805097452;1950.1906708715867;117193;118774;119162.21256681156;122185;125892.37053814763;9503.287253455881;9905.60377584903;10053.69838904834;10178.23663225261;11688;12134.817977730341;12445.25328480294;12488.000959923365;12539.498420063095;12541.184822986477;7949.046114466186;8052.59737013147;8065.354771618257;8077.151899050633;8177.632006559719;8155.469894456842;8164;8287.254147126667;8455.365847561457;8460.069392366835;3659.1218107654504;3848.4419759134767;3866.6713329366758;3925.391564307537;3931.8623848165175;1582.4936020473567;1667.241405160649;1690.9661806763795;1935.2549268531716;1986.8311193548711;855.6919508976763;945.2768631996528;945.7541039329824;958.5925981457852;990.65327135502;20394.69407958882;20488.590228195353;20710;20725.27461538869;21391.8930405347;907.3421769217397;923.5936188077225;925.9675911343205;957.3824882950458;958.8178246133212;10939.453027348602;11199.160062995144;11200.383978881076;11223.887761122447;11249.212555121227;524473;532406.3379683077;538065;540731;645992.5400746026;97215;114811.99999999833;120655.39672301582;127440;132133;917.5091326140483;953.2612225525273;969.6703120938914;972.9367591106555;974.6101559376306;14452.855471445151;18271;18318.17568209407;18827.811721882605;19344.70982935229;40388.59611403966;50940.98118037544;51751;52104.47895520996;53313;77733;80639.59680201561;85464.71802922837;87872;91130.54434727784;7813.693432843408;8005.799855003672;8129.187081291823;8425.283850872744;8486.957565212135;9269.397489163182;9982.850257246002;11998.160128790902;12776.10567260283;13076.150050246704;10969.451527423756;11052.889471105185;11306.208565400671;11395.886041139815;11466.541338346706;8870.112988701077;10571;10715.571377144826;10989.626296712911;11301.604443844426;11167.664970050912;11215.49530271156;11495.137864660017;11650.359230242419;11818.763624727286;8092.150324215872;8192.877106843522;8311.916880831357;8424.62089205975;8515.957420212859;9611.615535378507;11231.719207019563;11286.379249141377;11433.485493152662;11461.369624670722 10:07:43 INFO - PROCESS | 1635 | |3;mootools.html;1450.2168828832428;1483.4659522571872;1654.8014238291398;1769.4780039888233;1781.7060185069465;7483.513571617841;7716.498427602216;7959.3234575061115;8433.24100830925;8435.367347448942;7978.640961156747;8210.30212431944;8267.627956741946;8299.58502074897;8309.293710034639;2284.6915666385075;2302.3093072078395;2327.522857814144;2329.3827135809024;2340.192633541434;2284.2690339091496;2325.5232677301115;2352.3178278299274;2371.312319427364;2377.702787151606;2302.470431800687;2314.5486630107134;2320.7795259450327;2347.108098922407;2350.9882450587725;2279.270633397305;2281.634938409863;2282.429392651837;2288.8168946484243;2298.9655155172686;2256.954860902773;2275.613145765224;2309;2313.6760853480528;2314.109068008812;8257;8330.62512186953;8341.20758527945;8367.748967530984;8385.6226469809;4996.350474438336;5108.974455127701;5127.743612819344;5165.199394093921;5170.482951704837;3160.620725512953;3249.772515923886;3390.6439823818637;3392.3215356928467;3401.9489707654407;1026.7484466305727;1042.11940909931;1056.2183983851971;1068.6374872038136;1077.272840832435;10815.459227038617;10871.67384978452;10907.83638245427;10956.835647465294;10960.177986650953;4476.239039363316;4555.954440455619;4642.16441040616;4647.419072615923;4682.297655351656;8173.918260817434;8201.58992050407;9260.120288572574;9308.953455232817;9402.529873506297;5106.540411362995;5187.662801917863;5193.662411943212;5201.427842937273;5204.1673332266655;4020.2763502569233;4136.6483848872585;4573.702709323883;4716.976415117902;4718.504557021532;3305.173706573357;3797.316483033025;3800.7339486235696;3870.4194370844034;3934.213157368538;1267.9982813577171;1268.0869773762877;1277.106025781938;1281.135233717237;1283.4866053578457;770.8612449759097;892.607252808762;892.8214357128601;902.5532361481108;924.4915296586851;4658.254679251371;5672.14917762339;5759.429153924593;5787.681677507778;5977.551683623746;895.9080546923008;898.2948385517337;901.8962819275736;930.50683137937;967.3373738988815;3681.687056600219;3739.3269211541638;3784.8675296365045;3787.6780473660046;3831.7317787755146;9964.501774911225;10081.344712593806;10296.845547316796;10307.381557106597;10366.326188797704;10030.29787914839;10131.898681013095;10187.7453063674;10263.973602639677;10292.485375731183;7454.85090298191;7645.273698998586;7744.3417309528195;7806.7267645633265;7866.174051724601;5344.866378340573;5563;5568.554515638658;5639.6334238274385;5693.9430605693415;9050.230730387992;9239.214666753132;9290.489023103793;9304.95347523285;9310.860337094813;9423.340366174301;9439.952800235955;9442.819647544056;9445.433274003582;9463.432194068102;7099.148102227766;7294.744683936249;7539.509931854412;7771.844563108819;7831.451798374059;7787.5716835574585;7927.16764739694;7942.64258108375;7947.562884041202;7948.960255198687;7034.859302814018;7063.046488723957;7240.131184257712;7242.167150777728;7319.158296795937;6924.272951340238;7096.964515177391;7109.395701365442;7132.536385134949;7299.781006569811;6506.60960342381;6643;6644.235912870082;6677.866442671217;6698;4779.593734532604;4792.281157826354;4812.989272252726;4835.468098509232;4858.319835223001;4782.115308667908;4818.253170758538;4903.264510323337;4909.950900491092;4938.283948827472 10:07:43 INFO - PROCESS | 1635 | |4;prototype.html;297.97914146009776;301.4272881525102;309;309.34573377307;312.6919983815941;4361.411209486719;5369.812056578021;5418.972905135473;5592.832215033555;5647.661140331583;4321.595481468527;5240.633155679104;5258.44786297439;5533.889322213553;5568.276124103871;4226.873193804183;4262.167268074727;4268;4271.423357846682;4275.615194632483;3003.6121283579496;3380.4760262159402;3451.3960056990054;3978.5026871641044;4284.33592793116;3641.362761516737;4178;4191.874243772691;4197.139586384784;4235;3627.4196128619296;3818.293615681108;4129.050318426779;4176.853810116647;4200.6219440250525;3074.6925307469296;3633.691136253395;3702.9259414811822;3722.758020728644;3725;5146;5304.957591327367;5377.381601115882;5391.757370918279;5435.347758269033;4524.914020635057;4841.515848415165;4852.660313778002;4853.93213493031;4910.705357678551;4924.876878078041;5755.611421260172;5785.82642520725;5825.271841019872;5827.970860145714;2898.796999245311;3206.551082848403;3908.4332771748223;3972.8410863565714;4013.227566087868;4858.562729354339;6120.724567394478;6158.784442544489;6182.990528078392;6247.344028876948;3650.8357123929486;3773.2830762155104;3815.637514436124;4002.2995975704134;4012.598740125964;3483.390406678821;3599.920023992792;3933.193695292458;3939.862104826317;3942.704297177723;4115.54728979812;4314.827406903752;4744.027474367816;4758.7858546364805;4782.473927867932;5628.85927851807;5868.941310586925;5881.852953676107;5890.4698577128265;5965.254343207099;1006.5168719014767;1212.5452955141823;1301.804729290614;1303.7197002644477;1304.9086563940432;808.6886548678801;853.9871901921476;861.1947828779986;882.9426087304305;886.7295474880094;3910.882673519799;5164.819231326961;5176.818811341585;5178.271644416014;5239.502247286502;4871.5128487151005;4883.145449546315;5162.948370516322;5725.484706376446;5788.450097240755;4475.187549042578;4713.8350157745;4886.633502487328;5080.034793389244;5403.567714582824;2927.487689654302;2936.838473883957;2956.63042119735;2985.8059226150667;2994.930196548555;5238.266642670029;5891;5919.763209471573;5949.935783071722;5982.46157845796;5637.774489020475;5849.561282903799;5870.236869206976;5883.852648733541;5925.318588362394;5092.770825312869;5265.578753699619;5941.970290148608;5956.604735300837;5981.671008094597;4889.535494128052;5390.975714614211;5421.783128674809;5500.3949565548555;5810.451019276665;4862.124817532736;5473.457030664618;5563.610547261815;5650.519705825051;5709.800156994485;5505.421930697219;5528.640638358494;5939.613925094855;6036.456718895321;6037.78867739627;3562.982185089058;3792.3363411403448;3804.2581696568905;3807.9619203808716;3830.1190726133705;5407.351117865881;5420.322459692538;5633.267675202197;5657.830265092054;5719.456651618089;4361.738295702268;4957.3059771632725;5696.003199440164;5772.9031484017905;5916.408359164049;5251.842444726664;5260.552853007537;5535.72678284005;5817.7963771267805;5889.941100589111;4949.406071271326;5626.1560765883805;5819.301683797971;5837.423895548093;5894.322152952465;5131.7940284032475;5731.598788084961;5794.362620111869;5986.964432093109;5990.820275391745;4610.625527853092;5564;5804.448577385142;5819.6508209507565;5885.941140588539 10:07:43 INFO - PROCESS | 1635 | |5;yui.html;676.9966150169248;719.6042176802757;745.2994185465664;748.3227678950545;751.7293774241274;14044;14614.269286535684;14624.122552646822;14636.999999999987;14677.339519721612;13040.93479532601;13070.477180912752;13082.542111026116;13092.952563794897;13162.670933226675;511.5753924242879;638.4732595608618;655.1417642887808;798.5528104261602;803.3252068262658;804.8792681097824;806.2179685704887;807.7334479621711;809.0493669937829;811.9675212991473;853.6585365853646;862.5040601654042;864.8486514859906;865;870.5951732444412;826.7561069484517;834.6744769539885;858.8368210040072;859.179483593167;859.5659192107977;632.905064240363;638.5402510192654;639.9232092148925;640.6444423345042;642.7332656947361;13302.40139193729;13770.96717746173;13772.24252666103;13778.931105344509;14267.429302827875;11255.54977800887;11271.661850144508;13369.398377073056;13485.988550858725;13609.11540749858;10696.358218506837;10969.670909872717;11031.889681103166;11235.325880447117;11253.099752019818;3623.8912832615065;3677.485152078711;3679.1170119171206;3787.9810600947094;3817.9236415271816;70399.94400084003;87917.68123478089;88040.5597972006;90343;91339;8595.441296315845;8782.209601135928;9232.215261702697;10496.475176241158;10515.211359147941;19885.502862428555;19955.700664490043;20109.09509072095;20201.898990505248;20468.283610073577;6533;7242.782716518513;7370.8894366584545;7428.739994100289;7430.925690743132;4097.467329247179;4142.855000075043;4160.750354978711;4165.146145040259;4179.2059508693255;3171.8572664230164;3251.4797632378704;3268.3299923515624;3274.3942370661503;3286.6549012353835;636.8057742388523;639.88162189995;647.4270270810367;660.8579155481594;661.6195687479718;576.6655339902846;582.5776312173725;647.2782847125516;663.4758540752848;665.2017578905236;8319.584020798935;8758.124187581318;8838.646454141892;8895.866562001574;8981.619870734261;588.9499392551595;590.7016956437058;642.6979319719725;651.84681599824;655.2975687366418;4170.291890601938;4268.765217913045;4286.957130428718;4510;4543.5002149763495;72824.54350913058;102263.46604800934;117328.82671173349;122711;123341.76658233482;100641.49679251557;103312.48343758233;103653;103724;108154;90680;91167;91487.54256228676;93090.53454732682;95574;8552.358573106918;8581.613827377658;8603.612837422206;8610.311175105851;8871.64513419456;41061;59215.70392148012;59236;60368.69815650894;60518.697406512685;81991;84212.57893710492;84560.57719711363;86676.5666171665;87998.56000719956;10605.628802991858;11173.106151507698;11371.30422630837;11499.597514086967;11843.407829608657;11639;11876;12000.399980001139;12094.697632558904;12152.453139608562;10562.366258024544;11554.364509952033;13016.544420945222;13038.804417933548;13085.607431777062;12778.488860445477;13003.782249767468;13470.797938031134;13537.187768733907;13887.305634718427;12440.440180191732;12789.232646041268;13041.400798351442;13399.464021439424;13486.527971520949;9921.206303495559;10232.744181395525;10671.733206669895;10973.835392469278;11392.48733806964;10290.794184116425;11134.544480118393;11333.659990200307;11365.204435689422;11409.429528523706 10:07:43 INFO - PROCESS | 1635 | __end_tp_report 10:07:43 INFO - PROCESS | 1635 | __start_cc_report 10:07:43 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,369 10:07:43 INFO - PROCESS | 1635 | __end_cc_report 10:07:43 INFO - PROCESS | 1635 | __startTimestamp1484762863909__endTimestamp 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | ------- Summary: start ------- 10:07:43 INFO - PROCESS | 1635 | Number of tests: 6 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5812.80 Median:6056.36 stddev:635.87 (10.5%) stddev-sans-first:609.75 10:07:43 INFO - PROCESS | 1635 | Values: 3430.9 3435.3 3447.9 3456.3 3459.7 5812.7 5861.0 5882.6 5904.7 5905.4 5879.1 5936.7 5961.5 5968.3 5968.5 5273.4 5353.2 5366.7 5385.3 5388.6 4682.6 5400.1 5402.9 5419.4 5459.3 5264.2 5292.6 5427.8 5428.1 5430.7 5320.4 5342.6 5383.9 5410.2 5446.5 5231.4 5329.3 5336.6 5351.1 5369.9 5489.4 5662.1 5722.3 5851.1 5964.7 6223.7 6232.7 6259.5 6267.7 6297.7 6315.5 6318.2 6323.6 6335.9 6339.2 5969.3 5972.4 5977.0 5991.6 6004.3 6145.7 6209.9 6261.3 6270.4 6444.8 5186.6 6168.6 6234.1 6267.9 6278.0 5218.5 6238.0 6286.3 6308.3 6313.7 6113.8 6135.1 6166.5 6274.4 6275.9 5387.3 5649.0 5916.8 6206.3 6222.1 5024.7 5033.7 5036.2 5040.7 5049.2 4403.1 4458.0 4494.8 4500.2 4514.8 5764.5 5780.5 5967.5 5996.9 6132.3 6268.9 6269.9 6304.1 6310.4 6325.2 5875.4 5907.6 5945.6 5958.6 6056.4 5466.8 5509.8 5529.9 5559.4 5568.9 6484.1 6487.4 6488.9 6505.0 6507.5 5633.3 5856.7 6147.2 6234.9 6282.7 6378.7 6398.5 6472.2 6531.2 6549.5 6246.8 6262.7 6280.1 6286.4 6296.7 6134.7 6182.8 6269.1 6308.6 6319.8 5037.6 6155.6 6166.1 6170.6 6239.5 6164.8 6218.3 6255.6 6258.1 6260.4 5221.8 6085.4 6155.2 6159.4 6174.5 6087.0 6122.4 6130.6 6134.6 6140.7 6126.7 6128.6 6129.6 6138.7 6146.9 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:67237.66 Median:39815.40 stddev:146728.36 (368.5%) stddev-sans-first:147054.34 10:07:43 INFO - PROCESS | 1635 | Values: 471.5 496.3 526.3 527.9 529.2 28300.6 30802.8 38742.8 39754.0 39876.8 41035.0 41556.8 41577.4 41593.0 41609.0 9386.0 9534.7 9543.5 9558.8 9565.7 9482.0 9510.1 9555.0 9574.4 9575.2 9498.6 9536.0 9581.0 9592.9 9604.3 9442.2 9500.6 9548.9 9558.2 9568.8 7887.7 8004.2 8033.8 9318.3 9401.1 30399.0 38982.6 39482.4 39620.4 39915.2 3151.4 3330.2 3345.3 3538.8 3595.2 26710.2 29261.6 29838.0 29959.8 30705.8 2630.5 2657.1 2939.0 3169.4 3201.0 407127.0 425161.0 427189.0 428301.9 430625.0 61506.0 62099.7 62106.0 62201.0 62433.7 63899.7 64215.7 64792.0 65237.0 65532.3 15964.9 16039.9 16220.8 16250.5 16259.9 11972.0 12416.4 12425.3 12475.3 12496.8 17643.6 18268.9 18899.8 18920.4 19012.3 7198.7 7556.7 7735.4 7766.7 7775.8 847.7 875.2 939.3 972.7 978.3 29218.8 29504.3 29824.9 29861.3 30248.8 962.7 992.7 1019.5 1028.1 1036.3 13321.8 15559.3 15840.6 15888.9 15902.6 663435.0 666483.0 670975.0 672051.0 677214.0 55570.2 55727.0 55743.2 55800.4 55946.0 1323.3 1348.9 1362.5 1368.4 1372.4 29283.7 29623.4 29774.4 30155.2 30458.5 47886.3 48503.5 49113.5 49163.3 49357.5 520066.0 522411.0 523859.0 524417.0 527484.0 26069.3 26158.5 26643.0 27103.3 27422.3 44232.6 44563.0 44698.3 44912.3 46045.0 37062.9 37428.4 37536.8 38479.6 38670.8 31675.4 36408.1 37599.1 38606.4 38871.0 36484.5 36511.8 36970.8 37221.3 37329.6 32236.4 33318.5 33811.0 33849.8 34040.0 23276.3 25317.7 25557.7 25622.6 26022.9 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:32956.38 Median:3871.97 stddev:93633.95 (2418.3%) stddev-sans-first:93864.64 10:07:43 INFO - PROCESS | 1635 | Values: 366.2 371.6 374.9 377.4 395.2 18883.8 19116.0 19577.6 19826.0 20072.7 11498.3 11850.0 11904.3 11905.2 11923.6 5032.2 5307.9 5355.5 5395.1 5410.9 3877.3 4079.3 5323.7 5366.7 6262.5 6445.2 6551.9 6555.7 6561.2 6588.1 5903.7 5971.7 5978.5 5987.9 6212.4 5582.2 5693.8 5787.6 5789.0 5811.2 12881.4 13154.4 13176.8 13240.6 13256.2 2081.6 2225.7 2298.7 2558.2 2642.9 9498.1 9571.6 9668.8 9994.8 10112.3 1719.4 1814.3 1815.4 1922.0 1950.2 117193.0 118774.0 119162.2 122185.0 125892.4 9503.3 9905.6 10053.7 10178.2 11688.0 12134.8 12445.3 12488.0 12539.5 12541.2 7949.0 8052.6 8065.4 8077.2 8177.6 8155.5 8164.0 8287.3 8455.4 8460.1 3659.1 3848.4 3866.7 3925.4 3931.9 1582.5 1667.2 1691.0 1935.3 1986.8 855.7 945.3 945.8 958.6 990.7 20394.7 20488.6 20710.0 20725.3 21391.9 907.3 923.6 926.0 957.4 958.8 10939.5 11199.2 11200.4 11223.9 11249.2 524473.0 532406.3 538065.0 540731.0 645992.5 97215.0 114812.0 120655.4 127440.0 132133.0 917.5 953.3 969.7 972.9 974.6 14452.9 18271.0 18318.2 18827.8 19344.7 40388.6 50941.0 51751.0 52104.5 53313.0 77733.0 80639.6 85464.7 87872.0 91130.5 7813.7 8005.8 8129.2 8425.3 8487.0 9269.4 9982.9 11998.2 12776.1 13076.2 10969.5 11052.9 11306.2 11395.9 11466.5 8870.1 10571.0 10715.6 10989.6 11301.6 11167.7 11215.5 11495.1 11650.4 11818.8 8092.2 8192.9 8311.9 8424.6 8516.0 9611.6 11231.7 11286.4 11433.5 11461.4 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5437.01 Median:5051.45 stddev:2979.45 (59.0%) stddev-sans-first:2972.74 10:07:43 INFO - PROCESS | 1635 | Values: 1450.2 1483.5 1654.8 1769.5 1781.7 7483.5 7716.5 7959.3 8433.2 8435.4 7978.6 8210.3 8267.6 8299.6 8309.3 2284.7 2302.3 2327.5 2329.4 2340.2 2284.3 2325.5 2352.3 2371.3 2377.7 2302.5 2314.5 2320.8 2347.1 2351.0 2279.3 2281.6 2282.4 2288.8 2299.0 2257.0 2275.6 2309.0 2313.7 2314.1 8257.0 8330.6 8341.2 8367.7 8385.6 4996.4 5109.0 5127.7 5165.2 5170.5 3160.6 3249.8 3390.6 3392.3 3401.9 1026.7 1042.1 1056.2 1068.6 1077.3 10815.5 10871.7 10907.8 10956.8 10960.2 4476.2 4556.0 4642.2 4647.4 4682.3 8173.9 8201.6 9260.1 9309.0 9402.5 5106.5 5187.7 5193.7 5201.4 5204.2 4020.3 4136.6 4573.7 4717.0 4718.5 3305.2 3797.3 3800.7 3870.4 3934.2 1268.0 1268.1 1277.1 1281.1 1283.5 770.9 892.6 892.8 902.6 924.5 4658.3 5672.1 5759.4 5787.7 5977.6 895.9 898.3 901.9 930.5 967.3 3681.7 3739.3 3784.9 3787.7 3831.7 9964.5 10081.3 10296.8 10307.4 10366.3 10030.3 10131.9 10187.7 10264.0 10292.5 7454.9 7645.3 7744.3 7806.7 7866.2 5344.9 5563.0 5568.6 5639.6 5693.9 9050.2 9239.2 9290.5 9305.0 9310.9 9423.3 9440.0 9442.8 9445.4 9463.4 7099.1 7294.7 7539.5 7771.8 7831.5 7787.6 7927.2 7942.6 7947.6 7949.0 7034.9 7063.0 7240.1 7242.2 7319.2 6924.3 7097.0 7109.4 7132.5 7299.8 6506.6 6643.0 6644.2 6677.9 6698.0 4779.6 4792.3 4813.0 4835.5 4858.3 4782.1 4818.3 4903.3 4910.0 4938.3 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4578.16 Median:5163.88 stddev:1443.11 (27.9%) stddev-sans-first:1410.94 10:07:43 INFO - PROCESS | 1635 | Values: 298.0 301.4 309.0 309.3 312.7 4361.4 5369.8 5419.0 5592.8 5647.7 4321.6 5240.6 5258.4 5533.9 5568.3 4226.9 4262.2 4268.0 4271.4 4275.6 3003.6 3380.5 3451.4 3978.5 4284.3 3641.4 4178.0 4191.9 4197.1 4235.0 3627.4 3818.3 4129.1 4176.9 4200.6 3074.7 3633.7 3702.9 3722.8 3725.0 5146.0 5305.0 5377.4 5391.8 5435.3 4524.9 4841.5 4852.7 4853.9 4910.7 4924.9 5755.6 5785.8 5825.3 5828.0 2898.8 3206.6 3908.4 3972.8 4013.2 4858.6 6120.7 6158.8 6183.0 6247.3 3650.8 3773.3 3815.6 4002.3 4012.6 3483.4 3599.9 3933.2 3939.9 3942.7 4115.5 4314.8 4744.0 4758.8 4782.5 5628.9 5868.9 5881.9 5890.5 5965.3 1006.5 1212.5 1301.8 1303.7 1304.9 808.7 854.0 861.2 882.9 886.7 3910.9 5164.8 5176.8 5178.3 5239.5 4871.5 4883.1 5162.9 5725.5 5788.5 4475.2 4713.8 4886.6 5080.0 5403.6 2927.5 2936.8 2956.6 2985.8 2994.9 5238.3 5891.0 5919.8 5949.9 5982.5 5637.8 5849.6 5870.2 5883.9 5925.3 5092.8 5265.6 5942.0 5956.6 5981.7 4889.5 5391.0 5421.8 5500.4 5810.5 4862.1 5473.5 5563.6 5650.5 5709.8 5505.4 5528.6 5939.6 6036.5 6037.8 3563.0 3792.3 3804.3 3808.0 3830.1 5407.4 5420.3 5633.3 5657.8 5719.5 4361.7 4957.3 5696.0 5772.9 5916.4 5251.8 5260.6 5535.7 5817.8 5889.9 4949.4 5626.2 5819.3 5837.4 5894.3 5131.8 5731.6 5794.4 5987.0 5990.8 4610.6 5564.0 5804.4 5819.7 5885.9 10:07:43 INFO - PROCESS | 1635 | 10:07:43 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:20988.89 Median:4174.75 stddev:31667.80 (758.6%) stddev-sans-first:31719.91 10:07:43 INFO - PROCESS | 1635 | Values: 677.0 719.6 745.3 748.3 751.7 14044.0 14614.3 14624.1 14637.0 14677.3 13040.9 13070.5 13082.5 13093.0 13162.7 511.6 638.5 655.1 798.6 803.3 804.9 806.2 807.7 809.0 812.0 853.7 862.5 864.8 865.0 870.6 826.8 834.7 858.8 859.2 859.6 632.9 638.5 639.9 640.6 642.7 13302.4 13771.0 13772.2 13778.9 14267.4 11255.5 11271.7 13369.4 13486.0 13609.1 10696.4 10969.7 11031.9 11235.3 11253.1 3623.9 3677.5 3679.1 3788.0 3817.9 70399.9 87917.7 88040.6 90343.0 91339.0 8595.4 8782.2 9232.2 10496.5 10515.2 19885.5 19955.7 20109.1 20201.9 20468.3 6533.0 7242.8 7370.9 7428.7 7430.9 4097.5 4142.9 4160.8 4165.1 4179.2 3171.9 3251.5 3268.3 3274.4 3286.7 636.8 639.9 647.4 660.9 661.6 576.7 582.6 647.3 663.5 665.2 8319.6 8758.1 8838.6 8895.9 8981.6 588.9 590.7 642.7 651.8 655.3 4170.3 4268.8 4287.0 4510.0 4543.5 72824.5 102263.5 117328.8 122711.0 123341.8 100641.5 103312.5 103653.0 103724.0 108154.0 90680.0 91167.0 91487.5 93090.5 95574.0 8552.4 8581.6 8603.6 8610.3 8871.6 41061.0 59215.7 59236.0 60368.7 60518.7 81991.0 84212.6 84560.6 86676.6 87998.6 10605.6 11173.1 11371.3 11499.6 11843.4 11639.0 11876.0 12000.4 12094.7 12152.5 10562.4 11554.4 13016.5 13038.8 13085.6 12778.5 13003.8 13470.8 13537.2 13887.3 12440.4 12789.2 13041.4 13399.5 13486.5 9921.2 10232.7 10671.7 10973.8 11392.5 10290.8 11134.5 11333.7 11365.2 11409.4 10:07:43 INFO - PROCESS | 1635 | -------- Summary: end -------- 10:07:43 INFO - PROCESS | 1635 | 10:07:44 INFO - TEST-INFO | 1635: exit 0 10:07:44 INFO - TEST-OK | dromaeo_css | took 1116104ms 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 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQVrpSn/profile http://localhost:49241/getInfo.html) 10:07:47 INFO - PROCESS | 1643 | 2017-01-18 10:07:47.519 plugin-container[1644:10114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 10:07:47 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:47 INFO - PROCESS | 1643 | 2017-01-18 10:07:47.523 plugin-container[1644:10114] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8913, name = 'com.apple.CFPasteboardClient' 10:07:47 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:47 INFO - PROCESS | 1643 | 2017-01-18 10:07:47.523 plugin-container[1644:10114] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:07:47 INFO - PROCESS | 1643 | __metrics Screen width/height:1600/1200 10:07:47 INFO - PROCESS | 1643 | colorDepth:24 10:07:47 INFO - PROCESS | 1643 | Browser inner width/height: 1024/689 10:07:47 INFO - PROCESS | 1643 | __metrics 10:07:47 ERROR - PROCESS | 1643 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 10:07:48 INFO - TEST-INFO | 1643: 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 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/T/tmpQVrpSn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:07:51 INFO - PROCESS | 1645 | 2017-01-18 10:07:51.080 plugin-container[1646:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843b, name = 'com.apple.tsm.portname' 10:07:51 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:51 INFO - PROCESS | 1645 | 2017-01-18 10:07:51.081 plugin-container[1646:10247] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 10:07:51 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:07:51 INFO - PROCESS | 1645 | 2017-01-18 10:07:51.081 plugin-container[1646:10247] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:08:00 INFO - PROCESS | 1645 | RSS: Main: 229171200 10:08:00 INFO - PROCESS | 1645 | 10:08:03 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 10:08:04 INFO - PROCESS | 1645 | RSS: Main: 241876992 10:08:04 INFO - PROCESS | 1645 | 10:08:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 10:08:07 INFO - PROCESS | 1645 | RSS: Main: 249430016 10:08:07 INFO - PROCESS | 1645 | 10:08:11 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 10:08:12 INFO - PROCESS | 1645 | RSS: Main: 253186048 10:08:12 INFO - PROCESS | 1645 | 10:08:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 10:08:14 INFO - PROCESS | 1645 | RSS: Main: 253517824 10:08:14 INFO - PROCESS | 1645 | 10:08:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:08:17 INFO - PROCESS | 1645 | RSS: Main: 253612032 10:08:17 INFO - PROCESS | 1645 | 10:08:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 10:08:20 INFO - PROCESS | 1645 | RSS: Main: 257642496 10:08:20 INFO - PROCESS | 1645 | 10:08:23 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 10:08:24 INFO - PROCESS | 1645 | RSS: Main: 258023424 10:08:24 INFO - PROCESS | 1645 | 10:08:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 10:08:28 INFO - PROCESS | 1645 | RSS: Main: 258154496 10:08:28 INFO - PROCESS | 1645 | 10:08:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:08:30 INFO - PROCESS | 1645 | RSS: Main: 257122304 10:08:30 INFO - PROCESS | 1645 | 10:08:32 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:08:32 INFO - PROCESS | 1645 | RSS: Main: 257245184 10:08:32 INFO - PROCESS | 1645 | 10:08:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:08:35 INFO - PROCESS | 1645 | RSS: Main: 257277952 10:08:35 INFO - PROCESS | 1645 | 10:08:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:08:39 INFO - PROCESS | 1645 | RSS: Main: 257544192 10:08:39 INFO - PROCESS | 1645 | 10:08:42 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:08:42 INFO - PROCESS | 1645 | RSS: Main: 258207744 10:08:42 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 10:08:44 INFO - PROCESS | 1645 | RSS: Main: 259592192 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | __start_tp_report 10:08:44 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 10:08:44 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 10:08:44 INFO - PROCESS | 1645 | |i|pagename|runs| 10:08:44 INFO - PROCESS | 1645 | |0;ai-astar;107;117;117;117;117;118;117;117;120;118 10:08:44 INFO - PROCESS | 1645 | |1;audio-beat-detection;144;115;135;145;97;149;149;146;177;144 10:08:44 INFO - PROCESS | 1645 | |2;audio-dft;196;194;198;199;194;190;269;189;276;286 10:08:44 INFO - PROCESS | 1645 | |3;audio-fft;75;59;59;84;85;53;50;50;79;59 10:08:44 INFO - PROCESS | 1645 | |4;audio-oscillator;84;71;88;72;70;71;85;69;71;87 10:08:44 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;89;126;83;132;137;114;90;87;88;77 10:08:44 INFO - PROCESS | 1645 | |6;imaging-darkroom;153;96;120;153;97;104;134;96;96;95 10:08:44 INFO - PROCESS | 1645 | |7;imaging-desaturate;109;127;88;86;72;142;156;90;91;86 10:08:44 INFO - PROCESS | 1645 | |8;json-parse-financial;56;56;57;60;59;61;60;60;60;75 10:08:44 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;70;70;83;106;111;101;73;74;73;73 10:08:44 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;84;85;84;82;84;85;83;87;87;81 10:08:44 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;143;140;141;141;143;149;144;143;179;153 10:08:44 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;166;167;166;163;168;165;152;213;231;216 10:08:44 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;70;68;67;64;65;71;68;67;67;66 10:08:44 INFO - PROCESS | 1645 | __end_tp_report 10:08:44 INFO - PROCESS | 1645 | __start_cc_report 10:08:44 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,969 10:08:44 INFO - PROCESS | 1645 | __end_cc_report 10:08:44 INFO - PROCESS | 1645 | __startTimestamp1484762924909__endTimestamp 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | ------- Summary: start ------- 10:08:44 INFO - PROCESS | 1645 | Number of tests: 14 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:116.50 Median:117.00 stddev:3.47 (3.0%) stddev-sans-first:1.01 10:08:44 INFO - PROCESS | 1645 | Values: 107.0 117.0 117.0 117.0 117.0 118.0 117.0 117.0 120.0 118.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:140.10 Median:147.50 stddev:21.40 (14.5%) stddev-sans-first:22.66 10:08:44 INFO - PROCESS | 1645 | Values: 144.0 115.0 135.0 145.0 97.0 149.0 149.0 146.0 177.0 144.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:219.10 Median:198.50 stddev:40.28 (20.3%) stddev-sans-first:41.84 10:08:44 INFO - PROCESS | 1645 | Values: 196.0 194.0 198.0 199.0 194.0 190.0 269.0 189.0 276.0 286.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:65.30 Median:67.00 stddev:13.98 (20.9%) stddev-sans-first:14.38 10:08:44 INFO - PROCESS | 1645 | Values: 75.0 59.0 59.0 84.0 85.0 53.0 50.0 50.0 79.0 59.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:76.80 Median:78.00 stddev:8.02 (10.3%) stddev-sans-first:8.08 10:08:44 INFO - PROCESS | 1645 | Values: 84.0 71.0 88.0 72.0 70.0 71.0 85.0 69.0 71.0 87.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:102.30 Median:85.00 stddev:22.52 (26.5%) stddev-sans-first:23.37 10:08:44 INFO - PROCESS | 1645 | Values: 89.0 126.0 83.0 132.0 137.0 114.0 90.0 87.0 88.0 77.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:114.40 Median:95.50 stddev:24.03 (25.2%) stddev-sans-first:21.04 10:08:44 INFO - PROCESS | 1645 | Values: 153.0 96.0 120.0 153.0 97.0 104.0 134.0 96.0 96.0 95.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:104.70 Median:86.00 stddev:27.87 (32.4%) stddev-sans-first:29.52 10:08:44 INFO - PROCESS | 1645 | Values: 109.0 127.0 88.0 86.0 72.0 142.0 156.0 90.0 91.0 86.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:60.40 Median:60.00 stddev:5.44 (9.1%) stddev-sans-first:5.53 10:08:44 INFO - PROCESS | 1645 | Values: 56.0 56.0 57.0 60.0 59.0 61.0 60.0 60.0 60.0 75.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:83.40 Median:73.00 stddev:16.17 (22.2%) stddev-sans-first:16.41 10:08:44 INFO - PROCESS | 1645 | Values: 70.0 70.0 83.0 106.0 111.0 101.0 73.0 74.0 73.0 73.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:1.93 (2.3%) stddev-sans-first:2.05 10:08:44 INFO - PROCESS | 1645 | Values: 84.0 85.0 84.0 82.0 84.0 85.0 83.0 87.0 87.0 81.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:147.60 Median:143.50 stddev:11.73 (8.2%) stddev-sans-first:12.32 10:08:44 INFO - PROCESS | 1645 | Values: 143.0 140.0 141.0 141.0 143.0 149.0 144.0 143.0 179.0 153.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.70 Median:167.50 stddev:27.86 (16.6%) stddev-sans-first:29.03 10:08:44 INFO - PROCESS | 1645 | Values: 166.0 167.0 166.0 163.0 168.0 165.0 152.0 213.0 231.0 216.0 10:08:44 INFO - PROCESS | 1645 | 10:08:44 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:2.11 (3.1%) stddev-sans-first:2.00 10:08:44 INFO - PROCESS | 1645 | Values: 70.0 68.0 67.0 64.0 65.0 71.0 68.0 67.0 67.0 66.0 10:08:44 INFO - PROCESS | 1645 | -------- Summary: end -------- 10:08:44 INFO - PROCESS | 1645 | 10:08:45 INFO - TEST-INFO | 1645: exit 0 10:08:45 INFO - TEST-OK | kraken | took 61361ms 10:08:45 INFO - SUITE-END | took 1177s 10:08:46 INFO - Completed test suite (00:19:37) 10:08:46 INFO - javascript benchmark 10:08:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7126.291909854727, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3430.9485357719636, 3435.3129374125174, 3447.9138021549475, 3456.2569047654756, 3459.7232221422287, 5812.709364531773, 5860.974329492343, 5882.647041177533, 5904.675242861641, 5905.379935106811, 5879.11813228016, 5936.673482958447, 5961.523078153748, 5968.343482216964, 5968.522518198544, 5273.419923808378, 5353.223782551528, 5366.731663416833, 5385.272988146589, 5388.568914486851, 4682.578567928903, 5400.108982017982, 5402.8919421611545, 5419.430959749209, 5459.344878614572, 5264.157734762439, 5292.629515933886, 5427.83716488506, 5428.077226871442, 5430.67415955042, 5320.414754377015, 5342.637627405006, 5383.946160538384, 5410.242566040757, 5446.455354464561, 5231.398389185217, 5329.25390445338, 5336.626436149471, 5351.063563876306, 5369.892602147936, 5489.39616642169, 5662.121446808908, 5722.313322401338, 5851.0638297872565, 5964.701764911781, 6223.719932602999, 6232.688365581703, 6259.4679452246155, 6267.686615669244, 6297.6536290504, 6315.463185629227, 6318.210223722035, 6323.557350985433, 6335.873282534323, 6339.175907132089, 5969.253843269591, 5972.432618901197, 5977.013792724152, 5991.610545314584, 6004.309504406961, 6145.723442445012, 6209.9379006210265, 6261.279952805395, 6270.372962703693, 6444.77443289492, 5186.559142472932, 6168.568200226031, 6234.127222188898, 6267.874642507124, 6277.96861015692, 5218.478152184751, 6238.0, 6286.339934306832, 6308.3060863305, 6313.652749098752, 6113.780377411019, 6135.110179431545, 6166.506679465632, 6274.372562743689, 6275.874482510416, 5387.326584176978, 5649.0, 5916.757480928967, 6206.317305096436, 6222.12890195373, 5024.698518088926, 5033.723145226974, 5036.194208926628, 5040.747962601855, 5049.192129259301, 4403.075354175659, 4457.974665826878, 4494.775261236925, 4500.234960056799, 4514.81940722374, 5764.510016648548, 5780.450857168562, 5967.522598192134, 5996.910046349309, 6132.264128304632, 6268.905966410507, 6269.8746025079245, 6304.117423560705, 6310.4005119514495, 6325.177726895474, 5875.383084776123, 5907.586468947218, 5945.64326140433, 5958.582899197015, 6056.36408177135, 5466.835994920159, 5509.807156749494, 5529.917051244234, 5559.444055594489, 5568.9443105568425, 6484.092227088211, 6487.3836985486305, 6488.935110648833, 6504.9674751625935, 6507.544471887112, 5633.295838020247, 5856.677882716492, 6147.200863887666, 6234.875302493834, 6282.654454004891, 6378.6810659467765, 6398.4561312287115, 6472.15861937945, 6531.150950376421, 6549.508786841101, 6246.812595622138, 6262.68686565679, 6280.1207830902795, 6286.4342209201395, 6296.748130074746, 6134.693265336626, 6182.783602573888, 6269.0909818075215, 6308.589941653778, 6319.778807741707, 5037.596992240538, 6155.630662160284, 6166.075088736726, 6170.598911070766, 6239.4696450800375, 6164.753409863555, 6218.347073557392, 6255.593386429868, 6258.092576576462, 6260.436560709558, 5221.7728833724805, 6085.361037091042, 6155.169052177898, 6159.4456498915315, 6174.475169610454, 6087.0, 6122.367660829107, 6130.642739936431, 6134.601250918676, 6140.657627863787, 6126.670355029888, 6128.625347140905, 6129.603311142587, 6138.738486743586, 6146.938530614815], "value": 5774.551720453526, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.50256479414224, 496.3101289208001, 526.2658591265183, 527.8838655495792, 529.2035486592678, 28300.57549136762, 30802.76788928443, 38742.80628596857, 39754.0, 39876.80061599688, 41035.0, 41556.79221603895, 41577.37633935493, 41593.0, 41609.0, 9385.953070234673, 9534.666286679967, 9543.47510886901, 9558.80882382352, 9565.713028609118, 9482.0, 9510.096540828645, 9555.0, 9574.425534467919, 9575.23398128148, 9498.620055197784, 9536.046395360443, 9581.0, 9592.856107158399, 9604.327697061208, 9442.244620430418, 9500.619975200983, 9548.904510954872, 9558.235341172689, 9568.808623827554, 7887.723929662434, 8004.239597238253, 8033.799155021113, 9318.254539636813, 9401.059894010612, 30399.0, 38982.610173898465, 39482.407763883566, 39620.40569391462, 39915.201695965916, 3151.43274210642, 3330.20075181957, 3345.330933813247, 3538.840752166159, 3595.1551385424405, 26710.198694039016, 29261.56107658387, 29838.0, 29959.801607935657, 30705.846470767505, 2630.4607555451084, 2657.0833062593374, 2938.9559156612668, 3169.413658473167, 3200.9519857202163, 407127.0, 425161.0, 427189.0, 428301.8584907087, 430625.0, 61506.0, 62099.6895015522, 62106.0, 62201.0, 62433.68783156055, 63899.68050159812, 64215.6789216051, 64792.0, 65237.0, 65532.34467655358, 15964.92017539905, 16039.919800400923, 16220.756688649679, 16250.51248462548, 16259.943103698222, 11972.0, 12416.379181040911, 12425.31660758667, 12475.251484910934, 12496.81254781179, 17643.56030508216, 18268.90865545664, 18899.81100189008, 18920.43238702841, 19012.334568290044, 7198.676059577331, 7556.697732090654, 7735.375571130013, 7766.728164514215, 7775.844483110306, 847.6694089305176, 875.2341701011615, 939.3135632092211, 972.6595691507915, 978.280963491833, 29218.831246750316, 29504.262393440335, 29824.850875745484, 29861.253468663457, 30248.84875575608, 962.6823148361024, 992.667456402099, 1019.4596863662271, 1028.1055481730941, 1036.264252380803, 13321.800172997413, 15559.29983150761, 15840.603984900239, 15888.92055539715, 15902.60243493922, 663435.0, 666483.0, 670975.0, 672051.0, 677214.0, 55570.16644750332, 55727.0, 55743.163852542246, 55800.441995579524, 55946.0, 1323.2523624152323, 1348.865113488663, 1362.495876525672, 1368.404743936371, 1372.444160115155, 29283.707162928098, 29623.407531849673, 29774.404511910074, 30155.246118846328, 30458.543121853632, 47886.28170577374, 48503.5149648499, 49113.508864910895, 49163.262551061045, 49357.506424935294, 520066.0, 522411.0, 523859.0, 524417.0, 527484.0, 26069.348266293495, 26158.476830462903, 26643.0, 27103.322416939733, 27422.314442139104, 44232.55767442413, 44563.0, 44698.3295250565, 44912.32631510595, 46045.0, 37062.90561908889, 37428.43857342197, 37536.812315938245, 38479.61520384872, 38670.8398748038, 31675.416229188908, 36408.08979775421, 37599.06002349963, 38606.42090368703, 38871.0, 36484.45273320955, 36511.81744091262, 36970.8151459241, 37221.25557488781, 37329.6267037337, 32236.35527289488, 33318.50022249717, 33811.0, 33849.830750846086, 34040.0, 23276.301710948697, 25317.74682253154, 25557.744422555537, 25622.615660765478, 26022.86988565045], "value": 19412.685248841903, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.1779305459244, 371.6357969190192, 374.8931554506966, 377.39428217710577, 395.2135250850806, 18883.811161888378, 19116.044197790106, 19577.608447831033, 19826.0, 20072.69890951637, 11498.25261358011, 11850.0, 11904.285742855434, 11905.16663833532, 11923.64229073127, 5032.220005899082, 5307.893842123155, 5355.518003379695, 5395.10980688186, 5410.864728381783, 3877.263319969197, 4079.3473044312914, 5323.733813309319, 5366.731663416833, 6262.499000080005, 6445.162128923257, 6551.901721474182, 6555.737770489175, 6561.245456772484, 6588.143541339643, 5903.675297858616, 5971.701414929236, 5978.521718262572, 5987.910181347262, 6212.44088032077, 5582.218489411485, 5693.77224911003, 5787.594868359217, 5789.04480760674, 5811.244538210048, 12881.355932203352, 13154.408051637603, 13176.802347964789, 13240.602781916557, 13256.204627722367, 2081.6461201595766, 2225.732912050564, 2298.747137814839, 2558.2325302409204, 2642.907498237572, 9498.14516693501, 9571.61713531465, 9668.806623867484, 9994.750131246705, 10112.292139550234, 1719.355241784331, 1814.2833580735667, 1815.4099917526726, 1922.0389805097452, 1950.1906708715867, 117193.0, 118774.0, 119162.21256681156, 122185.0, 125892.37053814763, 9503.287253455881, 9905.60377584903, 10053.69838904834, 10178.23663225261, 11688.0, 12134.817977730341, 12445.25328480294, 12488.000959923365, 12539.498420063095, 12541.184822986477, 7949.046114466186, 8052.59737013147, 8065.354771618257, 8077.151899050633, 8177.632006559719, 8155.469894456842, 8164.0, 8287.254147126667, 8455.365847561457, 8460.069392366835, 3659.1218107654504, 3848.4419759134767, 3866.6713329366758, 3925.391564307537, 3931.8623848165175, 1582.4936020473567, 1667.241405160649, 1690.9661806763795, 1935.2549268531716, 1986.8311193548711, 855.6919508976763, 945.2768631996528, 945.7541039329824, 958.5925981457852, 990.65327135502, 20394.69407958882, 20488.590228195353, 20710.0, 20725.27461538869, 21391.8930405347, 907.3421769217397, 923.5936188077225, 925.9675911343205, 957.3824882950458, 958.8178246133212, 10939.453027348602, 11199.160062995144, 11200.383978881076, 11223.887761122447, 11249.212555121227, 524473.0, 532406.3379683077, 538065.0, 540731.0, 645992.5400746026, 97215.0, 114811.99999999833, 120655.39672301582, 127440.0, 132133.0, 917.5091326140483, 953.2612225525273, 969.6703120938914, 972.9367591106555, 974.6101559376306, 14452.855471445151, 18271.0, 18318.17568209407, 18827.811721882605, 19344.70982935229, 40388.59611403966, 50940.98118037544, 51751.0, 52104.47895520996, 53313.0, 77733.0, 80639.59680201561, 85464.71802922837, 87872.0, 91130.54434727784, 7813.693432843408, 8005.799855003672, 8129.187081291823, 8425.283850872744, 8486.957565212135, 9269.397489163182, 9982.850257246002, 11998.160128790902, 12776.10567260283, 13076.150050246704, 10969.451527423756, 11052.889471105185, 11306.208565400671, 11395.886041139815, 11466.541338346706, 8870.112988701077, 10571.0, 10715.571377144826, 10989.626296712911, 11301.604443844426, 11167.664970050912, 11215.49530271156, 11495.137864660017, 11650.359230242419, 11818.763624727286, 8092.150324215872, 8192.877106843522, 8311.916880831357, 8424.62089205975, 8515.957420212859, 9611.615535378507, 11231.719207019563, 11286.379249141377, 11433.485493152662, 11461.369624670722], "value": 9001.686298869909, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.2168828832428, 1483.4659522571872, 1654.8014238291398, 1769.4780039888233, 1781.7060185069465, 7483.513571617841, 7716.498427602216, 7959.3234575061115, 8433.24100830925, 8435.367347448942, 7978.640961156747, 8210.30212431944, 8267.627956741946, 8299.58502074897, 8309.293710034639, 2284.6915666385075, 2302.3093072078395, 2327.522857814144, 2329.3827135809024, 2340.192633541434, 2284.2690339091496, 2325.5232677301115, 2352.3178278299274, 2371.312319427364, 2377.702787151606, 2302.470431800687, 2314.5486630107134, 2320.7795259450327, 2347.108098922407, 2350.9882450587725, 2279.270633397305, 2281.634938409863, 2282.429392651837, 2288.8168946484243, 2298.9655155172686, 2256.954860902773, 2275.613145765224, 2309.0, 2313.6760853480528, 2314.109068008812, 8257.0, 8330.62512186953, 8341.20758527945, 8367.748967530984, 8385.6226469809, 4996.350474438336, 5108.974455127701, 5127.743612819344, 5165.199394093921, 5170.482951704837, 3160.620725512953, 3249.772515923886, 3390.6439823818637, 3392.3215356928467, 3401.9489707654407, 1026.7484466305727, 1042.11940909931, 1056.2183983851971, 1068.6374872038136, 1077.272840832435, 10815.459227038617, 10871.67384978452, 10907.83638245427, 10956.835647465294, 10960.177986650953, 4476.239039363316, 4555.954440455619, 4642.16441040616, 4647.419072615923, 4682.297655351656, 8173.918260817434, 8201.58992050407, 9260.120288572574, 9308.953455232817, 9402.529873506297, 5106.540411362995, 5187.662801917863, 5193.662411943212, 5201.427842937273, 5204.1673332266655, 4020.2763502569233, 4136.6483848872585, 4573.702709323883, 4716.976415117902, 4718.504557021532, 3305.173706573357, 3797.316483033025, 3800.7339486235696, 3870.4194370844034, 3934.213157368538, 1267.9982813577171, 1268.0869773762877, 1277.106025781938, 1281.135233717237, 1283.4866053578457, 770.8612449759097, 892.607252808762, 892.8214357128601, 902.5532361481108, 924.4915296586851, 4658.254679251371, 5672.14917762339, 5759.429153924593, 5787.681677507778, 5977.551683623746, 895.9080546923008, 898.2948385517337, 901.8962819275736, 930.50683137937, 967.3373738988815, 3681.687056600219, 3739.3269211541638, 3784.8675296365045, 3787.6780473660046, 3831.7317787755146, 9964.501774911225, 10081.344712593806, 10296.845547316796, 10307.381557106597, 10366.326188797704, 10030.29787914839, 10131.898681013095, 10187.7453063674, 10263.973602639677, 10292.485375731183, 7454.85090298191, 7645.273698998586, 7744.3417309528195, 7806.7267645633265, 7866.174051724601, 5344.866378340573, 5563.0, 5568.554515638658, 5639.6334238274385, 5693.9430605693415, 9050.230730387992, 9239.214666753132, 9290.489023103793, 9304.95347523285, 9310.860337094813, 9423.340366174301, 9439.952800235955, 9442.819647544056, 9445.433274003582, 9463.432194068102, 7099.148102227766, 7294.744683936249, 7539.509931854412, 7771.844563108819, 7831.451798374059, 7787.5716835574585, 7927.16764739694, 7942.64258108375, 7947.562884041202, 7948.960255198687, 7034.859302814018, 7063.046488723957, 7240.131184257712, 7242.167150777728, 7319.158296795937, 6924.272951340238, 7096.964515177391, 7109.395701365442, 7132.536385134949, 7299.781006569811, 6506.60960342381, 6643.0, 6644.235912870082, 6677.866442671217, 6698.0, 4779.593734532604, 4792.281157826354, 4812.989272252726, 4835.468098509232, 4858.319835223001, 4782.115308667908, 4818.253170758538, 4903.264510323337, 4909.950900491092, 4938.283948827472], "value": 4411.246093051904, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.97914146009776, 301.4272881525102, 309.0, 309.34573377307, 312.6919983815941, 4361.411209486719, 5369.812056578021, 5418.972905135473, 5592.832215033555, 5647.661140331583, 4321.595481468527, 5240.633155679104, 5258.44786297439, 5533.889322213553, 5568.276124103871, 4226.873193804183, 4262.167268074727, 4268.0, 4271.423357846682, 4275.615194632483, 3003.6121283579496, 3380.4760262159402, 3451.3960056990054, 3978.5026871641044, 4284.33592793116, 3641.362761516737, 4178.0, 4191.874243772691, 4197.139586384784, 4235.0, 3627.4196128619296, 3818.293615681108, 4129.050318426779, 4176.853810116647, 4200.6219440250525, 3074.6925307469296, 3633.691136253395, 3702.9259414811822, 3722.758020728644, 3725.0, 5146.0, 5304.957591327367, 5377.381601115882, 5391.757370918279, 5435.347758269033, 4524.914020635057, 4841.515848415165, 4852.660313778002, 4853.93213493031, 4910.705357678551, 4924.876878078041, 5755.611421260172, 5785.82642520725, 5825.271841019872, 5827.970860145714, 2898.796999245311, 3206.551082848403, 3908.4332771748223, 3972.8410863565714, 4013.227566087868, 4858.562729354339, 6120.724567394478, 6158.784442544489, 6182.990528078392, 6247.344028876948, 3650.8357123929486, 3773.2830762155104, 3815.637514436124, 4002.2995975704134, 4012.598740125964, 3483.390406678821, 3599.920023992792, 3933.193695292458, 3939.862104826317, 3942.704297177723, 4115.54728979812, 4314.827406903752, 4744.027474367816, 4758.7858546364805, 4782.473927867932, 5628.85927851807, 5868.941310586925, 5881.852953676107, 5890.4698577128265, 5965.254343207099, 1006.5168719014767, 1212.5452955141823, 1301.804729290614, 1303.7197002644477, 1304.9086563940432, 808.6886548678801, 853.9871901921476, 861.1947828779986, 882.9426087304305, 886.7295474880094, 3910.882673519799, 5164.819231326961, 5176.818811341585, 5178.271644416014, 5239.502247286502, 4871.5128487151005, 4883.145449546315, 5162.948370516322, 5725.484706376446, 5788.450097240755, 4475.187549042578, 4713.8350157745, 4886.633502487328, 5080.034793389244, 5403.567714582824, 2927.487689654302, 2936.838473883957, 2956.63042119735, 2985.8059226150667, 2994.930196548555, 5238.266642670029, 5891.0, 5919.763209471573, 5949.935783071722, 5982.46157845796, 5637.774489020475, 5849.561282903799, 5870.236869206976, 5883.852648733541, 5925.318588362394, 5092.770825312869, 5265.578753699619, 5941.970290148608, 5956.604735300837, 5981.671008094597, 4889.535494128052, 5390.975714614211, 5421.783128674809, 5500.3949565548555, 5810.451019276665, 4862.124817532736, 5473.457030664618, 5563.610547261815, 5650.519705825051, 5709.800156994485, 5505.421930697219, 5528.640638358494, 5939.613925094855, 6036.456718895321, 6037.78867739627, 3562.982185089058, 3792.3363411403448, 3804.2581696568905, 3807.9619203808716, 3830.1190726133705, 5407.351117865881, 5420.322459692538, 5633.267675202197, 5657.830265092054, 5719.456651618089, 4361.738295702268, 4957.3059771632725, 5696.003199440164, 5772.9031484017905, 5916.408359164049, 5251.842444726664, 5260.552853007537, 5535.72678284005, 5817.7963771267805, 5889.941100589111, 4949.406071271326, 5626.1560765883805, 5819.301683797971, 5837.423895548093, 5894.322152952465, 5131.7940284032475, 5731.598788084961, 5794.362620111869, 5986.964432093109, 5990.820275391745, 4610.625527853092, 5564.0, 5804.448577385142, 5819.6508209507565, 5885.941140588539], "value": 4113.367890148098, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [676.9966150169248, 719.6042176802757, 745.2994185465664, 748.3227678950545, 751.7293774241274, 14044.0, 14614.269286535684, 14624.122552646822, 14636.999999999987, 14677.339519721612, 13040.93479532601, 13070.477180912752, 13082.542111026116, 13092.952563794897, 13162.670933226675, 511.5753924242879, 638.4732595608618, 655.1417642887808, 798.5528104261602, 803.3252068262658, 804.8792681097824, 806.2179685704887, 807.7334479621711, 809.0493669937829, 811.9675212991473, 853.6585365853646, 862.5040601654042, 864.8486514859906, 865.0, 870.5951732444412, 826.7561069484517, 834.6744769539885, 858.8368210040072, 859.179483593167, 859.5659192107977, 632.905064240363, 638.5402510192654, 639.9232092148925, 640.6444423345042, 642.7332656947361, 13302.40139193729, 13770.96717746173, 13772.24252666103, 13778.931105344509, 14267.429302827875, 11255.54977800887, 11271.661850144508, 13369.398377073056, 13485.988550858725, 13609.11540749858, 10696.358218506837, 10969.670909872717, 11031.889681103166, 11235.325880447117, 11253.099752019818, 3623.8912832615065, 3677.485152078711, 3679.1170119171206, 3787.9810600947094, 3817.9236415271816, 70399.94400084003, 87917.68123478089, 88040.5597972006, 90343.0, 91339.0, 8595.441296315845, 8782.209601135928, 9232.215261702697, 10496.475176241158, 10515.211359147941, 19885.502862428555, 19955.700664490043, 20109.09509072095, 20201.898990505248, 20468.283610073577, 6533.0, 7242.782716518513, 7370.8894366584545, 7428.739994100289, 7430.925690743132, 4097.467329247179, 4142.855000075043, 4160.750354978711, 4165.146145040259, 4179.2059508693255, 3171.8572664230164, 3251.4797632378704, 3268.3299923515624, 3274.3942370661503, 3286.6549012353835, 636.8057742388523, 639.88162189995, 647.4270270810367, 660.8579155481594, 661.6195687479718, 576.6655339902846, 582.5776312173725, 647.2782847125516, 663.4758540752848, 665.2017578905236, 8319.584020798935, 8758.124187581318, 8838.646454141892, 8895.866562001574, 8981.619870734261, 588.9499392551595, 590.7016956437058, 642.6979319719725, 651.84681599824, 655.2975687366418, 4170.291890601938, 4268.765217913045, 4286.957130428718, 4510.0, 4543.5002149763495, 72824.54350913058, 102263.46604800934, 117328.82671173349, 122711.0, 123341.76658233482, 100641.49679251557, 103312.48343758233, 103653.0, 103724.0, 108154.0, 90680.0, 91167.0, 91487.54256228676, 93090.53454732682, 95574.0, 8552.358573106918, 8581.613827377658, 8603.612837422206, 8610.311175105851, 8871.64513419456, 41061.0, 59215.70392148012, 59236.0, 60368.69815650894, 60518.697406512685, 81991.0, 84212.57893710492, 84560.57719711363, 86676.5666171665, 87998.56000719956, 10605.628802991858, 11173.106151507698, 11371.30422630837, 11499.597514086967, 11843.407829608657, 11639.0, 11876.0, 12000.399980001139, 12094.697632558904, 12152.453139608562, 10562.366258024544, 11554.364509952033, 13016.544420945222, 13038.804417933548, 13085.607431777062, 12778.488860445477, 13003.782249767468, 13470.797938031134, 13537.187768733907, 13887.305634718427, 12440.440180191732, 12789.232646041268, 13041.400798351442, 13399.464021439424, 13486.527971520949, 9921.206303495559, 10232.744181395525, 10671.733206669895, 10973.835392469278, 11392.48733806964, 10290.794184116425, 11134.544480118393, 11333.659990200307, 11365.204435689422, 11409.429528523706], "value": 7152.372658002377, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 120.0, 118.0], "value": 116.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 115.0, 135.0, 145.0, 97.0, 149.0, 149.0, 146.0, 177.0, 144.0], "value": 140.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 194.0, 198.0, 199.0, 194.0, 190.0, 269.0, 189.0, 276.0, 286.0], "value": 219.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 59.0, 59.0, 84.0, 85.0, 53.0, 50.0, 50.0, 79.0, 59.0], "value": 65.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 71.0, 88.0, 72.0, 70.0, 71.0, 85.0, 69.0, 71.0, 87.0], "value": 76.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 126.0, 83.0, 132.0, 137.0, 114.0, 90.0, 87.0, 88.0, 77.0], "value": 102.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 96.0, 120.0, 153.0, 97.0, 104.0, 134.0, 96.0, 96.0, 95.0], "value": 114.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 127.0, 88.0, 86.0, 72.0, 142.0, 156.0, 90.0, 91.0, 86.0], "value": 104.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 60.0, 59.0, 61.0, 60.0, 60.0, 60.0, 75.0], "value": 60.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 83.0, 106.0, 111.0, 101.0, 73.0, 74.0, 73.0, 73.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 82.0, 84.0, 85.0, 83.0, 87.0, 87.0, 81.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 141.0, 141.0, 143.0, 149.0, 144.0, 143.0, 179.0, 153.0], "value": 147.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 163.0, 168.0, 165.0, 152.0, 213.0, 231.0, 216.0], "value": 180.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 64.0, 65.0, 71.0, 68.0, 67.0, 67.0, 66.0], "value": 67.3, "unit": "score"}]}]} 10:08:46 INFO - Return code: 0 10:08:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:08:46 ERROR - # TBPL SUCCESS # 10:08:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:08:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:08:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:08:46 INFO - 2017-01-18 10:08:46.226 screenresolution[1650:10626] starting screenresolution argv=screenresolution get 10:08:46 INFO - 2017-01-18 10:08:46.229 screenresolution[1650:10626] Display 0: 1600x1200x32@60 10:08:46 INFO - 2017-01-18 10:08:46.235 screenresolution[1651:10629] starting screenresolution argv=screenresolution list 10:08:46 INFO - Available Modes on Display 0 10:08:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:08:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:08:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:08:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:08:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:08:46 INFO - Intel Iris: 10:08:46 INFO - Chipset Model: Intel Iris 10:08:46 INFO - Type: GPU 10:08:46 INFO - Bus: Built-In 10:08:46 INFO - VRAM (Dynamic, Max): 1536 MB 10:08:46 INFO - Vendor: Intel (0x8086) 10:08:46 INFO - Device ID: 0x0a2e 10:08:46 INFO - Revision ID: 0x0009 10:08:46 INFO - Displays: 10:08:46 INFO - Display: 10:08:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:08:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:08:46 INFO - Display Serial Number: 1600x1200 60 10:08:46 INFO - Main Display: Yes 10:08:46 INFO - Mirror: Off 10:08:46 INFO - Online: Yes 10:08:46 INFO - Rotation: Supported 10:08:46 INFO - Return code: 0 10:08:46 INFO - Running post-action listener: _resource_record_post_action 10:08:46 INFO - [mozharness: 2017-01-18 18:08:46.484291Z] Finished run-tests step (success) 10:08:46 INFO - Running post-run listener: _resource_record_post_run 10:08:46 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 45718528; Write bytes: 662568960; Read time: 431; Write time: 2132 10:08:46 INFO - TinderboxPrint: CPU usage
54.2% 10:08:46 INFO - TinderboxPrint: I/O read bytes / time
45,718,528 / 431 10:08:46 INFO - TinderboxPrint: I/O write bytes / time
662,568,960 / 2,132 10:08:46 INFO - TinderboxPrint: CPU idle
2,218.2 (45.8%) 10:08:46 INFO - TinderboxPrint: CPU system
975.4 (20.1%) 10:08:46 INFO - TinderboxPrint: CPU user
1,648.0 (34.0%) 10:08:46 INFO - TinderboxPrint: Swap in / out
402,923,520 / 0 10:08:46 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 333752832; Write bytes: 334788096; Read time: 26106; Write time: 660 10:08:46 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 44760064; Write bytes: 310057472; Read time: 417; Write time: 1422 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:47 INFO - (blobuploader) - INFO - Open directory for files ... 10:08:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:08:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:08:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:08:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_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-e10s_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-e10s_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:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:08:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:08:52 INFO - (blobuploader) - INFO - Done attempting. 10:08:52 INFO - (blobuploader) - INFO - Iteration through files over. 10:08:52 INFO - Return code: 0 10:08:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:08:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:08:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee26118b1edb5bc73ad9a76051953af8e573b8b1660da5d24906d75a71d06dc8df70870974b70fa3722292d9ba6a9cc7b83c80535c126ff77b2e554f42825ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca6a8a37959eec112821087c40b2d2a76f4c582428a2b151487e72b82471a909498dcb05ae97b171519f4984b859daebec8bd58fbda6855cb03fdd70804a9cf4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9be809266a3bb4f670e189624e842eec2253dd96c5eecd24c4de659a481bc19803ef90cf490600e2853de1b24a30fa15f8d1aed46255d1b4ef1fe03260a9a58"} 10:08:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:08:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:08:52 INFO - Contents: 10:08:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee26118b1edb5bc73ad9a76051953af8e573b8b1660da5d24906d75a71d06dc8df70870974b70fa3722292d9ba6a9cc7b83c80535c126ff77b2e554f42825ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca6a8a37959eec112821087c40b2d2a76f4c582428a2b151487e72b82471a909498dcb05ae97b171519f4984b859daebec8bd58fbda6855cb03fdd70804a9cf4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9be809266a3bb4f670e189624e842eec2253dd96c5eecd24c4de659a481bc19803ef90cf490600e2853de1b24a30fa15f8d1aed46255d1b4ef1fe03260a9a58"} 10:08:52 INFO - Running post-run listener: copy_logs_to_upload_dir 10:08:52 INFO - Copying logs to upload dir... 10:08:52 INFO - mkdir: /builds/slave/test/build/upload/logs 10:08:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.548504 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-18 10:08:52.339841) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:52.343055) ========= 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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee26118b1edb5bc73ad9a76051953af8e573b8b1660da5d24906d75a71d06dc8df70870974b70fa3722292d9ba6a9cc7b83c80535c126ff77b2e554f42825ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca6a8a37959eec112821087c40b2d2a76f4c582428a2b151487e72b82471a909498dcb05ae97b171519f4984b859daebec8bd58fbda6855cb03fdd70804a9cf4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9be809266a3bb4f670e189624e842eec2253dd96c5eecd24c4de659a481bc19803ef90cf490600e2853de1b24a30fa15f8d1aed46255d1b4ef1fe03260a9a58"} 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.011305 build_url: 'https://queue.taskcluster.net/v1/task/AiQn5yoWQ1-DESkeLdVMxw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ee26118b1edb5bc73ad9a76051953af8e573b8b1660da5d24906d75a71d06dc8df70870974b70fa3722292d9ba6a9cc7b83c80535c126ff77b2e554f42825ed", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ca6a8a37959eec112821087c40b2d2a76f4c582428a2b151487e72b82471a909498dcb05ae97b171519f4984b859daebec8bd58fbda6855cb03fdd70804a9cf4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c9be809266a3bb4f670e189624e842eec2253dd96c5eecd24c4de659a481bc19803ef90cf490600e2853de1b24a30fa15f8d1aed46255d1b4ef1fe03260a9a58"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:52.383119) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:52.383671) ========= 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.Hsk37HItvW/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.VRxkfTXpXS/Listeners TMPDIR=/var/folders/9y/wxkdvkmj0lb2gjy9jhxrw28800000w/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.005156 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-18 10:08:52.435174) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 10:08:52.435891) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-18 10:08:52.436582) ========= ========= Total master_lag: 0.19 =========