builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0052 starttime: 1494548233.97 results: success (0) revision: 7e0e20683d5ab7ac8238251fa411ef3027daa22e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:13.971280) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:13.971739) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:13.972035) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:14.000461) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:14.000861) ========= 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.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-05-11 17:17:14-- 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% 9.44M=0.001s 2017-05-11 17:17:14 (9.44 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086027 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:14.111089) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:14.111525) ========= 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.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.032186 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:14.165280) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 17:17:14.165671) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 7e0e20683d5ab7ac8238251fa411ef3027daa22e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 7e0e20683d5ab7ac8238251fa411ef3027daa22e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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-05-11 17:17:14,274 truncating revision to first 12 chars 2017-05-11 17:17:14,274 Setting DEBUG logging. 2017-05-11 17:17:14,274 attempt 1/10 2017-05-11 17:17:14,274 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/7e0e20683d5a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-11 17:17:15,405 unpacking tar archive at: mozilla-central-7e0e20683d5a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.410763 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-11 17:17:15.598184) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:15.598504) ========= script_repo_revision: 7e0e20683d5ab7ac8238251fa411ef3027daa22e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:15.598911) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:15.599193) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-11 17:17:15.618404) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 22 secs) (at 2017-05-11 17:17:15.618801) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:17:15 INFO - MultiFileLogger online at 20170511 17:17:15 in /builds/slave/test 17:17:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:17:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:17:15 INFO - {'append_to_log': False, 17:17:15 INFO - 'base_work_dir': '/builds/slave/test', 17:17:15 INFO - 'blob_upload_branch': 'Firefox', 17:17:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:17:15 INFO - 'branch': 'Firefox', 17:17:15 INFO - 'buildbot_json_path': 'buildprops.json', 17:17:15 INFO - 'config_files': ('talos/mac_config.py',), 17:17:15 INFO - 'default_actions': ('clobber', 17:17:15 INFO - 'read-buildbot-config', 17:17:15 INFO - 'download-and-extract', 17:17:15 INFO - 'populate-webroot', 17:17:15 INFO - 'create-virtualenv', 17:17:15 INFO - 'install', 17:17:15 INFO - 'run-tests'), 17:17:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:17:15 INFO - 'download_minidump_stackwalk': True, 17:17:15 INFO - 'download_symbols': 'ondemand', 17:17:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:17:15 INFO - 'tooltool.py': '/tools/tooltool.py', 17:17:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:17:15 INFO - '/tools/misc-python/virtualenv.py')}, 17:17:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:17:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:17:15 INFO - 'gecko_profile': False, 17:17:15 INFO - 'gecko_profile_interval': 0, 17:17:15 INFO - 'installer_path': 'installer.exe', 17:17:15 INFO - 'log_level': 'info', 17:17:15 INFO - 'log_name': 'talos', 17:17:15 INFO - 'log_to_console': True, 17:17:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:17:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:17:15 INFO - 'opt_config_files': (), 17:17:15 INFO - 'pip_index': False, 17:17:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:15 INFO - 'cmd': ('bash', 17:17:15 INFO - '-c', 17:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:15 INFO - 'enabled': True, 17:17:15 INFO - 'halt_on_failure': False, 17:17:15 INFO - 'name': 'check_screen_resolution'},), 17:17:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:17:15 INFO - 'cmd': ('bash', 17:17:15 INFO - '-c', 17:17:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:17:15 INFO - 'enabled': True, 17:17:15 INFO - 'halt_on_failure': False, 17:17:15 INFO - 'name': 'check_screen_resolution'},), 17:17:15 INFO - 'run_cmd_checks_enabled': True, 17:17:15 INFO - 'suite': 'dromaeojs-e10s', 17:17:15 INFO - 'system_bits': '32', 17:17:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:17:15 INFO - 'title': 't-yosemite-r7-0052', 17:17:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:17:15 INFO - 'use_talos_json': True, 17:17:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:17:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:17:15 INFO - 'work_dir': 'build'} 17:17:15 INFO - [mozharness: 2017-05-12 00:17:15.830788Z] Running clobber step. 17:17:15 INFO - Running pre-action listener: _resource_record_pre_action 17:17:15 INFO - Running main action method: clobber 17:17:15 INFO - rmtree: /builds/slave/test/build 17:17:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:17:18 INFO - Running post-action listener: _resource_record_post_action 17:17:18 INFO - [mozharness: 2017-05-12 00:17:18.312223Z] Finished clobber step (success) 17:17:18 INFO - [mozharness: 2017-05-12 00:17:18.312473Z] Running read-buildbot-config step. 17:17:18 INFO - Running pre-action listener: _resource_record_pre_action 17:17:18 INFO - Running main action method: read_buildbot_config 17:17:18 INFO - Using buildbot properties: 17:17:18 INFO - { 17:17:18 INFO - "project": "", 17:17:18 INFO - "product": "firefox", 17:17:18 INFO - "who": "eakhgari@mozilla.com", 17:17:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:17:18 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:17:18 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:17:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:17:18 INFO - "stage_platform": "macosx64", 17:17:18 INFO - "basedir": "/builds/slave/test", 17:17:18 INFO - "buildnumber": 60, 17:17:18 INFO - "platform": "yosemite_r7", 17:17:18 INFO - "parent_task_id": "FqKUAENdSTOt7TyVMIohig", 17:17:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 17:17:18 INFO - "slavebuilddir": "test", 17:17:18 INFO - "taskId": "NQST8tgUT4Ces7KTlQQeAQ", 17:17:18 INFO - "branch": "mozilla-central", 17:17:18 INFO - "script_repo_revision": "production", 17:17:18 INFO - "revision": "7e0e20683d5ab7ac8238251fa411ef3027daa22e", 17:17:18 INFO - "slavename": "t-yosemite-r7-0052", 17:17:18 INFO - "repo_path": "mozilla-central" 17:17:18 INFO - } 17:17:18 INFO - Finding installer, test and symbols from parent task. 17:17:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NQST8tgUT4Ces7KTlQQeAQ'}, attempt #1 17:17:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NQST8tgUT4Ces7KTlQQeAQ'}, attempt #1 17:17:19 INFO - Task dependencies: WYRzbGkxTNSckRZMCZ7L9Q 17:17:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig'}, attempt #1 17:17:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/test_packages.json 17:17:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/target.crashreporter-symbols.zip 17:17:19 INFO - Running post-action listener: _resource_record_post_action 17:17:19 INFO - [mozharness: 2017-05-12 00:17:19.680327Z] Finished read-buildbot-config step (success) 17:17:19 INFO - [mozharness: 2017-05-12 00:17:19.680435Z] Running download-and-extract step. 17:17:19 INFO - Running pre-action listener: _resource_record_pre_action 17:17:19 INFO - Running main action method: download_and_extract 17:17:19 INFO - mkdir: /builds/slave/test/build/tests 17:17: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')]} 17:17:19 INFO - trying https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:17:20 INFO - Downloaded 1325 bytes. 17:17:20 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:17:20 INFO - Using the following test package requirements: 17:17:20 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:17:20 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:20 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:17:20 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:17:20 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'jsshell-mac.zip'], 17:17:20 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:17:20 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:17:20 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:17:20 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:17:20 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:17:20 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:17:20 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:17:20 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:17:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:20 INFO - Fetch https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:22 INFO - Content-Length response header: 52709560 17:17:22 INFO - Bytes received: 52709560 17:17:38 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:17:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:38 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:38 INFO - Fetch https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:40 INFO - Content-Length response header: 52709560 17:17:40 INFO - Bytes received: 52709560 17:17:55 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:17:55 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:17:55 INFO - Fetch https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:17:56 INFO - Content-Length response header: 14409411 17:17:56 INFO - Bytes received: 14409411 17:17:57 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')]} 17:17:57 INFO - trying https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:57 INFO - Downloading https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:17:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:17:59 INFO - Downloaded 75541162 bytes. 17:17:59 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:59 INFO - mkdir: /builds/slave/test/properties 17:17:59 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:59 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:59 INFO - Contents: 17:17:59 INFO - build_url:https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:59 INFO - Running post-action listener: _resource_record_post_action 17:17:59 INFO - Running post-action listener: set_extra_try_arguments 17:17:59 INFO - [mozharness: 2017-05-12 00:17:59.374657Z] Finished download-and-extract step (success) 17:17:59 INFO - [mozharness: 2017-05-12 00:17:59.374802Z] Running populate-webroot step. 17:17:59 INFO - Running pre-action listener: _resource_record_pre_action 17:17:59 INFO - Running main action method: populate_webroot 17:17:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:59 INFO - '--tppagecycles', 17:17:59 INFO - '7'], 17:17:59 INFO - 'tests': ['tsvgm']}}, 17:17:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:17:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:59 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:59 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:59 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['damp', 'tps']}, 17:17:59 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'tests': ['damp', 'tps']}, 17:17:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['dromaeo_dom']}, 17:17:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:59 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:17:59 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:17:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['a11yr', 17:17:59 INFO - 'ts_paint', 17:17:59 INFO - 'tpaint', 17:17:59 INFO - 'sessionrestore', 17:17:59 INFO - 'sessionrestore_no_auto_restore', 17:17:59 INFO - 'tabpaint']}, 17:17:59 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:17:59 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:17:59 INFO - 'bloom_basic_ref']}, 17:17:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['tsvgx', 17:17:59 INFO - 'tsvgr_opacity', 17:17:59 INFO - 'tart', 17:17:59 INFO - 'tscrollx', 17:17:59 INFO - 'cart', 17:17:59 INFO - 'tsvg_static']}, 17:17:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:59 INFO - 'tsvgr_opacity', 17:17:59 INFO - 'tart', 17:17:59 INFO - 'tscrollx', 17:17:59 INFO - 'cart', 17:17:59 INFO - 'tsvg_static']}, 17:17:59 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'talos_options': ['--disable-e10s'], 17:17:59 INFO - 'tests': ['tp5o']}, 17:17:59 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:59 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'talos_options': ['--disable-e10s', 17:17:59 INFO - '--xperf_path', 17:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:59 INFO - 'tests': ['tp5n']}, 17:17:59 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:59 INFO - 'talos_options': ['--xperf_path', 17:17:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:59 INFO - 'tests': ['tp5n']}}, 17:17:59 INFO - 'talos.zip': {'path': '', 17:17:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:59 INFO - Running post-action listener: _resource_record_post_action 17:17:59 INFO - [mozharness: 2017-05-12 00:17:59.395921Z] Finished populate-webroot step (success) 17:17:59 INFO - [mozharness: 2017-05-12 00:17:59.396044Z] Running create-virtualenv step. 17:17:59 INFO - Running pre-action listener: _resource_record_pre_action 17:17:59 INFO - Running main action method: create_virtualenv 17:17:59 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:59 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 17:17:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:59 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:59 INFO - Using real prefix '/tools/python27' 17:17:59 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:18:00 INFO - Installing distribute.............................................................................................................................................................................................done. 17:18:03 INFO - Installing pip.................done. 17:18:03 INFO - Return code: 0 17:18:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:18:03 INFO - Reading from file tmpfile_stdout 17:18:03 INFO - Output received: 17:18:03 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:18:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:03 INFO - 'HOME': '/Users/cltbld', 17:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:03 INFO - 'LOGNAME': 'cltbld', 17:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:03 INFO - 'MOZ_NO_REMOTE': '1', 17:18:03 INFO - 'NO_EM_RESTART': '1', 17:18:03 INFO - 'PAGER': '/bin/cat', 17:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:03 INFO - 'PWD': '/builds/slave/test', 17:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:03 INFO - 'SHELL': '/bin/bash', 17:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:18:03 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:03 INFO - 'USER': 'cltbld', 17:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:03 INFO - 'XPC_FLAGS': '0x0', 17:18:03 INFO - 'XPC_SERVICE_NAME': '0', 17:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:03 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:18:03 INFO - Cleaning up... 17:18:03 INFO - Return code: 0 17:18:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:18:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:18:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:18:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:03 INFO - 'HOME': '/Users/cltbld', 17:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:03 INFO - 'LOGNAME': 'cltbld', 17:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:03 INFO - 'MOZ_NO_REMOTE': '1', 17:18:03 INFO - 'NO_EM_RESTART': '1', 17:18:03 INFO - 'PAGER': '/bin/cat', 17:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:03 INFO - 'PWD': '/builds/slave/test', 17:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:03 INFO - 'SHELL': '/bin/bash', 17:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:18:03 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:03 INFO - 'USER': 'cltbld', 17:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:03 INFO - 'XPC_FLAGS': '0x0', 17:18:03 INFO - 'XPC_SERVICE_NAME': '0', 17:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:18:03 INFO - Downloading/unpacking psutil>=3.1.1 17:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:18:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:18:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:20:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:36 INFO - Installing collected packages: psutil 17:20:36 INFO - Running setup.py install for psutil 17:20:36 INFO - building 'psutil._psutil_osx' extension 17:20:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:20:37 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 17:20:37 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 17:20:37 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 17:20:37 INFO - building 'psutil._psutil_posix' extension 17:20:37 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 17:20:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:20:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:20:37 INFO - ^ 17:20:37 INFO - 1 warning generated. 17:20:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:20:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:20:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:20:37 INFO - Successfully installed psutil 17:20:37 INFO - Cleaning up... 17:20:37 INFO - Return code: 0 17:20:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:20:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:20:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:20:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:20:38 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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:20:38 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 17:20:38 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 17:20:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:20:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:20:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:20:38 INFO - 'HOME': '/Users/cltbld', 17:20:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:20:38 INFO - 'LOGNAME': 'cltbld', 17:20:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:20:38 INFO - 'MOZ_NO_REMOTE': '1', 17:20:38 INFO - 'NO_EM_RESTART': '1', 17:20:38 INFO - 'PAGER': '/bin/cat', 17:20:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:20:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:20:38 INFO - 'PWD': '/builds/slave/test', 17:20:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:20:38 INFO - 'SHELL': '/bin/bash', 17:20:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:20:38 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:20:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:20:38 INFO - 'USER': 'cltbld', 17:20:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:20:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:20:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:20:38 INFO - 'XPC_FLAGS': '0x0', 17:20:38 INFO - 'XPC_SERVICE_NAME': '0', 17:20:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:20:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:20:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:20:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:20:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:20:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:10 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:23:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:23:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:23:10 INFO - Installing collected packages: mozsystemmonitor 17:23:10 INFO - Running setup.py install for mozsystemmonitor 17:23:11 INFO - Successfully installed mozsystemmonitor 17:23:11 INFO - Cleaning up... 17:23:11 INFO - Return code: 0 17:23:11 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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')]} 17:23:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:12 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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:23:12 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 17:23:12 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 17:23:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:23:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:12 INFO - 'HOME': '/Users/cltbld', 17:23:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:12 INFO - 'LOGNAME': 'cltbld', 17:23:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:12 INFO - 'MOZ_NO_REMOTE': '1', 17:23:12 INFO - 'NO_EM_RESTART': '1', 17:23:12 INFO - 'PAGER': '/bin/cat', 17:23:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:12 INFO - 'PWD': '/builds/slave/test', 17:23:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:12 INFO - 'SHELL': '/bin/bash', 17:23:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:23:12 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:23:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:12 INFO - 'USER': 'cltbld', 17:23:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:12 INFO - 'XPC_FLAGS': '0x0', 17:23:12 INFO - 'XPC_SERVICE_NAME': '0', 17:23:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:12 INFO - Downloading/unpacking jsonschema==2.5.1 17:23:12 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 17:23:12 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 17:23:12 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 17:23:12 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 17:23:12 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 17:23:12 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 17:25:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:45 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 17:25:45 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 17:25:45 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 17:25:45 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 17:25:45 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 17:25:45 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 17:28:16 INFO - Downloading functools32-3.2.3-2.tar.gz 17:28:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:28:16 INFO - warning: no files found matching '*.txt' 17:28:16 INFO - no previously-included directories found matching 'build' 17:28:16 INFO - no previously-included directories found matching 'dist' 17:28:16 INFO - no previously-included directories found matching '.git*' 17:28:16 INFO - Installing collected packages: jsonschema, functools32 17:28:16 INFO - Running setup.py install for functools32 17:28:16 INFO - warning: no files found matching '*.txt' 17:28:16 INFO - no previously-included directories found matching 'build' 17:28:16 INFO - no previously-included directories found matching 'dist' 17:28:16 INFO - no previously-included directories found matching '.git*' 17:28:16 INFO - Successfully installed jsonschema functools32 17:28:16 INFO - Cleaning up... 17:28:16 INFO - Return code: 0 17:28:16 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:28:16 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')]} 17:28:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28:16 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')]} 17:28:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:28: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 17:28: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 17:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:18 INFO - 'HOME': '/Users/cltbld', 17:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:18 INFO - 'LOGNAME': 'cltbld', 17:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:18 INFO - 'MOZ_NO_REMOTE': '1', 17:28:18 INFO - 'NO_EM_RESTART': '1', 17:28:18 INFO - 'PAGER': '/bin/cat', 17:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:18 INFO - 'PWD': '/builds/slave/test', 17:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:18 INFO - 'SHELL': '/bin/bash', 17:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:28:18 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:18 INFO - 'USER': 'cltbld', 17:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:18 INFO - 'XPC_FLAGS': '0x0', 17:28:18 INFO - 'XPC_SERVICE_NAME': '0', 17:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:18 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:28:18 INFO - Cleaning up... 17:28:18 INFO - Return code: 0 17:28:18 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:28: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')]} 17:28:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:28:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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')]} 17:28:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:28:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:28: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', '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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:28: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:28: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 blobuploader==1.2.4 17:28:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:28:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:28:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:28:18 INFO - 'HOME': '/Users/cltbld', 17:28:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:28:18 INFO - 'LOGNAME': 'cltbld', 17:28:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:28:18 INFO - 'MOZ_NO_REMOTE': '1', 17:28:18 INFO - 'NO_EM_RESTART': '1', 17:28:18 INFO - 'PAGER': '/bin/cat', 17:28:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:28:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:28:18 INFO - 'PWD': '/builds/slave/test', 17:28:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:28:18 INFO - 'SHELL': '/bin/bash', 17:28:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:28:18 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:28:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:28:18 INFO - 'USER': 'cltbld', 17:28:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:28:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:28:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:28:18 INFO - 'XPC_FLAGS': '0x0', 17:28:18 INFO - 'XPC_SERVICE_NAME': '0', 17:28:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:28:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:28:18 INFO - Downloading/unpacking blobuploader==1.2.4 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:28: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 17:30:50 INFO - Downloading blobuploader-1.2.4.tar.gz 17:30:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:30:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:30:50 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 17:30:50 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 17:30:50 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 17:30:50 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 17:30:50 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 17:30:50 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 17:33:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:33:21 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:33:21 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 17:35:52 INFO - Downloading docopt-0.6.1.tar.gz 17:35:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:35:52 INFO - Installing collected packages: blobuploader, requests, docopt 17:35:52 INFO - Running setup.py install for blobuploader 17:35:52 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:35:52 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:35:52 INFO - Running setup.py install for requests 17:35:52 INFO - Running setup.py install for docopt 17:35:53 INFO - Successfully installed blobuploader requests docopt 17:35:53 INFO - Cleaning up... 17:35:53 INFO - Return code: 0 17:35:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:53 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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:35:53 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 17:35:53 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 17:35:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:35:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:53 INFO - 'HOME': '/Users/cltbld', 17:35:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:53 INFO - 'LOGNAME': 'cltbld', 17:35:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:53 INFO - 'MOZ_NO_REMOTE': '1', 17:35:53 INFO - 'NO_EM_RESTART': '1', 17:35:53 INFO - 'PAGER': '/bin/cat', 17:35:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:53 INFO - 'PWD': '/builds/slave/test', 17:35:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:53 INFO - 'SHELL': '/bin/bash', 17:35:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:35:53 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:35:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:53 INFO - 'USER': 'cltbld', 17:35:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:53 INFO - 'XPC_FLAGS': '0x0', 17:35:53 INFO - 'XPC_SERVICE_NAME': '0', 17:35:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-F7Cysp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-rlbbd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-d1dPMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-qisrlT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-inPTyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-dhyS_k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-6GmHxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-UCU6ky-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:54 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-bxfzTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-tgerpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-FsOfhB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ITG6fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-sfJad3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-xU1fxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Vj1DF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-bcqGwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:35:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:35:55 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-zKB6e7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:35:55 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:35:55 INFO - Running setup.py install for manifestparser 17:35:55 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Running setup.py install for mozcrash 17:35:56 INFO - Running setup.py install for mozdebug 17:35:56 INFO - Running setup.py install for mozdevice 17:35:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Running setup.py install for mozfile 17:35:56 INFO - Running setup.py install for mozhttpd 17:35:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Running setup.py install for mozinfo 17:35:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Running setup.py install for mozInstall 17:35:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:35:56 INFO - Running setup.py install for mozleak 17:35:57 INFO - Running setup.py install for mozlog 17:35:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Running setup.py install for moznetwork 17:35:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Running setup.py install for mozprocess 17:35:57 INFO - Running setup.py install for mozprofile 17:35:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Running setup.py install for mozrunner 17:35:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:35:57 INFO - Running setup.py install for mozscreenshot 17:35:58 INFO - Running setup.py install for moztest 17:35:58 INFO - Running setup.py install for mozversion 17:35:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:35:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:35:58 INFO - Cleaning up... 17:35:58 INFO - Return code: 0 17:35:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:35: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')]} 17:35:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:35:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35: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')]} 17:35:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:35:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:35:58 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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:35:58 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 17:35:58 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 17:35:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:35:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:58 INFO - 'HOME': '/Users/cltbld', 17:35:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:58 INFO - 'LOGNAME': 'cltbld', 17:35:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:58 INFO - 'MOZ_NO_REMOTE': '1', 17:35:58 INFO - 'NO_EM_RESTART': '1', 17:35:58 INFO - 'PAGER': '/bin/cat', 17:35:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:58 INFO - 'PWD': '/builds/slave/test', 17:35:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:58 INFO - 'SHELL': '/bin/bash', 17:35:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:35:58 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:35:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:58 INFO - 'USER': 'cltbld', 17:35:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:58 INFO - 'XPC_FLAGS': '0x0', 17:35:58 INFO - 'XPC_SERVICE_NAME': '0', 17:35:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:35:58 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-sZmBhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:35:58 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)) 17:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:35:58 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-69ukKj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:35:58 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)) 17:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:35:58 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Up7BNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:35:58 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)) 17:35:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:35:58 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-DTSysz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:35:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-EYnjXe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-lx_JQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-HQlLKu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-rb5Vcn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-SSL5lZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Pj4lN8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-VSlLn2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:35:59 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-5iZZl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:35:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 17:35:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:35:59 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-Lsy7qG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:36:00 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)) 17:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:36:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-ZnZDkn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:36:00 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)) 17:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-OwDSRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:36:00 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)) 17:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:36:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-iJl9bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:36:00 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)) 17:36:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:36:00 INFO - Running setup.py (path:/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/pip-uQEskp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:36:00 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)) 17:36:00 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)) 17:36:00 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)) 17:36:00 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)) 17:36:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:36:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:36:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:36:00 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 17:36:00 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 17:36:00 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 17:36:00 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 17:36:00 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 17:36:00 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 17:38:33 INFO - Downloading blessings-1.6.tar.gz 17:38:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:38:33 INFO - Installing collected packages: blessings 17:38:33 INFO - Running setup.py install for blessings 17:38:33 INFO - Successfully installed blessings 17:38:33 INFO - Cleaning up... 17:38:33 INFO - Return code: 0 17:38:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:38:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:33 INFO - Reading from file tmpfile_stdout 17:38:33 INFO - Current package versions: 17:38:33 INFO - blessings == 1.6 17:38:33 INFO - blobuploader == 1.2.4 17:38:33 INFO - docopt == 0.6.1 17:38:33 INFO - functools32 == 3.2.3-2 17:38:33 INFO - jsonschema == 2.5.1 17:38:33 INFO - manifestparser == 1.1 17:38:33 INFO - mozInstall == 1.12 17:38:33 INFO - mozcrash == 1.0 17:38:33 INFO - mozdebug == 0.1 17:38:33 INFO - mozdevice == 0.50 17:38:33 INFO - mozfile == 1.2 17:38:33 INFO - mozhttpd == 0.7 17:38:33 INFO - mozinfo == 0.9 17:38:33 INFO - mozleak == 0.1 17:38:33 INFO - mozlog == 3.4 17:38:33 INFO - moznetwork == 0.27 17:38:33 INFO - mozprocess == 0.25 17:38:33 INFO - mozprofile == 0.28 17:38:33 INFO - mozrunner == 6.13 17:38:33 INFO - mozscreenshot == 0.1 17:38:33 INFO - mozsystemmonitor == 0.3 17:38:33 INFO - moztest == 0.8 17:38:33 INFO - mozversion == 1.4 17:38:33 INFO - psutil == 3.1.1 17:38:33 INFO - requests == 1.2.3 17:38:33 INFO - wsgiref == 0.1.2 17:38:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:38:33 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')]} 17:38:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38:33 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')]} 17:38:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:38: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 17:38: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 17:38:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:38:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:34 INFO - 'HOME': '/Users/cltbld', 17:38:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:34 INFO - 'LOGNAME': 'cltbld', 17:38:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:34 INFO - 'MOZ_NO_REMOTE': '1', 17:38:34 INFO - 'NO_EM_RESTART': '1', 17:38:34 INFO - 'PAGER': '/bin/cat', 17:38:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:34 INFO - 'PWD': '/builds/slave/test', 17:38:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:34 INFO - 'SHELL': '/bin/bash', 17:38:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:38:34 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:38:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:34 INFO - 'USER': 'cltbld', 17:38:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:34 INFO - 'XPC_FLAGS': '0x0', 17:38:34 INFO - 'XPC_SERVICE_NAME': '0', 17:38:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38: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)) 17:38:35 INFO - Cleaning up... 17:38:35 INFO - Return code: 0 17:38:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:38: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')]} 17:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:38:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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')]} 17:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:38:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:38: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 0x1099b3b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1099b6768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffaa0cab790>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, '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.yYCAA4h6B4/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.uALPk5eLJu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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 17:38: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 17:38: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 17:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:35 INFO - 'HOME': '/Users/cltbld', 17:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:35 INFO - 'LOGNAME': 'cltbld', 17:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:35 INFO - 'MOZ_NO_REMOTE': '1', 17:38:35 INFO - 'NO_EM_RESTART': '1', 17:38:35 INFO - 'PAGER': '/bin/cat', 17:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:35 INFO - 'PWD': '/builds/slave/test', 17:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:35 INFO - 'SHELL': '/bin/bash', 17:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:38:35 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:35 INFO - 'USER': 'cltbld', 17:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:35 INFO - 'XPC_FLAGS': '0x0', 17:38:35 INFO - 'XPC_SERVICE_NAME': '0', 17:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:38:35 INFO - Cleaning up... 17:38:35 INFO - Return code: 0 17:38:35 INFO - Running post-action listener: _resource_record_post_action 17:38:35 INFO - Running post-action listener: _start_resource_monitoring 17:38:35 INFO - Starting resource monitoring. 17:38:35 INFO - [mozharness: 2017-05-12 00:38:35.502893Z] Finished create-virtualenv step (success) 17:38:35 INFO - [mozharness: 2017-05-12 00:38:35.503865Z] Running install step. 17:38:35 INFO - Running pre-action listener: _resource_record_pre_action 17:38:35 INFO - Running main action method: install 17:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:38:35 INFO - Reading from file tmpfile_stdout 17:38:35 INFO - Detecting whether we're running mozinstall >=1.0... 17:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:38:35 INFO - Reading from file tmpfile_stdout 17:38:35 INFO - Output received: 17:38:35 INFO - Usage: mozinstall [options] installer 17:38:35 INFO - Options: 17:38:35 INFO - -h, --help show this help message and exit 17:38:35 INFO - -d DEST, --destination=DEST 17:38:35 INFO - Directory to install application into. [default: 17:38:35 INFO - "/builds/slave/test"] 17:38:35 INFO - --app=APP Application being installed. [default: firefox] 17:38:35 INFO - mkdir: /builds/slave/test/build/application 17:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:38:56 INFO - Reading from file tmpfile_stdout 17:38:56 INFO - Output received: 17:38:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:38:56 INFO - Running post-action listener: _resource_record_post_action 17:38:56 INFO - [mozharness: 2017-05-12 00:38:56.017458Z] Finished install step (success) 17:38:56 INFO - [mozharness: 2017-05-12 00:38:56.017588Z] Running run-tests step. 17:38:56 INFO - Running pre-action listener: _resource_record_pre_action 17:38:56 INFO - Running main action method: run_tests 17:38:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:56 INFO - 2017-05-11 17:38:56.031 screenresolution[1630:8467] starting screenresolution argv=screenresolution get 17:38:56 INFO - 2017-05-11 17:38:56.034 screenresolution[1630:8467] Display 0: 1600x1200x32@60 17:38:56 INFO - 2017-05-11 17:38:56.040 screenresolution[1631:8469] starting screenresolution argv=screenresolution list 17:38:56 INFO - Available Modes on Display 0 17:38:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:56 INFO - Intel Iris: 17:38:56 INFO - Chipset Model: Intel Iris 17:38:56 INFO - Type: GPU 17:38:56 INFO - Bus: Built-In 17:38:56 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:56 INFO - Vendor: Intel (0x8086) 17:38:56 INFO - Device ID: 0x0a2e 17:38:56 INFO - Revision ID: 0x0009 17:38:56 INFO - Displays: 17:38:56 INFO - Display: 17:38:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:56 INFO - Display Serial Number: 1600x1200 60 17:38:56 INFO - Main Display: Yes 17:38:56 INFO - Mirror: Off 17:38:56 INFO - Online: Yes 17:38:56 INFO - Rotation: Supported 17:38:56 INFO - Return code: 0 17:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:38:56 INFO - Python 2.7.3 17:38:56 INFO - Return code: 0 17:38:56 INFO - grabbing minidump binary from tooltool 17:38:56 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')]} 17:38:56 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 0x1096c4d30>, '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 0x109677db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1096b8be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:38:56 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 17:38:56 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 17:38:56 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 17:38:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:38:56 INFO - Return code: 0 17:38:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:38:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:38:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:38:56 INFO - ENV: RUST_BACKTRACE is now 1 17:38:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:38:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 17:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0052 --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 17:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yYCAA4h6B4/Render', 17:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:38:56 INFO - 'HOME': '/Users/cltbld', 17:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:38:56 INFO - 'LOGNAME': 'cltbld', 17:38:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:38:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:38:56 INFO - 'MOZ_NO_REMOTE': '1', 17:38:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:38:56 INFO - 'NO_EM_RESTART': '1', 17:38:56 INFO - 'PAGER': '/bin/cat', 17:38:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:38:56 INFO - 'PWD': '/builds/slave/test', 17:38:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:38:56 INFO - 'RUST_BACKTRACE': '1', 17:38:56 INFO - 'SHELL': '/bin/bash', 17:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.uALPk5eLJu/Listeners', 17:38:56 INFO - 'TMPDIR': '/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/', 17:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:38:56 INFO - 'USER': 'cltbld', 17:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:38:56 INFO - 'XPC_FLAGS': '0x0', 17:38:56 INFO - 'XPC_SERVICE_NAME': '0', 17:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:38:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0052', '--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 17:38:56 INFO - mozversion application_buildid: 20170511160457 17:38:56 INFO - mozversion application_changeset: 7e0e20683d5ab7ac8238251fa411ef3027daa22e 17:38:56 INFO - mozversion application_display_name: Nightly 17:38:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:38:56 INFO - mozversion application_name: Firefox 17:38:56 INFO - mozversion application_remotingname: firefox 17:38:56 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:38:56 INFO - mozversion application_vendor: Mozilla 17:38:56 INFO - mozversion application_version: 55.0a1 17:38:56 INFO - mozversion platform_buildid: 20170511160457 17:38:56 INFO - mozversion platform_changeset: 7e0e20683d5ab7ac8238251fa411ef3027daa22e 17:38:56 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:38:56 INFO - mozversion platform_version: 55.0a1 17:38:56 INFO - using testdate: 1494549536 17:38:56 INFO - actual date: 1494549536 17:38:56 INFO - starting webserver on 'localhost:49248' 17:38:56 INFO - SUITE-START | Running 2 tests 17:38:56 INFO - TEST-START | dromaeo_css 17:38:56 INFO - operating with platform_type : mac_ 17:38:56 INFO - Initialising browser for dromaeo_css test... 17:38:56 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmp7Wilog/profile http://localhost:49248/getInfo.html) 17:38:59 INFO - PID 1641 | 2017-05-11 17:38:59.300 plugin-container[1644:8661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 17:38:59 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:38:59 INFO - PID 1641 | 2017-05-11 17:38:59.303 plugin-container[1644:8661] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1f, name = 'com.apple.CFPasteboardClient' 17:38:59 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:38:59 INFO - PID 1641 | 2017-05-11 17:38:59.303 plugin-container[1644:8661] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:38:59 INFO - PID 1641 | __metrics Screen width/height:1600/1200 17:38:59 INFO - PID 1641 | colorDepth:24 17:38:59 INFO - PID 1641 | Browser inner width/height: 1024/691 17:38:59 INFO - PID 1641 | __metrics 17:39:00 INFO - TEST-INFO | 1641: exit 0 17:39:00 INFO - Browser initialized. 17:39:00 INFO - Running cycle 1/1 for dromaeo_css test... 17:39:00 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmp7Wilog/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:39:02 INFO - PID 1646 | 2017-05-11 17:39:02.909 plugin-container[1647:8816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954b, name = 'com.apple.tsm.portname' 17:39:02 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:02 INFO - PID 1646 | 2017-05-11 17:39:02.911 plugin-container[1647:8816] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4317, name = 'com.apple.CFPasteboardClient' 17:39:02 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:02 INFO - PID 1646 | 2017-05-11 17:39:02.911 plugin-container[1647:8816] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:03 INFO - PID 1646 | 2017-05-11 17:39:03.237 plugin-container[1648:8858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3f, name = 'com.apple.tsm.portname' 17:39:03 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:03 INFO - PID 1646 | 2017-05-11 17:39:03.238 plugin-container[1648:8858] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 17:39:03 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:39:03 INFO - PID 1646 | 2017-05-11 17:39:03.238 plugin-container[1648:8858] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:39:12 INFO - PID 1646 | RSS: Main: 228884480 17:39:12 INFO - PID 1646 | 17:42:16 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 17:42:18 INFO - PID 1646 | RSS: Main: 250507264 17:42:18 INFO - PID 1646 | 17:45:20 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 17:45:20 INFO - PID 1646 | RSS: Main: 256413696 17:45:20 INFO - PID 1646 | 17:48:22 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 17:48:22 INFO - PID 1646 | RSS: Main: 266190848 17:48:22 INFO - PID 1646 | 17:51:24 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 17:51:25 INFO - PID 1646 | RSS: Main: 259637248 17:51:25 INFO - PID 1646 | 17:54:28 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 17:54:28 INFO - PID 1646 | RSS: Main: 263569408 17:54:28 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 17:57:30 INFO - PID 1646 | RSS: Main: 262545408 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | __start_tp_report 17:57:30 INFO - PID 1646 | _x_x_mozilla_page_load 17:57:30 INFO - PID 1646 | _x_x_mozilla_page_load_details 17:57:30 INFO - PID 1646 | |i|pagename|runs| 17:57:30 INFO - PID 1646 | |0;dojo.html;3022.788404811663;3635.4365073413614;3692.8153592320377;3695.8891233263003;3705.7035437165027;6134.846628834271;6256.405641464059;6264.5928014679075;6328.335524769896;6361.459275961549;6210.4100110489435;6221.720022598983;6255.968720156404;6283.6543990080645;6350.523710721703;5487.6158668893195;5487.725613719318;5495.120780675093;5512.366077901032;5547.140193270049;4942.579880710126;5487.478689524509;5606;5648.293963254593;5672.17753425753;4336.1978034063795;5524.309461317335;5558.138488534284;5566.359868615121;5594.9720251398685;5474.753636086386;5476.096444086721;5524.558035357162;5586.441355864422;5589.413111623262;4893.290472881412;4975.7760900759195;5484.972575137139;5519.254900588409;5573.219749235111;6088.208532890741;6302.9369706302805;6327.14583531222;6346.174997250374;6354.301026887085;6498.772542961021;6607.636579988099;6776.119104516381;6781.694823732944;6792.93207067928;5761.654300741927;6849.349311815369;6873.037774711544;6883.586984780929;6890.2420733719255;6281.528885333618;6420.165378500765;6433;6440.8389790255615;6473.2232132144445;6758.695858686371;7051.29487051301;7056.223815380304;7071.540349877241;7085.50401471892;6535.477161827042;6590.077389165521;6649.235337936103;6709;6714.160729908762;6880.277570855088;6895.172579290517;6900.930990690029;6906.965465172642;6912.55068420551;6503.9349606504275;6711.865083723613;6729.091572637729;6794.320567943166;6800.523963322619;6610.471162307004;6714.462842972551;6784.864302713918;6808.455323574102;6844.28135045823;5450.781968721207;5457.727113644302;5459.044667183227;5483.561315094783;5489.917651235235;4867.612730371827;4875;4882.5361590649545;4902.3872015998;4933.383327084114;6166.044263139221;6376.043593461018;6382.425581697669;6430;6448.226212854487;6033.276006879202;6675;6853.17761868579;6891.552049116792;6905.9309406906295;6447.580907241015;6463.15978922737;6475.417212450902;6478.1578394808375;6482.35176482348;4895.559399654048;5062.797488100508;6019.879602408015;6045.939540604626;6139.2325959255095;5226.738663066907;7001.36987671112;7056.576605403692;7108;7117.359437650636;6607;6896.241413444617;6918.342757438036;6919.342662447059;6926.896096558657;6859.07402500676;6963.6866341013765;6968.268331825288;6982.197047339621;7028.121484814398;6737.157855268091;6780.220274668279;6794.014867844036;6802.761903333359;6814.114165158498;6331.303556608861;6642.6014439133805;6700.5309628327495;6937.132858392701;6940.444764418935;6574.967125164344;6629.602223866584;6644.800655980328;6665.833354166185;6676.365745254193;6653.833654158685;6680.964450510179;6685.832854178684;6693.665316734046;6698.799036028927;5564.1931919870585;5983.37174596661;6522.086907833002;6548.214214294315;6589.571677840925;6432.09950606906;6562.901556476752;6605.53761236728;6644.269130395749;6678.165229346332;6462.483001359974;6496.935030649633;6508.576942498722;6514.446272066927;6519.445847102859 17:57:30 INFO - PID 1646 | |1;ext.html;813.5891374855698;842.5829214538804;1105.2870898270617;1145.0667705819762;1152.4583445780481;35257.82371088144;36277.45583816238;36321.81839090801;36337.81831090844;36344.273114537726;37994.24011519768;38313.80843095788;38322.8083859581;38383.23233535328;38420.42369364462;5894.056950887859;5989.101634754778;8238.835223295546;8393.664253429855;8422.957885210566;8129.634166462494;8267.710630127916;8361.16388361165;8361.289290410292;8400.285975692073;8305.626246818907;8435.873461898076;8442.62008209632;8461.196186362286;8472.78818029551;7223.711051557932;8190.2628763410985;8370.916290837074;8442.409031367837;8461.703840365619;6965.582065076061;8231.629576669064;8283.337333013344;8356.582170891492;8357.540335281557;36486.817565912;36498;36667;36736.448953265724;36780.26439471196;3679.300932822755;3696.1129328960856;3740.121071548184;3741.850325986957;3744.5693745219105;23791.524169516684;29620.70379296201;29821.552676709867;29873;30105.397892042256;3126.9530957035663;3164.493681011027;3179.2369831240567;3196.6323872754697;3208.181913612014;283010;285066;285422;285489;285556.9999999979;38612.227755444736;39168;46552.30171547429;47794;48122.75938620354;41416.79291603523;49162.016759664606;49363.25955110676;49506;49508;13544.390502427414;13560.728785424237;13815.654608634864;13842.100263482842;13894.23581703016;9081.137291957253;11218.831717524243;11225.943870280596;11253.493592788343;11293.661190164308;9729.270304727173;12217.266963982189;12220.51117955272;12223.93888030554;12314.630561083182;5327.547158491494;5335.493128152819;5344.545713614386;5347.224652425377;5348.438413966555;1014.8224060789332;1031.6234574380296;1043.7808060307268;1084.5553323137633;1088.2273585754203;22413.66379504276;23235;24576.754232457803;24580;24966.0013599454;1113.9554417823358;1127.22784892349;1166.6091859227083;1167.7139100920158;1182.3378907811652;12369.876301236873;12511;12514.37313149303;12516.561920332744;12533.43599538023;296700;297023;297042;297471;299020;50812.49187508151;51131.744341278056;51206.71983200375;51491.48508514942;51523.48476515262;1532.685799411118;1547.4197176058979;1579.5577238373273;1581.7627355896477;1585.9127747973973;18152;18540.629187416445;23169;24157;24337.39156521101;37413;37739.62260377361;37766;37802.810985944896;37816.24367512689;231043;232078;232328;232369;233304;32846.507302390004;33090.33819323648;33845.661543385235;34344.65655343414;34561.13597160091;41662.166756665305;44711.329330060726;44766.3285050731;44851.551484486045;45036.77481612702;29393.412131757672;29948;30656.540151898185;31074.84462577673;31435.371292574477;24646.876765616056;30686.539701904934;30970.690293097683;31037.224069398446;31572.526412103376;36547.45178822373;36873.63126368702;37092;37112.25775484421;37113.62886371102;23793.524129516965;25448.363790905376;29874.85062574673;31499.685003149676;31591.89428369996;21710.89144554267;21788.128474860827;22042.66935995929;22983.88508057449;24348 17:57:30 INFO - PID 1646 | |2;jquery.html;371.7026378896883;383.60297092509256;405.3433437830715;431.5899895099656;455.1125305653974;20578.662386944838;20976.05607747651;21578.676319855174;21687.891560542175;21850.016749246282;21819.345419637437;21916.561668766615;22602.54794904105;22628.43428914278;22756.658650120262;5879.67661778602;6181.845453863644;6185.56701030928;6211.844703882416;6212.906806397885;5119.539241468267;6023.488003519684;6080.2399700037495;6127;6194.721237544299;6164.4143806338325;6172.506199504051;6177.66022868742;6184.5980011299125;6186.659733714644;6001.579889407743;6105.847353816146;6179.443850053472;6182;6186.84532886677;5296.205569164617;5454.672719636835;5466.1527463243265;5484.149956756709;5485.780568777244;23901;25266;26894.655267236754;27525.036623718075;27864.721352786415;1927.9903600482048;2188.070070220166;2374.3945293950105;2400.627902675088;2410.8071354291615;18916.148773305234;19014.334498292494;19097.61804763897;19493.902530487398;19565.1195696194;2073.2432662078386;2308.0767692922964;2347.272345572861;2406.867622280774;2418.6734790803193;133394;153079.703804443;156591.43408565884;158468.2076589621;164628;17732.60609817684;25012.24963251069;25072;25133.87433062823;25246.621300680505;41539.58460415417;41877.371839422434;41935;41978.37032444516;42186.57813421888;10980.835287470694;11159;11242.7189320266;11371.829422558669;11398.715032124097;8882.378233523592;8908;8909.331800115016;9073.183413492818;9075.273978081737;8965.282777377794;10680.465976701134;11615.76768464626;11669.824952625717;11699.824502632468;3910.921781564353;4360.018995726;4379.890502737457;4553.157665831832;4553.5218802025975;968.5205823117602;974.7027156717269;976.72651657536;1003.7741508160751;1019.7654539455966;20594.897025514776;21011.264605738725;21322.596437242035;21515.569688606454;21575;987.481572174614;998.9910190707437;1009.7828966772179;1042.4006196437078;1043.639944219243;10568.207384446043;10876.456177191109;10885.891141088647;10911.454427278604;10914.23600347968;498993;505689.47155264724;512171;512983;515099;126911.3654431722;127380;129981;131996;135091;1227.2636418149038;1253.7304479536942;1268.479923231489;1272.7390884868764;1285.0490636928605;21236.893815531443;22232.333030009126;22387;22396.664050039588;22504;51790;54765;55704.72147639236;56180;57028.859422810485;71886;72704;73033;73114;74091.51816963522;8861.379703420957;8948.194662480408;9092.408993415407;9103.863442048241;9259.074092590687;12022.879771202175;13955.790663140264;14181.929090354482;14282.07166534172;14662.193579352812;11749.353785541878;11952.34262115592;12087.214331068453;12195.573154939535;12290.754184916073;11308.43457827089;11476;11478.426078696199;11521.596744113916;11687.590934317259;12139.150259481752;12248.632541023784;12348.691282717644;12394.690132746753;12555.748885022065;8619.138086191331;8657.740267791976;8953.507557084162;9002.119321816956;9091.909080909107;11298.94350528275;11906;12131.45408456604;12239;12261.69345766363 17:57:30 INFO - PID 1646 | |3;mootools.html;1271.237257645413;1420.5738278516446;1431.7494438473263;1478.7042591481706;1484.962875928102;11317.886821131786;11411.315321080741;11778.587749428772;11790.056795456365;12145.696357591065;9448.858267126;11349.319040857554;11380.146489013316;11512.309261444298;11556.653300401002;2045.5806559655273;2547.8853451594723;2550.0947163756878;2561.9103331383403;2567.5763499022637;2464.3962229253857;2473.3816545863533;2480.5659009673327;2503.4617557225192;2508.8745562721883;2524.0913271222344;2543.605741110131;2550.1584477122597;2554.2848002559313;2568.2295311406597;2469.5678256305164;2490.7011158660985;2520.1683444463283;2522.545941730488;2523.7223905370392;2403.579373609629;2491.950160996788;2501.949961000788;2506.987465062663;2513.2711513661015;11816.527338906433;11919.344436056013;11925.821112683318;11950.282983021047;11951.641450756491;6591.242007169141;6790.083338749255;6850;6860.588364698134;6861.1080559526945;4017.13631569214;4349.934750978738;4387.670924680661;4389.978050109761;4416.3375493675885;1107.9197782162407;1209.76409600128;1238.325112813519;1263.3935710858839;1284.5183056353867;16288.674226515523;16408.589785255346;16528.09095499747;16530.256138473796;16836.83163168377;4779.713217206979;5514.6139770216505;5630.577706672016;5733.283339582552;5777.768889244468;11755.471003804849;11833.644990650295;11907.166498345032;11916.94041529804;11937;6616.636085015371;6641.767538136142;6644.568103073381;6646.966765166143;6652.301508341651;5907.113932910098;6103.35914728956;6149.41580549847;6149.600275982047;6221.222347206599;4258.425112609758;4423.734575925455;4426.75652839097;4478.216312145361;4485.708428952173;1288.671388795851;1311.9409626566828;1312.4618906248393;1324.5958585168082;1324.7218084202425;788.5308241596241;796.1719811396199;804.7062822070019;814.771863878115;819.5943008210974;6153.84615384619;6711.496637752188;6874.450043996469;6917.169939607179;6917.550359226634;570.9143628455682;598.9490893274034;600.2197143713155;617.0528239152968;618.0759764152531;3915.530136383652;4112.568180341081;4184.853530126431;4191.308434108338;4290.935635965463;13937.372818223204;15057.397704091714;15302.770458443132;15324.157171355682;15334.156621385708;12944.805827912589;15201.771973420407;15203.391864325304;15208;15223.847761522244;6290.4653104485715;8330.958345208357;8761.956190219136;8822.558872056372;8836.072212417603;6171.259448866164;6867.690953906987;7001.844695625368;7027.191872934678;7111.146662400545;13101.868981310065;13666;13672.65818354549;13821.930890345484;13916.095453795471;13806.65483362924;13995.510157144858;14064.789028164381;14117.505887294306;14246.145231286157;9341.813163736551;9417.246620270498;9515;9520.047995200424;9577.952110239405;10685.893141068489;10703.785924281627;10790.622328218475;10811.972862578043;10813.189010824377;9218.677346292847;9289.117533834275;9292.767680808034;9296.116868897443;9391.765205869908;8891.777705557413;8973.955130224307;9007.819843603222;9053.818923621622;9220.400673956172;6942.479314051568;8134.918650813416;8299.792505187419;8308.169183081643;8325.750227493185;5938.881222375442;5939.940600593939;6023.759049637965;6104.725287362168;6170.907436388368;6031.788887388921;6106.267247930276;6130.356312587114;6244.594101383395;6339.397757213057 17:57:30 INFO - PID 1646 | |4;prototype.html;407.19782029402074;408.77828999388333;412.3340804600571;413.5616246778417;421.34480882228144;5724.7137643117885;6196.273639598573;6291.622502649844;6416.197975253088;6456.515761317903;5317.9468205317935;5522.723863806814;5738.856528586777;6189.566730328879;6290.905636415458;4412.3160910059;4556.430446194226;4559.74921379324;4575.862724118282;4582.9312560311455;4543.568361005699;4564.402459139308;4568.885777855564;4595.241785105453;4606.907861842761;3707.94438083429;4505.752183629916;4506.526814684444;4517.774111294439;4519.864404067883;3708.2583483303447;3832;3935.2719746846637;4387.815289871717;4424.1288872236955;3455.2571197192724;3587.6950459211;3734.029152420363;3838.309104361214;3866.506103563186;5292.030976767424;5903.911441328383;6130.386961303879;6148.600340977822;6151.354107818659;4245.660347172219;5373.435789242151;5380.408155102936;5410.296661434028;5415.431379705153;5847.970760146172;5879.500242479395;5911.517162791638;6192.28788689302;6645.734170633169;3549.0240183949363;4108.198901214264;4375.584319489643;4383.868483945486;4386.5962891874615;6136.539759518009;6813.557118787313;7062.223155452896;7096.183938846995;7150.640288704715;4466.106778644219;4485.327200919888;4485.573870482299;4502.819887204475;4507.5943165115295;3873.9418908716393;4531.342955271467;4585.243434833215;4598.954010459853;4609.377734005866;4220.725652832556;4854.466008739036;5214.270002199695;5336.706481143497;5374.892502149935;5540.473655002768;6674.299198584079;6680.665966701646;6700.564463309869;6708.209861428278;1420.3750349846034;1527.5264667952747;1538.6307286251442;1542.7145977994107;1550.8991915525455;886.3308202307346;932.3100905330142;951.148721893915;1002.871769259583;1002.9297949143636;5143.305653736697;6390.114916099762;6407.583507072025;6449.774257900951;6482.337479324166;6373.968130159319;6964.79105626832;6997.615131167733;7015.964920175366;7042.59148170368;5123.8719032023755;5631.71841407928;6356.4279214870885;6368.203974503187;6458.644774537352;3180.6800177926366;3191.5850939377733;3616.173832214715;3632.418812989909;3634.727395445352;6653.401193892573;6830.180378354629;6919.342662447059;7221.783346499614;7252.38354739853;5726.226959360433;5776.393478684761;7072.398846098139;7089.873038088553;7126.073610430611;6081.604695694766;6817;6825.726970921207;6955.582665040114;7256.843273176185;5886.499647530008;6017.939820601738;6828.7268509259075;6876.553024053421;7004.894926576004;5197.948020519746;6620.966895165493;6778.525503214925;6802.727890884507;6874.140732408449;5998.520118390431;6631;6954.721811127701;7218.726101278081;7248.891266630899;3736.645018723217;3764.924701506009;4481.260592002283;4488.192125417456;4495.707778994355;6112.938870611237;6388.520860935541;6389.562348471557;6541.8691626168165;6955.200151982587;5955.880882382415;6461.57999730016;6638.145597393167;6660.400563949268;6735.393814556714;6289.289319301634;6296.653684047421;6707.767941006023;6716.865662686817;6719.294474080151;6320.62076275425;6589.505787066085;6625.4699624029;6633;6706.262311145672;5220.451852555427;6731.865362692816;6739.59562426256;6756.256811750605;6786.898196526958;6203.077046115719;6321.4196450887275;6579.901301480386;6600.669966501751;6602.60384376939 17:57:30 INFO - PID 1646 | |5;yui.html;740.8370158565116;831.0692024932077;832.6045128563933;864.0927026622047;865.0786911938785;16500.587485312873;17016.659666806656;17028.914855425737;18610.34863779768;19165.61668766624;17767.91116044421;18144.909275453603;18234.805994621245;18378.540536486595;18636.161372738225;980.176651612647;1002.4035698759227;1004.4626125023115;1010.9393436393804;1014.1785154025263;1014.543455445049;1022.4121130350042;1025.021104844875;1025.7179275699168;1032.3599368391608;1053.183782568508;1080.8811030786608;1084.9945750271238;1085.5223701571322;1086.413336798128;1070.427321383064;1077.956881724734;1080.0333701337338;1080.9243352965295;1081.9134469242442;764.8126209078756;767.547147183159;772.0118248641747;781.4022272961189;781.9022622172229;16634.33462661492;16859.494215173563;16933.238004289713;17093.487195384034;17098;17084.31662733489;17538;17543.649127017517;17550.561235969075;17605.823941760675;13502.324883755773;13822.861771382259;14058.367373468218;14076.366563504667;14087.36606852694;3445.3109378124477;3486.8430920608635;3513.2446523997464;3515.6484351564895;3519.1202199450136;88868.11131888663;131222.3438882809;132357;132396.33801831026;135071;13627.591172264847;13726.176429414267;13876;13956.023078384618;13992.230427326595;18544.536386590175;25242.74757252404;26185.083522076635;26460.8676956614;26733.465330693278;6587.473002159816;7681.270279323456;7723.53658780475;7853.607319633996;7859.449838511249;3186.7609929254936;3849.653531182207;3914.197589494147;3914.706397020235;3921.3333733265413;3996.220736956324;4219.346001369792;4338.436003319548;4414.690971632019;4415.2935530315;898.9280857531381;924.8289066522715;946.4699768129732;948.1135138645251;950.5959967013991;596.5257620191958;634.4828964394004;649.6751624187906;649.9870002599921;668.6924014953177;11463.140264480031;12023.579174728842;12080.275183489019;12186.81719774204;12270.631881043582;621.8849512840139;651.2835880531472;663.7809522857445;672.6838385958591;686.914674813793;5378.650387724785;5480.616356854982;5545.223668686387;5603;5612.775488980476;85344.14655853486;108201.91798082076;110210.44894775475;110656.4467177659;113423;87373.56313218393;90322.54838725895;90346.64480032805;90814.54592726994;92903;58134.674613015006;71064.64467677732;71519;76733.61633191798;87100.56449717711;11807.173497855068;12819.551315703906;12921.806172907225;12935.935320323339;12988.415521301375;38731.80634096811;39257.803710981265;40996.79501602473;41009.97475063028;41568;69982.65008674924;70782.6460867713;71398.64300678464;71531;74686.62656686682;16474.258658360162;16486.34054637801;17271.91364043222;17289.394871179953;17316.220770065127;18194.72707909409;18509.35217267389;18730.531736706693;18817.247310107443;19011;16672.749908751135;16994.320227191267;17289.48131556055;17411.47765567035;17534.298628055247;16663.166841658112;17251;17457;17596.73604895953;17644;16522.58693532671;16554.337826486804;17118.828811711723;17424.303027878745;17489.912550437675;10271.691849244236;11654.125940554322;11933.045356371296;11991.100667450151;12071.155019148575;12750.171238869443;13194.208347499181;13623.38694758758;13900.652483687989;13958.651033724238 17:57:30 INFO - PID 1646 | __end_tp_report 17:57:30 INFO - PID 1646 | __start_cc_report 17:57:30 INFO - PID 1646 | _x_x_mozilla_cycle_collect,419 17:57:30 INFO - PID 1646 | __end_cc_report 17:57:30 INFO - PID 1646 | __startTimestamp1494550650882__endTimestamp 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | ------- Summary: start ------- 17:57:30 INFO - PID 1646 | Number of tests: 6 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6223.98 Median:6482.35 stddev:777.53 (12.0%) stddev-sans-first:738.25 17:57:30 INFO - PID 1646 | Values: 3022.8 3635.4 3692.8 3695.9 3705.7 6134.8 6256.4 6264.6 6328.3 6361.5 6210.4 6221.7 6256.0 6283.7 6350.5 5487.6 5487.7 5495.1 5512.4 5547.1 4942.6 5487.5 5606.0 5648.3 5672.2 4336.2 5524.3 5558.1 5566.4 5595.0 5474.8 5476.1 5524.6 5586.4 5589.4 4893.3 4975.8 5485.0 5519.3 5573.2 6088.2 6302.9 6327.1 6346.2 6354.3 6498.8 6607.6 6776.1 6781.7 6792.9 5761.7 6849.3 6873.0 6883.6 6890.2 6281.5 6420.2 6433.0 6440.8 6473.2 6758.7 7051.3 7056.2 7071.5 7085.5 6535.5 6590.1 6649.2 6709.0 6714.2 6880.3 6895.2 6900.9 6907.0 6912.6 6503.9 6711.9 6729.1 6794.3 6800.5 6610.5 6714.5 6784.9 6808.5 6844.3 5450.8 5457.7 5459.0 5483.6 5489.9 4867.6 4875.0 4882.5 4902.4 4933.4 6166.0 6376.0 6382.4 6430.0 6448.2 6033.3 6675.0 6853.2 6891.6 6905.9 6447.6 6463.2 6475.4 6478.2 6482.4 4895.6 5062.8 6019.9 6045.9 6139.2 5226.7 7001.4 7056.6 7108.0 7117.4 6607.0 6896.2 6918.3 6919.3 6926.9 6859.1 6963.7 6968.3 6982.2 7028.1 6737.2 6780.2 6794.0 6802.8 6814.1 6331.3 6642.6 6700.5 6937.1 6940.4 6575.0 6629.6 6644.8 6665.8 6676.4 6653.8 6681.0 6685.8 6693.7 6698.8 5564.2 5983.4 6522.1 6548.2 6589.6 6432.1 6562.9 6605.5 6644.3 6678.2 6462.5 6496.9 6508.6 6514.4 6519.4 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:42026.12 Median:18027.34 stddev:71474.49 (396.5%) stddev-sans-first:71608.01 17:57:30 INFO - PID 1646 | Values: 813.6 842.6 1105.3 1145.1 1152.5 35257.8 36277.5 36321.8 36337.8 36344.3 37994.2 38313.8 38322.8 38383.2 38420.4 5894.1 5989.1 8238.8 8393.7 8423.0 8129.6 8267.7 8361.2 8361.3 8400.3 8305.6 8435.9 8442.6 8461.2 8472.8 7223.7 8190.3 8370.9 8442.4 8461.7 6965.6 8231.6 8283.3 8356.6 8357.5 36486.8 36498.0 36667.0 36736.4 36780.3 3679.3 3696.1 3740.1 3741.9 3744.6 23791.5 29620.7 29821.6 29873.0 30105.4 3127.0 3164.5 3179.2 3196.6 3208.2 283010.0 285066.0 285422.0 285489.0 285557.0 38612.2 39168.0 46552.3 47794.0 48122.8 41416.8 49162.0 49363.3 49506.0 49508.0 13544.4 13560.7 13815.7 13842.1 13894.2 9081.1 11218.8 11225.9 11253.5 11293.7 9729.3 12217.3 12220.5 12223.9 12314.6 5327.5 5335.5 5344.5 5347.2 5348.4 1014.8 1031.6 1043.8 1084.6 1088.2 22413.7 23235.0 24576.8 24580.0 24966.0 1114.0 1127.2 1166.6 1167.7 1182.3 12369.9 12511.0 12514.4 12516.6 12533.4 296700.0 297023.0 297042.0 297471.0 299020.0 50812.5 51131.7 51206.7 51491.5 51523.5 1532.7 1547.4 1579.6 1581.8 1585.9 18152.0 18540.6 23169.0 24157.0 24337.4 37413.0 37739.6 37766.0 37802.8 37816.2 231043.0 232078.0 232328.0 232369.0 233304.0 32846.5 33090.3 33845.7 34344.7 34561.1 41662.2 44711.3 44766.3 44851.6 45036.8 29393.4 29948.0 30656.5 31074.8 31435.4 24646.9 30686.5 30970.7 31037.2 31572.5 36547.5 36873.6 37092.0 37112.3 37113.6 23793.5 25448.4 29874.9 31499.7 31591.9 21710.9 21788.1 22042.7 22983.9 24348.0 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:35627.06 Median:13137.70 stddev:86716.48 (660.1%) stddev-sans-first:86919.34 17:57:30 INFO - PID 1646 | Values: 371.7 383.6 405.3 431.6 455.1 20578.7 20976.1 21578.7 21687.9 21850.0 21819.3 21916.6 22602.5 22628.4 22756.7 5879.7 6181.8 6185.6 6211.8 6212.9 5119.5 6023.5 6080.2 6127.0 6194.7 6164.4 6172.5 6177.7 6184.6 6186.7 6001.6 6105.8 6179.4 6182.0 6186.8 5296.2 5454.7 5466.2 5484.1 5485.8 23901.0 25266.0 26894.7 27525.0 27864.7 1928.0 2188.1 2374.4 2400.6 2410.8 18916.1 19014.3 19097.6 19493.9 19565.1 2073.2 2308.1 2347.3 2406.9 2418.7 133394.0 153079.7 156591.4 158468.2 164628.0 17732.6 25012.2 25072.0 25133.9 25246.6 41539.6 41877.4 41935.0 41978.4 42186.6 10980.8 11159.0 11242.7 11371.8 11398.7 8882.4 8908.0 8909.3 9073.2 9075.3 8965.3 10680.5 11615.8 11669.8 11699.8 3910.9 4360.0 4379.9 4553.2 4553.5 968.5 974.7 976.7 1003.8 1019.8 20594.9 21011.3 21322.6 21515.6 21575.0 987.5 999.0 1009.8 1042.4 1043.6 10568.2 10876.5 10885.9 10911.5 10914.2 498993.0 505689.5 512171.0 512983.0 515099.0 126911.4 127380.0 129981.0 131996.0 135091.0 1227.3 1253.7 1268.5 1272.7 1285.0 21236.9 22232.3 22387.0 22396.7 22504.0 51790.0 54765.0 55704.7 56180.0 57028.9 71886.0 72704.0 73033.0 73114.0 74091.5 8861.4 8948.2 9092.4 9103.9 9259.1 12022.9 13955.8 14181.9 14282.1 14662.2 11749.4 11952.3 12087.2 12195.6 12290.8 11308.4 11476.0 11478.4 11521.6 11687.6 12139.2 12248.6 12348.7 12394.7 12555.7 8619.1 8657.7 8953.5 9002.1 9091.9 11298.9 11906.0 12131.5 12239.0 12261.7 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7131.21 Median:4064.85 stddev:4529.70 (111.4%) stddev-sans-first:4521.00 17:57:30 INFO - PID 1646 | Values: 1271.2 1420.6 1431.7 1478.7 1485.0 11317.9 11411.3 11778.6 11790.1 12145.7 9448.9 11349.3 11380.1 11512.3 11556.7 2045.6 2547.9 2550.1 2561.9 2567.6 2464.4 2473.4 2480.6 2503.5 2508.9 2524.1 2543.6 2550.2 2554.3 2568.2 2469.6 2490.7 2520.2 2522.5 2523.7 2403.6 2492.0 2501.9 2507.0 2513.3 11816.5 11919.3 11925.8 11950.3 11951.6 6591.2 6790.1 6850.0 6860.6 6861.1 4017.1 4349.9 4387.7 4390.0 4416.3 1107.9 1209.8 1238.3 1263.4 1284.5 16288.7 16408.6 16528.1 16530.3 16836.8 4779.7 5514.6 5630.6 5733.3 5777.8 11755.5 11833.6 11907.2 11916.9 11937.0 6616.6 6641.8 6644.6 6647.0 6652.3 5907.1 6103.4 6149.4 6149.6 6221.2 4258.4 4423.7 4426.8 4478.2 4485.7 1288.7 1311.9 1312.5 1324.6 1324.7 788.5 796.2 804.7 814.8 819.6 6153.8 6711.5 6874.5 6917.2 6917.6 570.9 598.9 600.2 617.1 618.1 3915.5 4112.6 4184.9 4191.3 4290.9 13937.4 15057.4 15302.8 15324.2 15334.2 12944.8 15201.8 15203.4 15208.0 15223.8 6290.5 8331.0 8762.0 8822.6 8836.1 6171.3 6867.7 7001.8 7027.2 7111.1 13101.9 13666.0 13672.7 13821.9 13916.1 13806.7 13995.5 14064.8 14117.5 14246.1 9341.8 9417.2 9515.0 9520.0 9578.0 10685.9 10703.8 10790.6 10812.0 10813.2 9218.7 9289.1 9292.8 9296.1 9391.8 8891.8 8974.0 9007.8 9053.8 9220.4 6942.5 8134.9 8299.8 8308.2 8325.8 5938.9 5939.9 6023.8 6104.7 6170.9 6031.8 6106.3 6130.4 6244.6 6339.4 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5292.01 Median:5977.20 stddev:1713.15 (28.7%) stddev-sans-first:1678.27 17:57:30 INFO - PID 1646 | Values: 407.2 408.8 412.3 413.6 421.3 5724.7 6196.3 6291.6 6416.2 6456.5 5317.9 5522.7 5738.9 6189.6 6290.9 4412.3 4556.4 4559.7 4575.9 4582.9 4543.6 4564.4 4568.9 4595.2 4606.9 3707.9 4505.8 4506.5 4517.8 4519.9 3708.3 3832.0 3935.3 4387.8 4424.1 3455.3 3587.7 3734.0 3838.3 3866.5 5292.0 5903.9 6130.4 6148.6 6151.4 4245.7 5373.4 5380.4 5410.3 5415.4 5848.0 5879.5 5911.5 6192.3 6645.7 3549.0 4108.2 4375.6 4383.9 4386.6 6136.5 6813.6 7062.2 7096.2 7150.6 4466.1 4485.3 4485.6 4502.8 4507.6 3873.9 4531.3 4585.2 4599.0 4609.4 4220.7 4854.5 5214.3 5336.7 5374.9 5540.5 6674.3 6680.7 6700.6 6708.2 1420.4 1527.5 1538.6 1542.7 1550.9 886.3 932.3 951.1 1002.9 1002.9 5143.3 6390.1 6407.6 6449.8 6482.3 6374.0 6964.8 6997.6 7016.0 7042.6 5123.9 5631.7 6356.4 6368.2 6458.6 3180.7 3191.6 3616.2 3632.4 3634.7 6653.4 6830.2 6919.3 7221.8 7252.4 5726.2 5776.4 7072.4 7089.9 7126.1 6081.6 6817.0 6825.7 6955.6 7256.8 5886.5 6017.9 6828.7 6876.6 7004.9 5197.9 6621.0 6778.5 6802.7 6874.1 5998.5 6631.0 6954.7 7218.7 7248.9 3736.6 3764.9 4481.3 4488.2 4495.7 6112.9 6388.5 6389.6 6541.9 6955.2 5955.9 6461.6 6638.1 6660.4 6735.4 6289.3 6296.7 6707.8 6716.9 6719.3 6320.6 6589.5 6625.5 6633.0 6706.3 5220.5 6731.9 6739.6 6756.3 6786.9 6203.1 6321.4 6579.9 6600.7 6602.6 17:57:30 INFO - PID 1646 | 17:57:30 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:22138.64 Median:17956.41 stddev:30883.41 (172.0%) stddev-sans-first:30928.25 17:57:30 INFO - PID 1646 | Values: 740.8 831.1 832.6 864.1 865.1 16500.6 17016.7 17028.9 18610.3 19165.6 17767.9 18144.9 18234.8 18378.5 18636.2 980.2 1002.4 1004.5 1010.9 1014.2 1014.5 1022.4 1025.0 1025.7 1032.4 1053.2 1080.9 1085.0 1085.5 1086.4 1070.4 1078.0 1080.0 1080.9 1081.9 764.8 767.5 772.0 781.4 781.9 16634.3 16859.5 16933.2 17093.5 17098.0 17084.3 17538.0 17543.6 17550.6 17605.8 13502.3 13822.9 14058.4 14076.4 14087.4 3445.3 3486.8 3513.2 3515.6 3519.1 88868.1 131222.3 132357.0 132396.3 135071.0 13627.6 13726.2 13876.0 13956.0 13992.2 18544.5 25242.7 26185.1 26460.9 26733.5 6587.5 7681.3 7723.5 7853.6 7859.4 3186.8 3849.7 3914.2 3914.7 3921.3 3996.2 4219.3 4338.4 4414.7 4415.3 898.9 924.8 946.5 948.1 950.6 596.5 634.5 649.7 650.0 668.7 11463.1 12023.6 12080.3 12186.8 12270.6 621.9 651.3 663.8 672.7 686.9 5378.7 5480.6 5545.2 5603.0 5612.8 85344.1 108201.9 110210.4 110656.4 113423.0 87373.6 90322.5 90346.6 90814.5 92903.0 58134.7 71064.6 71519.0 76733.6 87100.6 11807.2 12819.6 12921.8 12935.9 12988.4 38731.8 39257.8 40996.8 41010.0 41568.0 69982.7 70782.6 71398.6 71531.0 74686.6 16474.3 16486.3 17271.9 17289.4 17316.2 18194.7 18509.4 18730.5 18817.2 19011.0 16672.7 16994.3 17289.5 17411.5 17534.3 16663.2 17251.0 17457.0 17596.7 17644.0 16522.6 16554.3 17118.8 17424.3 17489.9 10271.7 11654.1 11933.0 11991.1 12071.2 12750.2 13194.2 13623.4 13900.7 13958.7 17:57:30 INFO - PID 1646 | -------- Summary: end -------- 17:57:30 INFO - PID 1646 | 17:57:31 INFO - TEST-INFO | 1646: exit 0 17:57:31 INFO - TEST-OK | dromaeo_css | took 1114892ms 17:57:31 INFO - TEST-START | kraken 17:57:31 INFO - operating with platform_type : mac_ 17:57:31 INFO - Initialising browser for kraken test... 17:57:31 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpf01eKH/profile http://localhost:49248/getInfo.html) 17:57:33 INFO - PID 1657 | 2017-05-11 17:57:33.920 plugin-container[1658:11024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x943f, name = 'com.apple.tsm.portname' 17:57:33 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:33 INFO - PID 1657 | 2017-05-11 17:57:33.923 plugin-container[1658:11024] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 17:57:33 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:33 INFO - PID 1657 | 2017-05-11 17:57:33.923 plugin-container[1658:11024] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:33 INFO - PID 1657 | __metrics Screen width/height:1600/1200 17:57:33 INFO - PID 1657 | colorDepth:24 17:57:33 INFO - PID 1657 | Browser inner width/height: 1024/691 17:57:33 INFO - PID 1657 | __metrics 17:57:35 INFO - TEST-INFO | 1657: exit 0 17:57:35 INFO - Browser initialized. 17:57:35 INFO - Running cycle 1/1 for kraken test... 17:57:35 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/T/tmpf01eKH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.363 plugin-container[1661:11178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 17:57:37 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.363 plugin-container[1661:11178] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4617, name = 'com.apple.CFPasteboardClient' 17:57:37 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.363 plugin-container[1661:11178] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.918 plugin-container[1662:11220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 17:57:37 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.919 plugin-container[1662:11220] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 17:57:37 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:57:37 INFO - PID 1660 | 2017-05-11 17:57:37.919 plugin-container[1662:11220] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:57:47 INFO - PID 1660 | RSS: Main: 228933632 17:57:47 INFO - PID 1660 | 17:57:49 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 17:57:50 INFO - PID 1660 | RSS: Main: 242573312 17:57:50 INFO - PID 1660 | 17:57:53 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 17:57:54 INFO - PID 1660 | RSS: Main: 250732544 17:57:54 INFO - PID 1660 | 17:57:57 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 17:57:58 INFO - PID 1660 | RSS: Main: 253050880 17:57:58 INFO - PID 1660 | 17:58:00 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 17:58:00 INFO - PID 1660 | RSS: Main: 254177280 17:58:00 INFO - PID 1660 | 17:58:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:58:03 INFO - PID 1660 | RSS: Main: 254595072 17:58:03 INFO - PID 1660 | 17:58:06 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 17:58:06 INFO - PID 1660 | RSS: Main: 261570560 17:58:06 INFO - PID 1660 | 17:58:09 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 17:58:10 INFO - PID 1660 | RSS: Main: 263577600 17:58:10 INFO - PID 1660 | 17:58:13 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 17:58:13 INFO - PID 1660 | RSS: Main: 262729728 17:58:13 INFO - PID 1660 | 17:58:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:58:15 INFO - PID 1660 | RSS: Main: 262459392 17:58:15 INFO - PID 1660 | 17:58:17 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:58:18 INFO - PID 1660 | RSS: Main: 262246400 17:58:18 INFO - PID 1660 | 17:58:20 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:58:21 INFO - PID 1660 | RSS: Main: 261984256 17:58:21 INFO - PID 1660 | 17:58:23 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:58:24 INFO - PID 1660 | RSS: Main: 262053888 17:58:24 INFO - PID 1660 | 17:58:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:58:28 INFO - PID 1660 | RSS: Main: 259645440 17:58:28 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 17:58:30 INFO - PID 1660 | RSS: Main: 261677056 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | __start_tp_report 17:58:30 INFO - PID 1660 | _x_x_mozilla_page_load 17:58:30 INFO - PID 1660 | _x_x_mozilla_page_load_details 17:58:30 INFO - PID 1660 | |i|pagename|runs| 17:58:30 INFO - PID 1660 | |0;ai-astar;101;114;114;112;114;116;112;113;114;113 17:58:30 INFO - PID 1660 | |1;audio-beat-detection;136;133;151;133;94;150;147;135;148;109 17:58:30 INFO - PID 1660 | |2;audio-dft;196;199;200;223;253;287;196;240;280;222 17:58:30 INFO - PID 1660 | |3;audio-fft;84;57;58;84;65;75;86;60;80;56 17:58:30 INFO - PID 1660 | |4;audio-oscillator;88;71;84;72;68;69;84;69;68;90 17:58:30 INFO - PID 1660 | |5;imaging-gaussian-blur;88;90;89;111;90;88;88;95;88;88 17:58:30 INFO - PID 1660 | |6;imaging-darkroom;97;96;95;97;96;97;97;121;95;96 17:58:30 INFO - PID 1660 | |7;imaging-desaturate;92;95;91;90;93;89;90;94;92;91 17:58:30 INFO - PID 1660 | |8;json-parse-financial;49;49;50;49;48;49;49;50;51;52 17:58:30 INFO - PID 1660 | |9;json-stringify-tinderbox;55;60;57;55;65;58;60;59;58;59 17:58:30 INFO - PID 1660 | |10;stanford-crypto-aes;81;82;83;84;83;83;81;85;81;84 17:58:30 INFO - PID 1660 | |11;stanford-crypto-ccm;134;138;145;144;144;141;156;163;219;138 17:58:30 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;207;234;231;196;233;239;214;189;172;236 17:58:30 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;69;68;66;68;69;67;68;94;97;97 17:58:30 INFO - PID 1660 | __end_tp_report 17:58:30 INFO - PID 1660 | __start_cc_report 17:58:30 INFO - PID 1660 | _x_x_mozilla_cycle_collect,993 17:58:30 INFO - PID 1660 | __end_cc_report 17:58:30 INFO - PID 1660 | __startTimestamp1494550710796__endTimestamp 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | ------- Summary: start ------- 17:58:30 INFO - PID 1660 | Number of tests: 14 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:112.30 Median:114.00 stddev:4.14 (3.6%) stddev-sans-first:1.24 17:58:30 INFO - PID 1660 | Values: 101.0 114.0 114.0 112.0 114.0 116.0 112.0 113.0 114.0 113.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:133.60 Median:147.50 stddev:18.68 (12.7%) stddev-sans-first:19.79 17:58:30 INFO - PID 1660 | Values: 136.0 133.0 151.0 133.0 94.0 150.0 147.0 135.0 148.0 109.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:229.60 Median:231.50 stddev:34.42 (14.9%) stddev-sans-first:34.29 17:58:30 INFO - PID 1660 | Values: 196.0 199.0 200.0 223.0 253.0 287.0 196.0 240.0 280.0 222.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:70.50 Median:77.50 stddev:12.49 (16.1%) stddev-sans-first:12.26 17:58:30 INFO - PID 1660 | Values: 84.0 57.0 58.0 84.0 65.0 75.0 86.0 60.0 80.0 56.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:76.30 Median:78.00 stddev:9.03 (11.6%) stddev-sans-first:8.53 17:58:30 INFO - PID 1660 | Values: 88.0 71.0 84.0 72.0 68.0 69.0 84.0 69.0 68.0 90.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:88.50 stddev:7.18 (8.1%) stddev-sans-first:7.51 17:58:30 INFO - PID 1660 | Values: 88.0 90.0 89.0 111.0 90.0 88.0 88.0 95.0 88.0 88.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:96.50 stddev:7.87 (8.2%) stddev-sans-first:8.33 17:58:30 INFO - PID 1660 | Values: 97.0 96.0 95.0 97.0 96.0 97.0 97.0 121.0 95.0 96.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:1.89 (2.1%) stddev-sans-first:2.00 17:58:30 INFO - PID 1660 | Values: 92.0 95.0 91.0 90.0 93.0 89.0 90.0 94.0 92.0 91.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:49.60 Median:49.50 stddev:1.17 (2.4%) stddev-sans-first:1.22 17:58:30 INFO - PID 1660 | Values: 49.0 49.0 50.0 49.0 48.0 49.0 49.0 50.0 51.0 52.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:58.60 Median:59.00 stddev:2.88 (4.9%) stddev-sans-first:2.74 17:58:30 INFO - PID 1660 | Values: 55.0 60.0 57.0 55.0 65.0 58.0 60.0 59.0 58.0 59.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.42 (1.7%) stddev-sans-first:1.36 17:58:30 INFO - PID 1660 | Values: 81.0 82.0 83.0 84.0 83.0 83.0 81.0 85.0 81.0 84.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:152.20 Median:144.50 stddev:25.03 (17.3%) stddev-sans-first:25.67 17:58:30 INFO - PID 1660 | Values: 134.0 138.0 145.0 144.0 144.0 141.0 156.0 163.0 219.0 138.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:215.10 Median:232.00 stddev:23.35 (10.1%) stddev-sans-first:24.59 17:58:30 INFO - PID 1660 | Values: 207.0 234.0 231.0 196.0 233.0 239.0 214.0 189.0 172.0 236.0 17:58:30 INFO - PID 1660 | 17:58:30 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.30 Median:69.00 stddev:13.65 (19.8%) stddev-sans-first:14.22 17:58:30 INFO - PID 1660 | Values: 69.0 68.0 66.0 68.0 69.0 67.0 68.0 94.0 97.0 97.0 17:58:30 INFO - PID 1660 | -------- Summary: end -------- 17:58:30 INFO - PID 1660 | 17:58:31 INFO - TEST-INFO | 1660: exit 0 17:58:31 INFO - TEST-OK | kraken | took 60189ms 17:58:31 INFO - SUITE-END | took 1175s 17:58:31 INFO - Completed test suite (00:19:35) 17:58:31 INFO - javascript benchmark 17:58:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7977.836627799696, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3022.788404811663, 3635.4365073413614, 3692.8153592320377, 3695.8891233263003, 3705.7035437165027, 6134.846628834271, 6256.405641464059, 6264.5928014679075, 6328.335524769896, 6361.459275961549, 6210.4100110489435, 6221.720022598983, 6255.968720156404, 6283.6543990080645, 6350.523710721703, 5487.6158668893195, 5487.725613719318, 5495.120780675093, 5512.366077901032, 5547.140193270049, 4942.579880710126, 5487.478689524509, 5606.0, 5648.293963254593, 5672.17753425753, 4336.1978034063795, 5524.309461317335, 5558.138488534284, 5566.359868615121, 5594.9720251398685, 5474.753636086386, 5476.096444086721, 5524.558035357162, 5586.441355864422, 5589.413111623262, 4893.290472881412, 4975.7760900759195, 5484.972575137139, 5519.254900588409, 5573.219749235111, 6088.208532890741, 6302.9369706302805, 6327.14583531222, 6346.174997250374, 6354.301026887085, 6498.772542961021, 6607.636579988099, 6776.119104516381, 6781.694823732944, 6792.93207067928, 5761.654300741927, 6849.349311815369, 6873.037774711544, 6883.586984780929, 6890.2420733719255, 6281.528885333618, 6420.165378500765, 6433.0, 6440.8389790255615, 6473.2232132144445, 6758.695858686371, 7051.29487051301, 7056.223815380304, 7071.540349877241, 7085.50401471892, 6535.477161827042, 6590.077389165521, 6649.235337936103, 6709.0, 6714.160729908762, 6880.277570855088, 6895.172579290517, 6900.930990690029, 6906.965465172642, 6912.55068420551, 6503.9349606504275, 6711.865083723613, 6729.091572637729, 6794.320567943166, 6800.523963322619, 6610.471162307004, 6714.462842972551, 6784.864302713918, 6808.455323574102, 6844.28135045823, 5450.781968721207, 5457.727113644302, 5459.044667183227, 5483.561315094783, 5489.917651235235, 4867.612730371827, 4875.0, 4882.5361590649545, 4902.3872015998, 4933.383327084114, 6166.044263139221, 6376.043593461018, 6382.425581697669, 6430.0, 6448.226212854487, 6033.276006879202, 6675.0, 6853.17761868579, 6891.552049116792, 6905.9309406906295, 6447.580907241015, 6463.15978922737, 6475.417212450902, 6478.1578394808375, 6482.35176482348, 4895.559399654048, 5062.797488100508, 6019.879602408015, 6045.939540604626, 6139.2325959255095, 5226.738663066907, 7001.36987671112, 7056.576605403692, 7108.0, 7117.359437650636, 6607.0, 6896.241413444617, 6918.342757438036, 6919.342662447059, 6926.896096558657, 6859.07402500676, 6963.6866341013765, 6968.268331825288, 6982.197047339621, 7028.121484814398, 6737.157855268091, 6780.220274668279, 6794.014867844036, 6802.761903333359, 6814.114165158498, 6331.303556608861, 6642.6014439133805, 6700.5309628327495, 6937.132858392701, 6940.444764418935, 6574.967125164344, 6629.602223866584, 6644.800655980328, 6665.833354166185, 6676.365745254193, 6653.833654158685, 6680.964450510179, 6685.832854178684, 6693.665316734046, 6698.799036028927, 5564.1931919870585, 5983.37174596661, 6522.086907833002, 6548.214214294315, 6589.571677840925, 6432.09950606906, 6562.901556476752, 6605.53761236728, 6644.269130395749, 6678.165229346332, 6462.483001359974, 6496.935030649633, 6508.576942498722, 6514.446272066927, 6519.445847102859], "value": 6172.171151933572, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.5891374855698, 842.5829214538804, 1105.2870898270617, 1145.0667705819762, 1152.4583445780481, 35257.82371088144, 36277.45583816238, 36321.81839090801, 36337.81831090844, 36344.273114537726, 37994.24011519768, 38313.80843095788, 38322.8083859581, 38383.23233535328, 38420.42369364462, 5894.056950887859, 5989.101634754778, 8238.835223295546, 8393.664253429855, 8422.957885210566, 8129.634166462494, 8267.710630127916, 8361.16388361165, 8361.289290410292, 8400.285975692073, 8305.626246818907, 8435.873461898076, 8442.62008209632, 8461.196186362286, 8472.78818029551, 7223.711051557932, 8190.2628763410985, 8370.916290837074, 8442.409031367837, 8461.703840365619, 6965.582065076061, 8231.629576669064, 8283.337333013344, 8356.582170891492, 8357.540335281557, 36486.817565912, 36498.0, 36667.0, 36736.448953265724, 36780.26439471196, 3679.300932822755, 3696.1129328960856, 3740.121071548184, 3741.850325986957, 3744.5693745219105, 23791.524169516684, 29620.70379296201, 29821.552676709867, 29873.0, 30105.397892042256, 3126.9530957035663, 3164.493681011027, 3179.2369831240567, 3196.6323872754697, 3208.181913612014, 283010.0, 285066.0, 285422.0, 285489.0, 285556.9999999979, 38612.227755444736, 39168.0, 46552.30171547429, 47794.0, 48122.75938620354, 41416.79291603523, 49162.016759664606, 49363.25955110676, 49506.0, 49508.0, 13544.390502427414, 13560.728785424237, 13815.654608634864, 13842.100263482842, 13894.23581703016, 9081.137291957253, 11218.831717524243, 11225.943870280596, 11253.493592788343, 11293.661190164308, 9729.270304727173, 12217.266963982189, 12220.51117955272, 12223.93888030554, 12314.630561083182, 5327.547158491494, 5335.493128152819, 5344.545713614386, 5347.224652425377, 5348.438413966555, 1014.8224060789332, 1031.6234574380296, 1043.7808060307268, 1084.5553323137633, 1088.2273585754203, 22413.66379504276, 23235.0, 24576.754232457803, 24580.0, 24966.0013599454, 1113.9554417823358, 1127.22784892349, 1166.6091859227083, 1167.7139100920158, 1182.3378907811652, 12369.876301236873, 12511.0, 12514.37313149303, 12516.561920332744, 12533.43599538023, 296700.0, 297023.0, 297042.0, 297471.0, 299020.0, 50812.49187508151, 51131.744341278056, 51206.71983200375, 51491.48508514942, 51523.48476515262, 1532.685799411118, 1547.4197176058979, 1579.5577238373273, 1581.7627355896477, 1585.9127747973973, 18152.0, 18540.629187416445, 23169.0, 24157.0, 24337.39156521101, 37413.0, 37739.62260377361, 37766.0, 37802.810985944896, 37816.24367512689, 231043.0, 232078.0, 232328.0, 232369.0, 233304.0, 32846.507302390004, 33090.33819323648, 33845.661543385235, 34344.65655343414, 34561.13597160091, 41662.166756665305, 44711.329330060726, 44766.3285050731, 44851.551484486045, 45036.77481612702, 29393.412131757672, 29948.0, 30656.540151898185, 31074.84462577673, 31435.371292574477, 24646.876765616056, 30686.539701904934, 30970.690293097683, 31037.224069398446, 31572.526412103376, 36547.45178822373, 36873.63126368702, 37092.0, 37112.25775484421, 37113.62886371102, 23793.524129516965, 25448.363790905376, 29874.85062574673, 31499.685003149676, 31591.89428369996, 21710.89144554267, 21788.128474860827, 22042.66935995929, 22983.88508057449, 24348.0], "value": 16937.86090361063, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.7026378896883, 383.60297092509256, 405.3433437830715, 431.5899895099656, 455.1125305653974, 20578.662386944838, 20976.05607747651, 21578.676319855174, 21687.891560542175, 21850.016749246282, 21819.345419637437, 21916.561668766615, 22602.54794904105, 22628.43428914278, 22756.658650120262, 5879.67661778602, 6181.845453863644, 6185.56701030928, 6211.844703882416, 6212.906806397885, 5119.539241468267, 6023.488003519684, 6080.2399700037495, 6127.0, 6194.721237544299, 6164.4143806338325, 6172.506199504051, 6177.66022868742, 6184.5980011299125, 6186.659733714644, 6001.579889407743, 6105.847353816146, 6179.443850053472, 6182.0, 6186.84532886677, 5296.205569164617, 5454.672719636835, 5466.1527463243265, 5484.149956756709, 5485.780568777244, 23901.0, 25266.0, 26894.655267236754, 27525.036623718075, 27864.721352786415, 1927.9903600482048, 2188.070070220166, 2374.3945293950105, 2400.627902675088, 2410.8071354291615, 18916.148773305234, 19014.334498292494, 19097.61804763897, 19493.902530487398, 19565.1195696194, 2073.2432662078386, 2308.0767692922964, 2347.272345572861, 2406.867622280774, 2418.6734790803193, 133394.0, 153079.703804443, 156591.43408565884, 158468.2076589621, 164628.0, 17732.60609817684, 25012.24963251069, 25072.0, 25133.87433062823, 25246.621300680505, 41539.58460415417, 41877.371839422434, 41935.0, 41978.37032444516, 42186.57813421888, 10980.835287470694, 11159.0, 11242.7189320266, 11371.829422558669, 11398.715032124097, 8882.378233523592, 8908.0, 8909.331800115016, 9073.183413492818, 9075.273978081737, 8965.282777377794, 10680.465976701134, 11615.76768464626, 11669.824952625717, 11699.824502632468, 3910.921781564353, 4360.018995726, 4379.890502737457, 4553.157665831832, 4553.5218802025975, 968.5205823117602, 974.7027156717269, 976.72651657536, 1003.7741508160751, 1019.7654539455966, 20594.897025514776, 21011.264605738725, 21322.596437242035, 21515.569688606454, 21575.0, 987.481572174614, 998.9910190707437, 1009.7828966772179, 1042.4006196437078, 1043.639944219243, 10568.207384446043, 10876.456177191109, 10885.891141088647, 10911.454427278604, 10914.23600347968, 498993.0, 505689.47155264724, 512171.0, 512983.0, 515099.0, 126911.3654431722, 127380.0, 129981.0, 131996.0, 135091.0, 1227.2636418149038, 1253.7304479536942, 1268.479923231489, 1272.7390884868764, 1285.0490636928605, 21236.893815531443, 22232.333030009126, 22387.0, 22396.664050039588, 22504.0, 51790.0, 54765.0, 55704.72147639236, 56180.0, 57028.859422810485, 71886.0, 72704.0, 73033.0, 73114.0, 74091.51816963522, 8861.379703420957, 8948.194662480408, 9092.408993415407, 9103.863442048241, 9259.074092590687, 12022.879771202175, 13955.790663140264, 14181.929090354482, 14282.07166534172, 14662.193579352812, 11749.353785541878, 11952.34262115592, 12087.214331068453, 12195.573154939535, 12290.754184916073, 11308.43457827089, 11476.0, 11478.426078696199, 11521.596744113916, 11687.590934317259, 12139.150259481752, 12248.632541023784, 12348.691282717644, 12394.690132746753, 12555.748885022065, 8619.138086191331, 8657.740267791976, 8953.507557084162, 9002.119321816956, 9091.909080909107, 11298.94350528275, 11906.0, 12131.45408456604, 12239.0, 12261.69345766363], "value": 11393.731344116499, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1271.237257645413, 1420.5738278516446, 1431.7494438473263, 1478.7042591481706, 1484.962875928102, 11317.886821131786, 11411.315321080741, 11778.587749428772, 11790.056795456365, 12145.696357591065, 9448.858267126, 11349.319040857554, 11380.146489013316, 11512.309261444298, 11556.653300401002, 2045.5806559655273, 2547.8853451594723, 2550.0947163756878, 2561.9103331383403, 2567.5763499022637, 2464.3962229253857, 2473.3816545863533, 2480.5659009673327, 2503.4617557225192, 2508.8745562721883, 2524.0913271222344, 2543.605741110131, 2550.1584477122597, 2554.2848002559313, 2568.2295311406597, 2469.5678256305164, 2490.7011158660985, 2520.1683444463283, 2522.545941730488, 2523.7223905370392, 2403.579373609629, 2491.950160996788, 2501.949961000788, 2506.987465062663, 2513.2711513661015, 11816.527338906433, 11919.344436056013, 11925.821112683318, 11950.282983021047, 11951.641450756491, 6591.242007169141, 6790.083338749255, 6850.0, 6860.588364698134, 6861.1080559526945, 4017.13631569214, 4349.934750978738, 4387.670924680661, 4389.978050109761, 4416.3375493675885, 1107.9197782162407, 1209.76409600128, 1238.325112813519, 1263.3935710858839, 1284.5183056353867, 16288.674226515523, 16408.589785255346, 16528.09095499747, 16530.256138473796, 16836.83163168377, 4779.713217206979, 5514.6139770216505, 5630.577706672016, 5733.283339582552, 5777.768889244468, 11755.471003804849, 11833.644990650295, 11907.166498345032, 11916.94041529804, 11937.0, 6616.636085015371, 6641.767538136142, 6644.568103073381, 6646.966765166143, 6652.301508341651, 5907.113932910098, 6103.35914728956, 6149.41580549847, 6149.600275982047, 6221.222347206599, 4258.425112609758, 4423.734575925455, 4426.75652839097, 4478.216312145361, 4485.708428952173, 1288.671388795851, 1311.9409626566828, 1312.4618906248393, 1324.5958585168082, 1324.7218084202425, 788.5308241596241, 796.1719811396199, 804.7062822070019, 814.771863878115, 819.5943008210974, 6153.84615384619, 6711.496637752188, 6874.450043996469, 6917.169939607179, 6917.550359226634, 570.9143628455682, 598.9490893274034, 600.2197143713155, 617.0528239152968, 618.0759764152531, 3915.530136383652, 4112.568180341081, 4184.853530126431, 4191.308434108338, 4290.935635965463, 13937.372818223204, 15057.397704091714, 15302.770458443132, 15324.157171355682, 15334.156621385708, 12944.805827912589, 15201.771973420407, 15203.391864325304, 15208.0, 15223.847761522244, 6290.4653104485715, 8330.958345208357, 8761.956190219136, 8822.558872056372, 8836.072212417603, 6171.259448866164, 6867.690953906987, 7001.844695625368, 7027.191872934678, 7111.146662400545, 13101.868981310065, 13666.0, 13672.65818354549, 13821.930890345484, 13916.095453795471, 13806.65483362924, 13995.510157144858, 14064.789028164381, 14117.505887294306, 14246.145231286157, 9341.813163736551, 9417.246620270498, 9515.0, 9520.047995200424, 9577.952110239405, 10685.893141068489, 10703.785924281627, 10790.622328218475, 10811.972862578043, 10813.189010824377, 9218.677346292847, 9289.117533834275, 9292.767680808034, 9296.116868897443, 9391.765205869908, 8891.777705557413, 8973.955130224307, 9007.819843603222, 9053.818923621622, 9220.400673956172, 6942.479314051568, 8134.918650813416, 8299.792505187419, 8308.169183081643, 8325.750227493185, 5938.881222375442, 5939.940600593939, 6023.759049637965, 6104.725287362168, 6170.907436388368, 6031.788887388921, 6106.267247930276, 6130.356312587114, 6244.594101383395, 6339.397757213057], "value": 5340.5837954428325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.19782029402074, 408.77828999388333, 412.3340804600571, 413.5616246778417, 421.34480882228144, 5724.7137643117885, 6196.273639598573, 6291.622502649844, 6416.197975253088, 6456.515761317903, 5317.9468205317935, 5522.723863806814, 5738.856528586777, 6189.566730328879, 6290.905636415458, 4412.3160910059, 4556.430446194226, 4559.74921379324, 4575.862724118282, 4582.9312560311455, 4543.568361005699, 4564.402459139308, 4568.885777855564, 4595.241785105453, 4606.907861842761, 3707.94438083429, 4505.752183629916, 4506.526814684444, 4517.774111294439, 4519.864404067883, 3708.2583483303447, 3832.0, 3935.2719746846637, 4387.815289871717, 4424.1288872236955, 3455.2571197192724, 3587.6950459211, 3734.029152420363, 3838.309104361214, 3866.506103563186, 5292.030976767424, 5903.911441328383, 6130.386961303879, 6148.600340977822, 6151.354107818659, 4245.660347172219, 5373.435789242151, 5380.408155102936, 5410.296661434028, 5415.431379705153, 5847.970760146172, 5879.500242479395, 5911.517162791638, 6192.28788689302, 6645.734170633169, 3549.0240183949363, 4108.198901214264, 4375.584319489643, 4383.868483945486, 4386.5962891874615, 6136.539759518009, 6813.557118787313, 7062.223155452896, 7096.183938846995, 7150.640288704715, 4466.106778644219, 4485.327200919888, 4485.573870482299, 4502.819887204475, 4507.5943165115295, 3873.9418908716393, 4531.342955271467, 4585.243434833215, 4598.954010459853, 4609.377734005866, 4220.725652832556, 4854.466008739036, 5214.270002199695, 5336.706481143497, 5374.892502149935, 5540.473655002768, 6674.299198584079, 6680.665966701646, 6700.564463309869, 6708.209861428278, 1420.3750349846034, 1527.5264667952747, 1538.6307286251442, 1542.7145977994107, 1550.8991915525455, 886.3308202307346, 932.3100905330142, 951.148721893915, 1002.871769259583, 1002.9297949143636, 5143.305653736697, 6390.114916099762, 6407.583507072025, 6449.774257900951, 6482.337479324166, 6373.968130159319, 6964.79105626832, 6997.615131167733, 7015.964920175366, 7042.59148170368, 5123.8719032023755, 5631.71841407928, 6356.4279214870885, 6368.203974503187, 6458.644774537352, 3180.6800177926366, 3191.5850939377733, 3616.173832214715, 3632.418812989909, 3634.727395445352, 6653.401193892573, 6830.180378354629, 6919.342662447059, 7221.783346499614, 7252.38354739853, 5726.226959360433, 5776.393478684761, 7072.398846098139, 7089.873038088553, 7126.073610430611, 6081.604695694766, 6817.0, 6825.726970921207, 6955.582665040114, 7256.843273176185, 5886.499647530008, 6017.939820601738, 6828.7268509259075, 6876.553024053421, 7004.894926576004, 5197.948020519746, 6620.966895165493, 6778.525503214925, 6802.727890884507, 6874.140732408449, 5998.520118390431, 6631.0, 6954.721811127701, 7218.726101278081, 7248.891266630899, 3736.645018723217, 3764.924701506009, 4481.260592002283, 4488.192125417456, 4495.707778994355, 6112.938870611237, 6388.520860935541, 6389.562348471557, 6541.8691626168165, 6955.200151982587, 5955.880882382415, 6461.57999730016, 6638.145597393167, 6660.400563949268, 6735.393814556714, 6289.289319301634, 6296.653684047421, 6707.767941006023, 6716.865662686817, 6719.294474080151, 6320.62076275425, 6589.505787066085, 6625.4699624029, 6633.0, 6706.262311145672, 5220.451852555427, 6731.865362692816, 6739.59562426256, 6756.256811750605, 6786.898196526958, 6203.077046115719, 6321.4196450887275, 6579.901301480386, 6600.669966501751, 6602.60384376939], "value": 4760.243687481077, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.8370158565116, 831.0692024932077, 832.6045128563933, 864.0927026622047, 865.0786911938785, 16500.587485312873, 17016.659666806656, 17028.914855425737, 18610.34863779768, 19165.61668766624, 17767.91116044421, 18144.909275453603, 18234.805994621245, 18378.540536486595, 18636.161372738225, 980.176651612647, 1002.4035698759227, 1004.4626125023115, 1010.9393436393804, 1014.1785154025263, 1014.543455445049, 1022.4121130350042, 1025.021104844875, 1025.7179275699168, 1032.3599368391608, 1053.183782568508, 1080.8811030786608, 1084.9945750271238, 1085.5223701571322, 1086.413336798128, 1070.427321383064, 1077.956881724734, 1080.0333701337338, 1080.9243352965295, 1081.9134469242442, 764.8126209078756, 767.547147183159, 772.0118248641747, 781.4022272961189, 781.9022622172229, 16634.33462661492, 16859.494215173563, 16933.238004289713, 17093.487195384034, 17098.0, 17084.31662733489, 17538.0, 17543.649127017517, 17550.561235969075, 17605.823941760675, 13502.324883755773, 13822.861771382259, 14058.367373468218, 14076.366563504667, 14087.36606852694, 3445.3109378124477, 3486.8430920608635, 3513.2446523997464, 3515.6484351564895, 3519.1202199450136, 88868.11131888663, 131222.3438882809, 132357.0, 132396.33801831026, 135071.0, 13627.591172264847, 13726.176429414267, 13876.0, 13956.023078384618, 13992.230427326595, 18544.536386590175, 25242.74757252404, 26185.083522076635, 26460.8676956614, 26733.465330693278, 6587.473002159816, 7681.270279323456, 7723.53658780475, 7853.607319633996, 7859.449838511249, 3186.7609929254936, 3849.653531182207, 3914.197589494147, 3914.706397020235, 3921.3333733265413, 3996.220736956324, 4219.346001369792, 4338.436003319548, 4414.690971632019, 4415.2935530315, 898.9280857531381, 924.8289066522715, 946.4699768129732, 948.1135138645251, 950.5959967013991, 596.5257620191958, 634.4828964394004, 649.6751624187906, 649.9870002599921, 668.6924014953177, 11463.140264480031, 12023.579174728842, 12080.275183489019, 12186.81719774204, 12270.631881043582, 621.8849512840139, 651.2835880531472, 663.7809522857445, 672.6838385958591, 686.914674813793, 5378.650387724785, 5480.616356854982, 5545.223668686387, 5603.0, 5612.775488980476, 85344.14655853486, 108201.91798082076, 110210.44894775475, 110656.4467177659, 113423.0, 87373.56313218393, 90322.54838725895, 90346.64480032805, 90814.54592726994, 92903.0, 58134.674613015006, 71064.64467677732, 71519.0, 76733.61633191798, 87100.56449717711, 11807.173497855068, 12819.551315703906, 12921.806172907225, 12935.935320323339, 12988.415521301375, 38731.80634096811, 39257.803710981265, 40996.79501602473, 41009.97475063028, 41568.0, 69982.65008674924, 70782.6460867713, 71398.64300678464, 71531.0, 74686.62656686682, 16474.258658360162, 16486.34054637801, 17271.91364043222, 17289.394871179953, 17316.220770065127, 18194.72707909409, 18509.35217267389, 18730.531736706693, 18817.247310107443, 19011.0, 16672.749908751135, 16994.320227191267, 17289.48131556055, 17411.47765567035, 17534.298628055247, 16663.166841658112, 17251.0, 17457.0, 17596.73604895953, 17644.0, 16522.58693532671, 16554.337826486804, 17118.828811711723, 17424.303027878745, 17489.912550437675, 10271.691849244236, 11654.125940554322, 11933.045356371296, 11991.100667450151, 12071.155019148575, 12750.171238869443, 13194.208347499181, 13623.38694758758, 13900.652483687989, 13958.651033724238], "value": 8513.327881272167, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1538.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 114.0, 114.0, 112.0, 114.0, 116.0, 112.0, 113.0, 114.0, 113.0], "value": 112.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 133.0, 151.0, 133.0, 94.0, 150.0, 147.0, 135.0, 148.0, 109.0], "value": 133.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 200.0, 223.0, 253.0, 287.0, 196.0, 240.0, 280.0, 222.0], "value": 229.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 57.0, 58.0, 84.0, 65.0, 75.0, 86.0, 60.0, 80.0, 56.0], "value": 70.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 84.0, 72.0, 68.0, 69.0, 84.0, 69.0, 68.0, 90.0], "value": 76.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 89.0, 111.0, 90.0, 88.0, 88.0, 95.0, 88.0, 88.0], "value": 91.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 95.0, 97.0, 96.0, 97.0, 97.0, 121.0, 95.0, 96.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 91.0, 90.0, 93.0, 89.0, 90.0, 94.0, 92.0, 91.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 50.0, 49.0, 48.0, 49.0, 49.0, 50.0, 51.0, 52.0], "value": 49.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 60.0, 57.0, 55.0, 65.0, 58.0, 60.0, 59.0, 58.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 83.0, 84.0, 83.0, 83.0, 81.0, 85.0, 81.0, 84.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 138.0, 145.0, 144.0, 144.0, 141.0, 156.0, 163.0, 219.0, 138.0], "value": 152.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 234.0, 231.0, 196.0, 233.0, 239.0, 214.0, 189.0, 172.0, 236.0], "value": 215.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 68.0, 69.0, 67.0, 68.0, 94.0, 97.0, 97.0], "value": 76.3, "unit": "score"}]}]} 17:58:32 INFO - Return code: 0 17:58:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:58:32 INFO - # TBPL SUCCESS # 17:58:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:58:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:58:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:58:32 INFO - 2017-05-11 17:58:32.119 screenresolution[1667:11607] starting screenresolution argv=screenresolution get 17:58:32 INFO - 2017-05-11 17:58:32.122 screenresolution[1667:11607] Display 0: 1600x1200x32@60 17:58:32 INFO - 2017-05-11 17:58:32.128 screenresolution[1668:11610] starting screenresolution argv=screenresolution list 17:58:32 INFO - Available Modes on Display 0 17:58:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:58:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:58:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:58:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:58:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:58:32 INFO - Intel Iris: 17:58:32 INFO - Chipset Model: Intel Iris 17:58:32 INFO - Type: GPU 17:58:32 INFO - Bus: Built-In 17:58:32 INFO - VRAM (Dynamic, Max): 1536 MB 17:58:32 INFO - Vendor: Intel (0x8086) 17:58:32 INFO - Device ID: 0x0a2e 17:58:32 INFO - Revision ID: 0x0009 17:58:32 INFO - Displays: 17:58:32 INFO - Display: 17:58:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:58:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:58:32 INFO - Display Serial Number: 1600x1200 60 17:58:32 INFO - Main Display: Yes 17:58:32 INFO - Mirror: Off 17:58:32 INFO - Online: Yes 17:58:32 INFO - Rotation: Supported 17:58:32 INFO - Return code: 0 17:58:32 INFO - Running post-action listener: _resource_record_post_action 17:58:32 INFO - [mozharness: 2017-05-12 00:58:32.369298Z] Finished run-tests step (success) 17:58:32 INFO - Running post-run listener: _resource_record_post_run 17:58:32 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 32715776; Write bytes: 530833408; Read time: 342; Write time: 1732 17:58:32 INFO - TinderboxPrint: CPU usage
54.0% 17:58:32 INFO - TinderboxPrint: I/O read bytes / time
32,715,776 / 342 17:58:32 INFO - TinderboxPrint: I/O write bytes / time
530,833,408 / 1,732 17:58:32 INFO - TinderboxPrint: CPU idle
2,202.8 (46.0%) 17:58:32 INFO - TinderboxPrint: CPU system
958.9 (20.0%) 17:58:32 INFO - TinderboxPrint: CPU user
1,624.0 (33.9%) 17:58:32 INFO - TinderboxPrint: Swap in / out
242,802,688 / 0 17:58:32 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 208880128; Write bytes: 206518272; Read time: 15905; Write time: 489 17:58:32 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 30737408; Write bytes: 313550848; Read time: 317; Write time: 1208 17:58:32 INFO - Running post-run listener: _upload_blobber_files 17:58:32 INFO - Blob upload gear active. 17:58:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:58:32 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 17:58:32 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:58:32 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:58:33 INFO - (blobuploader) - INFO - Open directory for files ... 17:58:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:58:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:58:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:33 INFO - (blobuploader) - INFO - Done attempting. 17:58:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:58:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:58:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:35 INFO - (blobuploader) - INFO - Done attempting. 17:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:58:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:36 INFO - (blobuploader) - INFO - Done attempting. 17:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:58:38 INFO - (blobuploader) - INFO - Done attempting. 17:58:38 INFO - (blobuploader) - INFO - Iteration through files over. 17:58:38 INFO - Return code: 0 17:58:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:58:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:58:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f078dab6dbca1553c7188afb5c9b1ef6d5b4e83ae4e03e73c7bae58f2fa1f99665146b7387b53a2ec19ce27cf0cdb46529b4109854a32aa26bdcd499f4ac897f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7086aba6c6b8ebd812156e6632a474e045f0d2fc0e4d2cdba8526b53f7c77788790888b38ddeaf27beb42e0d8c3caa82032abb43174a96609c9fc5391b2f30", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0632c141ff6532ce7cb08a6600f664a42ba050e25ae176f747b8faa898f78909ae7e888aff80b8af307779748f8d36639cf175da1096fcc68d63f122d9d9235", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68275fcde0788d79b436c22f736f4676f9d7018fe552269ba21cf20474b110a3ccd2fff9fae210f975e126930e75e7df549c3f6a9e25752297e9d5845d2eb92a"} 17:58:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:58:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:58:38 INFO - Contents: 17:58:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f078dab6dbca1553c7188afb5c9b1ef6d5b4e83ae4e03e73c7bae58f2fa1f99665146b7387b53a2ec19ce27cf0cdb46529b4109854a32aa26bdcd499f4ac897f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7086aba6c6b8ebd812156e6632a474e045f0d2fc0e4d2cdba8526b53f7c77788790888b38ddeaf27beb42e0d8c3caa82032abb43174a96609c9fc5391b2f30", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0632c141ff6532ce7cb08a6600f664a42ba050e25ae176f747b8faa898f78909ae7e888aff80b8af307779748f8d36639cf175da1096fcc68d63f122d9d9235", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68275fcde0788d79b436c22f736f4676f9d7018fe552269ba21cf20474b110a3ccd2fff9fae210f975e126930e75e7df549c3f6a9e25752297e9d5845d2eb92a"} 17:58:38 INFO - Running post-run listener: copy_logs_to_upload_dir 17:58:38 INFO - Copying logs to upload dir... 17:58:38 INFO - mkdir: /builds/slave/test/build/upload/logs 17:58:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=2482.641384 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 41 mins, 22 secs) (at 2017-05-11 17:58:38.299704) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 17:58:38.303367) ========= 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.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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/Firefox/sha512/f078dab6dbca1553c7188afb5c9b1ef6d5b4e83ae4e03e73c7bae58f2fa1f99665146b7387b53a2ec19ce27cf0cdb46529b4109854a32aa26bdcd499f4ac897f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7086aba6c6b8ebd812156e6632a474e045f0d2fc0e4d2cdba8526b53f7c77788790888b38ddeaf27beb42e0d8c3caa82032abb43174a96609c9fc5391b2f30", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0632c141ff6532ce7cb08a6600f664a42ba050e25ae176f747b8faa898f78909ae7e888aff80b8af307779748f8d36639cf175da1096fcc68d63f122d9d9235", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68275fcde0788d79b436c22f736f4676f9d7018fe552269ba21cf20474b110a3ccd2fff9fae210f975e126930e75e7df549c3f6a9e25752297e9d5845d2eb92a"} build_url:https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011093 build_url: 'https://queue.taskcluster.net/v1/task/FqKUAENdSTOt7TyVMIohig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f078dab6dbca1553c7188afb5c9b1ef6d5b4e83ae4e03e73c7bae58f2fa1f99665146b7387b53a2ec19ce27cf0cdb46529b4109854a32aa26bdcd499f4ac897f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1d7086aba6c6b8ebd812156e6632a474e045f0d2fc0e4d2cdba8526b53f7c77788790888b38ddeaf27beb42e0d8c3caa82032abb43174a96609c9fc5391b2f30", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e0632c141ff6532ce7cb08a6600f664a42ba050e25ae176f747b8faa898f78909ae7e888aff80b8af307779748f8d36639cf175da1096fcc68d63f122d9d9235", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68275fcde0788d79b436c22f736f4676f9d7018fe552269ba21cf20474b110a3ccd2fff9fae210f975e126930e75e7df549c3f6a9e25752297e9d5845d2eb92a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-11 17:58:38.336246) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:58:38.336570) ========= 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.yYCAA4h6B4/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.uALPk5eLJu/Listeners TMPDIR=/var/folders/dm/mjgvqsnx6cj20gvj80clxb7c00000w/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.005151 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-11 17:58:38.385476) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 17:58:38.385832) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-11 17:58:38.386347) ========= ========= Total master_lag: 0.17 =========