builder: cedar_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0104 starttime: 1496703550.64 results: success (0) revision: 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:10.638202) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:10.638632) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:10.638909) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:10.831242) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 15:59:10.831962) ========= 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.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-06-05 15:59:10-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.1M=0s 2017-06-05 15:59:11 (67.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.627848 ========= master_lag: 1.25 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-05 15:59:12.708448) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:12.708719) ========= 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.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.030516 ========= master_lag: 0.24 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:12.982282) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 15:59:12.982593) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo projects/cedar --rev 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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-06-05 15:59:13,086 truncating revision to first 12 chars 2017-06-05 15:59:13,086 Setting DEBUG logging. 2017-06-05 15:59:13,086 attempt 1/10 2017-06-05 15:59:13,087 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/12a17d1ce8e2?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 15:59:13,798 unpacking tar archive at: cedar-12a17d1ce8e2/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002883 ========= master_lag: 1.86 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 15:59:15.841245) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:15.841604) ========= script_repo_revision: 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:15.842019) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:15.842360) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 15:59:15.876464) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-05 15:59:15.876724) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 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', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 15:59:16 INFO - MultiFileLogger online at 20170605 15:59:16 in /builds/slave/test 15:59:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 15:59:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:59:16 INFO - {'append_to_log': False, 15:59:16 INFO - 'base_work_dir': '/builds/slave/test', 15:59:16 INFO - 'blob_upload_branch': 'Cedar', 15:59:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:59:16 INFO - 'branch': 'Cedar', 15:59:16 INFO - 'buildbot_json_path': 'buildprops.json', 15:59:16 INFO - 'config_files': ('talos/mac_config.py',), 15:59:16 INFO - 'default_actions': ('clobber', 15:59:16 INFO - 'read-buildbot-config', 15:59:16 INFO - 'download-and-extract', 15:59:16 INFO - 'populate-webroot', 15:59:16 INFO - 'create-virtualenv', 15:59:16 INFO - 'install', 15:59:16 INFO - 'setup-mitmproxy', 15:59:16 INFO - 'run-tests'), 15:59:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:59:16 INFO - 'download_minidump_stackwalk': True, 15:59:16 INFO - 'download_symbols': 'ondemand', 15:59:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:59:16 INFO - 'tooltool.py': '/tools/tooltool.py', 15:59:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:59:16 INFO - '/tools/misc-python/virtualenv.py')}, 15:59:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:59:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:59:16 INFO - 'gecko_profile': False, 15:59:16 INFO - 'gecko_profile_interval': 0, 15:59:16 INFO - 'installer_path': 'installer.exe', 15:59:16 INFO - 'log_level': 'info', 15:59:16 INFO - 'log_name': 'talos', 15:59:16 INFO - 'log_to_console': True, 15:59:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:59:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:59:16 INFO - 'opt_config_files': (), 15:59:16 INFO - 'pip_index': False, 15:59:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:16 INFO - 'cmd': ('bash', 15:59:16 INFO - '-c', 15:59:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:16 INFO - 'enabled': True, 15:59:16 INFO - 'halt_on_failure': False, 15:59:16 INFO - 'name': 'check_screen_resolution'},), 15:59:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:59:16 INFO - 'cmd': ('bash', 15:59:16 INFO - '-c', 15:59:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:59:16 INFO - 'enabled': True, 15:59:16 INFO - 'halt_on_failure': False, 15:59:16 INFO - 'name': 'check_screen_resolution'},), 15:59:16 INFO - 'run_cmd_checks_enabled': True, 15:59:16 INFO - 'suite': 'dromaeojs-e10s', 15:59:16 INFO - 'system_bits': '32', 15:59:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:59:16 INFO - 'title': 't-yosemite-r7-0104', 15:59:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:59:16 INFO - 'use_talos_json': True, 15:59:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:59:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:59:16 INFO - 'work_dir': 'build'} 15:59:16 INFO - [mozharness: 2017-06-05 22:59:16.091526Z] Running clobber step. 15:59:16 INFO - Running pre-action listener: _resource_record_pre_action 15:59:16 INFO - Running main action method: clobber 15:59:16 INFO - rmtree: /builds/slave/test/build 15:59:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:59:18 INFO - Running post-action listener: _resource_record_post_action 15:59:18 INFO - [mozharness: 2017-06-05 22:59:18.548354Z] Finished clobber step (success) 15:59:18 INFO - [mozharness: 2017-06-05 22:59:18.548497Z] Running read-buildbot-config step. 15:59:18 INFO - Running pre-action listener: _resource_record_pre_action 15:59:18 INFO - Running main action method: read_buildbot_config 15:59:18 INFO - Using buildbot properties: 15:59:18 INFO - { 15:59:18 INFO - "project": "", 15:59:18 INFO - "product": "firefox", 15:59:18 INFO - "who": "mconley@mozilla.com", 15:59:18 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 15:59:18 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs-e10s", 15:59:18 INFO - "repository": "https://hg.mozilla.org/projects/cedar/", 15:59:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs-e10s", 15:59:18 INFO - "stage_platform": "macosx64", 15:59:18 INFO - "basedir": "/builds/slave/test", 15:59:18 INFO - "buildnumber": 0, 15:59:18 INFO - "platform": "yosemite_r7", 15:59:18 INFO - "parent_task_id": "fbZh9LdPQhCaZH982ZkTEQ", 15:59:18 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:59:18 INFO - "slavebuilddir": "test", 15:59:18 INFO - "taskId": "NFQIAcXuQkiWmCoNl8p6VA", 15:59:18 INFO - "branch": "cedar", 15:59:18 INFO - "script_repo_revision": "default", 15:59:18 INFO - "revision": "12a17d1ce8e218ba3570fcd38d24e71b76dd39e9", 15:59:18 INFO - "slavename": "t-yosemite-r7-0104", 15:59:18 INFO - "repo_path": "projects/cedar" 15:59:18 INFO - } 15:59:18 INFO - Finding installer, test and symbols from parent task. 15:59:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NFQIAcXuQkiWmCoNl8p6VA'}, attempt #1 15:59:19 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NFQIAcXuQkiWmCoNl8p6VA'}, attempt #1 15:59:20 INFO - Task dependencies: Ih1tmO8dQQCdMK9iGIaVHA fbZh9LdPQhCaZH982ZkTEQ 15:59:20 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ'}, attempt #1 15:59:20 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:59:20 INFO - Set test_url: https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/test_packages.json 15:59:20 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/target.crashreporter-symbols.zip 15:59:20 INFO - Running post-action listener: _resource_record_post_action 15:59:20 INFO - [mozharness: 2017-06-05 22:59:20.565820Z] Finished read-buildbot-config step (success) 15:59:20 INFO - [mozharness: 2017-06-05 22:59:20.565968Z] Running download-and-extract step. 15:59:20 INFO - Running pre-action listener: _resource_record_pre_action 15:59:20 INFO - Running main action method: download_and_extract 15:59:20 INFO - mkdir: /builds/slave/test/build/tests 15:59:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 15:59:20 INFO - trying https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:59:20 INFO - Downloading https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/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 15:59:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/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 15:59:21 INFO - Downloaded 1328 bytes. 15:59:21 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:59:21 INFO - Using the following test package requirements: 15:59:21 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:59:21 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:59:21 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:59:21 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:59:21 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'jsshell-mac.zip'], 15:59:21 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:59:21 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:59:21 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:59:21 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:59:21 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 15:59:21 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:59:21 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:59:21 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:59:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:59:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:59:21 INFO - Fetch https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:59:23 INFO - Content-Length response header: 57636318 15:59:23 INFO - Bytes received: 57636318 15:59:41 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 15:59:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:59:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:59:41 INFO - Fetch https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:59:43 INFO - Content-Length response header: 57636318 15:59:43 INFO - Bytes received: 57636318 16:00:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 16:00:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:00:01 INFO - Fetch https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 16:00:02 INFO - Content-Length response header: 14447425 16:00:02 INFO - Bytes received: 14447425 16:00:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:02 INFO - trying https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:02 INFO - Downloading https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 16:00:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/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 16:00:04 INFO - Downloaded 67974662 bytes. 16:00:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:04 INFO - mkdir: /builds/slave/test/properties 16:00:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:00:04 INFO - Writing to file /builds/slave/test/properties/build_url 16:00:04 INFO - Contents: 16:00:04 INFO - build_url:https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 16:00:04 INFO - Running post-action listener: _resource_record_post_action 16:00:04 INFO - Running post-action listener: set_extra_try_arguments 16:00:04 INFO - [mozharness: 2017-06-05 23:00:04.459127Z] Finished download-and-extract step (success) 16:00:04 INFO - [mozharness: 2017-06-05 23:00:04.459275Z] Running populate-webroot step. 16:00:04 INFO - Running pre-action listener: _resource_record_pre_action 16:00:04 INFO - Running main action method: populate_webroot 16:00:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:00:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:00:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:00:04 INFO - '--tppagecycles', 16:00:04 INFO - '7'], 16:00:04 INFO - 'tests': ['tsvgm']}}, 16:00:04 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:00:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:00:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:00:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:00:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['damp', 'tps']}, 16:00:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'tests': ['damp', 'tps']}, 16:00:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['dromaeo_dom']}, 16:00:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:00:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 16:00:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 16:00:04 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 16:00:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'other-e10s': {'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['a11yr', 16:00:04 INFO - 'ts_paint', 16:00:04 INFO - 'tpaint', 16:00:04 INFO - 'sessionrestore', 16:00:04 INFO - 'sessionrestore_no_auto_restore', 16:00:04 INFO - 'tabpaint']}, 16:00:04 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 16:00:04 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 16:00:04 INFO - 'bloom_basic_ref']}, 16:00:04 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 16:00:04 INFO - 'talos_options': ['--mitmproxy', 16:00:04 INFO - 'mitmproxy-recording-1.mp', 16:00:04 INFO - '--firstNonBlankPaint'], 16:00:04 INFO - 'tests': ['Quantum_1']}, 16:00:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['tsvgx', 16:00:04 INFO - 'tsvgr_opacity', 16:00:04 INFO - 'tart', 16:00:04 INFO - 'tscrollx', 16:00:04 INFO - 'cart', 16:00:04 INFO - 'tsvg_static']}, 16:00:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:00:04 INFO - 'tsvgr_opacity', 16:00:04 INFO - 'tart', 16:00:04 INFO - 'tscrollx', 16:00:04 INFO - 'cart', 16:00:04 INFO - 'tsvg_static']}, 16:00:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'talos_options': ['--disable-e10s'], 16:00:04 INFO - 'tests': ['tp5o']}, 16:00:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 16:00:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'talos_options': ['--disable-e10s', 16:00:04 INFO - '--xperf_path', 16:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:04 INFO - 'tests': ['tp5n']}, 16:00:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 16:00:04 INFO - 'talos_options': ['--xperf_path', 16:00:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:00:04 INFO - 'tests': ['tp5n']}}, 16:00:04 INFO - 'talos.zip': {'path': '', 16:00:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:00:04 INFO - Running post-action listener: _resource_record_post_action 16:00:04 INFO - [mozharness: 2017-06-05 23:00:04.480711Z] Finished populate-webroot step (success) 16:00:04 INFO - [mozharness: 2017-06-05 23:00:04.480835Z] Running create-virtualenv step. 16:00:04 INFO - Running pre-action listener: _resource_record_pre_action 16:00:04 INFO - Running main action method: create_virtualenv 16:00:04 INFO - Creating virtualenv /builds/slave/test/build/venv 16:00:04 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 16:00:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:00:04 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 16:00:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:00:04 INFO - Using real prefix '/tools/python27' 16:00:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:00:05 INFO - Installing distribute.............................................................................................................................................................................................done. 16:00:08 INFO - Installing pip.................done. 16:00:08 INFO - Return code: 0 16:00:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:00:08 INFO - Reading from file tmpfile_stdout 16:00:08 INFO - Output received: 16:00:08 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) 16:00:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:08 INFO - 'HOME': '/Users/cltbld', 16:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:08 INFO - 'LOGNAME': 'cltbld', 16:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:08 INFO - 'MOZ_NO_REMOTE': '1', 16:00:08 INFO - 'NO_EM_RESTART': '1', 16:00:08 INFO - 'PAGER': '/bin/cat', 16:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:08 INFO - 'PWD': '/builds/slave/test', 16:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:08 INFO - 'SHELL': '/bin/bash', 16:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:08 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:08 INFO - 'USER': 'cltbld', 16:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:08 INFO - 'XPC_FLAGS': '0x0', 16:00:08 INFO - 'XPC_SERVICE_NAME': '0', 16:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:08 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 16:00:08 INFO - Cleaning up... 16:00:08 INFO - Return code: 0 16:00:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:00:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:08 INFO - 'HOME': '/Users/cltbld', 16:00:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:08 INFO - 'LOGNAME': 'cltbld', 16:00:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:08 INFO - 'MOZ_NO_REMOTE': '1', 16:00:08 INFO - 'NO_EM_RESTART': '1', 16:00:08 INFO - 'PAGER': '/bin/cat', 16:00:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:08 INFO - 'PWD': '/builds/slave/test', 16:00:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:08 INFO - 'SHELL': '/bin/bash', 16:00:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:08 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:08 INFO - 'USER': 'cltbld', 16:00:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:08 INFO - 'XPC_FLAGS': '0x0', 16:00:08 INFO - 'XPC_SERVICE_NAME': '0', 16:00:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:09 INFO - Downloading/unpacking psutil>=3.1.1 16:00:09 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 16:00:09 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 16:00:09 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 16:00:09 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 16:00:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:00:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:11 INFO - Installing collected packages: psutil 16:00:11 INFO - Running setup.py install for psutil 16:00:11 INFO - building 'psutil._psutil_osx' extension 16:00:11 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 16:00:12 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 16:00:12 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 16:00:12 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 16:00:12 INFO - building 'psutil._psutil_posix' extension 16:00:12 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 16:00:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:00:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:00:12 INFO - ^ 16:00:12 INFO - 1 warning generated. 16:00:12 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 16:00:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:00:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:00:12 INFO - Successfully installed psutil 16:00:12 INFO - Cleaning up... 16:00:12 INFO - Return code: 0 16:00:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00: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/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:00:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:12 INFO - 'HOME': '/Users/cltbld', 16:00:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:12 INFO - 'LOGNAME': 'cltbld', 16:00:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:12 INFO - 'MOZ_NO_REMOTE': '1', 16:00:12 INFO - 'NO_EM_RESTART': '1', 16:00:12 INFO - 'PAGER': '/bin/cat', 16:00:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:12 INFO - 'PWD': '/builds/slave/test', 16:00:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:12 INFO - 'SHELL': '/bin/bash', 16:00:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:12 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:12 INFO - 'USER': 'cltbld', 16:00:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:12 INFO - 'XPC_FLAGS': '0x0', 16:00:12 INFO - 'XPC_SERVICE_NAME': '0', 16:00:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:00:13 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 16:00:13 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 16:00:13 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 16:00:13 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 16:00:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:00:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:00:14 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:00:14 INFO - Installing collected packages: mozsystemmonitor 16:00:14 INFO - Running setup.py install for mozsystemmonitor 16:00:14 INFO - Successfully installed mozsystemmonitor 16:00:14 INFO - Cleaning up... 16:00:14 INFO - Return code: 0 16:00:14 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:14 INFO - 'HOME': '/Users/cltbld', 16:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:14 INFO - 'LOGNAME': 'cltbld', 16:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:14 INFO - 'MOZ_NO_REMOTE': '1', 16:00:14 INFO - 'NO_EM_RESTART': '1', 16:00:14 INFO - 'PAGER': '/bin/cat', 16:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:14 INFO - 'PWD': '/builds/slave/test', 16:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:14 INFO - 'SHELL': '/bin/bash', 16:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:14 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:14 INFO - 'USER': 'cltbld', 16:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:14 INFO - 'XPC_FLAGS': '0x0', 16:00:14 INFO - 'XPC_SERVICE_NAME': '0', 16:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:15 INFO - Downloading/unpacking jsonschema==2.5.1 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:00:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:16 INFO - Downloading functools32-3.2.3-2.tar.gz 16:00:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:00:16 INFO - warning: no files found matching '*.txt' 16:00:16 INFO - no previously-included directories found matching 'build' 16:00:16 INFO - no previously-included directories found matching 'dist' 16:00:16 INFO - no previously-included directories found matching '.git*' 16:00:16 INFO - Installing collected packages: jsonschema, functools32 16:00:16 INFO - Running setup.py install for functools32 16:00:17 INFO - warning: no files found matching '*.txt' 16:00:17 INFO - no previously-included directories found matching 'build' 16:00:17 INFO - no previously-included directories found matching 'dist' 16:00:17 INFO - no previously-included directories found matching '.git*' 16:00:17 INFO - Successfully installed jsonschema functools32 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:17 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/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:17 INFO - 'HOME': '/Users/cltbld', 16:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:17 INFO - 'LOGNAME': 'cltbld', 16:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:17 INFO - 'MOZ_NO_REMOTE': '1', 16:00:17 INFO - 'NO_EM_RESTART': '1', 16:00:17 INFO - 'PAGER': '/bin/cat', 16:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:17 INFO - 'PWD': '/builds/slave/test', 16:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:17 INFO - 'SHELL': '/bin/bash', 16:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:17 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:17 INFO - 'USER': 'cltbld', 16:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:17 INFO - 'XPC_FLAGS': '0x0', 16:00:17 INFO - 'XPC_SERVICE_NAME': '0', 16:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:00:17 INFO - Cleaning up... 16:00:17 INFO - Return code: 0 16:00:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:17 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/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--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 16:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:17 INFO - 'HOME': '/Users/cltbld', 16:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:17 INFO - 'LOGNAME': 'cltbld', 16:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:17 INFO - 'MOZ_NO_REMOTE': '1', 16:00:17 INFO - 'NO_EM_RESTART': '1', 16:00:17 INFO - 'PAGER': '/bin/cat', 16:00:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:17 INFO - 'PWD': '/builds/slave/test', 16:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:17 INFO - 'SHELL': '/bin/bash', 16:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:17 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:17 INFO - 'USER': 'cltbld', 16:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:17 INFO - 'XPC_FLAGS': '0x0', 16:00:17 INFO - 'XPC_SERVICE_NAME': '0', 16:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:17 INFO - Downloading/unpacking blobuploader==1.2.4 16:00:17 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 16:00:17 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 16:00:17 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 16:00:17 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 16:00:19 INFO - Downloading blobuploader-1.2.4.tar.gz 16:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:00:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:00:19 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:19 INFO - Downloading docopt-0.6.1.tar.gz 16:00:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:00:20 INFO - Installing collected packages: blobuploader, requests, docopt 16:00:20 INFO - Running setup.py install for blobuploader 16:00:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:00:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:00:20 INFO - Running setup.py install for requests 16:00:20 INFO - Running setup.py install for docopt 16:00:20 INFO - Successfully installed blobuploader requests docopt 16:00:20 INFO - Cleaning up... 16:00:20 INFO - Return code: 0 16:00:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:00:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:20 INFO - 'HOME': '/Users/cltbld', 16:00:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:20 INFO - 'LOGNAME': 'cltbld', 16:00:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:20 INFO - 'MOZ_NO_REMOTE': '1', 16:00:20 INFO - 'NO_EM_RESTART': '1', 16:00:20 INFO - 'PAGER': '/bin/cat', 16:00:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:20 INFO - 'PWD': '/builds/slave/test', 16:00:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:20 INFO - 'SHELL': '/bin/bash', 16:00:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:20 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:20 INFO - 'USER': 'cltbld', 16:00:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:20 INFO - 'XPC_FLAGS': '0x0', 16:00:20 INFO - 'XPC_SERVICE_NAME': '0', 16:00:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7Q6r1J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-PRgLpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-wVBlph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-t7t_8b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-yRFNMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JfiUTP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1dirXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-cua7Iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:21 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Ln3x0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Wu5V2j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-xVNdCX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-dbn89Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Lh2RgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-YU_Xz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-G79XoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gJIAu6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:22 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-LBN99T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:22 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:00:22 INFO - Running setup.py install for manifestparser 16:00:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:00:23 INFO - Running setup.py install for mozcrash 16:00:23 INFO - Running setup.py install for mozdebug 16:00:23 INFO - Running setup.py install for mozdevice 16:00:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:00:23 INFO - Running setup.py install for mozfile 16:00:23 INFO - Running setup.py install for mozhttpd 16:00:23 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:00:23 INFO - Running setup.py install for mozinfo 16:00:23 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:00:23 INFO - Running setup.py install for mozInstall 16:00:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Running setup.py install for mozleak 16:00:24 INFO - Running setup.py install for mozlog 16:00:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Running setup.py install for moznetwork 16:00:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Running setup.py install for mozprocess 16:00:24 INFO - Running setup.py install for mozprofile 16:00:24 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:00:24 INFO - Running setup.py install for mozrunner 16:00:24 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:00:25 INFO - Running setup.py install for mozscreenshot 16:00:25 INFO - Running setup.py install for moztest 16:00:25 INFO - Running setup.py install for mozversion 16:00:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:00:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:00:25 INFO - Cleaning up... 16:00:25 INFO - Return code: 0 16:00:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:00:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:25 INFO - 'HOME': '/Users/cltbld', 16:00:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:25 INFO - 'LOGNAME': 'cltbld', 16:00:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:25 INFO - 'MOZ_NO_REMOTE': '1', 16:00:25 INFO - 'NO_EM_RESTART': '1', 16:00:25 INFO - 'PAGER': '/bin/cat', 16:00:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:25 INFO - 'PWD': '/builds/slave/test', 16:00:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:25 INFO - 'SHELL': '/bin/bash', 16:00:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:25 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:25 INFO - 'USER': 'cltbld', 16:00:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:25 INFO - 'XPC_FLAGS': '0x0', 16:00:25 INFO - 'XPC_SERVICE_NAME': '0', 16:00:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:00:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-uMyaRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:00:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-uHgyyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:00:25 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:00:25 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-yfsh3U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:00:26 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)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OUyzXC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 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)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-AFs40e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-IiGUvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2tIiYJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-s8daBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7HxI9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Ws7Met-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-U59aKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:00:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-bbl0P_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:00:26 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)) 16:00:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:00:26 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-x7TXES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:00:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8_E4GY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1mErC5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:00:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-2S06xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:00:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:00:27 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-C0tKSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:00:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.51->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:00:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:28 INFO - Downloading blessings-1.6.tar.gz 16:00:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:00:28 INFO - Installing collected packages: blessings 16:00:28 INFO - Running setup.py install for blessings 16:00:29 INFO - Successfully installed blessings 16:00:29 INFO - Cleaning up... 16:00:29 INFO - Return code: 0 16:00:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:00:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:29 INFO - Reading from file tmpfile_stdout 16:00:29 INFO - Current package versions: 16:00:29 INFO - blessings == 1.6 16:00:29 INFO - blobuploader == 1.2.4 16:00:29 INFO - docopt == 0.6.1 16:00:29 INFO - functools32 == 3.2.3-2 16:00:29 INFO - jsonschema == 2.5.1 16:00:29 INFO - manifestparser == 1.1 16:00:29 INFO - mozInstall == 1.12 16:00:29 INFO - mozcrash == 1.0 16:00:29 INFO - mozdebug == 0.1 16:00:29 INFO - mozdevice == 0.51 16:00:29 INFO - mozfile == 1.2 16:00:29 INFO - mozhttpd == 0.7 16:00:29 INFO - mozinfo == 0.9 16:00:29 INFO - mozleak == 0.1 16:00:29 INFO - mozlog == 3.4 16:00:29 INFO - moznetwork == 0.27 16:00:29 INFO - mozprocess == 0.25 16:00:29 INFO - mozprofile == 0.28 16:00:29 INFO - mozrunner == 6.13 16:00:29 INFO - mozscreenshot == 0.1 16:00:29 INFO - mozsystemmonitor == 0.3 16:00:29 INFO - moztest == 0.8 16:00:29 INFO - mozversion == 1.4 16:00:29 INFO - psutil == 3.1.1 16:00:29 INFO - requests == 1.2.3 16:00:29 INFO - wsgiref == 0.1.2 16:00:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:29 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/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:29 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/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:00:29 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/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:00:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:29 INFO - 'HOME': '/Users/cltbld', 16:00:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:29 INFO - 'LOGNAME': 'cltbld', 16:00:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:29 INFO - 'MOZ_NO_REMOTE': '1', 16:00:29 INFO - 'NO_EM_RESTART': '1', 16:00:29 INFO - 'PAGER': '/bin/cat', 16:00:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:29 INFO - 'PWD': '/builds/slave/test', 16:00:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:29 INFO - 'SHELL': '/bin/bash', 16:00:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:29 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:29 INFO - 'USER': 'cltbld', 16:00:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:29 INFO - 'XPC_FLAGS': '0x0', 16:00:29 INFO - 'XPC_SERVICE_NAME': '0', 16:00:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 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)) 16:00:29 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:00:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:00:31 INFO - Downloading marionette_driver-1.4.0.tar.gz 16:00:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 16:00:31 INFO - Installing collected packages: marionette-driver 16:00:31 INFO - Running setup.py install for marionette-driver 16:00:31 INFO - Successfully installed marionette-driver 16:00:31 INFO - Cleaning up... 16:00:31 INFO - Return code: 0 16:00:31 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:00:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:00:31 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/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 0x1060e0c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1060e4768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa151d7c900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, '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.hhAqqBKacD/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.Id70RTMR3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 16:00:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:00:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:31 INFO - 'HOME': '/Users/cltbld', 16:00:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:31 INFO - 'LOGNAME': 'cltbld', 16:00:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:31 INFO - 'MOZ_NO_REMOTE': '1', 16:00:31 INFO - 'NO_EM_RESTART': '1', 16:00:31 INFO - 'PAGER': '/bin/cat', 16:00:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:31 INFO - 'PWD': '/builds/slave/test', 16:00:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:31 INFO - 'SHELL': '/bin/bash', 16:00:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:31 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:31 INFO - 'USER': 'cltbld', 16:00:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:31 INFO - 'XPC_FLAGS': '0x0', 16:00:31 INFO - 'XPC_SERVICE_NAME': '0', 16:00:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:00:31 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:00:31 INFO - Cleaning up... 16:00:31 INFO - Return code: 0 16:00:31 INFO - Running post-action listener: _resource_record_post_action 16:00:31 INFO - Running post-action listener: _start_resource_monitoring 16:00:31 INFO - Starting resource monitoring. 16:00:31 INFO - [mozharness: 2017-06-05 23:00:31.976725Z] Finished create-virtualenv step (success) 16:00:31 INFO - [mozharness: 2017-06-05 23:00:31.977717Z] Running install step. 16:00:31 INFO - Running pre-action listener: _resource_record_pre_action 16:00:31 INFO - Running main action method: install 16:00:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:00:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:00:32 INFO - Reading from file tmpfile_stdout 16:00:32 INFO - Detecting whether we're running mozinstall >=1.0... 16:00:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:00:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:00:32 INFO - Reading from file tmpfile_stdout 16:00:32 INFO - Output received: 16:00:32 INFO - Usage: mozinstall [options] installer 16:00:32 INFO - Options: 16:00:32 INFO - -h, --help show this help message and exit 16:00:32 INFO - -d DEST, --destination=DEST 16:00:32 INFO - Directory to install application into. [default: 16:00:32 INFO - "/builds/slave/test"] 16:00:32 INFO - --app=APP Application being installed. [default: firefox] 16:00:32 INFO - mkdir: /builds/slave/test/build/application 16:00:32 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'] 16:00:32 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 16:00:54 INFO - Reading from file tmpfile_stdout 16:00:54 INFO - Output received: 16:00:54 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:00:54 INFO - Running post-action listener: _resource_record_post_action 16:00:54 INFO - [mozharness: 2017-06-05 23:00:54.030206Z] Finished install step (success) 16:00:54 INFO - [mozharness: 2017-06-05 23:00:54.030337Z] Running setup-mitmproxy step. 16:00:54 INFO - Running pre-action listener: _resource_record_pre_action 16:00:54 INFO - Running main action method: setup_mitmproxy 16:00:54 INFO - Skipping: mitmproxy is not required 16:00:54 INFO - Running post-action listener: _resource_record_post_action 16:00:54 INFO - [mozharness: 2017-06-05 23:00:54.031035Z] Finished setup-mitmproxy step (success) 16:00:54 INFO - [mozharness: 2017-06-05 23:00:54.031174Z] Running run-tests step. 16:00:54 INFO - Running pre-action listener: _resource_record_pre_action 16:00:54 INFO - Running main action method: run_tests 16:00:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:00:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:00:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:00:54 INFO - 2017-06-05 16:00:54.045 screenresolution[1626:8252] starting screenresolution argv=screenresolution get 16:00:54 INFO - 2017-06-05 16:00:54.048 screenresolution[1626:8252] Display 0: 1600x1200x32@60 16:00:54 INFO - 2017-06-05 16:00:54.054 screenresolution[1627:8255] starting screenresolution argv=screenresolution list 16:00:54 INFO - Available Modes on Display 0 16:00:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:00:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:00:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:00:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:00:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:00:54 INFO - Intel Iris: 16:00:54 INFO - Chipset Model: Intel Iris 16:00:54 INFO - Type: GPU 16:00:54 INFO - Bus: Built-In 16:00:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:00:54 INFO - Vendor: Intel (0x8086) 16:00:54 INFO - Device ID: 0x0a2e 16:00:54 INFO - Revision ID: 0x0009 16:00:54 INFO - Displays: 16:00:54 INFO - Display: 16:00:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:00:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:00:54 INFO - Display Serial Number: 1600x1200 60 16:00:54 INFO - Main Display: Yes 16:00:54 INFO - Mirror: Off 16:00:54 INFO - Online: Yes 16:00:54 INFO - Rotation: Supported 16:00:54 INFO - Return code: 0 16:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:00:54 INFO - Python 2.7.3 16:00:54 INFO - Return code: 0 16:00:54 INFO - grabbing minidump binary from tooltool 16:00:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.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')]} 16:00:54 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 0x106069c60>, '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 0x105dfecd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105e27be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:00:54 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 16:00:54 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 16:00:54 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 16:00:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:00:54 INFO - Return code: 0 16:00:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:00:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:00:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:00:54 INFO - ENV: RUST_BACKTRACE is now 1 16:00:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:00:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:00:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 16:00:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 16:00:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hhAqqBKacD/Render', 16:00:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:00:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:00:54 INFO - 'HOME': '/Users/cltbld', 16:00:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:00:54 INFO - 'LOGNAME': 'cltbld', 16:00:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:00:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:00:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:00:54 INFO - 'MOZ_NO_REMOTE': '1', 16:00:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:00:54 INFO - 'NO_EM_RESTART': '1', 16:00:54 INFO - 'PAGER': '/bin/cat', 16:00:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:00:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:00:54 INFO - 'PWD': '/builds/slave/test', 16:00:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:00:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:00:54 INFO - 'RUST_BACKTRACE': '1', 16:00:54 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 16:00:54 INFO - 'SHELL': '/bin/bash', 16:00:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Id70RTMR3i/Listeners', 16:00:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 16:00:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:00:54 INFO - 'USER': 'cltbld', 16:00:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:00:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:00:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:00:54 INFO - 'XPC_FLAGS': '0x0', 16:00:54 INFO - 'XPC_SERVICE_NAME': '0', 16:00:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:00:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 16:00:54 INFO - mozversion application_buildid: 20170605143356 16:00:54 INFO - mozversion application_changeset: 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 16:00:54 INFO - mozversion application_display_name: Nightly 16:00:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:00:54 INFO - mozversion application_name: Firefox 16:00:54 INFO - mozversion application_remotingname: firefox 16:00:54 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 16:00:54 INFO - mozversion application_vendor: Mozilla 16:00:54 INFO - mozversion application_version: 55.0a1 16:00:54 INFO - mozversion platform_buildid: 20170605143356 16:00:54 INFO - mozversion platform_changeset: 12a17d1ce8e218ba3570fcd38d24e71b76dd39e9 16:00:54 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 16:00:54 INFO - mozversion platform_version: 55.0a1 16:00:54 INFO - using testdate: 1496703654 16:00:54 INFO - actual date: 1496703654 16:00:54 INFO - starting webserver on 'localhost:49227' 16:00:54 INFO - SUITE-START | Running 2 tests 16:00:54 INFO - TEST-START | dromaeo_css 16:00:54 INFO - operating with platform_type : mac_ 16:00:54 INFO - Initialising browser for dromaeo_css test... 16:00:54 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpes5Bx5/profile 16:00:54 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 16:01:28 INFO - TEST-INFO | 1637: killed by SIGTERM 16:01:28 INFO - Browser initialized. 16:01:28 INFO - Running cycle 1/1 for dromaeo_css test... 16:01:28 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpes5Bx5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:01:30 INFO - PID 1645 | 2017-06-05 16:01:30.805 plugin-container[1646:8749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e43, name = 'com.apple.tsm.portname' 16:01:30 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:30 INFO - PID 1645 | 2017-06-05 16:01:30.805 plugin-container[1646:8749] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa30b, name = 'com.apple.CFPasteboardClient' 16:01:30 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:30 INFO - PID 1645 | 2017-06-05 16:01:30.805 plugin-container[1646:8749] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:01:31 INFO - PID 1645 | 2017-06-05 16:01:31.299 plugin-container[1647:8796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 16:01:31 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:31 INFO - PID 1645 | 2017-06-05 16:01:31.300 plugin-container[1647:8796] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0f, name = 'com.apple.CFPasteboardClient' 16:01:31 INFO - PID 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:01:31 INFO - PID 1645 | 2017-06-05 16:01:31.300 plugin-container[1647:8796] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:01:40 INFO - PID 1645 | RSS: Main: 267747328 16:01:40 INFO - PID 1645 | 16:04:42 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 16:04:44 INFO - PID 1645 | RSS: Main: 268947456 16:04:44 INFO - PID 1645 | 16:07:46 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 16:07:47 INFO - PID 1645 | RSS: Main: 284295168 16:07:47 INFO - PID 1645 | 16:10:49 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 16:10:49 INFO - PID 1645 | RSS: Main: 283107328 16:10:49 INFO - PID 1645 | 16:13:51 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 16:13:52 INFO - PID 1645 | RSS: Main: 287477760 16:13:52 INFO - PID 1645 | 16:16:54 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 16:16:55 INFO - PID 1645 | RSS: Main: 287985664 16:16:55 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 16:19:57 INFO - PID 1645 | RSS: Main: 294187008 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | __start_tp_report 16:19:57 INFO - PID 1645 | _x_x_mozilla_page_load 16:19:57 INFO - PID 1645 | _x_x_mozilla_page_load_details 16:19:57 INFO - PID 1645 | |i|pagename|runs| 16:19:57 INFO - PID 1645 | |0;dojo.html;2949.8377589232596;3568.286342731454;3582.6596473335057;3598.19040715839;3604.963950360496;4703.764811759411;5601.271834661488;6040.516758659308;6188.226471691039;6205.286392064908;6129.4199191187845;6130.570860039799;6134.773364742522;6221.066839974006;6223.222097237845;5072.8731781705565;5206.843794686166;5246.396664383587;5270.341207349082;5291.629585928987;5354.036273470773;5364.275822763916;5376.650517716355;5379.085555455582;5396.082665946778;4351.477822661285;5261.289725886994;5307.52232299095;5331.600129990227;5376.1129413647095;5087.745612719386;5406.297181366436;5408.188771684239;5427.267318911975;5432.185172224158;5334.759935802898;5345.091334473149;5361.356637203521;5361.731913404353;5393.946060539383;6055.152278680988;6132.969335153296;6201.906971395432;6223.222097237845;6229.470495007885;6465.256495502983;6480.935190648081;6546.18172728409;6645.501587380917;6649.135612370409;6767.221769496522;6774.9661251693915;6783.186017677861;6802.65986700668;6806.863862722717;5689.886202275973;5921.171036054956;6307.211598550181;6330.620162790247;6397.96801015992;6132.4778862607845;6580.5722628029025;6655.267920528738;6884.311568843075;6923.411510021604;6283.7800676976085;6300.8424789379715;6446.580972236789;6533.444657204191;6545.05096760967;6756;6768.289329620417;6770.187577490733;6779.118714567075;6798.52410331272;6598.241202261705;6619.106420633249;6639;6662.200535935622;6666.366695163952;5323.494268044529;5484.204790305464;6397.488200943913;6457.547971642034;6578.2106147261675;5285.814996475105;5323.387810401776;5342.519173274424;5346.97326513365;5388.353397592314;4712.552307530779;4731.1720448921305;4739.478657347689;4757.26262429324;4784.712917224978;6225.62646241227;6237.157983672237;6260.436560709467;6266.216722909637;6341.55609107358;5558.805441809598;5684.403137670485;6773.254941956381;6784.457243420515;6785.525013249124;6354.078658594478;6354.205724284465;6367.840803979845;6382.329855365213;6417.839554011187;5712.371639119693;5795.536357091422;5945.494632956247;5959.433853783884;5966.880662386728;6705.832354191184;6717.596944183365;6780.32196780318;6844.623545704835;6877.484188685969;6631.5689480185565;6737.59574425536;6747.763828265987;6748.628825414649;6755.1556055493065;6861.4510839133745;6894.207166175954;6919.6886140124825;6924.58452492852;6930.13373328334;5372.5433338166695;6321.209848768904;6404.551681382445;6486.383793539606;6556.573822701509;6676.799696009128;6684.197896252286;6692.5315227935525;6704.631245281556;6711.261761206361;6287.685615719287;6357.96821015892;6372.840678983062;6509.479241660751;6523.836904077436;6471.967640161769;6497.480201583768;6527.11883895668;6539.11721917535;6542.313057129123;6295.087212354275;6408.903866441914;6409.839754006187;6427.967860160857;6431.678416079084;6332.52506062038;6528.771492997722;6539.869202616016;6548.738050477928;6558.081868538504;6259.655718935503;6304.464120549805;6340.841478963062;6365.809025729235;6382.489400848014 16:19:57 INFO - PID 1645 | |1;ext.html;774.6707649249071;821.5153059694779;842.6334544473154;1250.3560666256874;1308.5093090091216;34691.13272168197;39438;40267.791966240984;40908.18183636329;41590.792046039765;41187.38218926711;41501.79249103758;41524.97887806266;41558;41582.792086039604;7871.960640196791;8070.2333278338465;8271.710490132846;8497.150284971514;8507.276881465083;8376.49741015538;8494.74515764528;8510.617022233984;8516.02065762436;8532.616032278562;8479.152084791533;8490.702825401113;8569.828603427928;8574.314054875626;8583.95708021459;8447.493150410934;8482.151784821499;8527.147285271485;8527.872081918777;8536.487810731376;7200.351968322823;7904.841903161904;8212.794680133044;8228.465149765247;8290.792730181733;38481.80759096215;38686.80656596699;38748.61251387478;38815;38815.22369552593;3048.2684155802667;3084.475639141329;3535.805530695811;3577.8568857245677;3578.4453409721536;30591.847040764875;30716.2320941976;30747.538786918216;30759.231019224473;30861.382772344426;3076.446239676857;3102.37952409519;3114.9065528034193;3115.1121930249997;3124.218945263684;231804;235577;260555.69722151457;273753.63123184454;276000;48795.5120448791;48882.5111748878;49155;49383.75308123436;49399;51207.41548091598;51262;51366.48633513692;51525.7423712879;51716.74141629268;12852.871471285354;12932.353382330846;13066.412011459508;13830.723385532234;13958.302084895715;10783.730406739738;10818.94590527058;10897.945510272399;10932.289401189057;10936.726581835359;13779.89760819132;13797;13813.825824805004;13882.861171388156;13998.650033749034;6029.6683682397015;6092.634441933409;6107.908381374283;6130.264368275835;6134.4172303631085;995.955182016811;998.3211416237028;1020.9540570674337;1040.9479526023667;1043.6347278452633;22944.901491145778;23227.070917163124;23346.766532334797;24164.758352416604;24542.141025064393;1073.3023534702506;1080.2654195147375;1125.5272785430138;1128.0468004536153;1139.7036770439745;11205.71985700364;11886.108541859396;12510;12834.607673901457;12875.871241287654;333688;334090;335745;335766;336128.31935840164;54780.72609636926;55177;55290.17064744032;55467.72266138643;55472.445275547536;1438.5828109848237;1471.6247356923702;1474.7787831825312;1481.274175653922;1500.3323521033253;22456.5508689822;22589.774102259424;23343.76656233416;24108;24361.26916192517;38892;39006.80496597499;39159.80420097881;45915.31127033164;47234.055318892744;253268;255436;256056;256195;262149;35944.46083308805;36909.630903691694;37229.81385093057;37517.1227740392;37937.810310949375;48063.75968120137;48438.5156148434;49820;50092.74953625354;50266;31237.531437028916;31977.840110799298;32795.080737889504;33433;33683.66316336805;31139.09843514903;32049.519257210693;32124.678753213102;32884.177895552806;33013.844515441844;39085.41371879481;40010.79994600009;40951.59048409478;41061.38407923824;41251.38122928218;31440.528392074593;32650.34699306048;33236.83381583077;33648.49527257044;34306.62773489132;22441.438964025376;22467.775322247224;25843.966241350136;26090.478190435704;26164.82258298419 16:19:57 INFO - PID 1645 | |2;jquery.html;430.33513222071895;433.9500957389901;441.7371663860004;448.49992258631613;457.2934815709728;21181.470463238427;21636.567268654617;22440.775592244074;22475.438114047138;24098;22422.88788556059;23576;24100.397490062714;24101.87949060252;24129.75870241297;6147.354527774586;6283.183186185813;6312.589681670676;6319.74721011159;6320;6231.5326350523665;6296.4018418250425;6300.149479820235;6301.842453938642;6313.652749098797;5802.419758024206;6087.11736798164;6139.355367686396;6141.938580614204;6148.385161483839;6092.390760923917;6131.325554189036;6133.325334213233;6145.877082458371;6161.7535298588;5410.729463526847;5429.294191755086;5445.727713614304;5471.534919531845;5482.342118945712;25571.97712091514;28393;28863.278418039507;28864;28927.842886284732;2066.2974588639936;2225.3880182949656;2226.2096955580696;2235.5528894221056;2236.888155592214;18219.362322318786;18279.90860045691;18367.72448413275;18489;18503.444896653124;2121.5226574020876;2158.881261530615;2196.3191410662744;2198.472366631997;2209.5359974405396;124758;160071.99820004593;164454;166335.3366466332;191850;12722.045846561545;12967;13167.934160329138;13221.669458263621;13269.73460530784;16691.833081669272;16913.661726765396;16948.57628559296;16991;17034.574135646708;8852.159044890854;8870.979837318662;9112.498812565373;9159.450432974043;9179.77050573741;8622.439541429787;8636.309095272363;8663.523506207219;8719.65121395137;8736.606852691644;8375.664973401117;8402.663893444194;8426.483233018116;8447.788805279793;8501.14988501145;2264.29806759905;2265.501589650274;2276.46503071778;2279.9544009119727;2454.4536941726956;913.8583519554479;927.679950417105;939.389396892012;978.1392374710209;983.2674657380297;15074.472393466176;21372.824494652632;21980;21990;22422.551548968928;874.7987962768599;944.9196818270386;947.554649314821;955.6607404371487;994.7811481474064;10122.797544049077;10251.58993640261;10304;10531.315464494783;10761.30051546647;532903;537211;543258;543639;544342;134719.32640336736;136873;136915;138572.614273858;140660;981.2003217377815;1009.2279406254221;1038.1435315864442;1050.3802756373775;1129.0910816792561;21551.8922405387;22498.77501224967;23618.055277788695;24141.396465088514;24165;62060.827871024296;62982.74034519376;67287;69486;69521.65239173974;69914.95127573192;88077;88669.55665221633;88898;89792.10207898098;9218.953905230432;10058.647947321808;10291.43397113136;10591;10611.09805666527;18220.271189152816;18304.450866474028;18508;18524.44426667202;18605;14867.405303787727;14992.625184370478;14994.625134371729;15057.472988445352;15168.24158792034;11347.432628368384;13255.469781208643;13313;13380.264085475392;13562.728745424838;14924.328405221573;15225.543233703007;15330.540083797503;15392.845536585024;15411.922940385226;10375.688729338131;10391.324563903323;10487.632932847313;10491.475426228506;10507.688308572102;12403.441845116811;14673.899457540514;14854.405823766929;14876.330565124812;14956.953013288965 16:19:57 INFO - PID 1645 | |3;mootools.html;1235.6354875311781;1257.3775980889461;1423.58715972368;1435.0456946130823;1445.1256989521337;11742.471588778504;11927.463264153112;12452.439640216178;12492.500299988002;12551.30967796773;10108.888022317538;10710.143188544916;11721.941390293037;11978.76042479152;12186.146969712123;2434.6713193718797;2451.669024681672;2469.1234611712853;2471.5921872890954;2507.2979565721625;2511.2717311979595;2530.5192013517467;2579.6904371475357;2581.2127301173136;2595.221433569931;2156.946076348096;2469.7404323794904;2505.6241563765398;2507.147569826259;2510.535550923085;2433.9756602433927;2495.8252922295446;2515.6792683840945;2529.215943057649;2538.7207407185197;2467.247489515697;2509.4102885822;2525.595904655246;2529.582618867885;2578.6147813272646;11743.295402275891;11869.643910682607;11880.702982425422;11884.10869184806;11916.225445346025;6486.578372405778;6682.331766823328;6713.597184168917;6765.830854228634;6768.221654509745;4175.31107367284;4243.814435565686;4332.826686932519;4333.8699839004885;4351;1253.2417887178308;1255.5919326219016;1260.6659235302652;1273.8025606030988;1279.5969269680022;15556.655319106334;16016.279267432994;16694.248758805883;16797.58006049846;16858.578535536708;5586.888262234814;5636.0982242841765;5715.599908006399;5795.913061304083;5803.071508558694;12591.118621696392;12594.307313097685;12610.369481525888;12611.747765044647;12621.432035558422;6483.2544257411;6644.501662375341;6644.767433139817;6647.135872336566;6661.833454163588;6100.23747031621;6212.192414986113;6254.749810007549;6263.937360626426;6281.842953926189;4343.891402714894;4443.622292105208;4493.820247190141;4505.5269196734525;4547.613452856544;1262.0030176160913;1272.8663490333568;1283.8138469921996;1291.393045268741;1296.3647812571771;808.9191080891864;811.9106898241189;834.1575009240589;845.6321500147333;859.3941271403645;5692.402297758758;6442.549021568445;6468.676566171673;6733.8989915151315;6758.256591774901;496.9031038947443;588.7291845750917;598.5421152818097;614.3089024847047;618.4155972605935;3720.3861362874823;4203.369494575838;4334.739915605074;4645.86062418128;4871.805127794849;14801.851981480262;15061.548153555412;15066;15278.541643750483;15323.46367877119;14819;15275.388984440498;15326.386944522093;15473.22633868302;15487.690246195012;8619.913800862036;8633.179847914438;8641.567921603895;8715.91284087151;8747.868781968276;6241.937580624317;7313.926860731324;7425.7029718810645;7675.155732869492;7765.5728934906865;10467.738306542398;13008.674783130498;13617.795733063815;13718.45126194941;13901.721965560835;13680.452781888613;14203.715925681636;14296.57110286693;14385.568432947028;14390.992630515764;9509.857352139585;9828.361156524803;9840.065193806577;9841.45871977048;9852.605895764089;9926.404415735078;10030.799384012133;10696.679099626712;10736.355818650905;10865.402402867601;9234.769130771785;9286.814263714823;9333.346665733605;9341.906580934103;9443.811123777348;7905.4070944680525;8582.485050896967;9006.63973441055;9012.909870901207;9085.318601104811;8225.424220304521;8242.793930151794;8287.12985136552;8304.916950830415;8306.252437280673;5397.325334333208;6216.285127210429;6483.09236706852;6502.934970650233;6748.5613435128525;6104.969475152596;6212.417100098756;6519.380658837403;6601.867962640817;6608.636524991171 16:19:57 INFO - PID 1645 | |4;prototype.html;324.3852898756857;402.46271227910745;404.0323425396176;404.9392591111335;408.95910408959105;5805.042168042268;6375.776847810327;6450.709718062681;6452.257990331113;6490.878292284398;5428.782848686048;5913.919395617021;6211.968940155304;6224.84437889053;6225.937740622592;4457.531959144292;4594.518959761741;4618.468876079261;4622.699524530912;4636.049609829994;4607.63138948885;4608.907821843561;4647.7676116194225;4652.953470465286;4657.301404789275;4556.931646025312;4561.361409402686;4567.292069729181;4574.290984897346;4600.516945720701;3821.006538300034;4584.426946631671;4587.128445595343;4589.082183563284;4596.402467679214;3330.600327960636;3724.925501489955;3944.5996671181606;3974.721769476138;4011.959880401217;5077.619178561586;5082.872928176788;6173.074038894157;6181;6208.099825525319;4197.18154959783;4927.669529227089;5472.534834539069;5529.170624406331;5565.777368905239;6729.798106056777;6785.982102684637;6791;6814.454843612499;6819.965900170517;3854.325493038707;4536.092781443725;4548.181327361073;4559.703619264737;4565.201089809303;6846.965765171192;7045.612491312976;7197.832953500495;7315.634218289064;7336.486445948785;4144.8134833932545;4205.053862880888;4536.228841097021;4559.772011399416;4604.194266003436;3924.9607503925167;4129.834806607702;4336.2845130553105;4623.8844028898875;4710.740909249956;5015.498450154955;5459.808906688327;5465.699386533779;5469.371022332403;5478.397376288605;5849.590528662953;5899.144624029577;6608.405243528009;6623.701933413008;6797.6601169941305;1451.151076620189;1521.7565189569616;1599.1524492019016;1600.927958241882;1607.4052600537875;976.5654283843621;1000.3397757480174;1005.1456262177092;1057.751428414322;1067.4235912607105;5826.592138550261;6191.721372538246;6489.902651460232;6517.348265173539;6522.510811689142;6979.232284448706;7014.859702805916;7064.540804847668;7098.716051357887;7110.075690160246;5714.085746280575;6240.063990401385;6481.4166724994975;6489.935100648933;6496.675166241669;3075.3541756230993;3621.5916415583342;3693.372126738461;3694.799190263175;3753.249350129985;6891.827704307332;7103.644817759091;7266.63666816657;7299.927000729925;7309.817254568572;6260.467860231931;7262.055932728817;7267.963660181771;7306.96346518264;7315.378192853667;6730.32696730323;7214.907676773677;7226.819329516804;7258.709651613982;7262.620102180569;6511.413972742476;7065.505414620927;7083.82290442743;7120.928790712027;7152;6266.655333956676;6453.419192272718;6588.143541339595;6937.410320122846;6959.443244540324;6023.789167379121;6622.205335359788;7179.784606461814;7236.529625574532;7246.4202863771825;4347.3261644445165;4361.651067914496;4568.921140880958;4613.331066995334;4675.719456832601;5502.972485137549;6762.396477356352;6907.447404207752;6987.829006535026;7136.571805691675;5448.482394172547;6782.457403407814;6810.944303632945;6818.761343352959;6827.556208846409;6791.422729068085;6802.965985170043;6806.829829254309;6811.216710078206;6856.668732630929;6676.332366763285;6681.265060843206;6685.331466853276;6685.665716714242;6752.561083529555;5774.960507108761;6523;6703.229128650268;6737.698527721479;6882.724691012303;6059.242594675666;6144.969275153596;6298.779542715983;6365.777197798055;6400.455961243347 16:19:57 INFO - PID 1645 | |5;yui.html;827.5324441690448;838.6351936907446;844.2528362399279;865.2558799432487;873.3100850328241;13614.387352569134;14289.928550357234;14841.703165936675;16805.41181058662;16909.915450422744;16855.494335169966;17431.21559529821;17647;17696.557586060357;17715.73426398605;950.7195377363677;982.8918818929913;986.3046552180696;993.1806259835629;1006.2302338710892;902.6028547439139;976.9755756106083;996.3772642098688;1003.2225025605178;1005.6027868991744;1024.55943944104;1029.3669393323128;1029.3978022856638;1031.6698656429946;1038.6312858935082;1011.7925825205815;1022.0852337158276;1023.2172388123055;1027.6762819711805;1034.7154532503585;750.6059318857589;767.2864236260276;768.8423873106;775.255754475704;791.5409062743595;17337.826621733748;17665.38171164003;17756.733648995276;17908.91045544777;17937.461876143738;17480.300787968466;17642.294308227654;17989.190486428142;18045.097745112693;18050.90974545119;14377.993540452173;14694.971352005365;14722.779158312635;14727.705445891022;14819.77770333446;3571.607123216444;3583.928321433583;3590.533230680021;3624.9818750906074;3629.201575653352;153853;154914.22542887327;156926;156998;157902.42097578992;13545.322733863464;13658.521951731642;13676.316184190751;13750.381232844546;13829.30853457323;26467.602985955225;26954.32614184661;27061.594076088873;27236.45527089487;27463.725362746118;6754.493412994045;6953.235144134142;7524.811879702942;7792.649330780015;7855.096663883613;3596.9029446018694;3670.504481894963;3935.330993731073;4014.698897582693;4016.116454380032;3696.2237930034994;3931.66580840626;4213.325867861127;4217.746935183899;4246.341817018305;757.3070640364116;940.9529523523796;945.5225111318878;956.3496822160859;959.3476436023432;651.501601275025;665.1519312876121;674.6930146783202;675.8040168351222;699.1121275979477;10790.406527640898;11677.35774532392;12186.08604354677;12205.69485762863;12441.066919981038;607.3652648573858;651.2868409092036;664.7341063574512;676.5331920974512;686.0600976661906;4489.281714925596;5042.974785126051;5055.7219352935945;5378.650387724785;5692.7722891083895;137135.94296085567;141857;143376.28311858373;143418;147114;122532.54934901252;126893.7310626882;127427.36286318509;127739;129211;96269;96639.51680241694;101990.47014294792;102955.4852225749;103122.10973628296;10675.679729608124;10988.450577471254;11293.43532823372;11621.128415368712;11720.062395008208;43467;45561;45947.540524595664;46853.82865428255;47016.88780458518;51473.48526514687;54417.63955901134;58816.70591647185;59393.70303148457;59414;14502;14592.562223133324;14818.407263709762;14941.103533788008;15087.170205638795;15965.281562329956;16252.837471625133;16435.26041328119;18359.35742249015;18563.535911602317;15533.844661553692;16692.165391730123;16780.32878684839;17394.304227830744;17563.121843908008;16966.57583560421;17065.573360666083;17067.914660426617;17074.573135671708;17191.7421238679;16396.098214597834;16439.342426302814;16813.907096038718;16934.745978810573;16980.32078716887;9273.768155796159;10290.948545257226;10317.793644127225;10828.837567436338;11178.329300242012;12845.935770321088;12894.29081400532;13050.869491304966;13119.606411807661;13221.338933053117 16:19:57 INFO - PID 1645 | __end_tp_report 16:19:57 INFO - PID 1645 | __start_cc_report 16:19:57 INFO - PID 1645 | _x_x_mozilla_cycle_collect,450 16:19:57 INFO - PID 1645 | __end_cc_report 16:19:57 INFO - PID 1645 | __startTimestamp1496704797496__endTimestamp 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | ------- Summary: start ------- 16:19:57 INFO - PID 1645 | Number of tests: 6 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#0] dojo.html Cycles:165 Average:6071.88 Median:6340.84 stddev:759.14 (12.0%) stddev-sans-first:720.88 16:19:57 INFO - PID 1645 | Values: 2949.8 3568.3 3582.7 3598.2 3605.0 4703.8 5601.3 6040.5 6188.2 6205.3 6129.4 6130.6 6134.8 6221.1 6223.2 5072.9 5206.8 5246.4 5270.3 5291.6 5354.0 5364.3 5376.7 5379.1 5396.1 4351.5 5261.3 5307.5 5331.6 5376.1 5087.7 5406.3 5408.2 5427.3 5432.2 5334.8 5345.1 5361.4 5361.7 5393.9 6055.2 6133.0 6201.9 6223.2 6229.5 6465.3 6480.9 6546.2 6645.5 6649.1 6767.2 6775.0 6783.2 6802.7 6806.9 5689.9 5921.2 6307.2 6330.6 6398.0 6132.5 6580.6 6655.3 6884.3 6923.4 6283.8 6300.8 6446.6 6533.4 6545.1 6756.0 6768.3 6770.2 6779.1 6798.5 6598.2 6619.1 6639.0 6662.2 6666.4 5323.5 5484.2 6397.5 6457.5 6578.2 5285.8 5323.4 5342.5 5347.0 5388.4 4712.6 4731.2 4739.5 4757.3 4784.7 6225.6 6237.2 6260.4 6266.2 6341.6 5558.8 5684.4 6773.3 6784.5 6785.5 6354.1 6354.2 6367.8 6382.3 6417.8 5712.4 5795.5 5945.5 5959.4 5966.9 6705.8 6717.6 6780.3 6844.6 6877.5 6631.6 6737.6 6747.8 6748.6 6755.2 6861.5 6894.2 6919.7 6924.6 6930.1 5372.5 6321.2 6404.6 6486.4 6556.6 6676.8 6684.2 6692.5 6704.6 6711.3 6287.7 6358.0 6372.8 6509.5 6523.8 6472.0 6497.5 6527.1 6539.1 6542.3 6295.1 6408.9 6409.8 6428.0 6431.7 6332.5 6528.8 6539.9 6548.7 6558.1 6259.7 6304.5 6340.8 6365.8 6382.5 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#1] ext.html Cycles:180 Average:44473.53 Median:33995.15 stddev:74671.16 (219.7%) stddev-sans-first:74808.54 16:19:57 INFO - PID 1645 | Values: 774.7 821.5 842.6 1250.4 1308.5 34691.1 39438.0 40267.8 40908.2 41590.8 41187.4 41501.8 41525.0 41558.0 41582.8 7872.0 8070.2 8271.7 8497.2 8507.3 8376.5 8494.7 8510.6 8516.0 8532.6 8479.2 8490.7 8569.8 8574.3 8584.0 8447.5 8482.2 8527.1 8527.9 8536.5 7200.4 7904.8 8212.8 8228.5 8290.8 38481.8 38686.8 38748.6 38815.0 38815.2 3048.3 3084.5 3535.8 3577.9 3578.4 30591.8 30716.2 30747.5 30759.2 30861.4 3076.4 3102.4 3114.9 3115.1 3124.2 231804.0 235577.0 260555.7 273753.6 276000.0 48795.5 48882.5 49155.0 49383.8 49399.0 51207.4 51262.0 51366.5 51525.7 51716.7 12852.9 12932.4 13066.4 13830.7 13958.3 10783.7 10818.9 10897.9 10932.3 10936.7 13779.9 13797.0 13813.8 13882.9 13998.7 6029.7 6092.6 6107.9 6130.3 6134.4 996.0 998.3 1021.0 1040.9 1043.6 22944.9 23227.1 23346.8 24164.8 24542.1 1073.3 1080.3 1125.5 1128.0 1139.7 11205.7 11886.1 12510.0 12834.6 12875.9 333688.0 334090.0 335745.0 335766.0 336128.3 54780.7 55177.0 55290.2 55467.7 55472.4 1438.6 1471.6 1474.8 1481.3 1500.3 22456.6 22589.8 23343.8 24108.0 24361.3 38892.0 39006.8 39159.8 45915.3 47234.1 253268.0 255436.0 256056.0 256195.0 262149.0 35944.5 36909.6 37229.8 37517.1 37937.8 48063.8 48438.5 49820.0 50092.7 50266.0 31237.5 31977.8 32795.1 33433.0 33683.7 31139.1 32049.5 32124.7 32884.2 33013.8 39085.4 40010.8 40951.6 41061.4 41251.4 31440.5 32650.3 33236.8 33648.5 34306.6 22441.4 22467.8 25844.0 26090.5 26164.8 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#2] jquery.html Cycles:180 Average:36954.66 Median:2264.90 stddev:92369.85 (4078.3%) stddev-sans-first:92588.26 16:19:57 INFO - PID 1645 | Values: 430.3 434.0 441.7 448.5 457.3 21181.5 21636.6 22440.8 22475.4 24098.0 22422.9 23576.0 24100.4 24101.9 24129.8 6147.4 6283.2 6312.6 6319.7 6320.0 6231.5 6296.4 6300.1 6301.8 6313.7 5802.4 6087.1 6139.4 6141.9 6148.4 6092.4 6131.3 6133.3 6145.9 6161.8 5410.7 5429.3 5445.7 5471.5 5482.3 25572.0 28393.0 28863.3 28864.0 28927.8 2066.3 2225.4 2226.2 2235.6 2236.9 18219.4 18279.9 18367.7 18489.0 18503.4 2121.5 2158.9 2196.3 2198.5 2209.5 124758.0 160072.0 164454.0 166335.3 191850.0 12722.0 12967.0 13167.9 13221.7 13269.7 16691.8 16913.7 16948.6 16991.0 17034.6 8852.2 8871.0 9112.5 9159.5 9179.8 8622.4 8636.3 8663.5 8719.7 8736.6 8375.7 8402.7 8426.5 8447.8 8501.1 2264.3 2265.5 2276.5 2280.0 2454.5 913.9 927.7 939.4 978.1 983.3 15074.5 21372.8 21980.0 21990.0 22422.6 874.8 944.9 947.6 955.7 994.8 10122.8 10251.6 10304.0 10531.3 10761.3 532903.0 537211.0 543258.0 543639.0 544342.0 134719.3 136873.0 136915.0 138572.6 140660.0 981.2 1009.2 1038.1 1050.4 1129.1 21551.9 22498.8 23618.1 24141.4 24165.0 62060.8 62982.7 67287.0 69486.0 69521.7 69915.0 88077.0 88669.6 88898.0 89792.1 9219.0 10058.6 10291.4 10591.0 10611.1 18220.3 18304.5 18508.0 18524.4 18605.0 14867.4 14992.6 14994.6 15057.5 15168.2 11347.4 13255.5 13313.0 13380.3 13562.7 14924.3 15225.5 15330.5 15392.8 15411.9 10375.7 10391.3 10487.6 10491.5 10507.7 12403.4 14673.9 14854.4 14876.3 14957.0 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#3] mootools.html Cycles:180 Average:7213.38 Median:4189.34 stddev:4571.30 (109.1%) stddev-sans-first:4562.05 16:19:57 INFO - PID 1645 | Values: 1235.6 1257.4 1423.6 1435.0 1445.1 11742.5 11927.5 12452.4 12492.5 12551.3 10108.9 10710.1 11721.9 11978.8 12186.1 2434.7 2451.7 2469.1 2471.6 2507.3 2511.3 2530.5 2579.7 2581.2 2595.2 2156.9 2469.7 2505.6 2507.1 2510.5 2434.0 2495.8 2515.7 2529.2 2538.7 2467.2 2509.4 2525.6 2529.6 2578.6 11743.3 11869.6 11880.7 11884.1 11916.2 6486.6 6682.3 6713.6 6765.8 6768.2 4175.3 4243.8 4332.8 4333.9 4351.0 1253.2 1255.6 1260.7 1273.8 1279.6 15556.7 16016.3 16694.2 16797.6 16858.6 5586.9 5636.1 5715.6 5795.9 5803.1 12591.1 12594.3 12610.4 12611.7 12621.4 6483.3 6644.5 6644.8 6647.1 6661.8 6100.2 6212.2 6254.7 6263.9 6281.8 4343.9 4443.6 4493.8 4505.5 4547.6 1262.0 1272.9 1283.8 1291.4 1296.4 808.9 811.9 834.2 845.6 859.4 5692.4 6442.5 6468.7 6733.9 6758.3 496.9 588.7 598.5 614.3 618.4 3720.4 4203.4 4334.7 4645.9 4871.8 14801.9 15061.5 15066.0 15278.5 15323.5 14819.0 15275.4 15326.4 15473.2 15487.7 8619.9 8633.2 8641.6 8715.9 8747.9 6241.9 7313.9 7425.7 7675.2 7765.6 10467.7 13008.7 13617.8 13718.5 13901.7 13680.5 14203.7 14296.6 14385.6 14391.0 9509.9 9828.4 9840.1 9841.5 9852.6 9926.4 10030.8 10696.7 10736.4 10865.4 9234.8 9286.8 9333.3 9341.9 9443.8 7905.4 8582.5 9006.6 9012.9 9085.3 8225.4 8242.8 8287.1 8304.9 8306.3 5397.3 6216.3 6483.1 6502.9 6748.6 6105.0 6212.4 6519.4 6601.9 6608.6 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#4] prototype.html Cycles:180 Average:5448.01 Median:6186.36 stddev:1752.80 (28.3%) stddev-sans-first:1715.01 16:19:57 INFO - PID 1645 | Values: 324.4 402.5 404.0 404.9 409.0 5805.0 6375.8 6450.7 6452.3 6490.9 5428.8 5913.9 6212.0 6224.8 6225.9 4457.5 4594.5 4618.5 4622.7 4636.0 4607.6 4608.9 4647.8 4653.0 4657.3 4556.9 4561.4 4567.3 4574.3 4600.5 3821.0 4584.4 4587.1 4589.1 4596.4 3330.6 3724.9 3944.6 3974.7 4012.0 5077.6 5082.9 6173.1 6181.0 6208.1 4197.2 4927.7 5472.5 5529.2 5565.8 6729.8 6786.0 6791.0 6814.5 6820.0 3854.3 4536.1 4548.2 4559.7 4565.2 6847.0 7045.6 7197.8 7315.6 7336.5 4144.8 4205.1 4536.2 4559.8 4604.2 3925.0 4129.8 4336.3 4623.9 4710.7 5015.5 5459.8 5465.7 5469.4 5478.4 5849.6 5899.1 6608.4 6623.7 6797.7 1451.2 1521.8 1599.2 1600.9 1607.4 976.6 1000.3 1005.1 1057.8 1067.4 5826.6 6191.7 6489.9 6517.3 6522.5 6979.2 7014.9 7064.5 7098.7 7110.1 5714.1 6240.1 6481.4 6489.9 6496.7 3075.4 3621.6 3693.4 3694.8 3753.2 6891.8 7103.6 7266.6 7299.9 7309.8 6260.5 7262.1 7268.0 7307.0 7315.4 6730.3 7214.9 7226.8 7258.7 7262.6 6511.4 7065.5 7083.8 7120.9 7152.0 6266.7 6453.4 6588.1 6937.4 6959.4 6023.8 6622.2 7179.8 7236.5 7246.4 4347.3 4361.7 4568.9 4613.3 4675.7 5503.0 6762.4 6907.4 6987.8 7136.6 5448.5 6782.5 6810.9 6818.8 6827.6 6791.4 6803.0 6806.8 6811.2 6856.7 6676.3 6681.3 6685.3 6685.7 6752.6 5775.0 6523.0 6703.2 6737.7 6882.7 6059.2 6145.0 6298.8 6365.8 6400.5 16:19:57 INFO - PID 1645 | 16:19:57 INFO - PID 1645 | [#5] yui.html Cycles:180 Average:25354.16 Median:17521.71 stddev:40030.89 (228.5%) stddev-sans-first:40100.83 16:19:57 INFO - PID 1645 | Values: 827.5 838.6 844.3 865.3 873.3 13614.4 14289.9 14841.7 16805.4 16909.9 16855.5 17431.2 17647.0 17696.6 17715.7 950.7 982.9 986.3 993.2 1006.2 902.6 977.0 996.4 1003.2 1005.6 1024.6 1029.4 1029.4 1031.7 1038.6 1011.8 1022.1 1023.2 1027.7 1034.7 750.6 767.3 768.8 775.3 791.5 17337.8 17665.4 17756.7 17908.9 17937.5 17480.3 17642.3 17989.2 18045.1 18050.9 14378.0 14695.0 14722.8 14727.7 14819.8 3571.6 3583.9 3590.5 3625.0 3629.2 153853.0 154914.2 156926.0 156998.0 157902.4 13545.3 13658.5 13676.3 13750.4 13829.3 26467.6 26954.3 27061.6 27236.5 27463.7 6754.5 6953.2 7524.8 7792.6 7855.1 3596.9 3670.5 3935.3 4014.7 4016.1 3696.2 3931.7 4213.3 4217.7 4246.3 757.3 941.0 945.5 956.3 959.3 651.5 665.2 674.7 675.8 699.1 10790.4 11677.4 12186.1 12205.7 12441.1 607.4 651.3 664.7 676.5 686.1 4489.3 5043.0 5055.7 5378.7 5692.8 137135.9 141857.0 143376.3 143418.0 147114.0 122532.5 126893.7 127427.4 127739.0 129211.0 96269.0 96639.5 101990.5 102955.5 103122.1 10675.7 10988.5 11293.4 11621.1 11720.1 43467.0 45561.0 45947.5 46853.8 47016.9 51473.5 54417.6 58816.7 59393.7 59414.0 14502.0 14592.6 14818.4 14941.1 15087.2 15965.3 16252.8 16435.3 18359.4 18563.5 15533.8 16692.2 16780.3 17394.3 17563.1 16966.6 17065.6 17067.9 17074.6 17191.7 16396.1 16439.3 16813.9 16934.7 16980.3 9273.8 10290.9 10317.8 10828.8 11178.3 12845.9 12894.3 13050.9 13119.6 13221.3 16:19:57 INFO - PID 1645 | -------- Summary: end -------- 16:19:57 INFO - PID 1645 | 16:19:58 INFO - TEST-INFO | 1645: exit 0 16:19:58 INFO - TEST-OK | dromaeo_css | took 1143418ms 16:19:58 INFO - TEST-START | kraken 16:19:58 INFO - operating with platform_type : mac_ 16:19:58 INFO - Initialising browser for kraken test... 16:19:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpiYi0Pa/profile 16:19:58 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 16:20:31 INFO - TEST-INFO | 1653: killed by SIGTERM 16:20:31 INFO - Browser initialized. 16:20:31 INFO - Running cycle 1/1 for kraken test... 16:20:31 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpiYi0Pa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:20:33 INFO - PID 1657 | 2017-06-05 16:20:33.910 plugin-container[1658:11287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a3f, name = 'com.apple.tsm.portname' 16:20:33 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:33 INFO - PID 1657 | 2017-06-05 16:20:33.911 plugin-container[1658:11287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480b, name = 'com.apple.CFPasteboardClient' 16:20:33 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:33 INFO - PID 1657 | 2017-06-05 16:20:33.911 plugin-container[1658:11287] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:34 INFO - PID 1657 | 2017-06-05 16:20:34.351 plugin-container[1659:11333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4943, name = 'com.apple.tsm.portname' 16:20:34 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:34 INFO - PID 1657 | 2017-06-05 16:20:34.352 plugin-container[1659:11333] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 16:20:34 INFO - PID 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:20:34 INFO - PID 1657 | 2017-06-05 16:20:34.352 plugin-container[1659:11333] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:20:43 INFO - PID 1657 | RSS: Main: 267816960 16:20:43 INFO - PID 1657 | 16:20:46 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 16:20:47 INFO - PID 1657 | RSS: Main: 275570688 16:20:47 INFO - PID 1657 | 16:20:49 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 16:20:50 INFO - PID 1657 | RSS: Main: 283971584 16:20:50 INFO - PID 1657 | 16:20:53 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 16:20:54 INFO - PID 1657 | RSS: Main: 281808896 16:20:54 INFO - PID 1657 | 16:20:56 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 16:20:57 INFO - PID 1657 | RSS: Main: 283996160 16:20:57 INFO - PID 1657 | 16:20:59 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:20:59 INFO - PID 1657 | RSS: Main: 284528640 16:20:59 INFO - PID 1657 | 16:21:02 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 16:21:03 INFO - PID 1657 | RSS: Main: 288841728 16:21:03 INFO - PID 1657 | 16:21:06 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 16:21:07 INFO - PID 1657 | RSS: Main: 291966976 16:21:07 INFO - PID 1657 | 16:21:09 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 16:21:10 INFO - PID 1657 | RSS: Main: 291139584 16:21:10 INFO - PID 1657 | 16:21:12 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:21:12 INFO - PID 1657 | RSS: Main: 290390016 16:21:12 INFO - PID 1657 | 16:21:14 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:21:15 INFO - PID 1657 | RSS: Main: 290320384 16:21:15 INFO - PID 1657 | 16:21:17 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:21:18 INFO - PID 1657 | RSS: Main: 290242560 16:21:18 INFO - PID 1657 | 16:21:20 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:21:21 INFO - PID 1657 | RSS: Main: 290021376 16:21:21 INFO - PID 1657 | 16:21:24 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:21:25 INFO - PID 1657 | RSS: Main: 290254848 16:21:25 INFO - PID 1657 | 16:21:26 INFO - PID 1657 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 16:21:27 INFO - PID 1657 | RSS: Main: 291926016 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | __start_tp_report 16:21:27 INFO - PID 1657 | _x_x_mozilla_page_load 16:21:27 INFO - PID 1657 | _x_x_mozilla_page_load_details 16:21:27 INFO - PID 1657 | |i|pagename|runs| 16:21:27 INFO - PID 1657 | |0;ai-astar;104;115;118;115;114;115;120;163;114;113 16:21:27 INFO - PID 1657 | |1;audio-beat-detection;147;111;112;152;113;113;113;118;139;111 16:21:27 INFO - PID 1657 | |2;audio-dft;199;202;203;211;222;235;276;241;201;197 16:21:27 INFO - PID 1657 | |3;audio-fft;76;57;58;85;57;59;57;53;76;61 16:21:27 INFO - PID 1657 | |4;audio-oscillator;81;95;112;70;70;97;108;105;84;89 16:21:27 INFO - PID 1657 | |5;imaging-gaussian-blur;88;89;88;88;89;88;89;89;119;139 16:21:27 INFO - PID 1657 | |6;imaging-darkroom;97;98;95;95;97;98;96;97;113;163 16:21:27 INFO - PID 1657 | |7;imaging-desaturate;90;90;94;90;99;94;91;91;91;91 16:21:27 INFO - PID 1657 | |8;json-parse-financial;50;52;51;52;53;52;50;54;48;50 16:21:27 INFO - PID 1657 | |9;json-stringify-tinderbox;60;63;57;71;66;63;58;60;59;59 16:21:27 INFO - PID 1657 | |10;stanford-crypto-aes;84;89;88;89;89;110;117;90;114;95 16:21:27 INFO - PID 1657 | |11;stanford-crypto-ccm;137;141;137;138;142;140;137;141;138;141 16:21:27 INFO - PID 1657 | |12;stanford-crypto-pbkdf2;187;182;183;186;195;186;184;185;183;185 16:21:27 INFO - PID 1657 | |13;stanford-crypto-sha256-iterative;65;77;63;86;96;98;74;66;75;67 16:21:27 INFO - PID 1657 | __end_tp_report 16:21:27 INFO - PID 1657 | __start_cc_report 16:21:27 INFO - PID 1657 | _x_x_mozilla_cycle_collect,1201 16:21:27 INFO - PID 1657 | __end_cc_report 16:21:27 INFO - PID 1657 | __startTimestamp1496704887209__endTimestamp 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | ------- Summary: start ------- 16:21:27 INFO - PID 1657 | Number of tests: 14 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#0] ai-astar Cycles:10 Average:119.10 Median:115.00 stddev:15.98 (13.9%) stddev-sans-first:15.98 16:21:27 INFO - PID 1657 | Values: 104.0 115.0 118.0 115.0 114.0 115.0 120.0 163.0 114.0 113.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#1] audio-beat-detection Cycles:10 Average:122.90 Median:115.50 stddev:16.35 (14.2%) stddev-sans-first:14.84 16:21:27 INFO - PID 1657 | Values: 147.0 111.0 112.0 152.0 113.0 113.0 113.0 118.0 139.0 111.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#2] audio-dft Cycles:10 Average:218.70 Median:216.50 stddev:25.42 (11.7%) stddev-sans-first:25.94 16:21:27 INFO - PID 1657 | Values: 199.0 202.0 203.0 211.0 222.0 235.0 276.0 241.0 201.0 197.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#3] audio-fft Cycles:10 Average:63.90 Median:60.00 stddev:10.89 (18.1%) stddev-sans-first:10.63 16:21:27 INFO - PID 1657 | Values: 76.0 57.0 58.0 85.0 57.0 59.0 57.0 53.0 76.0 61.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#4] audio-oscillator Cycles:10 Average:91.10 Median:82.50 stddev:14.96 (18.1%) stddev-sans-first:15.41 16:21:27 INFO - PID 1657 | Values: 81.0 95.0 112.0 70.0 70.0 97.0 108.0 105.0 84.0 89.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:96.60 Median:88.50 stddev:17.72 (20.0%) stddev-sans-first:18.52 16:21:27 INFO - PID 1657 | Values: 88.0 89.0 88.0 88.0 89.0 88.0 89.0 89.0 119.0 139.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#6] imaging-darkroom Cycles:10 Average:104.90 Median:97.00 stddev:21.08 (21.7%) stddev-sans-first:22.16 16:21:27 INFO - PID 1657 | Values: 97.0 98.0 95.0 95.0 97.0 98.0 96.0 97.0 113.0 163.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#7] imaging-desaturate Cycles:10 Average:92.10 Median:91.00 stddev:2.85 (3.1%) stddev-sans-first:2.92 16:21:27 INFO - PID 1657 | Values: 90.0 90.0 94.0 90.0 99.0 94.0 91.0 91.0 91.0 91.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#8] json-parse-financial Cycles:10 Average:51.20 Median:52.00 stddev:1.75 (3.4%) stddev-sans-first:1.80 16:21:27 INFO - PID 1657 | Values: 50.0 52.0 51.0 52.0 53.0 52.0 50.0 54.0 48.0 50.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:61.60 Median:61.50 stddev:4.27 (6.9%) stddev-sans-first:4.49 16:21:27 INFO - PID 1657 | Values: 60.0 63.0 57.0 71.0 66.0 63.0 58.0 60.0 59.0 59.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:89.00 stddev:12.25 (13.8%) stddev-sans-first:12.13 16:21:27 INFO - PID 1657 | Values: 84.0 89.0 88.0 89.0 89.0 110.0 117.0 90.0 114.0 95.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:139.20 Median:140.50 stddev:1.99 (1.4%) stddev-sans-first:1.94 16:21:27 INFO - PID 1657 | Values: 137.0 141.0 137.0 138.0 142.0 140.0 137.0 141.0 138.0 141.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.60 Median:185.50 stddev:3.66 (2.0%) stddev-sans-first:3.84 16:21:27 INFO - PID 1657 | Values: 187.0 182.0 183.0 186.0 195.0 186.0 184.0 185.0 183.0 185.0 16:21:27 INFO - PID 1657 | 16:21:27 INFO - PID 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:76.00 stddev:12.72 (16.7%) stddev-sans-first:12.77 16:21:27 INFO - PID 1657 | Values: 65.0 77.0 63.0 86.0 96.0 98.0 74.0 66.0 75.0 67.0 16:21:27 INFO - PID 1657 | -------- Summary: end -------- 16:21:27 INFO - PID 1657 | 16:21:28 INFO - TEST-INFO | 1657: exit 0 16:21:28 INFO - TEST-OK | kraken | took 90009ms 16:21:28 INFO - SUITE-END | took 1233s 16:21:28 INFO - Completed test suite (00:20:33) 16:21:28 INFO - javascript benchmark 16:21:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8032.369196672038, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2949.8377589232596, 3568.286342731454, 3582.6596473335057, 3598.19040715839, 3604.963950360496, 4703.764811759411, 5601.271834661488, 6040.516758659308, 6188.226471691039, 6205.286392064908, 6129.4199191187845, 6130.570860039799, 6134.773364742522, 6221.066839974006, 6223.222097237845, 5072.8731781705565, 5206.843794686166, 5246.396664383587, 5270.341207349082, 5291.629585928987, 5354.036273470773, 5364.275822763916, 5376.650517716355, 5379.085555455582, 5396.082665946778, 4351.477822661285, 5261.289725886994, 5307.52232299095, 5331.600129990227, 5376.1129413647095, 5087.745612719386, 5406.297181366436, 5408.188771684239, 5427.267318911975, 5432.185172224158, 5334.759935802898, 5345.091334473149, 5361.356637203521, 5361.731913404353, 5393.946060539383, 6055.152278680988, 6132.969335153296, 6201.906971395432, 6223.222097237845, 6229.470495007885, 6465.256495502983, 6480.935190648081, 6546.18172728409, 6645.501587380917, 6649.135612370409, 6767.221769496522, 6774.9661251693915, 6783.186017677861, 6802.65986700668, 6806.863862722717, 5689.886202275973, 5921.171036054956, 6307.211598550181, 6330.620162790247, 6397.96801015992, 6132.4778862607845, 6580.5722628029025, 6655.267920528738, 6884.311568843075, 6923.411510021604, 6283.7800676976085, 6300.8424789379715, 6446.580972236789, 6533.444657204191, 6545.05096760967, 6756.0, 6768.289329620417, 6770.187577490733, 6779.118714567075, 6798.52410331272, 6598.241202261705, 6619.106420633249, 6639.0, 6662.200535935622, 6666.366695163952, 5323.494268044529, 5484.204790305464, 6397.488200943913, 6457.547971642034, 6578.2106147261675, 5285.814996475105, 5323.387810401776, 5342.519173274424, 5346.97326513365, 5388.353397592314, 4712.552307530779, 4731.1720448921305, 4739.478657347689, 4757.26262429324, 4784.712917224978, 6225.62646241227, 6237.157983672237, 6260.436560709467, 6266.216722909637, 6341.55609107358, 5558.805441809598, 5684.403137670485, 6773.254941956381, 6784.457243420515, 6785.525013249124, 6354.078658594478, 6354.205724284465, 6367.840803979845, 6382.329855365213, 6417.839554011187, 5712.371639119693, 5795.536357091422, 5945.494632956247, 5959.433853783884, 5966.880662386728, 6705.832354191184, 6717.596944183365, 6780.32196780318, 6844.623545704835, 6877.484188685969, 6631.5689480185565, 6737.59574425536, 6747.763828265987, 6748.628825414649, 6755.1556055493065, 6861.4510839133745, 6894.207166175954, 6919.6886140124825, 6924.58452492852, 6930.13373328334, 5372.5433338166695, 6321.209848768904, 6404.551681382445, 6486.383793539606, 6556.573822701509, 6676.799696009128, 6684.197896252286, 6692.5315227935525, 6704.631245281556, 6711.261761206361, 6287.685615719287, 6357.96821015892, 6372.840678983062, 6509.479241660751, 6523.836904077436, 6471.967640161769, 6497.480201583768, 6527.11883895668, 6539.11721917535, 6542.313057129123, 6295.087212354275, 6408.903866441914, 6409.839754006187, 6427.967860160857, 6431.678416079084, 6332.52506062038, 6528.771492997722, 6539.869202616016, 6548.738050477928, 6558.081868538504, 6259.655718935503, 6304.464120549805, 6340.841478963062, 6365.809025729235, 6382.489400848014], "value": 6020.670031764844, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [774.6707649249071, 821.5153059694779, 842.6334544473154, 1250.3560666256874, 1308.5093090091216, 34691.13272168197, 39438.0, 40267.791966240984, 40908.18183636329, 41590.792046039765, 41187.38218926711, 41501.79249103758, 41524.97887806266, 41558.0, 41582.792086039604, 7871.960640196791, 8070.2333278338465, 8271.710490132846, 8497.150284971514, 8507.276881465083, 8376.49741015538, 8494.74515764528, 8510.617022233984, 8516.02065762436, 8532.616032278562, 8479.152084791533, 8490.702825401113, 8569.828603427928, 8574.314054875626, 8583.95708021459, 8447.493150410934, 8482.151784821499, 8527.147285271485, 8527.872081918777, 8536.487810731376, 7200.351968322823, 7904.841903161904, 8212.794680133044, 8228.465149765247, 8290.792730181733, 38481.80759096215, 38686.80656596699, 38748.61251387478, 38815.0, 38815.22369552593, 3048.2684155802667, 3084.475639141329, 3535.805530695811, 3577.8568857245677, 3578.4453409721536, 30591.847040764875, 30716.2320941976, 30747.538786918216, 30759.231019224473, 30861.382772344426, 3076.446239676857, 3102.37952409519, 3114.9065528034193, 3115.1121930249997, 3124.218945263684, 231804.0, 235577.0, 260555.69722151457, 273753.63123184454, 276000.0, 48795.5120448791, 48882.5111748878, 49155.0, 49383.75308123436, 49399.0, 51207.41548091598, 51262.0, 51366.48633513692, 51525.7423712879, 51716.74141629268, 12852.871471285354, 12932.353382330846, 13066.412011459508, 13830.723385532234, 13958.302084895715, 10783.730406739738, 10818.94590527058, 10897.945510272399, 10932.289401189057, 10936.726581835359, 13779.89760819132, 13797.0, 13813.825824805004, 13882.861171388156, 13998.650033749034, 6029.6683682397015, 6092.634441933409, 6107.908381374283, 6130.264368275835, 6134.4172303631085, 995.955182016811, 998.3211416237028, 1020.9540570674337, 1040.9479526023667, 1043.6347278452633, 22944.901491145778, 23227.070917163124, 23346.766532334797, 24164.758352416604, 24542.141025064393, 1073.3023534702506, 1080.2654195147375, 1125.5272785430138, 1128.0468004536153, 1139.7036770439745, 11205.71985700364, 11886.108541859396, 12510.0, 12834.607673901457, 12875.871241287654, 333688.0, 334090.0, 335745.0, 335766.0, 336128.31935840164, 54780.72609636926, 55177.0, 55290.17064744032, 55467.72266138643, 55472.445275547536, 1438.5828109848237, 1471.6247356923702, 1474.7787831825312, 1481.274175653922, 1500.3323521033253, 22456.5508689822, 22589.774102259424, 23343.76656233416, 24108.0, 24361.26916192517, 38892.0, 39006.80496597499, 39159.80420097881, 45915.31127033164, 47234.055318892744, 253268.0, 255436.0, 256056.0, 256195.0, 262149.0, 35944.46083308805, 36909.630903691694, 37229.81385093057, 37517.1227740392, 37937.810310949375, 48063.75968120137, 48438.5156148434, 49820.0, 50092.74953625354, 50266.0, 31237.531437028916, 31977.840110799298, 32795.080737889504, 33433.0, 33683.66316336805, 31139.09843514903, 32049.519257210693, 32124.678753213102, 32884.177895552806, 33013.844515441844, 39085.41371879481, 40010.79994600009, 40951.59048409478, 41061.38407923824, 41251.38122928218, 31440.528392074593, 32650.34699306048, 33236.83381583077, 33648.49527257044, 34306.62773489132, 22441.438964025376, 22467.775322247224, 25843.966241350136, 26090.478190435704, 26164.82258298419], "value": 17748.169873419843, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.33513222071895, 433.9500957389901, 441.7371663860004, 448.49992258631613, 457.2934815709728, 21181.470463238427, 21636.567268654617, 22440.775592244074, 22475.438114047138, 24098.0, 22422.88788556059, 23576.0, 24100.397490062714, 24101.87949060252, 24129.75870241297, 6147.354527774586, 6283.183186185813, 6312.589681670676, 6319.74721011159, 6320.0, 6231.5326350523665, 6296.4018418250425, 6300.149479820235, 6301.842453938642, 6313.652749098797, 5802.419758024206, 6087.11736798164, 6139.355367686396, 6141.938580614204, 6148.385161483839, 6092.390760923917, 6131.325554189036, 6133.325334213233, 6145.877082458371, 6161.7535298588, 5410.729463526847, 5429.294191755086, 5445.727713614304, 5471.534919531845, 5482.342118945712, 25571.97712091514, 28393.0, 28863.278418039507, 28864.0, 28927.842886284732, 2066.2974588639936, 2225.3880182949656, 2226.2096955580696, 2235.5528894221056, 2236.888155592214, 18219.362322318786, 18279.90860045691, 18367.72448413275, 18489.0, 18503.444896653124, 2121.5226574020876, 2158.881261530615, 2196.3191410662744, 2198.472366631997, 2209.5359974405396, 124758.0, 160071.99820004593, 164454.0, 166335.3366466332, 191850.0, 12722.045846561545, 12967.0, 13167.934160329138, 13221.669458263621, 13269.73460530784, 16691.833081669272, 16913.661726765396, 16948.57628559296, 16991.0, 17034.574135646708, 8852.159044890854, 8870.979837318662, 9112.498812565373, 9159.450432974043, 9179.77050573741, 8622.439541429787, 8636.309095272363, 8663.523506207219, 8719.65121395137, 8736.606852691644, 8375.664973401117, 8402.663893444194, 8426.483233018116, 8447.788805279793, 8501.14988501145, 2264.29806759905, 2265.501589650274, 2276.46503071778, 2279.9544009119727, 2454.4536941726956, 913.8583519554479, 927.679950417105, 939.389396892012, 978.1392374710209, 983.2674657380297, 15074.472393466176, 21372.824494652632, 21980.0, 21990.0, 22422.551548968928, 874.7987962768599, 944.9196818270386, 947.554649314821, 955.6607404371487, 994.7811481474064, 10122.797544049077, 10251.58993640261, 10304.0, 10531.315464494783, 10761.30051546647, 532903.0, 537211.0, 543258.0, 543639.0, 544342.0, 134719.32640336736, 136873.0, 136915.0, 138572.614273858, 140660.0, 981.2003217377815, 1009.2279406254221, 1038.1435315864442, 1050.3802756373775, 1129.0910816792561, 21551.8922405387, 22498.77501224967, 23618.055277788695, 24141.396465088514, 24165.0, 62060.827871024296, 62982.74034519376, 67287.0, 69486.0, 69521.65239173974, 69914.95127573192, 88077.0, 88669.55665221633, 88898.0, 89792.10207898098, 9218.953905230432, 10058.647947321808, 10291.43397113136, 10591.0, 10611.09805666527, 18220.271189152816, 18304.450866474028, 18508.0, 18524.44426667202, 18605.0, 14867.405303787727, 14992.625184370478, 14994.625134371729, 15057.472988445352, 15168.24158792034, 11347.432628368384, 13255.469781208643, 13313.0, 13380.264085475392, 13562.728745424838, 14924.328405221573, 15225.543233703007, 15330.540083797503, 15392.845536585024, 15411.922940385226, 10375.688729338131, 10391.324563903323, 10487.632932847313, 10491.475426228506, 10507.688308572102, 12403.441845116811, 14673.899457540514, 14854.405823766929, 14876.330565124812, 14956.953013288965], "value": 11086.396125479932, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1235.6354875311781, 1257.3775980889461, 1423.58715972368, 1435.0456946130823, 1445.1256989521337, 11742.471588778504, 11927.463264153112, 12452.439640216178, 12492.500299988002, 12551.30967796773, 10108.888022317538, 10710.143188544916, 11721.941390293037, 11978.76042479152, 12186.146969712123, 2434.6713193718797, 2451.669024681672, 2469.1234611712853, 2471.5921872890954, 2507.2979565721625, 2511.2717311979595, 2530.5192013517467, 2579.6904371475357, 2581.2127301173136, 2595.221433569931, 2156.946076348096, 2469.7404323794904, 2505.6241563765398, 2507.147569826259, 2510.535550923085, 2433.9756602433927, 2495.8252922295446, 2515.6792683840945, 2529.215943057649, 2538.7207407185197, 2467.247489515697, 2509.4102885822, 2525.595904655246, 2529.582618867885, 2578.6147813272646, 11743.295402275891, 11869.643910682607, 11880.702982425422, 11884.10869184806, 11916.225445346025, 6486.578372405778, 6682.331766823328, 6713.597184168917, 6765.830854228634, 6768.221654509745, 4175.31107367284, 4243.814435565686, 4332.826686932519, 4333.8699839004885, 4351.0, 1253.2417887178308, 1255.5919326219016, 1260.6659235302652, 1273.8025606030988, 1279.5969269680022, 15556.655319106334, 16016.279267432994, 16694.248758805883, 16797.58006049846, 16858.578535536708, 5586.888262234814, 5636.0982242841765, 5715.599908006399, 5795.913061304083, 5803.071508558694, 12591.118621696392, 12594.307313097685, 12610.369481525888, 12611.747765044647, 12621.432035558422, 6483.2544257411, 6644.501662375341, 6644.767433139817, 6647.135872336566, 6661.833454163588, 6100.23747031621, 6212.192414986113, 6254.749810007549, 6263.937360626426, 6281.842953926189, 4343.891402714894, 4443.622292105208, 4493.820247190141, 4505.5269196734525, 4547.613452856544, 1262.0030176160913, 1272.8663490333568, 1283.8138469921996, 1291.393045268741, 1296.3647812571771, 808.9191080891864, 811.9106898241189, 834.1575009240589, 845.6321500147333, 859.3941271403645, 5692.402297758758, 6442.549021568445, 6468.676566171673, 6733.8989915151315, 6758.256591774901, 496.9031038947443, 588.7291845750917, 598.5421152818097, 614.3089024847047, 618.4155972605935, 3720.3861362874823, 4203.369494575838, 4334.739915605074, 4645.86062418128, 4871.805127794849, 14801.851981480262, 15061.548153555412, 15066.0, 15278.541643750483, 15323.46367877119, 14819.0, 15275.388984440498, 15326.386944522093, 15473.22633868302, 15487.690246195012, 8619.913800862036, 8633.179847914438, 8641.567921603895, 8715.91284087151, 8747.868781968276, 6241.937580624317, 7313.926860731324, 7425.7029718810645, 7675.155732869492, 7765.5728934906865, 10467.738306542398, 13008.674783130498, 13617.795733063815, 13718.45126194941, 13901.721965560835, 13680.452781888613, 14203.715925681636, 14296.57110286693, 14385.568432947028, 14390.992630515764, 9509.857352139585, 9828.361156524803, 9840.065193806577, 9841.45871977048, 9852.605895764089, 9926.404415735078, 10030.799384012133, 10696.679099626712, 10736.355818650905, 10865.402402867601, 9234.769130771785, 9286.814263714823, 9333.346665733605, 9341.906580934103, 9443.811123777348, 7905.4070944680525, 8582.485050896967, 9006.63973441055, 9012.909870901207, 9085.318601104811, 8225.424220304521, 8242.793930151794, 8287.12985136552, 8304.916950830415, 8306.252437280673, 5397.325334333208, 6216.285127210429, 6483.09236706852, 6502.934970650233, 6748.5613435128525, 6104.969475152596, 6212.417100098756, 6519.380658837403, 6601.867962640817, 6608.636524991171], "value": 5391.544930073458, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.3852898756857, 402.46271227910745, 404.0323425396176, 404.9392591111335, 408.95910408959105, 5805.042168042268, 6375.776847810327, 6450.709718062681, 6452.257990331113, 6490.878292284398, 5428.782848686048, 5913.919395617021, 6211.968940155304, 6224.84437889053, 6225.937740622592, 4457.531959144292, 4594.518959761741, 4618.468876079261, 4622.699524530912, 4636.049609829994, 4607.63138948885, 4608.907821843561, 4647.7676116194225, 4652.953470465286, 4657.301404789275, 4556.931646025312, 4561.361409402686, 4567.292069729181, 4574.290984897346, 4600.516945720701, 3821.006538300034, 4584.426946631671, 4587.128445595343, 4589.082183563284, 4596.402467679214, 3330.600327960636, 3724.925501489955, 3944.5996671181606, 3974.721769476138, 4011.959880401217, 5077.619178561586, 5082.872928176788, 6173.074038894157, 6181.0, 6208.099825525319, 4197.18154959783, 4927.669529227089, 5472.534834539069, 5529.170624406331, 5565.777368905239, 6729.798106056777, 6785.982102684637, 6791.0, 6814.454843612499, 6819.965900170517, 3854.325493038707, 4536.092781443725, 4548.181327361073, 4559.703619264737, 4565.201089809303, 6846.965765171192, 7045.612491312976, 7197.832953500495, 7315.634218289064, 7336.486445948785, 4144.8134833932545, 4205.053862880888, 4536.228841097021, 4559.772011399416, 4604.194266003436, 3924.9607503925167, 4129.834806607702, 4336.2845130553105, 4623.8844028898875, 4710.740909249956, 5015.498450154955, 5459.808906688327, 5465.699386533779, 5469.371022332403, 5478.397376288605, 5849.590528662953, 5899.144624029577, 6608.405243528009, 6623.701933413008, 6797.6601169941305, 1451.151076620189, 1521.7565189569616, 1599.1524492019016, 1600.927958241882, 1607.4052600537875, 976.5654283843621, 1000.3397757480174, 1005.1456262177092, 1057.751428414322, 1067.4235912607105, 5826.592138550261, 6191.721372538246, 6489.902651460232, 6517.348265173539, 6522.510811689142, 6979.232284448706, 7014.859702805916, 7064.540804847668, 7098.716051357887, 7110.075690160246, 5714.085746280575, 6240.063990401385, 6481.4166724994975, 6489.935100648933, 6496.675166241669, 3075.3541756230993, 3621.5916415583342, 3693.372126738461, 3694.799190263175, 3753.249350129985, 6891.827704307332, 7103.644817759091, 7266.63666816657, 7299.927000729925, 7309.817254568572, 6260.467860231931, 7262.055932728817, 7267.963660181771, 7306.96346518264, 7315.378192853667, 6730.32696730323, 7214.907676773677, 7226.819329516804, 7258.709651613982, 7262.620102180569, 6511.413972742476, 7065.505414620927, 7083.82290442743, 7120.928790712027, 7152.0, 6266.655333956676, 6453.419192272718, 6588.143541339595, 6937.410320122846, 6959.443244540324, 6023.789167379121, 6622.205335359788, 7179.784606461814, 7236.529625574532, 7246.4202863771825, 4347.3261644445165, 4361.651067914496, 4568.921140880958, 4613.331066995334, 4675.719456832601, 5502.972485137549, 6762.396477356352, 6907.447404207752, 6987.829006535026, 7136.571805691675, 5448.482394172547, 6782.457403407814, 6810.944303632945, 6818.761343352959, 6827.556208846409, 6791.422729068085, 6802.965985170043, 6806.829829254309, 6811.216710078206, 6856.668732630929, 6676.332366763285, 6681.265060843206, 6685.331466853276, 6685.665716714242, 6752.561083529555, 5774.960507108761, 6523.0, 6703.229128650268, 6737.698527721479, 6882.724691012303, 6059.242594675666, 6144.969275153596, 6298.779542715983, 6365.777197798055, 6400.455961243347], "value": 4893.644748157764, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [827.5324441690448, 838.6351936907446, 844.2528362399279, 865.2558799432487, 873.3100850328241, 13614.387352569134, 14289.928550357234, 14841.703165936675, 16805.41181058662, 16909.915450422744, 16855.494335169966, 17431.21559529821, 17647.0, 17696.557586060357, 17715.73426398605, 950.7195377363677, 982.8918818929913, 986.3046552180696, 993.1806259835629, 1006.2302338710892, 902.6028547439139, 976.9755756106083, 996.3772642098688, 1003.2225025605178, 1005.6027868991744, 1024.55943944104, 1029.3669393323128, 1029.3978022856638, 1031.6698656429946, 1038.6312858935082, 1011.7925825205815, 1022.0852337158276, 1023.2172388123055, 1027.6762819711805, 1034.7154532503585, 750.6059318857589, 767.2864236260276, 768.8423873106, 775.255754475704, 791.5409062743595, 17337.826621733748, 17665.38171164003, 17756.733648995276, 17908.91045544777, 17937.461876143738, 17480.300787968466, 17642.294308227654, 17989.190486428142, 18045.097745112693, 18050.90974545119, 14377.993540452173, 14694.971352005365, 14722.779158312635, 14727.705445891022, 14819.77770333446, 3571.607123216444, 3583.928321433583, 3590.533230680021, 3624.9818750906074, 3629.201575653352, 153853.0, 154914.22542887327, 156926.0, 156998.0, 157902.42097578992, 13545.322733863464, 13658.521951731642, 13676.316184190751, 13750.381232844546, 13829.30853457323, 26467.602985955225, 26954.32614184661, 27061.594076088873, 27236.45527089487, 27463.725362746118, 6754.493412994045, 6953.235144134142, 7524.811879702942, 7792.649330780015, 7855.096663883613, 3596.9029446018694, 3670.504481894963, 3935.330993731073, 4014.698897582693, 4016.116454380032, 3696.2237930034994, 3931.66580840626, 4213.325867861127, 4217.746935183899, 4246.341817018305, 757.3070640364116, 940.9529523523796, 945.5225111318878, 956.3496822160859, 959.3476436023432, 651.501601275025, 665.1519312876121, 674.6930146783202, 675.8040168351222, 699.1121275979477, 10790.406527640898, 11677.35774532392, 12186.08604354677, 12205.69485762863, 12441.066919981038, 607.3652648573858, 651.2868409092036, 664.7341063574512, 676.5331920974512, 686.0600976661906, 4489.281714925596, 5042.974785126051, 5055.7219352935945, 5378.650387724785, 5692.7722891083895, 137135.94296085567, 141857.0, 143376.28311858373, 143418.0, 147114.0, 122532.54934901252, 126893.7310626882, 127427.36286318509, 127739.0, 129211.0, 96269.0, 96639.51680241694, 101990.47014294792, 102955.4852225749, 103122.10973628296, 10675.679729608124, 10988.450577471254, 11293.43532823372, 11621.128415368712, 11720.062395008208, 43467.0, 45561.0, 45947.540524595664, 46853.82865428255, 47016.88780458518, 51473.48526514687, 54417.63955901134, 58816.70591647185, 59393.70303148457, 59414.0, 14502.0, 14592.562223133324, 14818.407263709762, 14941.103533788008, 15087.170205638795, 15965.281562329956, 16252.837471625133, 16435.26041328119, 18359.35742249015, 18563.535911602317, 15533.844661553692, 16692.165391730123, 16780.32878684839, 17394.304227830744, 17563.121843908008, 16966.57583560421, 17065.573360666083, 17067.914660426617, 17074.573135671708, 17191.7421238679, 16396.098214597834, 16439.342426302814, 16813.907096038718, 16934.745978810573, 16980.32078716887, 9273.768155796159, 10290.948545257226, 10317.793644127225, 10828.837567436338, 11178.329300242012, 12845.935770321088, 12894.29081400532, 13050.869491304966, 13119.606411807661, 13221.338933053117], "value": 8592.040279640014, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1520.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 118.0, 115.0, 114.0, 115.0, 120.0, 163.0, 114.0, 113.0], "value": 119.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 112.0, 152.0, 113.0, 113.0, 113.0, 118.0, 139.0, 111.0], "value": 122.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 202.0, 203.0, 211.0, 222.0, 235.0, 276.0, 241.0, 201.0, 197.0], "value": 218.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 57.0, 58.0, 85.0, 57.0, 59.0, 57.0, 53.0, 76.0, 61.0], "value": 63.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 95.0, 112.0, 70.0, 70.0, 97.0, 108.0, 105.0, 84.0, 89.0], "value": 91.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 88.0, 88.0, 89.0, 88.0, 89.0, 89.0, 119.0, 139.0], "value": 96.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 98.0, 95.0, 95.0, 97.0, 98.0, 96.0, 97.0, 113.0, 163.0], "value": 104.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 94.0, 90.0, 99.0, 94.0, 91.0, 91.0, 91.0, 91.0], "value": 92.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 52.0, 51.0, 52.0, 53.0, 52.0, 50.0, 54.0, 48.0, 50.0], "value": 51.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 63.0, 57.0, 71.0, 66.0, 63.0, 58.0, 60.0, 59.0, 59.0], "value": 61.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 89.0, 88.0, 89.0, 89.0, 110.0, 117.0, 90.0, 114.0, 95.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 141.0, 137.0, 138.0, 142.0, 140.0, 137.0, 141.0, 138.0, 141.0], "value": 139.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 182.0, 183.0, 186.0, 195.0, 186.0, 184.0, 185.0, 183.0, 185.0], "value": 185.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 77.0, 63.0, 86.0, 96.0, 98.0, 74.0, 66.0, 75.0, 67.0], "value": 76.7, "unit": "score"}]}]} 16:21:28 INFO - Return code: 0 16:21:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:21:28 INFO - # TBPL SUCCESS # 16:21:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:21:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:21:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:21:28 INFO - 2017-06-05 16:21:28.595 screenresolution[1665:11718] starting screenresolution argv=screenresolution get 16:21:28 INFO - 2017-06-05 16:21:28.597 screenresolution[1665:11718] Display 0: 1600x1200x32@60 16:21:28 INFO - 2017-06-05 16:21:28.603 screenresolution[1666:11721] starting screenresolution argv=screenresolution list 16:21:28 INFO - Available Modes on Display 0 16:21:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:21:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:21:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:21:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:21:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:21:28 INFO - Intel Iris: 16:21:28 INFO - Chipset Model: Intel Iris 16:21:28 INFO - Type: GPU 16:21:28 INFO - Bus: Built-In 16:21:28 INFO - VRAM (Dynamic, Max): 1536 MB 16:21:28 INFO - Vendor: Intel (0x8086) 16:21:28 INFO - Device ID: 0x0a2e 16:21:28 INFO - Revision ID: 0x0009 16:21:28 INFO - Displays: 16:21:28 INFO - Display: 16:21:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:21:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:21:28 INFO - Display Serial Number: 1600x1200 60 16:21:28 INFO - Main Display: Yes 16:21:28 INFO - Mirror: Off 16:21:28 INFO - Online: Yes 16:21:28 INFO - Rotation: Supported 16:21:28 INFO - Return code: 0 16:21:28 INFO - Running post-action listener: _resource_record_post_action 16:21:28 INFO - [mozharness: 2017-06-05 23:21:28.840093Z] Finished run-tests step (success) 16:21:28 INFO - Running post-run listener: _resource_record_post_run 16:21:28 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 35816448; Write bytes: 571746816; Read time: 332; Write time: 2077 16:21:28 INFO - TinderboxPrint: CPU usage
53.2% 16:21:28 INFO - TinderboxPrint: I/O read bytes / time
35,816,448 / 332 16:21:28 INFO - TinderboxPrint: I/O write bytes / time
571,746,816 / 2,077 16:21:28 INFO - TinderboxPrint: CPU idle
2,349.6 (46.8%) 16:21:28 INFO - TinderboxPrint: CPU system
1,015.1 (20.2%) 16:21:28 INFO - TinderboxPrint: CPU user
1,661.1 (33.1%) 16:21:28 INFO - TinderboxPrint: Swap in / out
278,261,760 / 0 16:21:28 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 247955968; Write bytes: 249320960; Read time: 18050; Write time: 462 16:21:28 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 16:21:28 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 30647296; Write bytes: 321045504; Read time: 295; Write time: 1609 16:21:29 INFO - Running post-run listener: _upload_blobber_files 16:21:29 INFO - Blob upload gear active. 16:21:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:21:29 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 16:21:29 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', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:21:29 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 Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:21:29 INFO - (blobuploader) - INFO - Open directory for files ... 16:21:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:21:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:21:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:31 INFO - (blobuploader) - INFO - Done attempting. 16:21:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:21:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:21:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:33 INFO - (blobuploader) - INFO - Done attempting. 16:21:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 16:21:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:34 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 16:21:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:34 INFO - (blobuploader) - INFO - Done attempting. 16:21:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:21:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:21:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:21:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:21:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:21:36 INFO - (blobuploader) - INFO - Done attempting. 16:21:36 INFO - (blobuploader) - INFO - Iteration through files over. 16:21:36 INFO - Return code: 0 16:21:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:21:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:21:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d6c2e997748004e9996877f7b17abdf3c5cd7765ac0d5ef4ea61c0edae89acffe93c10e4430f00b1f4c14366f97068b5cc966ee47b629de36c831c9a255404da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9715bf8c2f741d6e7df33d9ee00a5be4132e1d4c82a2ac9ba92cfd7e6f3b0211b0262e7da6c058e931f88b665c237f59c72ebd0307fdd60f1957b10be4759569", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bbb1846ea03b6b102d977fd60f84e26a97c352b67177a389a4b71c0575b57546b9104cee8e88aa95f5479752bec2a0cb3e951c2601e315cdadc0be9f0f4ef7cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3d9b4d08e33029a096119b3ec061fa3691d7e75fa4771b850e95899d1f3945fd71d770d6c6c47423f1e6ff7ad6361e3594728c53df5c9ee7b5bca96d6ca80ef0"} 16:21:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:21:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:21:36 INFO - Contents: 16:21:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d6c2e997748004e9996877f7b17abdf3c5cd7765ac0d5ef4ea61c0edae89acffe93c10e4430f00b1f4c14366f97068b5cc966ee47b629de36c831c9a255404da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9715bf8c2f741d6e7df33d9ee00a5be4132e1d4c82a2ac9ba92cfd7e6f3b0211b0262e7da6c058e931f88b665c237f59c72ebd0307fdd60f1957b10be4759569", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bbb1846ea03b6b102d977fd60f84e26a97c352b67177a389a4b71c0575b57546b9104cee8e88aa95f5479752bec2a0cb3e951c2601e315cdadc0be9f0f4ef7cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3d9b4d08e33029a096119b3ec061fa3691d7e75fa4771b850e95899d1f3945fd71d770d6c6c47423f1e6ff7ad6361e3594728c53df5c9ee7b5bca96d6ca80ef0"} 16:21:36 INFO - Running post-run listener: copy_logs_to_upload_dir 16:21:36 INFO - Copying logs to upload dir... 16:21:36 INFO - mkdir: /builds/slave/test/build/upload/logs 16:21:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.266322 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-05 16:21:36.186327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 16:21:36.189859) ========= 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.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/Cedar/sha512/d6c2e997748004e9996877f7b17abdf3c5cd7765ac0d5ef4ea61c0edae89acffe93c10e4430f00b1f4c14366f97068b5cc966ee47b629de36c831c9a255404da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9715bf8c2f741d6e7df33d9ee00a5be4132e1d4c82a2ac9ba92cfd7e6f3b0211b0262e7da6c058e931f88b665c237f59c72ebd0307fdd60f1957b10be4759569", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bbb1846ea03b6b102d977fd60f84e26a97c352b67177a389a4b71c0575b57546b9104cee8e88aa95f5479752bec2a0cb3e951c2601e315cdadc0be9f0f4ef7cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3d9b4d08e33029a096119b3ec061fa3691d7e75fa4771b850e95899d1f3945fd71d770d6c6c47423f1e6ff7ad6361e3594728c53df5c9ee7b5bca96d6ca80ef0"} build_url:https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011579 build_url: 'https://queue.taskcluster.net/v1/task/fbZh9LdPQhCaZH982ZkTEQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/d6c2e997748004e9996877f7b17abdf3c5cd7765ac0d5ef4ea61c0edae89acffe93c10e4430f00b1f4c14366f97068b5cc966ee47b629de36c831c9a255404da", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/9715bf8c2f741d6e7df33d9ee00a5be4132e1d4c82a2ac9ba92cfd7e6f3b0211b0262e7da6c058e931f88b665c237f59c72ebd0307fdd60f1957b10be4759569", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/bbb1846ea03b6b102d977fd60f84e26a97c352b67177a389a4b71c0575b57546b9104cee8e88aa95f5479752bec2a0cb3e951c2601e315cdadc0be9f0f4ef7cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/3d9b4d08e33029a096119b3ec061fa3691d7e75fa4771b850e95899d1f3945fd71d770d6c6c47423f1e6ff7ad6361e3594728c53df5c9ee7b5bca96d6ca80ef0"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 16:21:36.225281) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 16:21:36.225640) ========= 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.hhAqqBKacD/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.Id70RTMR3i/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005355 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 16:21:36.274611) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 16:21:36.274929) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 16:21:36.275297) ========= ========= Total master_lag: 3.46 =========