builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0097 starttime: 1496421664.15 results: success (0) revision: 87c745019518b1d6cd782534f2553721e5735657 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.147778) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.148222) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.148496) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.175531) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.176142) ========= 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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-02 09:41:04-- 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% 13.4M=0.001s 2017-06-02 09:41:04 (13.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.173896 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.378939) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.379762) ========= 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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.026851 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:04.432555) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-02 09:41:04.433345) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 87c745019518b1d6cd782534f2553721e5735657 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 87c745019518b1d6cd782534f2553721e5735657 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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-02 09:41:04,526 truncating revision to first 12 chars 2017-06-02 09:41:04,526 Setting DEBUG logging. 2017-06-02 09:41:04,526 attempt 1/10 2017-06-02 09:41:04,526 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/87c745019518?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-02 09:41:06,354 unpacking tar archive at: mozilla-central-87c745019518/testing/mozharness/ program finished with exit code 0 elapsedTime=2.104190 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-02 09:41:06.561037) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:06.561616) ========= script_repo_revision: 87c745019518b1d6cd782534f2553721e5735657 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:06.562055) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:06.562367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-02 09:41:06.582105) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-02 09:41:06.582783) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:41:06 INFO - MultiFileLogger online at 20170602 09:41:06 in /builds/slave/test 09:41:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:41:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:41:06 INFO - {'append_to_log': False, 09:41:06 INFO - 'base_work_dir': '/builds/slave/test', 09:41:06 INFO - 'blob_upload_branch': 'Firefox', 09:41:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:41:06 INFO - 'branch': 'Firefox', 09:41:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:41:06 INFO - 'config_files': ('talos/mac_config.py',), 09:41:06 INFO - 'default_actions': ('clobber', 09:41:06 INFO - 'read-buildbot-config', 09:41:06 INFO - 'download-and-extract', 09:41:06 INFO - 'populate-webroot', 09:41:06 INFO - 'create-virtualenv', 09:41:06 INFO - 'install', 09:41:06 INFO - 'setup-mitmproxy', 09:41:06 INFO - 'run-tests'), 09:41:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:41:06 INFO - 'download_minidump_stackwalk': True, 09:41:06 INFO - 'download_symbols': 'ondemand', 09:41:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:41:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:41:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:41:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:41:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:41:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:41:06 INFO - 'gecko_profile': False, 09:41:06 INFO - 'gecko_profile_interval': 0, 09:41:06 INFO - 'installer_path': 'installer.exe', 09:41:06 INFO - 'log_level': 'info', 09:41:06 INFO - 'log_name': 'talos', 09:41:06 INFO - 'log_to_console': True, 09:41:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:41:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:41:06 INFO - 'opt_config_files': (), 09:41:06 INFO - 'pip_index': False, 09:41:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:06 INFO - 'cmd': ('bash', 09:41:06 INFO - '-c', 09:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:06 INFO - 'enabled': True, 09:41:06 INFO - 'halt_on_failure': False, 09:41:06 INFO - 'name': 'check_screen_resolution'},), 09:41:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:06 INFO - 'cmd': ('bash', 09:41:06 INFO - '-c', 09:41:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:06 INFO - 'enabled': True, 09:41:06 INFO - 'halt_on_failure': False, 09:41:06 INFO - 'name': 'check_screen_resolution'},), 09:41:06 INFO - 'run_cmd_checks_enabled': True, 09:41:06 INFO - 'suite': 'dromaeojs-e10s', 09:41:06 INFO - 'system_bits': '32', 09:41:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:41:06 INFO - 'title': 't-yosemite-r7-0097', 09:41:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:41:06 INFO - 'use_talos_json': True, 09:41:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:41:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:41:06 INFO - 'work_dir': 'build'} 09:41:06 INFO - [mozharness: 2017-06-02 16:41:06.790225Z] Running clobber step. 09:41:06 INFO - Running pre-action listener: _resource_record_pre_action 09:41:06 INFO - Running main action method: clobber 09:41:06 INFO - rmtree: /builds/slave/test/build 09:41:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:41:09 INFO - Running post-action listener: _resource_record_post_action 09:41:09 INFO - [mozharness: 2017-06-02 16:41:09.288531Z] Finished clobber step (success) 09:41:09 INFO - [mozharness: 2017-06-02 16:41:09.288690Z] Running read-buildbot-config step. 09:41:09 INFO - Running pre-action listener: _resource_record_pre_action 09:41:09 INFO - Running main action method: read_buildbot_config 09:41:09 INFO - Using buildbot properties: 09:41:09 INFO - { 09:41:09 INFO - "project": "", 09:41:09 INFO - "product": "firefox", 09:41:09 INFO - "who": "ffxbld@noreply.mozilla.org", 09:41:09 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 09:41:09 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 09:41:09 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 09:41:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 09:41:09 INFO - "stage_platform": "macosx64", 09:41:09 INFO - "basedir": "/builds/slave/test", 09:41:09 INFO - "buildnumber": 74, 09:41:09 INFO - "platform": "yosemite_r7", 09:41:09 INFO - "parent_task_id": "Mr8DWtRzRXCJpK8jv9bXMw", 09:41:09 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:41:09 INFO - "slavebuilddir": "test", 09:41:09 INFO - "taskId": "Z_xAkanDRgSzXCqoV16--w", 09:41:09 INFO - "branch": "mozilla-central", 09:41:09 INFO - "script_repo_revision": "production", 09:41:09 INFO - "revision": "87c745019518b1d6cd782534f2553721e5735657", 09:41:09 INFO - "slavename": "t-yosemite-r7-0097", 09:41:09 INFO - "repo_path": "mozilla-central" 09:41:09 INFO - } 09:41:09 INFO - Finding installer, test and symbols from parent task. 09:41:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z_xAkanDRgSzXCqoV16--w'}, attempt #1 09:41:09 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Z_xAkanDRgSzXCqoV16--w'}, attempt #1 09:41:10 INFO - Task dependencies: C9J-pIwWTg-52l31og92ug Mr8DWtRzRXCJpK8jv9bXMw 09:41:10 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw'}, attempt #1 09:41:10 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:41:10 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/test_packages.json 09:41:10 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/target.crashreporter-symbols.zip 09:41:10 INFO - Running post-action listener: _resource_record_post_action 09:41:10 INFO - [mozharness: 2017-06-02 16:41:10.702260Z] Finished read-buildbot-config step (success) 09:41:10 INFO - [mozharness: 2017-06-02 16:41:10.702375Z] Running download-and-extract step. 09:41:10 INFO - Running pre-action listener: _resource_record_pre_action 09:41:10 INFO - Running main action method: download_and_extract 09:41:10 INFO - mkdir: /builds/slave/test/build/tests 09:41:10 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')]} 09:41:10 INFO - trying https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 09:41:10 INFO - Downloading https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/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 09:41:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/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 09:41:11 INFO - Downloaded 1328 bytes. 09:41:11 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 09:41:11 INFO - Using the following test package requirements: 09:41:11 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 09:41:11 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:41:11 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 09:41:11 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 09:41:11 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'jsshell-mac.zip'], 09:41:11 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 09:41:11 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 09:41:11 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 09:41:11 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 09:41:11 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 09:41:11 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 09:41:11 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 09:41:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:41:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:41:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:41:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:41:12 INFO - Content-Length response header: 57630916 09:41:12 INFO - Bytes received: 57630916 09:41:30 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 09:41:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 09:41:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:41:30 INFO - Fetch https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 09:41:32 INFO - Content-Length response header: 57630916 09:41:32 INFO - Bytes received: 57630916 09:41:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 09:41:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:41:49 INFO - Fetch https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 09:41:51 INFO - Content-Length response header: 14447425 09:41:51 INFO - Bytes received: 14447425 09:41:51 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')]} 09:41:51 INFO - trying https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:41:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 09:41:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/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 09:41:53 INFO - Downloaded 67807641 bytes. 09:41:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:41:53 INFO - mkdir: /builds/slave/test/properties 09:41:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:41:53 INFO - Writing to file /builds/slave/test/properties/build_url 09:41:53 INFO - Contents: 09:41:53 INFO - build_url:https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 09:41:53 INFO - Running post-action listener: _resource_record_post_action 09:41:53 INFO - Running post-action listener: set_extra_try_arguments 09:41:53 INFO - [mozharness: 2017-06-02 16:41:53.798171Z] Finished download-and-extract step (success) 09:41:53 INFO - [mozharness: 2017-06-02 16:41:53.798316Z] Running populate-webroot step. 09:41:53 INFO - Running pre-action listener: _resource_record_pre_action 09:41:53 INFO - Running main action method: populate_webroot 09:41:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:41:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:41:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:41:53 INFO - '--tppagecycles', 09:41:53 INFO - '7'], 09:41:53 INFO - 'tests': ['tsvgm']}}, 09:41:53 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:41:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:41:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:41:53 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:41:53 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:41:53 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['damp', 'tps']}, 09:41:53 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'tests': ['damp', 'tps']}, 09:41:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['dromaeo_dom']}, 09:41:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:41:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 09:41:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 09:41:53 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 09:41:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'other-e10s': {'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['a11yr', 09:41:53 INFO - 'ts_paint', 09:41:53 INFO - 'tpaint', 09:41:53 INFO - 'sessionrestore', 09:41:53 INFO - 'sessionrestore_no_auto_restore', 09:41:53 INFO - 'tabpaint']}, 09:41:53 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 09:41:53 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 09:41:53 INFO - 'bloom_basic_ref']}, 09:41:53 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 09:41:53 INFO - 'talos_options': ['--mitmproxy', 09:41:53 INFO - 'mitmproxy-recording-1.mp', 09:41:53 INFO - '--firstNonBlankPaint'], 09:41:53 INFO - 'tests': ['Quantum_1']}, 09:41:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['tsvgx', 09:41:53 INFO - 'tsvgr_opacity', 09:41:53 INFO - 'tart', 09:41:53 INFO - 'tscrollx', 09:41:53 INFO - 'cart', 09:41:53 INFO - 'tsvg_static']}, 09:41:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:41:53 INFO - 'tsvgr_opacity', 09:41:53 INFO - 'tart', 09:41:53 INFO - 'tscrollx', 09:41:53 INFO - 'cart', 09:41:53 INFO - 'tsvg_static']}, 09:41:53 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'talos_options': ['--disable-e10s'], 09:41:53 INFO - 'tests': ['tp5o']}, 09:41:53 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:41:53 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'talos_options': ['--disable-e10s', 09:41:53 INFO - '--xperf_path', 09:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:41:53 INFO - 'tests': ['tp5n']}, 09:41:53 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:41:53 INFO - 'talos_options': ['--xperf_path', 09:41:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:41:53 INFO - 'tests': ['tp5n']}}, 09:41:53 INFO - 'talos.zip': {'path': '', 09:41:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:41:53 INFO - Running post-action listener: _resource_record_post_action 09:41:53 INFO - [mozharness: 2017-06-02 16:41:53.820398Z] Finished populate-webroot step (success) 09:41:53 INFO - [mozharness: 2017-06-02 16:41:53.820518Z] Running create-virtualenv step. 09:41:53 INFO - Running pre-action listener: _resource_record_pre_action 09:41:53 INFO - Running main action method: create_virtualenv 09:41:53 INFO - Creating virtualenv /builds/slave/test/build/venv 09:41:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:41:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:41:53 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 09:41:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:41:53 INFO - Using real prefix '/tools/python27' 09:41:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:41:54 INFO - Installing distribute.............................................................................................................................................................................................done. 09:41:57 INFO - Installing pip.................done. 09:41:57 INFO - Return code: 0 09:41:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:41:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:41:57 INFO - Reading from file tmpfile_stdout 09:41:57 INFO - Output received: 09:41:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:41:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:41:57 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')]} 09:41:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:58 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')]} 09:41:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:58 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:58 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 09:41:58 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 09:41:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:41:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:58 INFO - 'HOME': '/Users/cltbld', 09:41:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:58 INFO - 'LOGNAME': 'cltbld', 09:41:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:58 INFO - 'MOZ_NO_REMOTE': '1', 09:41:58 INFO - 'NO_EM_RESTART': '1', 09:41:58 INFO - 'PAGER': '/bin/cat', 09:41:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:58 INFO - 'PWD': '/builds/slave/test', 09:41:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:58 INFO - 'SHELL': '/bin/bash', 09:41:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:41:58 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:41:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:58 INFO - 'USER': 'cltbld', 09:41:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:58 INFO - 'XPC_FLAGS': '0x0', 09:41:58 INFO - 'XPC_SERVICE_NAME': '0', 09:41:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:59 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:41:59 INFO - Cleaning up... 09:41:59 INFO - Return code: 0 09:41:59 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:41:59 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')]} 09:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:59 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')]} 09:41:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:59 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:59 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 09:41:59 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 09:41:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:41:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:59 INFO - 'HOME': '/Users/cltbld', 09:41:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:59 INFO - 'LOGNAME': 'cltbld', 09:41:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:59 INFO - 'MOZ_NO_REMOTE': '1', 09:41:59 INFO - 'NO_EM_RESTART': '1', 09:41:59 INFO - 'PAGER': '/bin/cat', 09:41:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:59 INFO - 'PWD': '/builds/slave/test', 09:41:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:59 INFO - 'SHELL': '/bin/bash', 09:41:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:41:59 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:41:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:59 INFO - 'USER': 'cltbld', 09:41:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:59 INFO - 'XPC_FLAGS': '0x0', 09:41:59 INFO - 'XPC_SERVICE_NAME': '0', 09:41:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:59 INFO - Downloading/unpacking psutil>=3.1.1 09:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:41:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:41:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:42:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:02 INFO - Installing collected packages: psutil 09:42:02 INFO - Running setup.py install for psutil 09:42:02 INFO - building 'psutil._psutil_osx' extension 09:42:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:42:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:42:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:42:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:42:03 INFO - building 'psutil._psutil_posix' extension 09:42:03 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:42:03 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:42:03 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:42:03 INFO - ^ 09:42:03 INFO - 1 warning generated. 09:42:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:42:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:03 INFO - Successfully installed psutil 09:42:03 INFO - Cleaning up... 09:42:03 INFO - Return code: 0 09:42:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:42:03 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')]} 09:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:03 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')]} 09:42:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:03 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 09:42:03 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 09:42:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:03 INFO - 'HOME': '/Users/cltbld', 09:42:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:03 INFO - 'LOGNAME': 'cltbld', 09:42:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:03 INFO - 'MOZ_NO_REMOTE': '1', 09:42:03 INFO - 'NO_EM_RESTART': '1', 09:42:03 INFO - 'PAGER': '/bin/cat', 09:42:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:03 INFO - 'PWD': '/builds/slave/test', 09:42:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:03 INFO - 'SHELL': '/bin/bash', 09:42:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:03 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:03 INFO - 'USER': 'cltbld', 09:42:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:03 INFO - 'XPC_FLAGS': '0x0', 09:42:03 INFO - 'XPC_SERVICE_NAME': '0', 09:42:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:42:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:42:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:42:05 INFO - Installing collected packages: mozsystemmonitor 09:42:05 INFO - Running setup.py install for mozsystemmonitor 09:42:05 INFO - Successfully installed mozsystemmonitor 09:42:05 INFO - Cleaning up... 09:42:05 INFO - Return code: 0 09:42:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:42:05 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')]} 09:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:05 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')]} 09:42:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:05 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:05 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 09:42:05 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 09:42:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:05 INFO - 'HOME': '/Users/cltbld', 09:42:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:05 INFO - 'LOGNAME': 'cltbld', 09:42:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:05 INFO - 'MOZ_NO_REMOTE': '1', 09:42:05 INFO - 'NO_EM_RESTART': '1', 09:42:05 INFO - 'PAGER': '/bin/cat', 09:42:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:05 INFO - 'PWD': '/builds/slave/test', 09:42:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:05 INFO - 'SHELL': '/bin/bash', 09:42:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:05 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:05 INFO - 'USER': 'cltbld', 09:42:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:05 INFO - 'XPC_FLAGS': '0x0', 09:42:05 INFO - 'XPC_SERVICE_NAME': '0', 09:42:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:05 INFO - Downloading/unpacking jsonschema==2.5.1 09:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:06 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:42:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:07 INFO - Downloading functools32-3.2.3-2.tar.gz 09:42:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:42:07 INFO - warning: no files found matching '*.txt' 09:42:07 INFO - no previously-included directories found matching 'build' 09:42:07 INFO - no previously-included directories found matching 'dist' 09:42:07 INFO - no previously-included directories found matching '.git*' 09:42:07 INFO - Installing collected packages: jsonschema, functools32 09:42:07 INFO - Running setup.py install for functools32 09:42:07 INFO - warning: no files found matching '*.txt' 09:42:07 INFO - no previously-included directories found matching 'build' 09:42:07 INFO - no previously-included directories found matching 'dist' 09:42:07 INFO - no previously-included directories found matching '.git*' 09:42:07 INFO - Successfully installed jsonschema functools32 09:42:07 INFO - Cleaning up... 09:42:07 INFO - Return code: 0 09:42:07 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:42:07 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')]} 09:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:07 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')]} 09:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:07 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:07 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 09:42:07 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 09:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:07 INFO - 'HOME': '/Users/cltbld', 09:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:07 INFO - 'LOGNAME': 'cltbld', 09:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:07 INFO - 'MOZ_NO_REMOTE': '1', 09:42:07 INFO - 'NO_EM_RESTART': '1', 09:42:07 INFO - 'PAGER': '/bin/cat', 09:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:07 INFO - 'PWD': '/builds/slave/test', 09:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:07 INFO - 'SHELL': '/bin/bash', 09:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:07 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:07 INFO - 'USER': 'cltbld', 09:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:07 INFO - 'XPC_FLAGS': '0x0', 09:42:07 INFO - 'XPC_SERVICE_NAME': '0', 09:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:42:07 INFO - Cleaning up... 09:42:07 INFO - Return code: 0 09:42:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:42:07 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')]} 09:42:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:07 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')]} 09:42:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:07 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:07 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 09:42:07 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 09:42:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:07 INFO - 'HOME': '/Users/cltbld', 09:42:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:07 INFO - 'LOGNAME': 'cltbld', 09:42:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:07 INFO - 'MOZ_NO_REMOTE': '1', 09:42:07 INFO - 'NO_EM_RESTART': '1', 09:42:07 INFO - 'PAGER': '/bin/cat', 09:42:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:07 INFO - 'PWD': '/builds/slave/test', 09:42:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:07 INFO - 'SHELL': '/bin/bash', 09:42:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:07 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:07 INFO - 'USER': 'cltbld', 09:42:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:07 INFO - 'XPC_FLAGS': '0x0', 09:42:07 INFO - 'XPC_SERVICE_NAME': '0', 09:42:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:08 INFO - Downloading/unpacking blobuploader==1.2.4 09:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:09 INFO - Downloading blobuploader-1.2.4.tar.gz 09:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:42:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:42: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 09:42: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 09:42: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 09:42: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 09:42:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:42:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:10 INFO - Downloading docopt-0.6.1.tar.gz 09:42:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:42:10 INFO - Installing collected packages: blobuploader, requests, docopt 09:42:10 INFO - Running setup.py install for blobuploader 09:42:10 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:42:10 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:42:10 INFO - Running setup.py install for requests 09:42:11 INFO - Running setup.py install for docopt 09:42:11 INFO - Successfully installed blobuploader requests docopt 09:42:11 INFO - Cleaning up... 09:42:11 INFO - Return code: 0 09:42:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42:11 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')]} 09:42:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:11 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')]} 09:42:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:11 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:11 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 09:42:11 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 09:42:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:11 INFO - 'HOME': '/Users/cltbld', 09:42:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:11 INFO - 'LOGNAME': 'cltbld', 09:42:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:11 INFO - 'MOZ_NO_REMOTE': '1', 09:42:11 INFO - 'NO_EM_RESTART': '1', 09:42:11 INFO - 'PAGER': '/bin/cat', 09:42:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:11 INFO - 'PWD': '/builds/slave/test', 09:42:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:11 INFO - 'SHELL': '/bin/bash', 09:42:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:11 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:11 INFO - 'USER': 'cltbld', 09:42:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:11 INFO - 'XPC_FLAGS': '0x0', 09:42:11 INFO - 'XPC_SERVICE_NAME': '0', 09:42:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:11 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-2PUY31-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:11 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-QuH1QL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:11 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-6r0xcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:11 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-WmpZYq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-lknxSD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-AKLuVZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-k9ZICE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-ytrPD2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-6qHV5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-du5vgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-XGFHsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-46IniV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-KLfqpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:12 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-UGNO6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:13 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-f0GuTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:13 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-5MDQhn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:13 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-NKkXXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:13 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:42:13 INFO - Running setup.py install for manifestparser 09:42:13 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:42:13 INFO - Running setup.py install for mozcrash 09:42:13 INFO - Running setup.py install for mozdebug 09:42:13 INFO - Running setup.py install for mozdevice 09:42:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:42:13 INFO - Running setup.py install for mozfile 09:42:14 INFO - Running setup.py install for mozhttpd 09:42:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Running setup.py install for mozinfo 09:42:14 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Running setup.py install for mozInstall 09:42:14 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Running setup.py install for mozleak 09:42:14 INFO - Running setup.py install for mozlog 09:42:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:42:14 INFO - Running setup.py install for moznetwork 09:42:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Running setup.py install for mozprocess 09:42:15 INFO - Running setup.py install for mozprofile 09:42:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Running setup.py install for mozrunner 09:42:15 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Running setup.py install for mozscreenshot 09:42:15 INFO - Running setup.py install for moztest 09:42:15 INFO - Running setup.py install for mozversion 09:42:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:42:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:42:15 INFO - Cleaning up... 09:42:15 INFO - Return code: 0 09:42:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42:15 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')]} 09:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:15 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')]} 09:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:15 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:15 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 09:42:15 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 09:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:15 INFO - 'HOME': '/Users/cltbld', 09:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:15 INFO - 'LOGNAME': 'cltbld', 09:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:15 INFO - 'MOZ_NO_REMOTE': '1', 09:42:15 INFO - 'NO_EM_RESTART': '1', 09:42:15 INFO - 'PAGER': '/bin/cat', 09:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:15 INFO - 'PWD': '/builds/slave/test', 09:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:15 INFO - 'SHELL': '/bin/bash', 09:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:15 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:15 INFO - 'USER': 'cltbld', 09:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:15 INFO - 'XPC_FLAGS': '0x0', 09:42:15 INFO - 'XPC_SERVICE_NAME': '0', 09:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-Hz5UrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-20OT4e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-c0GY5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-YOv7zY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:16 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)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-V7K0b1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-dpx1Oh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-olqTpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:42:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:16 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-8OptgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-q0SEzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-qmbpQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-1ODg6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-qqo_8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:17 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)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-aWw6nj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-nfKta1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-1i5gVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-agKAlI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:42:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:17 INFO - Running setup.py (path:/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/pip-MAjkx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:42:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:42:17 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)) 09:42:17 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)) 09:42:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:42: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 09:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:19 INFO - Downloading blessings-1.6.tar.gz 09:42:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:42:19 INFO - Installing collected packages: blessings 09:42:19 INFO - Running setup.py install for blessings 09:42:19 INFO - Successfully installed blessings 09:42:19 INFO - Cleaning up... 09:42:19 INFO - Return code: 0 09:42:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:42:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:19 INFO - Reading from file tmpfile_stdout 09:42:19 INFO - Current package versions: 09:42:19 INFO - blessings == 1.6 09:42:19 INFO - blobuploader == 1.2.4 09:42:19 INFO - docopt == 0.6.1 09:42:19 INFO - functools32 == 3.2.3-2 09:42:19 INFO - jsonschema == 2.5.1 09:42:19 INFO - manifestparser == 1.1 09:42:19 INFO - mozInstall == 1.12 09:42:19 INFO - mozcrash == 1.0 09:42:19 INFO - mozdebug == 0.1 09:42:19 INFO - mozdevice == 0.51 09:42:19 INFO - mozfile == 1.2 09:42:19 INFO - mozhttpd == 0.7 09:42:19 INFO - mozinfo == 0.9 09:42:19 INFO - mozleak == 0.1 09:42:19 INFO - mozlog == 3.4 09:42:19 INFO - moznetwork == 0.27 09:42:19 INFO - mozprocess == 0.25 09:42:19 INFO - mozprofile == 0.28 09:42:19 INFO - mozrunner == 6.13 09:42:19 INFO - mozscreenshot == 0.1 09:42:19 INFO - mozsystemmonitor == 0.3 09:42:19 INFO - moztest == 0.8 09:42:19 INFO - mozversion == 1.4 09:42:19 INFO - psutil == 3.1.1 09:42:19 INFO - requests == 1.2.3 09:42:19 INFO - wsgiref == 0.1.2 09:42:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42:19 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')]} 09:42:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:19 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')]} 09:42:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:20 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:20 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 09:42:20 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 09:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:20 INFO - 'HOME': '/Users/cltbld', 09:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:20 INFO - 'LOGNAME': 'cltbld', 09:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:20 INFO - 'MOZ_NO_REMOTE': '1', 09:42:20 INFO - 'NO_EM_RESTART': '1', 09:42:20 INFO - 'PAGER': '/bin/cat', 09:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:20 INFO - 'PWD': '/builds/slave/test', 09:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:20 INFO - 'SHELL': '/bin/bash', 09:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:20 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:20 INFO - 'USER': 'cltbld', 09:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:20 INFO - 'XPC_FLAGS': '0x0', 09:42:20 INFO - 'XPC_SERVICE_NAME': '0', 09:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:42:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:42:20 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 09:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:21 INFO - Downloading marionette_driver-1.4.0.tar.gz 09:42:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 09:42:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:42:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:42:21 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)) 09:42:21 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)) 09:42:21 INFO - Installing collected packages: marionette-driver 09:42:21 INFO - Running setup.py install for marionette-driver 09:42:21 INFO - Successfully installed marionette-driver 09:42:21 INFO - Cleaning up... 09:42:21 INFO - Return code: 0 09:42:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:42:21 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')]} 09:42:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:21 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')]} 09:42:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:21 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 0x101ab5c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ab9768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe683d7e810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, '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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:42:21 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 09:42:21 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 09:42:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:21 INFO - 'HOME': '/Users/cltbld', 09:42:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:21 INFO - 'LOGNAME': 'cltbld', 09:42:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:21 INFO - 'MOZ_NO_REMOTE': '1', 09:42:21 INFO - 'NO_EM_RESTART': '1', 09:42:21 INFO - 'PAGER': '/bin/cat', 09:42:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:21 INFO - 'PWD': '/builds/slave/test', 09:42:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:21 INFO - 'SHELL': '/bin/bash', 09:42:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:21 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:21 INFO - 'USER': 'cltbld', 09:42:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:21 INFO - 'XPC_FLAGS': '0x0', 09:42:21 INFO - 'XPC_SERVICE_NAME': '0', 09:42:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:42:22 INFO - Cleaning up... 09:42:22 INFO - Return code: 0 09:42:22 INFO - Running post-action listener: _resource_record_post_action 09:42:22 INFO - Running post-action listener: _start_resource_monitoring 09:42:22 INFO - Starting resource monitoring. 09:42:22 INFO - [mozharness: 2017-06-02 16:42:22.280531Z] Finished create-virtualenv step (success) 09:42:22 INFO - [mozharness: 2017-06-02 16:42:22.281508Z] Running install step. 09:42:22 INFO - Running pre-action listener: _resource_record_pre_action 09:42:22 INFO - Running main action method: install 09:42:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:22 INFO - Reading from file tmpfile_stdout 09:42:22 INFO - Detecting whether we're running mozinstall >=1.0... 09:42:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:42:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:42:22 INFO - Reading from file tmpfile_stdout 09:42:22 INFO - Output received: 09:42:22 INFO - Usage: mozinstall [options] installer 09:42:22 INFO - Options: 09:42:22 INFO - -h, --help show this help message and exit 09:42:22 INFO - -d DEST, --destination=DEST 09:42:22 INFO - Directory to install application into. [default: 09:42:22 INFO - "/builds/slave/test"] 09:42:22 INFO - --app=APP Application being installed. [default: firefox] 09:42:22 INFO - mkdir: /builds/slave/test/build/application 09:42:22 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'] 09:42:22 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 09:42:43 INFO - Reading from file tmpfile_stdout 09:42:43 INFO - Output received: 09:42:43 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:42:43 INFO - Running post-action listener: _resource_record_post_action 09:42:43 INFO - [mozharness: 2017-06-02 16:42:43.779371Z] Finished install step (success) 09:42:43 INFO - [mozharness: 2017-06-02 16:42:43.779508Z] Running setup-mitmproxy step. 09:42:43 INFO - Running pre-action listener: _resource_record_pre_action 09:42:43 INFO - Running main action method: setup_mitmproxy 09:42:43 INFO - Skipping: mitmproxy is not required 09:42:43 INFO - Running post-action listener: _resource_record_post_action 09:42:43 INFO - [mozharness: 2017-06-02 16:42:43.780192Z] Finished setup-mitmproxy step (success) 09:42:43 INFO - [mozharness: 2017-06-02 16:42:43.780330Z] Running run-tests step. 09:42:43 INFO - Running pre-action listener: _resource_record_pre_action 09:42:43 INFO - Running main action method: run_tests 09:42:43 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:42:43 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:42:43 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:42:43 INFO - 2017-06-02 09:42:43.795 screenresolution[1625:7446] starting screenresolution argv=screenresolution get 09:42:43 INFO - 2017-06-02 09:42:43.797 screenresolution[1625:7446] Display 0: 1600x1200x32@60 09:42:43 INFO - 2017-06-02 09:42:43.803 screenresolution[1626:7448] starting screenresolution argv=screenresolution list 09:42:43 INFO - Available Modes on Display 0 09:42:43 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:42:43 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:42:43 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:42:43 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:42:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:42:44 INFO - Intel Iris: 09:42:44 INFO - Chipset Model: Intel Iris 09:42:44 INFO - Type: GPU 09:42:44 INFO - Bus: Built-In 09:42:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:42:44 INFO - Vendor: Intel (0x8086) 09:42:44 INFO - Device ID: 0x0a2e 09:42:44 INFO - Revision ID: 0x0009 09:42:44 INFO - Displays: 09:42:44 INFO - Display: 09:42:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:42:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:42:44 INFO - Display Serial Number: 1600x1200 60 09:42:44 INFO - Main Display: Yes 09:42:44 INFO - Mirror: Off 09:42:44 INFO - Online: Yes 09:42:44 INFO - Rotation: Supported 09:42:44 INFO - Return code: 0 09:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:42:44 INFO - Python 2.7.3 09:42:44 INFO - Return code: 0 09:42:44 INFO - grabbing minidump binary from tooltool 09:42:44 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')]} 09:42:44 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 0x101a3ec60>, '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 0x1017d3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1017fcbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:42:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:42:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:42:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:42:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:42:44 INFO - Return code: 0 09:42:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:42:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:42:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:42:44 INFO - ENV: RUST_BACKTRACE is now 1 09:42:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:42:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:42:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:42:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0097 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:42:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qqf9cUCtFk/Render', 09:42:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:44 INFO - 'HOME': '/Users/cltbld', 09:42:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:44 INFO - 'LOGNAME': 'cltbld', 09:42:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:42:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:42:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:44 INFO - 'MOZ_NO_REMOTE': '1', 09:42:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:42:44 INFO - 'NO_EM_RESTART': '1', 09:42:44 INFO - 'PAGER': '/bin/cat', 09:42:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:44 INFO - 'PWD': '/builds/slave/test', 09:42:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:42:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:44 INFO - 'RUST_BACKTRACE': '1', 09:42:44 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 09:42:44 INFO - 'SHELL': '/bin/bash', 09:42:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.BtYK7QV9Qd/Listeners', 09:42:44 INFO - 'TMPDIR': '/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/', 09:42:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:44 INFO - 'USER': 'cltbld', 09:42:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:44 INFO - 'XPC_FLAGS': '0x0', 09:42:44 INFO - 'XPC_SERVICE_NAME': '0', 09:42:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0097', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:42:44 INFO - mozversion application_buildid: 20170602082634 09:42:44 INFO - mozversion application_changeset: 87c745019518b1d6cd782534f2553721e5735657 09:42:44 INFO - mozversion application_display_name: Nightly 09:42:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:42:44 INFO - mozversion application_name: Firefox 09:42:44 INFO - mozversion application_remotingname: firefox 09:42:44 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:42:44 INFO - mozversion application_vendor: Mozilla 09:42:44 INFO - mozversion application_version: 55.0a1 09:42:44 INFO - mozversion platform_buildid: 20170602082634 09:42:44 INFO - mozversion platform_changeset: 87c745019518b1d6cd782534f2553721e5735657 09:42:44 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:42:44 INFO - mozversion platform_version: 55.0a1 09:42:44 INFO - using testdate: 1496421764 09:42:44 INFO - actual date: 1496421764 09:42:44 INFO - starting webserver on 'localhost:49230' 09:42:44 INFO - SUITE-START | Running 2 tests 09:42:44 INFO - TEST-START | dromaeo_css 09:42:44 INFO - operating with platform_type : mac_ 09:42:44 INFO - Initialising browser for dromaeo_css test... 09:42:44 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpBstRg5/profile 09:42:44 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 09:43:18 INFO - TEST-INFO | 1636: killed by SIGTERM 09:43:18 INFO - Browser initialized. 09:43:18 INFO - Running cycle 1/1 for dromaeo_css test... 09:43:18 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpBstRg5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:20 INFO - PID 1646 | 2017-06-02 09:43:20.583 plugin-container[1647:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993b, name = 'com.apple.tsm.portname' 09:43:20 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:20 INFO - PID 1646 | 2017-06-02 09:43:20.583 plugin-container[1647:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c1f, name = 'com.apple.CFPasteboardClient' 09:43:20 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:20 INFO - PID 1646 | 2017-06-02 09:43:20.583 plugin-container[1647:8026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:21 INFO - PID 1646 | 2017-06-02 09:43:21.085 plugin-container[1648:8073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 09:43:21 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:21 INFO - PID 1646 | 2017-06-02 09:43:21.085 plugin-container[1648:8073] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x490b, name = 'com.apple.CFPasteboardClient' 09:43:21 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:43:21 INFO - PID 1646 | 2017-06-02 09:43:21.085 plugin-container[1648:8073] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:43:30 INFO - PID 1646 | RSS: Main: 269062144 09:43:30 INFO - PID 1646 | 09:46:32 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 09:46:34 INFO - PID 1646 | RSS: Main: 268275712 09:46:34 INFO - PID 1646 | 09:49:35 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 09:49:36 INFO - PID 1646 | RSS: Main: 282697728 09:49:36 INFO - PID 1646 | 09:52:38 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 09:52:39 INFO - PID 1646 | RSS: Main: 282169344 09:52:39 INFO - PID 1646 | 09:55:40 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 09:55:41 INFO - PID 1646 | RSS: Main: 286883840 09:55:41 INFO - PID 1646 | 09:58:44 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 09:58:45 INFO - PID 1646 | RSS: Main: 286937088 09:58:45 INFO - PID 1646 | 10:01:46 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 10:01:47 INFO - PID 1646 | RSS: Main: 286814208 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | __start_tp_report 10:01:47 INFO - PID 1646 | _x_x_mozilla_page_load 10:01:47 INFO - PID 1646 | _x_x_mozilla_page_load_details 10:01:47 INFO - PID 1646 | |i|pagename|runs| 10:01:47 INFO - PID 1646 | |0;dojo.html;3251.3334766372896;3473.7394695397843;3626.0753507855493;3642.726795490339;3665.321915445643;6160.81517554473;6167.414095660916;6203.2245969253845;6243.406876346751;6274.968625156874;4444.555544445562;4874.025194961005;6221.191245138126;6245.7501699931945;6250.937490625092;5383.784648614051;5422.267993820843;5441.102218134003;5473.917891231635;5555.555555555546;4543.363929049936;5273.815416460425;5338.706371149585;5552.77788888444;5557.472040156199;5462.781488740486;5487.972560137214;5532.114861622142;5532.7786888524415;5544.251526043993;5353.2237825515085;5415.620906536544;5432.239486471897;5470.617056806025;5472.26124473195;4620.052889157714;5417.891642167135;5427.158790387496;5465.672059676433;5473.2063850741415;6110.877782444326;6213.844653883689;6216.7824126155365;6253.906191407133;6255.40573645503;5283.815066472655;6451.6774161292215;6533.346665333476;6724.159480064992;6732.83167920801;6695.665216739144;6701.932980670228;6818.18181818185;6821.931780682179;6822.965885170543;6261.592996455216;6270.059491076375;6291.02489114188;6291.842703932392;6299.811005669837;6738.663066846638;6794.796156115324;6893.827654308682;6902.344277293649;6948.513604047769;5828.358880523139;6429.710663020175;6437.452816510556;6450.6774661266745;6471.0940468334475;6748.763793267212;6752.189737231563;6758.087658166183;6761.425278851254;6767.695453704595;6497.707603157869;6610.3720146586;6643.10318107059;6663.433608143362;6667.899981500282;6560.967195163994;6581.308962558958;6660.666966651745;6684.532082754161;6726.56277341981;5355.571554275649;5379.273798037293;5380.354357477049;5382.219578161144;5393.7303134843105;4805.735684537384;4808.8797780055775;4811.542903424169;4815.277708343707;4827.903441931142;6179.598326108789;6225.097360882647;6256.843578910563;6312.084747711556;6386.169797926333;6824.897626535606;6854.3145685432055;6869.347411995853;6870.553414028072;6888.690008949609;6247.468965138014;6308.432241098231;6344.524160687967;6346.714397852015;6348.111264422984;5840.766369345179;5912.201852749909;5954.136650185699;6069.0592958091565;6074.787382441594;6846.178458585002;7014.789556313217;7023.578585284899;7026.824329391806;7042.014118023379;6767.763128290486;6831;6852.040714299902;6853.897191542231;6864.450843932374;6742.292059333699;6798.626075565891;6839.1451068616425;6860.348266914635;6876.587404755531;6623.569467984566;6627.183447035087;6640.236372816995;6656.933430665825;6673.733050677918;5455.754491047833;5490.85189830567;5592.133219351007;6632.933670663231;6788.830279243059;6555.410013098844;6573.5398522103;6608.537402381787;6618.139641846529;6632.137822083098;6564.737410503526;6595.868082638224;6597.60414375139;6611.041398997215;6622.768203112868;5501.669899806025;6252.468540174136;6319.6840157991;6340.809775706736;6402.935970640234;5515.255440515479;6483.546151769331;6535.411812936859;6557.1147895035465;6558.967205163944;6353.332900045429;6370.203724534434;6388.744450221939;6400.711967961546;6426.100345951664 10:01:47 INFO - PID 1646 | |1;ext.html;693.3828892285866;798.7044793426433;834.7537476444451;1253.868343823898;1313.743819955109;36620.267594648125;37380.99999999996;37420.43869341955;37486.437703434436;37491;37616;37860.43209351855;37908.620913790925;37926.810365948135;38027.42958855612;7947.880781788263;8172.387070969671;8179.959100204491;8210.261076503113;8212.055613604422;8041.396895232851;8158.632861521246;8161.428699991003;8167.387445941548;8179.100298967139;8057.234562716532;8092.393070519705;8096.190380961915;8175.95912020439;8185.467944583613;8029.7189598363775;8057.758267251992;8108.7431448125635;8122.959385203036;8124.959375203145;7957.721479748181;7969.322607578363;8022.27799498048;8043.316318112968;8060.5566693831815;35334.46998295028;35416;35472.290554188774;35592.28815423677;35680;3567.839447224881;3604.4052731299303;3613.277344531078;3617.4935509028755;3621.112827357288;29061.273468163254;29103.70896291031;29186.270343241376;29234.269143271376;29237;3018.7886847920654;3076.3539656672124;3086.4907290297074;3087.197328694533;3104.5498402731478;248939.75530122232;250189;268766;303926;305998;45776;45776.771116144875;45842.770786146524;45909.540904590525;45973.77013114913;47337;47660.28509572359;47668.52331476641;49477.52612369367;49823.75088124536;13974.231417271943;14086.859131408555;14099.436022558983;14102;14117.78823317651;11330.94334528322;11390.145739069603;11428.94285528567;11443.828342574869;11462.942685286687;13082.672933176747;13115.27865967381;13152.079354445097;13177.73644527104;13268.867311326763;5748.189320364072;6123.632582045091;6130.386961303834;6163.506919446433;6176.907346389808;881.8589025755848;999.3354419311222;1003.603576587244;1007.089511312308;1054.625607909181;18788.624227515375;23033.539329213658;23947.640785388234;24080.51838963211;24214.87892560561;1028.3726926574784;1106.9944650276698;1133.6825688807148;1143.2168964259508;1144.3477217985835;12225.755484890253;12340.259584424963;12348.444320005621;12355.876441235652;12370.75258494825;289669;331757;333024;333684.33157834056;335226;51180.48819511832;51585.48414515882;51678.48321516737;51678.48321516812;51712.70718231999;1503.421182844591;1546.8066491688764;1558.0262336039975;1560.251079481855;1573.0483057750125;19725.80274197297;21648.8093154878;23734;23839.880800595885;23931.401714957265;36588.45117323189;36653;36662;36665.81667091647;36765.44851827278;269825;269892;269974;270350;270544;33030;33039.843605474656;33096.007119786445;33204.83783067581;33232.667673322954;40969;41513.58486415218;41869.743907682816;42897;43174.136517270104;28238;29460.852695736383;30148.09555713332;30270;30569;24687.62968555509;29517.557236641896;30117.247068823453;30163.396732065674;30397.240068998453;35596.64403356037;36272.274554508236;36273.637263627024;36325.45511817272;36334;28290.85854570714;30776.69223307828;31313.868613138504;31475.527867081553;31575.684243158194;19683.803161968197;24039.639405408583;24308.75691243065;24462.633060504464;25017.374565636004 10:01:47 INFO - PID 1646 | |2;jquery.html;431.8143198424676;437.64550713921744;443.758151807265;453.961413279871;458.617054759276;21385;22150.446238844037;22930.541389172206;23121.421964450878;23168.768312316894;24595.508089838193;25231.24306270815;25264;25287.74712252877;25371.985120595153;6191;6355.173827402431;6364.936350636503;6366.204224471941;6453;6304.33804450533;6344.9530827413655;6346.492280617563;6357.777477788279;6358.0462930560325;5900.734466948976;6163.476104531121;6166.475849552793;6217.409346112112;6295.338989406115;6041.305249896231;6098.237720284965;6134.815955521297;6136.325004249529;6142.324344322099;5469.261649677323;5469.835904922858;5476.726163691799;5497.39528651848;5499.312585926759;29312;29433.411331773244;29481;29977.400451991056;30014.549781753292;2137.358792362285;2178.5316157026314;2192.3422973108163;2195.3962660268394;2197.516546359798;18308.176132074088;18770.530736731554;18781.966991815443;18783.154758035922;18818.529536761554;2132.349633361824;2158.4280165756086;2193.243331050785;2196.648536234195;2217.800397964191;166843.1657841703;175889.04943079725;202513.98743006337;207519;210607.9469602642;20471.692924606145;24893.62659560108;26000;26365.736342636712;26441.206763797116;43561.34657980133;44016.77991610085;44024;44032.779836101254;44067.33898991453;11525.077993760648;11550.075993920465;11652.892975167346;11723;11727.706807329714;9052.366334356531;9148.817023659489;9159.129882661136;9220.631174753069;9227.53862306882;9811.754706132404;10023.448710320856;11813.936745692929;12313;12387.504499819906;3921.5882332355263;4033.737807042533;4145.419641250227;4981.256560203899;4988;916.4455504419864;946.3564775952281;965.3773316211145;972.6401231544373;1005.2912696548915;21414.072733454064;21747.347579572637;21761.67357489607;21870.687758734526;22293.99677014506;1009.1825621246813;1013.7465633591602;1026.2200727447046;1032.7521394865328;1039.6335391356333;11129.888701112885;11277;11415.200935934405;11453.885461145448;11473.942630286796;490583;554244;563465.2881240709;565154.3484565102;570505;129864.350678246;152315;153148.234258828;153799.23100384427;153929;1144.056153673223;1193.5106606291379;1256.0767835640797;1267.66406902171;1268.892144167756;21751.456213594785;22689.760773161604;23064;23242.767572324738;23770.286891393287;62790.05814912689;63801.680991594745;63875.12561934817;65055.34944650493;65244.36889077811;84641.57679211565;87013.69479457686;87325.56337218273;87347.1265287339;90549.09450905406;9789.363691360039;10644.574217031231;10664.733381665521;10667.039966403061;10669.57321707123;18180.545486362946;18247.087645617932;18379.264829406675;18517.907410463402;18546.258149674402;14871.47949821751;14982.75258711202;14998.95007349475;15104.093754374773;15142.394304227708;11063.668089957315;11601.607807063207;13267.66830829237;13375.933120334725;13573.728525429633;15205.923970380078;15490.9225453872;15743.291551879964;15774.816888733161;15780.526584202493;10447.94776026115;10553.102986245947;10565.947170264408;10629.25595208328;10632.202584806017;14460.421583136978;15011.32449039774;15054.473093441677;15124.243787810785;15145.394184232508 10:01:47 INFO - PID 1646 | |3;mootools.html;1258.1696080586814;1466.516049703598;1470.2501724120696;1471.6026672798346;1483.1768368555454;11877.7624447511;12109.636710898665;12415.503379864806;12447.502099916004;12507.562235321766;12160;12169;12371.628851134457;12393.56622518212;12399.256044637306;2526.810489213307;2582.7546383093577;2584.8319859209187;2589.935251618715;2590;2514.9119780807673;2564.4101856572997;2567.9856456699595;2568.3322336192537;2569.6017117346746;2494.114589320792;2536.936576585582;2547.4013606802387;2547.4522977559823;2548.9362765930814;2477.6035834266613;2496.6379874918125;2506.699196096462;2509.3224829296078;2512.510060538196;2446.620773780067;2447.21689059501;2450.644656524794;2549.6557964674716;2549.7450254974538;11908.58319958806;12025.63923082309;12042.63872083839;12154.149209555335;12164.931961566466;6554.672266386661;6832.385085342294;6835.624040677761;6855.177378714537;6878.793636190922;4144.41978123063;4351.760653164075;4365.5416181300825;4393.670474714378;4414.09511050234;1250.8061250506187;1271.1292764456284;1297.7145028093805;1298.5455090718267;1301.0437328563498;15845;16293.91853040739;16891;16978.575535611708;16985.91507042457;5691.516221121168;5725.656460612377;5936.347001829795;5938.61399009063;5955.52355811534;12371.010319174624;12625.05312101596;12626.053046021585;12637.115401921777;12643.683907902376;6563.835904102436;6662.600243985376;6667.266600674019;6670.166229221348;6673.132492776005;5508.44915508446;5791.8552036199435;6169.784057557964;6248.50011999039;6248.750049997949;4447.021655235843;4493.168763778646;4514.0069184779295;4526.366308716782;4541.750203738826;1282.7242142939126;1300.9154404963647;1306.8301120854228;1312.2809120052762;1320.650027742649;838.1995194589152;845.3195177881864;858.8454078265972;862.6937437209825;888.8311220868014;6304;6748.089007983859;6893.689783959734;6910.343517365943;6915.688794004282;595.220261457492;599.4125756758314;601.3925934805877;616.7348040342606;622.330994181257;4371.475422949203;4418;4959.2561115832195;5194.584433245332;5409.83770486886;15219.391224350902;15241.542753717406;15274.236288185546;15419.61450963735;15439.228038598025;11393.772124557463;15269.9236503819;15313.46402875894;15509.302081406138;15544;8587.184217499327;8729.297643287719;8743.737687869374;8811.603477843384;8815.206631403205;7328.377087947584;7648.80877978055;7785.676435006176;7807.921920780719;7944.44388892772;13855.445782168601;13865.722685546434;13934.163950163023;13985.510507132201;14135.15189088658;13905.443782248596;14044.368003440075;14378.496752613608;14448.060876043024;14604.26978651042;9522.0954009369;9698.175655069399;9826.55780489894;9847.803043939224;9850.802983940137;10578;10678.519466624177;10688.572457101629;10699.090577301016;10734.838977415502;8857.557122143997;9114;9128.908710913072;9167.266618670357;9204;7687.577183254974;7734.187910269341;7805.258500442449;7883.093444253984;7945.642446089824;8425.915740842513;8432.409731318749;8528.701495447629;8528.872066919126;8537.871931920901;5825.737841797214;5899.879022985619;6389.2971773105855;6389.87220255583;6534.183227096613;6379.5215358847345;6386.904196436964;6497.577657452251;6642.867142657024;6748.662566871539 10:01:47 INFO - PID 1646 | |4;prototype.html;383.40572113224493;399.6123759952845;407.59647948530954;410.58530883807344;413.037622339948;4756.429228492577;5466.671999680012;5667.433256674336;6404.839879003021;6511.186101737283;5312.9545125126815;5532.695701736403;5765.673895004152;5852.882942341151;6296.307406185316;4601.676153542473;4619.42257217847;4621.953780462186;4640.953590464086;4649.256119020957;4453.6437085033285;4630.2360110581885;4643.815826964119;4649.67452278339;4669.953300467003;4586.816527338902;4598.425196850394;4601.447826260854;4603.240465323234;4606.378138951249;3731.9626803731885;3816.9809150954347;4534.641986167191;4614.7770840727235;4661.184292748772;3970.682345412389;3974.7813870237123;3980.144268982183;3982.38273067675;4012.257732319501;5021.39743230811;5881.529477641821;6210.720517576675;6220.626762394226;6255.593386429868;5476.534494567966;5478.561715062785;5509.531689806371;5516.91724624131;5518.19986102017;6062.666553339609;6614.404703576702;6763.492738044666;6789.694463749093;6800.319968003209;3800.4489349044234;4495.707778994355;4519.192323070765;4526.32105184225;4536.092781443725;6335.904961425582;6581.440577550914;6781.525293229476;6887.931120688779;7128.857422851565;3912.295786758411;4153.335466325373;4382.342648602735;4564.497905230422;4577.22187228172;4654.511276316006;4672.766361681903;4673.439187297546;4677.111348843709;4691.62467002639;4271.893202669959;4755.405574303212;5368.892622147535;5402.405735369106;5461.918071228934;6729.394354508117;6761.98570214462;6764.594124352555;6767.323267673193;6847.828804279933;1405.6626409661812;1582.5489735425579;1598.9520314390586;1600.7598860171067;1606.505949467007;794.7046018826209;881.9470831749987;1060.92846225313;1066.0512144191675;1077.1221454514546;6074.301455332693;6350.269718982284;6386.509261388062;6387.8060969515245;6431.163948686641;5821.883562328814;6925.792226233221;7012.894806577905;7028.4025857801635;7033.331833475914;5331.5201631853315;6323.873522529524;6325.367463253638;6349;6357.554971151975;3601.6398360163776;3627;3632.2735452909524;3635.5092062571216;3655.8903232903053;6911.686779511877;6927.58434493932;7262.527935684164;7293.38006269473;7298.635068246566;6333.461655759312;7175.784726458214;7206.702793497116;7217.9639101805205;7234.204237533867;5811.709414529257;6735.932640673628;7083.574985500886;7179.569225846466;7237.276272372826;6223.004319308889;6270.83989461951;6456.644884531396;6987.475939304674;7029;6238.315654773277;6811.965940170466;6898.99964505154;6911.101556797584;6918.5502942308585;6338.809835704937;6856.1913856852125;6865.28367908023;7123.358897699232;7197.532160409557;4037.5356833963297;4338;4395.934060989024;4590.42619672541;4616.584507394351;6060.363661815446;6434.2922278548385;6652.966735166293;6678.2320033194865;7008.264132266243;5152.587792976478;6464.515161362822;6610.735570577123;6688.264290927897;6723.193216814014;6717.496187786033;6747.6626168692355;6749.5612785168805;6750.949430341952;6776.390124888783;5531.640443371168;6506.869862602627;6553.377429144223;6639.302873198438;6663.234757575746;5935.5548333875995;6236.314005459487;6685.465162786868;6711.765088221889;6776;6037.577369584087;6570.605763654196;6607.372299631527;6611.768588099393;6629.602223866584 10:01:47 INFO - PID 1646 | |5;yui.html;816.8284660221354;838.3335248477459;846.4413487098512;876.58362277918;876.6581033396973;14238.572842814707;14827.258637068157;15942.36230550778;16399.590010249736;16717.080560569164;16033.759493607606;17925.28298868047;17948.730769038473;17980.921144731325;18011.27954881803;982.8476586129143;995.7908839143788;1015.5531566110925;1015.9034891685278;1018.4398580780578;984.807962447323;1007.7934023525196;1010.0152351457307;1010.5099026971913;1015.3349556040769;971.902809719026;1015.5836107196051;1085.218642577343;1090.4384242115316;1091.650671785029;1070.8661417322835;1073.0503504398584;1075.811732946735;1076;1078;756.34198247525;771.0477560213133;773.5822655765879;777.2848978939389;780.1340512031641;17295.394661186798;17641;17708.557286067822;17819;18238.179281932345;17955.192016359295;17968.460946171635;18036.639267214712;18373.35693250743;18388.08059597028;14951.327190276465;15103.867209959297;15122.168280744554;15145.772813407808;15186.544403667796;3558.87543935961;3617.5297211362617;3641.4901913732097;3698.2418604186078;3718.1819999600316;134404.999999999;153896.92206155925;154554;157197.8560428785;159323.6101458479;13911.165330080228;14160.221187834768;14395.640108997359;14398.136111833324;14407.711845763026;27153.32116697059;27328.59007114895;27461.725382746317;27555.586666200023;27869.303267418072;6757.797266082025;6925.930740692629;7591.848163036819;7768.223177682186;7771.261730135741;3923.5684074751753;3998.9200215995515;3999.34010888206;4006.8998275042773;4026.7382620129597;4169.583041695806;4212.347086201644;4244.2996905510245;4256.021115143534;4267.4025636410925;915.3683958068912;927.6521304510809;934.3179478980383;935.0789472369711;944.5466176235308;630.8706715123388;636.662568838507;656.0913135307538;682.1916029505053;687.0450074396592;10462.163026957825;10618.893811061791;11089;11643.243189192675;12281.570145044881;630.782380078872;654.9738010479623;658.5258613798057;663.1942190238883;674.6458109492457;5618.69097199658;5708.343540492813;5719.5996280260815;5810.535157187415;5829.5044921181325;144893;144980;145257;150538;151829;120106.79893201131;120787;121461.39269303597;122547.38726306311;122754.77245227613;107712;108029.45985270024;109047;110286;110742.44628776966;11333.036691880952;11454.828177577176;11516.884831151581;11556.248843825124;11818.40907954616;33342.666573333954;45306;46668;47165.764171178926;47494.050118998115;62958.685206573675;63141;63290.050649239376;63318.36681633125;63541.68229159009;14355.928220358832;14400.279986000867;14631.561053168421;14739.189344586151;14974.401023959355;15441.922790386425;15941.840581594035;16030.839691602934;16138.112403817902;16166;14790;14797.704045919236;14905.403783848524;14997.250137492863;15025.699486010437;14381.28093595337;14464.20446875432;14884.95805293619;15027.549173524812;15031.323590438238;13871.029027967945;14187.807743257685;14651.974361794571;14684.779728304298;14757.335919883846;7503.812404689927;9482.668106616235;9537.141657250602;9545.665901693408;9614.423134611945;8830.807840941648;10088.344257623232;10198.949005254926;10282.485875706036;10603.204759642904 10:01:47 INFO - PID 1646 | __end_tp_report 10:01:47 INFO - PID 1646 | __start_cc_report 10:01:47 INFO - PID 1646 | _x_x_mozilla_cycle_collect,459 10:01:47 INFO - PID 1646 | __end_cc_report 10:01:47 INFO - PID 1646 | __startTimestamp1496422907170__endTimestamp 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | ------- Summary: start ------- 10:01:47 INFO - PID 1646 | Number of tests: 6 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6123.09 Median:6346.71 stddev:764.71 (12.0%) stddev-sans-first:733.12 10:01:47 INFO - PID 1646 | Values: 3251.3 3473.7 3626.1 3642.7 3665.3 6160.8 6167.4 6203.2 6243.4 6275.0 4444.6 4874.0 6221.2 6245.8 6250.9 5383.8 5422.3 5441.1 5473.9 5555.6 4543.4 5273.8 5338.7 5552.8 5557.5 5462.8 5488.0 5532.1 5532.8 5544.3 5353.2 5415.6 5432.2 5470.6 5472.3 4620.1 5417.9 5427.2 5465.7 5473.2 6110.9 6213.8 6216.8 6253.9 6255.4 5283.8 6451.7 6533.3 6724.2 6732.8 6695.7 6701.9 6818.2 6821.9 6823.0 6261.6 6270.1 6291.0 6291.8 6299.8 6738.7 6794.8 6893.8 6902.3 6948.5 5828.4 6429.7 6437.5 6450.7 6471.1 6748.8 6752.2 6758.1 6761.4 6767.7 6497.7 6610.4 6643.1 6663.4 6667.9 6561.0 6581.3 6660.7 6684.5 6726.6 5355.6 5379.3 5380.4 5382.2 5393.7 4805.7 4808.9 4811.5 4815.3 4827.9 6179.6 6225.1 6256.8 6312.1 6386.2 6824.9 6854.3 6869.3 6870.6 6888.7 6247.5 6308.4 6344.5 6346.7 6348.1 5840.8 5912.2 5954.1 6069.1 6074.8 6846.2 7014.8 7023.6 7026.8 7042.0 6767.8 6831.0 6852.0 6853.9 6864.5 6742.3 6798.6 6839.1 6860.3 6876.6 6623.6 6627.2 6640.2 6656.9 6673.7 5455.8 5490.9 5592.1 6632.9 6788.8 6555.4 6573.5 6608.5 6618.1 6632.1 6564.7 6595.9 6597.6 6611.0 6622.8 5501.7 6252.5 6319.7 6340.8 6402.9 5515.3 6483.5 6535.4 6557.1 6559.0 6353.3 6370.2 6388.7 6400.7 6426.1 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:43643.41 Median:33218.75 stddev:76506.49 (230.3%) stddev-sans-first:76653.14 10:01:47 INFO - PID 1646 | Values: 693.4 798.7 834.8 1253.9 1313.7 36620.3 37381.0 37420.4 37486.4 37491.0 37616.0 37860.4 37908.6 37926.8 38027.4 7947.9 8172.4 8180.0 8210.3 8212.1 8041.4 8158.6 8161.4 8167.4 8179.1 8057.2 8092.4 8096.2 8176.0 8185.5 8029.7 8057.8 8108.7 8123.0 8125.0 7957.7 7969.3 8022.3 8043.3 8060.6 35334.5 35416.0 35472.3 35592.3 35680.0 3567.8 3604.4 3613.3 3617.5 3621.1 29061.3 29103.7 29186.3 29234.3 29237.0 3018.8 3076.4 3086.5 3087.2 3104.5 248939.8 250189.0 268766.0 303926.0 305998.0 45776.0 45776.8 45842.8 45909.5 45973.8 47337.0 47660.3 47668.5 49477.5 49823.8 13974.2 14086.9 14099.4 14102.0 14117.8 11330.9 11390.1 11428.9 11443.8 11462.9 13082.7 13115.3 13152.1 13177.7 13268.9 5748.2 6123.6 6130.4 6163.5 6176.9 881.9 999.3 1003.6 1007.1 1054.6 18788.6 23033.5 23947.6 24080.5 24214.9 1028.4 1107.0 1133.7 1143.2 1144.3 12225.8 12340.3 12348.4 12355.9 12370.8 289669.0 331757.0 333024.0 333684.3 335226.0 51180.5 51585.5 51678.5 51678.5 51712.7 1503.4 1546.8 1558.0 1560.3 1573.0 19725.8 21648.8 23734.0 23839.9 23931.4 36588.5 36653.0 36662.0 36665.8 36765.4 269825.0 269892.0 269974.0 270350.0 270544.0 33030.0 33039.8 33096.0 33204.8 33232.7 40969.0 41513.6 41869.7 42897.0 43174.1 28238.0 29460.9 30148.1 30270.0 30569.0 24687.6 29517.6 30117.2 30163.4 30397.2 35596.6 36272.3 36273.6 36325.5 36334.0 28290.9 30776.7 31313.9 31475.5 31575.7 19683.8 24039.6 24308.8 24462.6 25017.4 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:39861.06 Median:12184.12 stddev:95168.76 (781.1%) stddev-sans-first:95389.69 10:01:47 INFO - PID 1646 | Values: 431.8 437.6 443.8 454.0 458.6 21385.0 22150.4 22930.5 23121.4 23168.8 24595.5 25231.2 25264.0 25287.7 25372.0 6191.0 6355.2 6364.9 6366.2 6453.0 6304.3 6345.0 6346.5 6357.8 6358.0 5900.7 6163.5 6166.5 6217.4 6295.3 6041.3 6098.2 6134.8 6136.3 6142.3 5469.3 5469.8 5476.7 5497.4 5499.3 29312.0 29433.4 29481.0 29977.4 30014.5 2137.4 2178.5 2192.3 2195.4 2197.5 18308.2 18770.5 18782.0 18783.2 18818.5 2132.3 2158.4 2193.2 2196.6 2217.8 166843.2 175889.0 202514.0 207519.0 210607.9 20471.7 24893.6 26000.0 26365.7 26441.2 43561.3 44016.8 44024.0 44032.8 44067.3 11525.1 11550.1 11652.9 11723.0 11727.7 9052.4 9148.8 9159.1 9220.6 9227.5 9811.8 10023.4 11813.9 12313.0 12387.5 3921.6 4033.7 4145.4 4981.3 4988.0 916.4 946.4 965.4 972.6 1005.3 21414.1 21747.3 21761.7 21870.7 22294.0 1009.2 1013.7 1026.2 1032.8 1039.6 11129.9 11277.0 11415.2 11453.9 11473.9 490583.0 554244.0 563465.3 565154.3 570505.0 129864.4 152315.0 153148.2 153799.2 153929.0 1144.1 1193.5 1256.1 1267.7 1268.9 21751.5 22689.8 23064.0 23242.8 23770.3 62790.1 63801.7 63875.1 65055.3 65244.4 84641.6 87013.7 87325.6 87347.1 90549.1 9789.4 10644.6 10664.7 10667.0 10669.6 18180.5 18247.1 18379.3 18517.9 18546.3 14871.5 14982.8 14999.0 15104.1 15142.4 11063.7 11601.6 13267.7 13375.9 13573.7 15205.9 15490.9 15743.3 15774.8 15780.5 10447.9 10553.1 10565.9 10629.3 10632.2 14460.4 15011.3 15054.5 15124.2 15145.4 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7301.42 Median:4248.09 stddev:4610.81 (108.5%) stddev-sans-first:4601.37 10:01:47 INFO - PID 1646 | Values: 1258.2 1466.5 1470.3 1471.6 1483.2 11877.8 12109.6 12415.5 12447.5 12507.6 12160.0 12169.0 12371.6 12393.6 12399.3 2526.8 2582.8 2584.8 2589.9 2590.0 2514.9 2564.4 2568.0 2568.3 2569.6 2494.1 2536.9 2547.4 2547.5 2548.9 2477.6 2496.6 2506.7 2509.3 2512.5 2446.6 2447.2 2450.6 2549.7 2549.7 11908.6 12025.6 12042.6 12154.1 12164.9 6554.7 6832.4 6835.6 6855.2 6878.8 4144.4 4351.8 4365.5 4393.7 4414.1 1250.8 1271.1 1297.7 1298.5 1301.0 15845.0 16293.9 16891.0 16978.6 16985.9 5691.5 5725.7 5936.3 5938.6 5955.5 12371.0 12625.1 12626.1 12637.1 12643.7 6563.8 6662.6 6667.3 6670.2 6673.1 5508.4 5791.9 6169.8 6248.5 6248.8 4447.0 4493.2 4514.0 4526.4 4541.8 1282.7 1300.9 1306.8 1312.3 1320.7 838.2 845.3 858.8 862.7 888.8 6304.0 6748.1 6893.7 6910.3 6915.7 595.2 599.4 601.4 616.7 622.3 4371.5 4418.0 4959.3 5194.6 5409.8 15219.4 15241.5 15274.2 15419.6 15439.2 11393.8 15269.9 15313.5 15509.3 15544.0 8587.2 8729.3 8743.7 8811.6 8815.2 7328.4 7648.8 7785.7 7807.9 7944.4 13855.4 13865.7 13934.2 13985.5 14135.2 13905.4 14044.4 14378.5 14448.1 14604.3 9522.1 9698.2 9826.6 9847.8 9850.8 10578.0 10678.5 10688.6 10699.1 10734.8 8857.6 9114.0 9128.9 9167.3 9204.0 7687.6 7734.2 7805.3 7883.1 7945.6 8425.9 8432.4 8528.7 8528.9 8537.9 5825.7 5899.9 6389.3 6389.9 6534.2 6379.5 6386.9 6497.6 6642.9 6748.7 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5387.43 Median:6068.48 stddev:1706.45 (28.1%) stddev-sans-first:1669.39 10:01:47 INFO - PID 1646 | Values: 383.4 399.6 407.6 410.6 413.0 4756.4 5466.7 5667.4 6404.8 6511.2 5313.0 5532.7 5765.7 5852.9 6296.3 4601.7 4619.4 4622.0 4641.0 4649.3 4453.6 4630.2 4643.8 4649.7 4670.0 4586.8 4598.4 4601.4 4603.2 4606.4 3732.0 3817.0 4534.6 4614.8 4661.2 3970.7 3974.8 3980.1 3982.4 4012.3 5021.4 5881.5 6210.7 6220.6 6255.6 5476.5 5478.6 5509.5 5516.9 5518.2 6062.7 6614.4 6763.5 6789.7 6800.3 3800.4 4495.7 4519.2 4526.3 4536.1 6335.9 6581.4 6781.5 6887.9 7128.9 3912.3 4153.3 4382.3 4564.5 4577.2 4654.5 4672.8 4673.4 4677.1 4691.6 4271.9 4755.4 5368.9 5402.4 5461.9 6729.4 6762.0 6764.6 6767.3 6847.8 1405.7 1582.5 1599.0 1600.8 1606.5 794.7 881.9 1060.9 1066.1 1077.1 6074.3 6350.3 6386.5 6387.8 6431.2 5821.9 6925.8 7012.9 7028.4 7033.3 5331.5 6323.9 6325.4 6349.0 6357.6 3601.6 3627.0 3632.3 3635.5 3655.9 6911.7 6927.6 7262.5 7293.4 7298.6 6333.5 7175.8 7206.7 7218.0 7234.2 5811.7 6735.9 7083.6 7179.6 7237.3 6223.0 6270.8 6456.6 6987.5 7029.0 6238.3 6812.0 6899.0 6911.1 6918.6 6338.8 6856.2 6865.3 7123.4 7197.5 4037.5 4338.0 4395.9 4590.4 4616.6 6060.4 6434.3 6653.0 6678.2 7008.3 5152.6 6464.5 6610.7 6688.3 6723.2 6717.5 6747.7 6749.6 6750.9 6776.4 5531.6 6506.9 6553.4 6639.3 6663.2 5935.6 6236.3 6685.5 6711.8 6776.0 6037.6 6570.6 6607.4 6611.8 6629.6 10:01:47 INFO - PID 1646 | 10:01:47 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:25354.37 Median:17006.24 stddev:40427.30 (237.7%) stddev-sans-first:40498.73 10:01:47 INFO - PID 1646 | Values: 816.8 838.3 846.4 876.6 876.7 14238.6 14827.3 15942.4 16399.6 16717.1 16033.8 17925.3 17948.7 17980.9 18011.3 982.8 995.8 1015.6 1015.9 1018.4 984.8 1007.8 1010.0 1010.5 1015.3 971.9 1015.6 1085.2 1090.4 1091.7 1070.9 1073.1 1075.8 1076.0 1078.0 756.3 771.0 773.6 777.3 780.1 17295.4 17641.0 17708.6 17819.0 18238.2 17955.2 17968.5 18036.6 18373.4 18388.1 14951.3 15103.9 15122.2 15145.8 15186.5 3558.9 3617.5 3641.5 3698.2 3718.2 134405.0 153896.9 154554.0 157197.9 159323.6 13911.2 14160.2 14395.6 14398.1 14407.7 27153.3 27328.6 27461.7 27555.6 27869.3 6757.8 6925.9 7591.8 7768.2 7771.3 3923.6 3998.9 3999.3 4006.9 4026.7 4169.6 4212.3 4244.3 4256.0 4267.4 915.4 927.7 934.3 935.1 944.5 630.9 636.7 656.1 682.2 687.0 10462.2 10618.9 11089.0 11643.2 12281.6 630.8 655.0 658.5 663.2 674.6 5618.7 5708.3 5719.6 5810.5 5829.5 144893.0 144980.0 145257.0 150538.0 151829.0 120106.8 120787.0 121461.4 122547.4 122754.8 107712.0 108029.5 109047.0 110286.0 110742.4 11333.0 11454.8 11516.9 11556.2 11818.4 33342.7 45306.0 46668.0 47165.8 47494.1 62958.7 63141.0 63290.1 63318.4 63541.7 14355.9 14400.3 14631.6 14739.2 14974.4 15441.9 15941.8 16030.8 16138.1 16166.0 14790.0 14797.7 14905.4 14997.3 15025.7 14381.3 14464.2 14885.0 15027.5 15031.3 13871.0 14187.8 14652.0 14684.8 14757.3 7503.8 9482.7 9537.1 9545.7 9614.4 8830.8 10088.3 10198.9 10282.5 10603.2 10:01:47 INFO - PID 1646 | -------- Summary: end -------- 10:01:47 INFO - PID 1646 | 10:01:47 INFO - TEST-INFO | 1646: exit 0 10:01:47 INFO - TEST-OK | dromaeo_css | took 1143361ms 10:01:47 INFO - TEST-START | kraken 10:01:47 INFO - operating with platform_type : mac_ 10:01:47 INFO - Initialising browser for kraken test... 10:01:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpcIIyiX/profile 10:01:47 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 10:02:21 INFO - TEST-INFO | 1655: killed by SIGTERM 10:02:21 INFO - Browser initialized. 10:02:21 INFO - Running cycle 1/1 for kraken test... 10:02:21 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/tmpcIIyiX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:23 INFO - PID 1659 | 2017-06-02 10:02:23.632 plugin-container[1660:10656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 10:02:23 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:02:23 INFO - PID 1659 | 2017-06-02 10:02:23.633 plugin-container[1660:10656] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d23, name = 'com.apple.CFPasteboardClient' 10:02:23 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:02:23 INFO - PID 1659 | 2017-06-02 10:02:23.633 plugin-container[1660:10656] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:02:24 INFO - PID 1659 | 2017-06-02 10:02:24.082 plugin-container[1661:10701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x973f, name = 'com.apple.tsm.portname' 10:02:24 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:02:24 INFO - PID 1659 | 2017-06-02 10:02:24.083 plugin-container[1661:10701] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b27, name = 'com.apple.CFPasteboardClient' 10:02:24 INFO - PID 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:02:24 INFO - PID 1659 | 2017-06-02 10:02:24.083 plugin-container[1661:10701] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:02:33 INFO - PID 1659 | RSS: Main: 268476416 10:02:33 INFO - PID 1659 | 10:02:36 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection) 10:02:37 INFO - PID 1659 | RSS: Main: 276791296 10:02:37 INFO - PID 1659 | 10:02:39 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-dft) 10:02:40 INFO - PID 1659 | RSS: Main: 284631040 10:02:40 INFO - PID 1659 | 10:02:43 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-fft) 10:02:44 INFO - PID 1659 | RSS: Main: 283119616 10:02:44 INFO - PID 1659 | 10:02:46 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator) 10:02:47 INFO - PID 1659 | RSS: Main: 285040640 10:02:47 INFO - PID 1659 | 10:02:49 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:02:49 INFO - PID 1659 | RSS: Main: 285753344 10:02:49 INFO - PID 1659 | 10:02:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom) 10:02:53 INFO - PID 1659 | RSS: Main: 290045952 10:02:53 INFO - PID 1659 | 10:02:56 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate) 10:02:56 INFO - PID 1659 | RSS: Main: 292950016 10:02:56 INFO - PID 1659 | 10:02:59 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial) 10:03:00 INFO - PID 1659 | RSS: Main: 292163584 10:03:00 INFO - PID 1659 | 10:03:02 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:03:02 INFO - PID 1659 | RSS: Main: 291733504 10:03:02 INFO - PID 1659 | 10:03:04 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:03:05 INFO - PID 1659 | RSS: Main: 291733504 10:03:05 INFO - PID 1659 | 10:03:07 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:03:08 INFO - PID 1659 | RSS: Main: 291688448 10:03:08 INFO - PID 1659 | 10:03:10 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:03:11 INFO - PID 1659 | RSS: Main: 291299328 10:03:11 INFO - PID 1659 | 10:03:14 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:03:15 INFO - PID 1659 | RSS: Main: 291651584 10:03:15 INFO - PID 1659 | 10:03:16 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49230/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49230/tests/kraken/driver.html?testName=ai-astar) 10:03:17 INFO - PID 1659 | RSS: Main: 293560320 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | __start_tp_report 10:03:17 INFO - PID 1659 | _x_x_mozilla_page_load 10:03:17 INFO - PID 1659 | _x_x_mozilla_page_load_details 10:03:17 INFO - PID 1659 | |i|pagename|runs| 10:03:17 INFO - PID 1659 | |0;ai-astar;101;115;118;115;110;114;116;115;117;113 10:03:17 INFO - PID 1659 | |1;audio-beat-detection;158;148;139;135;144;139;151;157;155;145 10:03:17 INFO - PID 1659 | |2;audio-dft;197;196;217;221;266;270;201;203;193;199 10:03:17 INFO - PID 1659 | |3;audio-fft;82;56;59;82;61;58;67;91;75;83 10:03:17 INFO - PID 1659 | |4;audio-oscillator;84;68;82;72;69;68;84;69;69;91 10:03:17 INFO - PID 1659 | |5;imaging-gaussian-blur;89;90;88;89;90;90;89;97;90;89 10:03:17 INFO - PID 1659 | |6;imaging-darkroom;96;96;96;97;97;98;96;97;97;96 10:03:17 INFO - PID 1659 | |7;imaging-desaturate;91;119;129;88;138;139;89;91;95;89 10:03:17 INFO - PID 1659 | |8;json-parse-financial;52;49;54;61;76;79;49;50;48;51 10:03:17 INFO - PID 1659 | |9;json-stringify-tinderbox;56;55;61;56;62;61;55;57;56;55 10:03:17 INFO - PID 1659 | |10;stanford-crypto-aes;85;89;89;88;87;87;88;90;133;104 10:03:17 INFO - PID 1659 | |11;stanford-crypto-ccm;135;136;137;136;137;138;134;140;133;149 10:03:17 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;189;184;186;186;186;212;197;232;236;209 10:03:17 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;65;64;65;81;97;88;68;68;67;68 10:03:17 INFO - PID 1659 | __end_tp_report 10:03:17 INFO - PID 1659 | __start_cc_report 10:03:17 INFO - PID 1659 | _x_x_mozilla_cycle_collect,1219 10:03:17 INFO - PID 1659 | __end_cc_report 10:03:17 INFO - PID 1659 | __startTimestamp1496422997242__endTimestamp 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | ------- Summary: start ------- 10:03:17 INFO - PID 1659 | Number of tests: 14 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:113.40 Median:115.00 stddev:4.88 (4.2%) stddev-sans-first:2.33 10:03:17 INFO - PID 1659 | Values: 101.0 115.0 118.0 115.0 110.0 114.0 116.0 115.0 117.0 113.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:147.10 Median:149.50 stddev:8.08 (5.4%) stddev-sans-first:7.54 10:03:17 INFO - PID 1659 | Values: 158.0 148.0 139.0 135.0 144.0 139.0 151.0 157.0 155.0 145.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:216.30 Median:210.00 stddev:28.70 (13.7%) stddev-sans-first:29.58 10:03:17 INFO - PID 1659 | Values: 197.0 196.0 217.0 221.0 266.0 270.0 201.0 203.0 193.0 199.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:71.40 Median:78.50 stddev:12.71 (16.2%) stddev-sans-first:12.89 10:03:17 INFO - PID 1659 | Values: 82.0 56.0 59.0 82.0 61.0 58.0 67.0 91.0 75.0 83.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:77.00 stddev:8.68 (11.3%) stddev-sans-first:8.66 10:03:17 INFO - PID 1659 | Values: 84.0 68.0 82.0 72.0 69.0 68.0 84.0 69.0 69.0 91.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:90.10 Median:90.00 stddev:2.51 (2.8%) stddev-sans-first:2.64 10:03:17 INFO - PID 1659 | Values: 89.0 90.0 88.0 89.0 90.0 90.0 89.0 97.0 90.0 89.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:97.00 stddev:0.70 (0.7%) stddev-sans-first:0.71 10:03:17 INFO - PID 1659 | Values: 96.0 96.0 96.0 97.0 97.0 98.0 96.0 97.0 97.0 96.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:106.80 Median:89.00 stddev:21.80 (24.5%) stddev-sans-first:22.36 10:03:17 INFO - PID 1659 | Values: 91.0 119.0 129.0 88.0 138.0 139.0 89.0 91.0 95.0 89.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:53.00 stddev:11.49 (21.7%) stddev-sans-first:12.05 10:03:17 INFO - PID 1659 | Values: 52.0 49.0 54.0 61.0 76.0 79.0 49.0 50.0 48.0 51.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:57.40 Median:56.50 stddev:2.80 (5.0%) stddev-sans-first:2.92 10:03:17 INFO - PID 1659 | Values: 56.0 55.0 61.0 56.0 62.0 61.0 55.0 57.0 56.0 55.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:88.00 stddev:14.67 (16.7%) stddev-sans-first:15.20 10:03:17 INFO - PID 1659 | Values: 85.0 89.0 89.0 88.0 87.0 87.0 88.0 90.0 133.0 104.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:137.50 Median:137.00 stddev:4.50 (3.3%) stddev-sans-first:4.68 10:03:17 INFO - PID 1659 | Values: 135.0 136.0 137.0 136.0 137.0 138.0 134.0 140.0 133.0 149.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.70 Median:203.00 stddev:19.69 (9.7%) stddev-sans-first:20.34 10:03:17 INFO - PID 1659 | Values: 189.0 184.0 186.0 186.0 186.0 212.0 197.0 232.0 236.0 209.0 10:03:17 INFO - PID 1659 | 10:03:17 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:68.00 stddev:11.47 (16.9%) stddev-sans-first:11.79 10:03:17 INFO - PID 1659 | Values: 65.0 64.0 65.0 81.0 97.0 88.0 68.0 68.0 67.0 68.0 10:03:17 INFO - PID 1659 | -------- Summary: end -------- 10:03:17 INFO - PID 1659 | 10:03:18 INFO - TEST-INFO | 1659: exit 0 10:03:18 INFO - TEST-OK | kraken | took 90318ms 10:03:18 INFO - SUITE-END | took 1233s 10:03:18 INFO - Completed test suite (00:20:34) 10:03:18 INFO - javascript benchmark 10:03:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8130.3769967180515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3251.3334766372896, 3473.7394695397843, 3626.0753507855493, 3642.726795490339, 3665.321915445643, 6160.81517554473, 6167.414095660916, 6203.2245969253845, 6243.406876346751, 6274.968625156874, 4444.555544445562, 4874.025194961005, 6221.191245138126, 6245.7501699931945, 6250.937490625092, 5383.784648614051, 5422.267993820843, 5441.102218134003, 5473.917891231635, 5555.555555555546, 4543.363929049936, 5273.815416460425, 5338.706371149585, 5552.77788888444, 5557.472040156199, 5462.781488740486, 5487.972560137214, 5532.114861622142, 5532.7786888524415, 5544.251526043993, 5353.2237825515085, 5415.620906536544, 5432.239486471897, 5470.617056806025, 5472.26124473195, 4620.052889157714, 5417.891642167135, 5427.158790387496, 5465.672059676433, 5473.2063850741415, 6110.877782444326, 6213.844653883689, 6216.7824126155365, 6253.906191407133, 6255.40573645503, 5283.815066472655, 6451.6774161292215, 6533.346665333476, 6724.159480064992, 6732.83167920801, 6695.665216739144, 6701.932980670228, 6818.18181818185, 6821.931780682179, 6822.965885170543, 6261.592996455216, 6270.059491076375, 6291.02489114188, 6291.842703932392, 6299.811005669837, 6738.663066846638, 6794.796156115324, 6893.827654308682, 6902.344277293649, 6948.513604047769, 5828.358880523139, 6429.710663020175, 6437.452816510556, 6450.6774661266745, 6471.0940468334475, 6748.763793267212, 6752.189737231563, 6758.087658166183, 6761.425278851254, 6767.695453704595, 6497.707603157869, 6610.3720146586, 6643.10318107059, 6663.433608143362, 6667.899981500282, 6560.967195163994, 6581.308962558958, 6660.666966651745, 6684.532082754161, 6726.56277341981, 5355.571554275649, 5379.273798037293, 5380.354357477049, 5382.219578161144, 5393.7303134843105, 4805.735684537384, 4808.8797780055775, 4811.542903424169, 4815.277708343707, 4827.903441931142, 6179.598326108789, 6225.097360882647, 6256.843578910563, 6312.084747711556, 6386.169797926333, 6824.897626535606, 6854.3145685432055, 6869.347411995853, 6870.553414028072, 6888.690008949609, 6247.468965138014, 6308.432241098231, 6344.524160687967, 6346.714397852015, 6348.111264422984, 5840.766369345179, 5912.201852749909, 5954.136650185699, 6069.0592958091565, 6074.787382441594, 6846.178458585002, 7014.789556313217, 7023.578585284899, 7026.824329391806, 7042.014118023379, 6767.763128290486, 6831.0, 6852.040714299902, 6853.897191542231, 6864.450843932374, 6742.292059333699, 6798.626075565891, 6839.1451068616425, 6860.348266914635, 6876.587404755531, 6623.569467984566, 6627.183447035087, 6640.236372816995, 6656.933430665825, 6673.733050677918, 5455.754491047833, 5490.85189830567, 5592.133219351007, 6632.933670663231, 6788.830279243059, 6555.410013098844, 6573.5398522103, 6608.537402381787, 6618.139641846529, 6632.137822083098, 6564.737410503526, 6595.868082638224, 6597.60414375139, 6611.041398997215, 6622.768203112868, 5501.669899806025, 6252.468540174136, 6319.6840157991, 6340.809775706736, 6402.935970640234, 5515.255440515479, 6483.546151769331, 6535.411812936859, 6557.1147895035465, 6558.967205163944, 6353.332900045429, 6370.203724534434, 6388.744450221939, 6400.711967961546, 6426.100345951664], "value": 6072.977400969006, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.3828892285866, 798.7044793426433, 834.7537476444451, 1253.868343823898, 1313.743819955109, 36620.267594648125, 37380.99999999996, 37420.43869341955, 37486.437703434436, 37491.0, 37616.0, 37860.43209351855, 37908.620913790925, 37926.810365948135, 38027.42958855612, 7947.880781788263, 8172.387070969671, 8179.959100204491, 8210.261076503113, 8212.055613604422, 8041.396895232851, 8158.632861521246, 8161.428699991003, 8167.387445941548, 8179.100298967139, 8057.234562716532, 8092.393070519705, 8096.190380961915, 8175.95912020439, 8185.467944583613, 8029.7189598363775, 8057.758267251992, 8108.7431448125635, 8122.959385203036, 8124.959375203145, 7957.721479748181, 7969.322607578363, 8022.27799498048, 8043.316318112968, 8060.5566693831815, 35334.46998295028, 35416.0, 35472.290554188774, 35592.28815423677, 35680.0, 3567.839447224881, 3604.4052731299303, 3613.277344531078, 3617.4935509028755, 3621.112827357288, 29061.273468163254, 29103.70896291031, 29186.270343241376, 29234.269143271376, 29237.0, 3018.7886847920654, 3076.3539656672124, 3086.4907290297074, 3087.197328694533, 3104.5498402731478, 248939.75530122232, 250189.0, 268766.0, 303926.0, 305998.0, 45776.0, 45776.771116144875, 45842.770786146524, 45909.540904590525, 45973.77013114913, 47337.0, 47660.28509572359, 47668.52331476641, 49477.52612369367, 49823.75088124536, 13974.231417271943, 14086.859131408555, 14099.436022558983, 14102.0, 14117.78823317651, 11330.94334528322, 11390.145739069603, 11428.94285528567, 11443.828342574869, 11462.942685286687, 13082.672933176747, 13115.27865967381, 13152.079354445097, 13177.73644527104, 13268.867311326763, 5748.189320364072, 6123.632582045091, 6130.386961303834, 6163.506919446433, 6176.907346389808, 881.8589025755848, 999.3354419311222, 1003.603576587244, 1007.089511312308, 1054.625607909181, 18788.624227515375, 23033.539329213658, 23947.640785388234, 24080.51838963211, 24214.87892560561, 1028.3726926574784, 1106.9944650276698, 1133.6825688807148, 1143.2168964259508, 1144.3477217985835, 12225.755484890253, 12340.259584424963, 12348.444320005621, 12355.876441235652, 12370.75258494825, 289669.0, 331757.0, 333024.0, 333684.33157834056, 335226.0, 51180.48819511832, 51585.48414515882, 51678.48321516737, 51678.48321516812, 51712.70718231999, 1503.421182844591, 1546.8066491688764, 1558.0262336039975, 1560.251079481855, 1573.0483057750125, 19725.80274197297, 21648.8093154878, 23734.0, 23839.880800595885, 23931.401714957265, 36588.45117323189, 36653.0, 36662.0, 36665.81667091647, 36765.44851827278, 269825.0, 269892.0, 269974.0, 270350.0, 270544.0, 33030.0, 33039.843605474656, 33096.007119786445, 33204.83783067581, 33232.667673322954, 40969.0, 41513.58486415218, 41869.743907682816, 42897.0, 43174.136517270104, 28238.0, 29460.852695736383, 30148.09555713332, 30270.0, 30569.0, 24687.62968555509, 29517.557236641896, 30117.247068823453, 30163.396732065674, 30397.240068998453, 35596.64403356037, 36272.274554508236, 36273.637263627024, 36325.45511817272, 36334.0, 28290.85854570714, 30776.69223307828, 31313.868613138504, 31475.527867081553, 31575.684243158194, 19683.803161968197, 24039.639405408583, 24308.75691243065, 24462.633060504464, 25017.374565636004], "value": 17100.59629989844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.8143198424676, 437.64550713921744, 443.758151807265, 453.961413279871, 458.617054759276, 21385.0, 22150.446238844037, 22930.541389172206, 23121.421964450878, 23168.768312316894, 24595.508089838193, 25231.24306270815, 25264.0, 25287.74712252877, 25371.985120595153, 6191.0, 6355.173827402431, 6364.936350636503, 6366.204224471941, 6453.0, 6304.33804450533, 6344.9530827413655, 6346.492280617563, 6357.777477788279, 6358.0462930560325, 5900.734466948976, 6163.476104531121, 6166.475849552793, 6217.409346112112, 6295.338989406115, 6041.305249896231, 6098.237720284965, 6134.815955521297, 6136.325004249529, 6142.324344322099, 5469.261649677323, 5469.835904922858, 5476.726163691799, 5497.39528651848, 5499.312585926759, 29312.0, 29433.411331773244, 29481.0, 29977.400451991056, 30014.549781753292, 2137.358792362285, 2178.5316157026314, 2192.3422973108163, 2195.3962660268394, 2197.516546359798, 18308.176132074088, 18770.530736731554, 18781.966991815443, 18783.154758035922, 18818.529536761554, 2132.349633361824, 2158.4280165756086, 2193.243331050785, 2196.648536234195, 2217.800397964191, 166843.1657841703, 175889.04943079725, 202513.98743006337, 207519.0, 210607.9469602642, 20471.692924606145, 24893.62659560108, 26000.0, 26365.736342636712, 26441.206763797116, 43561.34657980133, 44016.77991610085, 44024.0, 44032.779836101254, 44067.33898991453, 11525.077993760648, 11550.075993920465, 11652.892975167346, 11723.0, 11727.706807329714, 9052.366334356531, 9148.817023659489, 9159.129882661136, 9220.631174753069, 9227.53862306882, 9811.754706132404, 10023.448710320856, 11813.936745692929, 12313.0, 12387.504499819906, 3921.5882332355263, 4033.737807042533, 4145.419641250227, 4981.256560203899, 4988.0, 916.4455504419864, 946.3564775952281, 965.3773316211145, 972.6401231544373, 1005.2912696548915, 21414.072733454064, 21747.347579572637, 21761.67357489607, 21870.687758734526, 22293.99677014506, 1009.1825621246813, 1013.7465633591602, 1026.2200727447046, 1032.7521394865328, 1039.6335391356333, 11129.888701112885, 11277.0, 11415.200935934405, 11453.885461145448, 11473.942630286796, 490583.0, 554244.0, 563465.2881240709, 565154.3484565102, 570505.0, 129864.350678246, 152315.0, 153148.234258828, 153799.23100384427, 153929.0, 1144.056153673223, 1193.5106606291379, 1256.0767835640797, 1267.66406902171, 1268.892144167756, 21751.456213594785, 22689.760773161604, 23064.0, 23242.767572324738, 23770.286891393287, 62790.05814912689, 63801.680991594745, 63875.12561934817, 65055.34944650493, 65244.36889077811, 84641.57679211565, 87013.69479457686, 87325.56337218273, 87347.1265287339, 90549.09450905406, 9789.363691360039, 10644.574217031231, 10664.733381665521, 10667.039966403061, 10669.57321707123, 18180.545486362946, 18247.087645617932, 18379.264829406675, 18517.907410463402, 18546.258149674402, 14871.47949821751, 14982.75258711202, 14998.95007349475, 15104.093754374773, 15142.394304227708, 11063.668089957315, 11601.607807063207, 13267.66830829237, 13375.933120334725, 13573.728525429633, 15205.923970380078, 15490.9225453872, 15743.291551879964, 15774.816888733161, 15780.526584202493, 10447.94776026115, 10553.102986245947, 10565.947170264408, 10629.25595208328, 10632.202584806017, 14460.421583136978, 15011.32449039774, 15054.473093441677, 15124.243787810785, 15145.394184232508], "value": 12302.253423284159, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1258.1696080586814, 1466.516049703598, 1470.2501724120696, 1471.6026672798346, 1483.1768368555454, 11877.7624447511, 12109.636710898665, 12415.503379864806, 12447.502099916004, 12507.562235321766, 12160.0, 12169.0, 12371.628851134457, 12393.56622518212, 12399.256044637306, 2526.810489213307, 2582.7546383093577, 2584.8319859209187, 2589.935251618715, 2590.0, 2514.9119780807673, 2564.4101856572997, 2567.9856456699595, 2568.3322336192537, 2569.6017117346746, 2494.114589320792, 2536.936576585582, 2547.4013606802387, 2547.4522977559823, 2548.9362765930814, 2477.6035834266613, 2496.6379874918125, 2506.699196096462, 2509.3224829296078, 2512.510060538196, 2446.620773780067, 2447.21689059501, 2450.644656524794, 2549.6557964674716, 2549.7450254974538, 11908.58319958806, 12025.63923082309, 12042.63872083839, 12154.149209555335, 12164.931961566466, 6554.672266386661, 6832.385085342294, 6835.624040677761, 6855.177378714537, 6878.793636190922, 4144.41978123063, 4351.760653164075, 4365.5416181300825, 4393.670474714378, 4414.09511050234, 1250.8061250506187, 1271.1292764456284, 1297.7145028093805, 1298.5455090718267, 1301.0437328563498, 15845.0, 16293.91853040739, 16891.0, 16978.575535611708, 16985.91507042457, 5691.516221121168, 5725.656460612377, 5936.347001829795, 5938.61399009063, 5955.52355811534, 12371.010319174624, 12625.05312101596, 12626.053046021585, 12637.115401921777, 12643.683907902376, 6563.835904102436, 6662.600243985376, 6667.266600674019, 6670.166229221348, 6673.132492776005, 5508.44915508446, 5791.8552036199435, 6169.784057557964, 6248.50011999039, 6248.750049997949, 4447.021655235843, 4493.168763778646, 4514.0069184779295, 4526.366308716782, 4541.750203738826, 1282.7242142939126, 1300.9154404963647, 1306.8301120854228, 1312.2809120052762, 1320.650027742649, 838.1995194589152, 845.3195177881864, 858.8454078265972, 862.6937437209825, 888.8311220868014, 6304.0, 6748.089007983859, 6893.689783959734, 6910.343517365943, 6915.688794004282, 595.220261457492, 599.4125756758314, 601.3925934805877, 616.7348040342606, 622.330994181257, 4371.475422949203, 4418.0, 4959.2561115832195, 5194.584433245332, 5409.83770486886, 15219.391224350902, 15241.542753717406, 15274.236288185546, 15419.61450963735, 15439.228038598025, 11393.772124557463, 15269.9236503819, 15313.46402875894, 15509.302081406138, 15544.0, 8587.184217499327, 8729.297643287719, 8743.737687869374, 8811.603477843384, 8815.206631403205, 7328.377087947584, 7648.80877978055, 7785.676435006176, 7807.921920780719, 7944.44388892772, 13855.445782168601, 13865.722685546434, 13934.163950163023, 13985.510507132201, 14135.15189088658, 13905.443782248596, 14044.368003440075, 14378.496752613608, 14448.060876043024, 14604.26978651042, 9522.0954009369, 9698.175655069399, 9826.55780489894, 9847.803043939224, 9850.802983940137, 10578.0, 10678.519466624177, 10688.572457101629, 10699.090577301016, 10734.838977415502, 8857.557122143997, 9114.0, 9128.908710913072, 9167.266618670357, 9204.0, 7687.577183254974, 7734.187910269341, 7805.258500442449, 7883.093444253984, 7945.642446089824, 8425.915740842513, 8432.409731318749, 8528.701495447629, 8528.872066919126, 8537.871931920901, 5825.737841797214, 5899.879022985619, 6389.2971773105855, 6389.87220255583, 6534.183227096613, 6379.5215358847345, 6386.904196436964, 6497.577657452251, 6642.867142657024, 6748.662566871539], "value": 5475.874945701599, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.40572113224493, 399.6123759952845, 407.59647948530954, 410.58530883807344, 413.037622339948, 4756.429228492577, 5466.671999680012, 5667.433256674336, 6404.839879003021, 6511.186101737283, 5312.9545125126815, 5532.695701736403, 5765.673895004152, 5852.882942341151, 6296.307406185316, 4601.676153542473, 4619.42257217847, 4621.953780462186, 4640.953590464086, 4649.256119020957, 4453.6437085033285, 4630.2360110581885, 4643.815826964119, 4649.67452278339, 4669.953300467003, 4586.816527338902, 4598.425196850394, 4601.447826260854, 4603.240465323234, 4606.378138951249, 3731.9626803731885, 3816.9809150954347, 4534.641986167191, 4614.7770840727235, 4661.184292748772, 3970.682345412389, 3974.7813870237123, 3980.144268982183, 3982.38273067675, 4012.257732319501, 5021.39743230811, 5881.529477641821, 6210.720517576675, 6220.626762394226, 6255.593386429868, 5476.534494567966, 5478.561715062785, 5509.531689806371, 5516.91724624131, 5518.19986102017, 6062.666553339609, 6614.404703576702, 6763.492738044666, 6789.694463749093, 6800.319968003209, 3800.4489349044234, 4495.707778994355, 4519.192323070765, 4526.32105184225, 4536.092781443725, 6335.904961425582, 6581.440577550914, 6781.525293229476, 6887.931120688779, 7128.857422851565, 3912.295786758411, 4153.335466325373, 4382.342648602735, 4564.497905230422, 4577.22187228172, 4654.511276316006, 4672.766361681903, 4673.439187297546, 4677.111348843709, 4691.62467002639, 4271.893202669959, 4755.405574303212, 5368.892622147535, 5402.405735369106, 5461.918071228934, 6729.394354508117, 6761.98570214462, 6764.594124352555, 6767.323267673193, 6847.828804279933, 1405.6626409661812, 1582.5489735425579, 1598.9520314390586, 1600.7598860171067, 1606.505949467007, 794.7046018826209, 881.9470831749987, 1060.92846225313, 1066.0512144191675, 1077.1221454514546, 6074.301455332693, 6350.269718982284, 6386.509261388062, 6387.8060969515245, 6431.163948686641, 5821.883562328814, 6925.792226233221, 7012.894806577905, 7028.4025857801635, 7033.331833475914, 5331.5201631853315, 6323.873522529524, 6325.367463253638, 6349.0, 6357.554971151975, 3601.6398360163776, 3627.0, 3632.2735452909524, 3635.5092062571216, 3655.8903232903053, 6911.686779511877, 6927.58434493932, 7262.527935684164, 7293.38006269473, 7298.635068246566, 6333.461655759312, 7175.784726458214, 7206.702793497116, 7217.9639101805205, 7234.204237533867, 5811.709414529257, 6735.932640673628, 7083.574985500886, 7179.569225846466, 7237.276272372826, 6223.004319308889, 6270.83989461951, 6456.644884531396, 6987.475939304674, 7029.0, 6238.315654773277, 6811.965940170466, 6898.99964505154, 6911.101556797584, 6918.5502942308585, 6338.809835704937, 6856.1913856852125, 6865.28367908023, 7123.358897699232, 7197.532160409557, 4037.5356833963297, 4338.0, 4395.934060989024, 4590.42619672541, 4616.584507394351, 6060.363661815446, 6434.2922278548385, 6652.966735166293, 6678.2320033194865, 7008.264132266243, 5152.587792976478, 6464.515161362822, 6610.735570577123, 6688.264290927897, 6723.193216814014, 6717.496187786033, 6747.6626168692355, 6749.5612785168805, 6750.949430341952, 6776.390124888783, 5531.640443371168, 6506.869862602627, 6553.377429144223, 6639.302873198438, 6663.234757575746, 5935.5548333875995, 6236.314005459487, 6685.465162786868, 6711.765088221889, 6776.0, 6037.577369584087, 6570.605763654196, 6607.372299631527, 6611.768588099393, 6629.602223866584], "value": 4851.431702958285, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [816.8284660221354, 838.3335248477459, 846.4413487098512, 876.58362277918, 876.6581033396973, 14238.572842814707, 14827.258637068157, 15942.36230550778, 16399.590010249736, 16717.080560569164, 16033.759493607606, 17925.28298868047, 17948.730769038473, 17980.921144731325, 18011.27954881803, 982.8476586129143, 995.7908839143788, 1015.5531566110925, 1015.9034891685278, 1018.4398580780578, 984.807962447323, 1007.7934023525196, 1010.0152351457307, 1010.5099026971913, 1015.3349556040769, 971.902809719026, 1015.5836107196051, 1085.218642577343, 1090.4384242115316, 1091.650671785029, 1070.8661417322835, 1073.0503504398584, 1075.811732946735, 1076.0, 1078.0, 756.34198247525, 771.0477560213133, 773.5822655765879, 777.2848978939389, 780.1340512031641, 17295.394661186798, 17641.0, 17708.557286067822, 17819.0, 18238.179281932345, 17955.192016359295, 17968.460946171635, 18036.639267214712, 18373.35693250743, 18388.08059597028, 14951.327190276465, 15103.867209959297, 15122.168280744554, 15145.772813407808, 15186.544403667796, 3558.87543935961, 3617.5297211362617, 3641.4901913732097, 3698.2418604186078, 3718.1819999600316, 134404.999999999, 153896.92206155925, 154554.0, 157197.8560428785, 159323.6101458479, 13911.165330080228, 14160.221187834768, 14395.640108997359, 14398.136111833324, 14407.711845763026, 27153.32116697059, 27328.59007114895, 27461.725382746317, 27555.586666200023, 27869.303267418072, 6757.797266082025, 6925.930740692629, 7591.848163036819, 7768.223177682186, 7771.261730135741, 3923.5684074751753, 3998.9200215995515, 3999.34010888206, 4006.8998275042773, 4026.7382620129597, 4169.583041695806, 4212.347086201644, 4244.2996905510245, 4256.021115143534, 4267.4025636410925, 915.3683958068912, 927.6521304510809, 934.3179478980383, 935.0789472369711, 944.5466176235308, 630.8706715123388, 636.662568838507, 656.0913135307538, 682.1916029505053, 687.0450074396592, 10462.163026957825, 10618.893811061791, 11089.0, 11643.243189192675, 12281.570145044881, 630.782380078872, 654.9738010479623, 658.5258613798057, 663.1942190238883, 674.6458109492457, 5618.69097199658, 5708.343540492813, 5719.5996280260815, 5810.535157187415, 5829.5044921181325, 144893.0, 144980.0, 145257.0, 150538.0, 151829.0, 120106.79893201131, 120787.0, 121461.39269303597, 122547.38726306311, 122754.77245227613, 107712.0, 108029.45985270024, 109047.0, 110286.0, 110742.44628776966, 11333.036691880952, 11454.828177577176, 11516.884831151581, 11556.248843825124, 11818.40907954616, 33342.666573333954, 45306.0, 46668.0, 47165.764171178926, 47494.050118998115, 62958.685206573675, 63141.0, 63290.050649239376, 63318.36681633125, 63541.68229159009, 14355.928220358832, 14400.279986000867, 14631.561053168421, 14739.189344586151, 14974.401023959355, 15441.922790386425, 15941.840581594035, 16030.839691602934, 16138.112403817902, 16166.0, 14790.0, 14797.704045919236, 14905.403783848524, 14997.250137492863, 15025.699486010437, 14381.28093595337, 14464.20446875432, 14884.95805293619, 15027.549173524812, 15031.323590438238, 13871.029027967945, 14187.807743257685, 14651.974361794571, 14684.779728304298, 14757.335919883846, 7503.812404689927, 9482.668106616235, 9537.141657250602, 9545.665901693408, 9614.423134611945, 8830.807840941648, 10088.344257623232, 10198.949005254926, 10282.485875706036, 10603.204759642904], "value": 8509.64612766323, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 115.0, 118.0, 115.0, 110.0, 114.0, 116.0, 115.0, 117.0, 113.0], "value": 113.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 148.0, 139.0, 135.0, 144.0, 139.0, 151.0, 157.0, 155.0, 145.0], "value": 147.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 196.0, 217.0, 221.0, 266.0, 270.0, 201.0, 203.0, 193.0, 199.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 56.0, 59.0, 82.0, 61.0, 58.0, 67.0, 91.0, 75.0, 83.0], "value": 71.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 82.0, 72.0, 69.0, 68.0, 84.0, 69.0, 69.0, 91.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 89.0, 90.0, 90.0, 89.0, 97.0, 90.0, 89.0], "value": 90.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 97.0, 97.0, 98.0, 96.0, 97.0, 97.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 119.0, 129.0, 88.0, 138.0, 139.0, 89.0, 91.0, 95.0, 89.0], "value": 106.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 49.0, 54.0, 61.0, 76.0, 79.0, 49.0, 50.0, 48.0, 51.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 61.0, 56.0, 62.0, 61.0, 55.0, 57.0, 56.0, 55.0], "value": 57.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 89.0, 88.0, 87.0, 87.0, 88.0, 90.0, 133.0, 104.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 137.0, 136.0, 137.0, 138.0, 134.0, 140.0, 133.0, 149.0], "value": 137.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 184.0, 186.0, 186.0, 186.0, 212.0, 197.0, 232.0, 236.0, 209.0], "value": 201.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 81.0, 97.0, 88.0, 68.0, 68.0, 67.0, 68.0], "value": 73.1, "unit": "score"}]}]} 10:03:18 INFO - Return code: 0 10:03:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:03:18 INFO - # TBPL SUCCESS # 10:03:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:03:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:03:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:03:18 INFO - 2017-06-02 10:03:18.618 screenresolution[1667:11093] starting screenresolution argv=screenresolution get 10:03:18 INFO - 2017-06-02 10:03:18.621 screenresolution[1667:11093] Display 0: 1600x1200x32@60 10:03:18 INFO - 2017-06-02 10:03:18.627 screenresolution[1668:11096] starting screenresolution argv=screenresolution list 10:03:18 INFO - Available Modes on Display 0 10:03:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:03:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:03:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:03:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:03:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:03:18 INFO - Intel Iris: 10:03:18 INFO - Chipset Model: Intel Iris 10:03:18 INFO - Type: GPU 10:03:18 INFO - Bus: Built-In 10:03:18 INFO - VRAM (Dynamic, Max): 1536 MB 10:03:18 INFO - Vendor: Intel (0x8086) 10:03:18 INFO - Device ID: 0x0a2e 10:03:18 INFO - Revision ID: 0x0009 10:03:18 INFO - Displays: 10:03:18 INFO - Display: 10:03:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:03:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:03:18 INFO - Display Serial Number: 1600x1200 60 10:03:18 INFO - Main Display: Yes 10:03:18 INFO - Mirror: Off 10:03:18 INFO - Online: Yes 10:03:18 INFO - Rotation: Supported 10:03:18 INFO - Return code: 0 10:03:18 INFO - Running post-action listener: _resource_record_post_action 10:03:18 INFO - [mozharness: 2017-06-02 17:03:18.856992Z] Finished run-tests step (success) 10:03:18 INFO - Running post-run listener: _resource_record_post_run 10:03:18 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 41470976; Write bytes: 570656768; Read time: 412; Write time: 1829 10:03:18 INFO - TinderboxPrint: CPU usage
53.3% 10:03:18 INFO - TinderboxPrint: I/O read bytes / time
41,470,976 / 412 10:03:18 INFO - TinderboxPrint: I/O write bytes / time
570,656,768 / 1,829 10:03:18 INFO - TinderboxPrint: CPU idle
2,349.1 (46.7%) 10:03:18 INFO - TinderboxPrint: CPU system
1,015.4 (20.2%) 10:03:18 INFO - TinderboxPrint: CPU user
1,661.2 (33.1%) 10:03:18 INFO - TinderboxPrint: Swap in / out
287,600,640 / 0 10:03:18 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 237406720; Write bytes: 231737344; Read time: 16988; Write time: 526 10:03:18 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 10:03:18 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 39406592; Write bytes: 319586304; Read time: 385; Write time: 1256 10:03:19 INFO - Running post-run listener: _upload_blobber_files 10:03:19 INFO - Blob upload gear active. 10:03:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:03:19 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:03:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:03:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:03:19 INFO - (blobuploader) - INFO - Open directory for files ... 10:03:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:03:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:03:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:21 INFO - (blobuploader) - INFO - Done attempting. 10:03:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:03:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:03:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:22 INFO - (blobuploader) - INFO - Done attempting. 10:03:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 10:03:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:23 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 10:03:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:23 INFO - (blobuploader) - INFO - Done attempting. 10:03:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:03:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:03:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:03:25 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:03:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:03:25 INFO - (blobuploader) - INFO - Done attempting. 10:03:25 INFO - (blobuploader) - INFO - Iteration through files over. 10:03:25 INFO - Return code: 0 10:03:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:03:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:03:25 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad4f7c9ae93700baeb9689c57eb720951bbcc66d34403ebbdec8fa99f4c142b8b874eba63d93855b6fa25b43f10aacf57d58e8250bf6b6ce19361398eb989a07", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acde15b499f034d866daea07d6a974585083d9cad6e8bc27c18e4c8357aeea26b6b05e1d5d4d0b262f9dfc0f2ebae1af8c41f8af3d1c52d2609e2f0e46cd7724", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae51b34c3eff1ba94ab981ee8524bc8a948ea0f19ccaaf205b8f9af8b546a0366ff5cab91edbbb523574c389efe129e7fd8433333011d96c996fb89df87626d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c232fd255d14e5d9beeb5bdba485d3bc9bdfbf6c6c8a28e86d6cadd82e5fb60d8e6083c0de4c69c33e7926ad9b5f7d9d1a970f07c9679b31bfdbd649275f0e"} 10:03:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:03:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:03:25 INFO - Contents: 10:03:25 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad4f7c9ae93700baeb9689c57eb720951bbcc66d34403ebbdec8fa99f4c142b8b874eba63d93855b6fa25b43f10aacf57d58e8250bf6b6ce19361398eb989a07", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acde15b499f034d866daea07d6a974585083d9cad6e8bc27c18e4c8357aeea26b6b05e1d5d4d0b262f9dfc0f2ebae1af8c41f8af3d1c52d2609e2f0e46cd7724", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae51b34c3eff1ba94ab981ee8524bc8a948ea0f19ccaaf205b8f9af8b546a0366ff5cab91edbbb523574c389efe129e7fd8433333011d96c996fb89df87626d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c232fd255d14e5d9beeb5bdba485d3bc9bdfbf6c6c8a28e86d6cadd82e5fb60d8e6083c0de4c69c33e7926ad9b5f7d9d1a970f07c9679b31bfdbd649275f0e"} 10:03:25 INFO - Running post-run listener: copy_logs_to_upload_dir 10:03:25 INFO - Copying logs to upload dir... 10:03:25 INFO - mkdir: /builds/slave/test/build/upload/logs 10:03:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.969570 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-02 10:03:25.596839) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 10:03:25.600518) ========= 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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad4f7c9ae93700baeb9689c57eb720951bbcc66d34403ebbdec8fa99f4c142b8b874eba63d93855b6fa25b43f10aacf57d58e8250bf6b6ce19361398eb989a07", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acde15b499f034d866daea07d6a974585083d9cad6e8bc27c18e4c8357aeea26b6b05e1d5d4d0b262f9dfc0f2ebae1af8c41f8af3d1c52d2609e2f0e46cd7724", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae51b34c3eff1ba94ab981ee8524bc8a948ea0f19ccaaf205b8f9af8b546a0366ff5cab91edbbb523574c389efe129e7fd8433333011d96c996fb89df87626d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c232fd255d14e5d9beeb5bdba485d3bc9bdfbf6c6c8a28e86d6cadd82e5fb60d8e6083c0de4c69c33e7926ad9b5f7d9d1a970f07c9679b31bfdbd649275f0e"} build_url:https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011252 build_url: 'https://queue.taskcluster.net/v1/task/Mr8DWtRzRXCJpK8jv9bXMw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ad4f7c9ae93700baeb9689c57eb720951bbcc66d34403ebbdec8fa99f4c142b8b874eba63d93855b6fa25b43f10aacf57d58e8250bf6b6ce19361398eb989a07", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/acde15b499f034d866daea07d6a974585083d9cad6e8bc27c18e4c8357aeea26b6b05e1d5d4d0b262f9dfc0f2ebae1af8c41f8af3d1c52d2609e2f0e46cd7724", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/dae51b34c3eff1ba94ab981ee8524bc8a948ea0f19ccaaf205b8f9af8b546a0366ff5cab91edbbb523574c389efe129e7fd8433333011d96c996fb89df87626d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/68c232fd255d14e5d9beeb5bdba485d3bc9bdfbf6c6c8a28e86d6cadd82e5fb60d8e6083c0de4c69c33e7926ad9b5f7d9d1a970f07c9679b31bfdbd649275f0e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-02 10:03:25.634386) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 10:03:25.634725) ========= 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.qqf9cUCtFk/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.BtYK7QV9Qd/Listeners TMPDIR=/var/folders/9r/dqqyy7gj0jq4nplzz_zpf1hm00000w/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.005357 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-02 10:03:25.682939) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 10:03:25.683306) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-02 10:03:25.683631) ========= ========= Total master_lag: 0.19 =========