builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0024 starttime: 1496016999.2 results: success (0) revision: 51d220b260d8d52a41b65aae2c5d74be99c2d59e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.203083) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.203519) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.204211) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.242153) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.242696) ========= 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.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-28 17:16:39-- 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% 159M=0s 2017-05-28 17:16:39 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.167039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.433711) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.434020) ========= 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.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.028498 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:39.483711) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-28 17:16:39.484040) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 51d220b260d8d52a41b65aae2c5d74be99c2d59e --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 51d220b260d8d52a41b65aae2c5d74be99c2d59e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-28 17:16:39,598 truncating revision to first 12 chars 2017-05-28 17:16:39,598 Setting DEBUG logging. 2017-05-28 17:16:39,598 attempt 1/10 2017-05-28 17:16:39,598 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/51d220b260d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-28 17:16:41,223 unpacking tar archive at: mozilla-central-51d220b260d8/testing/mozharness/ program finished with exit code 0 elapsedTime=2.156141 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-05-28 17:16:41.663123) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:41.663429) ========= script_repo_revision: 51d220b260d8d52a41b65aae2c5d74be99c2d59e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:41.664294) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:41.664561) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-28 17:16:41.690508) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-28 17:16:41.690842) ========= /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.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 17:16:41 INFO - MultiFileLogger online at 20170528 17:16:41 in /builds/slave/test 17:16:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:16:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:16:41 INFO - {'append_to_log': False, 17:16:41 INFO - 'base_work_dir': '/builds/slave/test', 17:16:41 INFO - 'blob_upload_branch': 'Firefox', 17:16:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:16:41 INFO - 'branch': 'Firefox', 17:16:41 INFO - 'buildbot_json_path': 'buildprops.json', 17:16:41 INFO - 'config_files': ('talos/mac_config.py',), 17:16:41 INFO - 'default_actions': ('clobber', 17:16:41 INFO - 'read-buildbot-config', 17:16:41 INFO - 'download-and-extract', 17:16:41 INFO - 'populate-webroot', 17:16:41 INFO - 'create-virtualenv', 17:16:41 INFO - 'install', 17:16:41 INFO - 'setup-mitmproxy', 17:16:41 INFO - 'run-tests'), 17:16:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:16:41 INFO - 'download_minidump_stackwalk': True, 17:16:41 INFO - 'download_symbols': 'ondemand', 17:16:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:16:41 INFO - 'tooltool.py': '/tools/tooltool.py', 17:16:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:16:41 INFO - '/tools/misc-python/virtualenv.py')}, 17:16:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:16:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:16:41 INFO - 'gecko_profile': False, 17:16:41 INFO - 'gecko_profile_interval': 0, 17:16:41 INFO - 'installer_path': 'installer.exe', 17:16:41 INFO - 'log_level': 'info', 17:16:41 INFO - 'log_name': 'talos', 17:16:41 INFO - 'log_to_console': True, 17:16:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:16:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:16:41 INFO - 'opt_config_files': (), 17:16:41 INFO - 'pip_index': False, 17:16:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:41 INFO - 'cmd': ('bash', 17:16:41 INFO - '-c', 17:16:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:41 INFO - 'enabled': True, 17:16:41 INFO - 'halt_on_failure': False, 17:16:41 INFO - 'name': 'check_screen_resolution'},), 17:16:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:16:41 INFO - 'cmd': ('bash', 17:16:41 INFO - '-c', 17:16:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:16:41 INFO - 'enabled': True, 17:16:41 INFO - 'halt_on_failure': False, 17:16:41 INFO - 'name': 'check_screen_resolution'},), 17:16:41 INFO - 'run_cmd_checks_enabled': True, 17:16:41 INFO - 'suite': 'dromaeojs-e10s', 17:16:41 INFO - 'system_bits': '32', 17:16:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:16:41 INFO - 'title': 't-yosemite-r7-0024', 17:16:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:16:41 INFO - 'use_talos_json': True, 17:16:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:16:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:16:41 INFO - 'work_dir': 'build'} 17:16:41 INFO - [mozharness: 2017-05-29 00:16:41.925874Z] Running clobber step. 17:16:41 INFO - Running pre-action listener: _resource_record_pre_action 17:16:41 INFO - Running main action method: clobber 17:16:41 INFO - rmtree: /builds/slave/test/build 17:16:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:16:44 INFO - Running post-action listener: _resource_record_post_action 17:16:44 INFO - [mozharness: 2017-05-29 00:16:44.396405Z] Finished clobber step (success) 17:16:44 INFO - [mozharness: 2017-05-29 00:16:44.396596Z] Running read-buildbot-config step. 17:16:44 INFO - Running pre-action listener: _resource_record_pre_action 17:16:44 INFO - Running main action method: read_buildbot_config 17:16:44 INFO - Using buildbot properties: 17:16:44 INFO - { 17:16:44 INFO - "project": "", 17:16:44 INFO - "product": "firefox", 17:16:44 INFO - "who": "archaeopteryx@coole-files.de", 17:16:44 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 17:16:44 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 17:16:44 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 17:16:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 17:16:44 INFO - "stage_platform": "macosx64", 17:16:44 INFO - "basedir": "/builds/slave/test", 17:16:44 INFO - "buildnumber": 228, 17:16:44 INFO - "platform": "yosemite_r7", 17:16:44 INFO - "parent_task_id": "RRCg1XvrRciWzKC6uCeKig", 17:16:44 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 17:16:44 INFO - "slavebuilddir": "test", 17:16:44 INFO - "taskId": "axd1fmT0S5SWkXoKB6VzYg", 17:16:44 INFO - "branch": "mozilla-central", 17:16:44 INFO - "script_repo_revision": "production", 17:16:44 INFO - "revision": "51d220b260d8d52a41b65aae2c5d74be99c2d59e", 17:16:44 INFO - "slavename": "t-yosemite-r7-0024", 17:16:44 INFO - "repo_path": "mozilla-central" 17:16:44 INFO - } 17:16:44 INFO - Finding installer, test and symbols from parent task. 17:16:44 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/axd1fmT0S5SWkXoKB6VzYg'}, attempt #1 17:16:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/axd1fmT0S5SWkXoKB6VzYg'}, attempt #1 17:16:45 INFO - Task dependencies: cheu9mx1SUSVam6oj4U4wQ RRCg1XvrRciWzKC6uCeKig 17:16:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig'}, attempt #1 17:16:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:16:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/test_packages.json 17:16:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/target.crashreporter-symbols.zip 17:16:46 INFO - Running post-action listener: _resource_record_post_action 17:16:46 INFO - [mozharness: 2017-05-29 00:16:46.078724Z] Finished read-buildbot-config step (success) 17:16:46 INFO - [mozharness: 2017-05-29 00:16:46.078876Z] Running download-and-extract step. 17:16:46 INFO - Running pre-action listener: _resource_record_pre_action 17:16:46 INFO - Running main action method: download_and_extract 17:16:46 INFO - mkdir: /builds/slave/test/build/tests 17:16:46 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')]} 17:16:46 INFO - trying https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 17:16:46 INFO - Downloading https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:16:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 17:16:46 INFO - Downloaded 1325 bytes. 17:16:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 17:16:46 INFO - Using the following test package requirements: 17:16:46 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 17:16:46 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:16:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 17:16:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 17:16:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'jsshell-mac.zip'], 17:16:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 17:16:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 17:16:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 17:16:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 17:16:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 17:16:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 17:16:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 17:16:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:16:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:16:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:16:46 INFO - Fetch https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:16:48 INFO - Content-Length response header: 57631773 17:16:48 INFO - Bytes received: 57631773 17:17:06 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:17:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 17:17:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:17:06 INFO - Fetch https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 17:17:09 INFO - Content-Length response header: 57631773 17:17:09 INFO - Bytes received: 57631773 17:17:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 17:17:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:17:26 INFO - Fetch https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 17:17:27 INFO - Content-Length response header: 14447425 17:17:27 INFO - Bytes received: 14447425 17:17:27 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')]} 17:17:27 INFO - trying https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:27 INFO - Downloading https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 17:17:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 17:17:29 INFO - Downloaded 67823960 bytes. 17:17:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:29 INFO - mkdir: /builds/slave/test/properties 17:17:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:17:29 INFO - Writing to file /builds/slave/test/properties/build_url 17:17:29 INFO - Contents: 17:17:29 INFO - build_url:https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 17:17:29 INFO - Running post-action listener: _resource_record_post_action 17:17:29 INFO - Running post-action listener: set_extra_try_arguments 17:17:29 INFO - [mozharness: 2017-05-29 00:17:29.527018Z] Finished download-and-extract step (success) 17:17:29 INFO - [mozharness: 2017-05-29 00:17:29.527156Z] Running populate-webroot step. 17:17:29 INFO - Running pre-action listener: _resource_record_pre_action 17:17:29 INFO - Running main action method: populate_webroot 17:17:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:17:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:17:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:17:29 INFO - '--tppagecycles', 17:17:29 INFO - '7'], 17:17:29 INFO - 'tests': ['tsvgm']}}, 17:17:29 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:17:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:17:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:17:29 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:29 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:17:29 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['damp', 'tps']}, 17:17:29 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'tests': ['damp', 'tps']}, 17:17:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['dromaeo_dom']}, 17:17:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:17:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 17:17:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 17:17:29 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 17:17:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'other-e10s': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['a11yr', 17:17:29 INFO - 'ts_paint', 17:17:29 INFO - 'tpaint', 17:17:29 INFO - 'sessionrestore', 17:17:29 INFO - 'sessionrestore_no_auto_restore', 17:17:29 INFO - 'tabpaint']}, 17:17:29 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 17:17:29 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 17:17:29 INFO - 'bloom_basic_ref']}, 17:17:29 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 17:17:29 INFO - 'talos_options': ['--mitmproxy', 17:17:29 INFO - 'mitmproxy-recording-1.mp', 17:17:29 INFO - '--firstNonBlankPaint'], 17:17:29 INFO - 'tests': ['Quantum_1']}, 17:17:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['tsvgx', 17:17:29 INFO - 'tsvgr_opacity', 17:17:29 INFO - 'tart', 17:17:29 INFO - 'tscrollx', 17:17:29 INFO - 'cart', 17:17:29 INFO - 'tsvg_static']}, 17:17:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:17:29 INFO - 'tsvgr_opacity', 17:17:29 INFO - 'tart', 17:17:29 INFO - 'tscrollx', 17:17:29 INFO - 'cart', 17:17:29 INFO - 'tsvg_static']}, 17:17:29 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'talos_options': ['--disable-e10s'], 17:17:29 INFO - 'tests': ['tp5o']}, 17:17:29 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 17:17:29 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'talos_options': ['--disable-e10s', 17:17:29 INFO - '--xperf_path', 17:17:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:29 INFO - 'tests': ['tp5n']}, 17:17:29 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 17:17:29 INFO - 'talos_options': ['--xperf_path', 17:17:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:17:29 INFO - 'tests': ['tp5n']}}, 17:17:29 INFO - 'talos.zip': {'path': '', 17:17:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:17:29 INFO - Running post-action listener: _resource_record_post_action 17:17:29 INFO - [mozharness: 2017-05-29 00:17:29.548665Z] Finished populate-webroot step (success) 17:17:29 INFO - [mozharness: 2017-05-29 00:17:29.548797Z] Running create-virtualenv step. 17:17:29 INFO - Running pre-action listener: _resource_record_pre_action 17:17:29 INFO - Running main action method: create_virtualenv 17:17:29 INFO - Creating virtualenv /builds/slave/test/build/venv 17:17:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 17:17:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:17:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:17:29 INFO - Using real prefix '/tools/python27' 17:17:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:17:30 INFO - Installing distribute.............................................................................................................................................................................................done. 17:17:33 INFO - Installing pip.................done. 17:17:33 INFO - Return code: 0 17:17:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:17:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:17:33 INFO - Reading from file tmpfile_stdout 17:17:33 INFO - Output received: 17:17:33 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 17:17:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:17:33 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')]} 17:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:33 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')]} 17:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:33 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:33 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 17:17:33 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 17:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:33 INFO - 'HOME': '/Users/cltbld', 17:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:33 INFO - 'LOGNAME': 'cltbld', 17:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:33 INFO - 'MOZ_NO_REMOTE': '1', 17:17:33 INFO - 'NO_EM_RESTART': '1', 17:17:33 INFO - 'PAGER': '/bin/cat', 17:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:33 INFO - 'PWD': '/builds/slave/test', 17:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:33 INFO - 'SHELL': '/bin/bash', 17:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:33 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:33 INFO - 'USER': 'cltbld', 17:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:33 INFO - 'XPC_FLAGS': '0x0', 17:17:33 INFO - 'XPC_SERVICE_NAME': '0', 17:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:17:33 INFO - Cleaning up... 17:17:33 INFO - Return code: 0 17:17:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:17:33 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')]} 17:17:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:33 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')]} 17:17:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:33 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:33 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 17:17:33 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 17:17:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:33 INFO - 'HOME': '/Users/cltbld', 17:17:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:33 INFO - 'LOGNAME': 'cltbld', 17:17:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:33 INFO - 'MOZ_NO_REMOTE': '1', 17:17:33 INFO - 'NO_EM_RESTART': '1', 17:17:33 INFO - 'PAGER': '/bin/cat', 17:17:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:33 INFO - 'PWD': '/builds/slave/test', 17:17:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:33 INFO - 'SHELL': '/bin/bash', 17:17:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:33 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:33 INFO - 'USER': 'cltbld', 17:17:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:33 INFO - 'XPC_FLAGS': '0x0', 17:17:33 INFO - 'XPC_SERVICE_NAME': '0', 17:17:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:34 INFO - Downloading/unpacking psutil>=3.1.1 17:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:17:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:36 INFO - Installing collected packages: psutil 17:17:36 INFO - Running setup.py install for psutil 17:17:36 INFO - building 'psutil._psutil_osx' extension 17:17:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 17:17:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 17:17:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 17:17:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 17:17:37 INFO - building 'psutil._psutil_posix' extension 17:17:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 17:17:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:17:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:17:37 INFO - ^ 17:17:37 INFO - 1 warning generated. 17:17:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 17:17:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:17:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:17:37 INFO - Successfully installed psutil 17:17:37 INFO - Cleaning up... 17:17:37 INFO - Return code: 0 17:17:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:17:37 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')]} 17:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:37 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')]} 17:17:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:37 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:37 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 17:17:37 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 17:17:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:37 INFO - 'HOME': '/Users/cltbld', 17:17:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:37 INFO - 'LOGNAME': 'cltbld', 17:17:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:37 INFO - 'MOZ_NO_REMOTE': '1', 17:17:37 INFO - 'NO_EM_RESTART': '1', 17:17:37 INFO - 'PAGER': '/bin/cat', 17:17:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:37 INFO - 'PWD': '/builds/slave/test', 17:17:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:37 INFO - 'SHELL': '/bin/bash', 17:17:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:37 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:37 INFO - 'USER': 'cltbld', 17:17:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:37 INFO - 'XPC_FLAGS': '0x0', 17:17:37 INFO - 'XPC_SERVICE_NAME': '0', 17:17:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:17:39 INFO - Installing collected packages: mozsystemmonitor 17:17:39 INFO - Running setup.py install for mozsystemmonitor 17:17:39 INFO - Successfully installed mozsystemmonitor 17:17:39 INFO - Cleaning up... 17:17:39 INFO - Return code: 0 17:17:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:17:39 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')]} 17:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:39 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')]} 17:17:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:39 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:39 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 17:17:39 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 17:17:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:39 INFO - 'HOME': '/Users/cltbld', 17:17:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:39 INFO - 'LOGNAME': 'cltbld', 17:17:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:39 INFO - 'MOZ_NO_REMOTE': '1', 17:17:39 INFO - 'NO_EM_RESTART': '1', 17:17:39 INFO - 'PAGER': '/bin/cat', 17:17:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:39 INFO - 'PWD': '/builds/slave/test', 17:17:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:39 INFO - 'SHELL': '/bin/bash', 17:17:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:39 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:39 INFO - 'USER': 'cltbld', 17:17:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:39 INFO - 'XPC_FLAGS': '0x0', 17:17:39 INFO - 'XPC_SERVICE_NAME': '0', 17:17:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:39 INFO - Downloading/unpacking jsonschema==2.5.1 17:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:17:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:41 INFO - Downloading functools32-3.2.3-2.tar.gz 17:17:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:17:41 INFO - warning: no files found matching '*.txt' 17:17:41 INFO - no previously-included directories found matching 'build' 17:17:41 INFO - no previously-included directories found matching 'dist' 17:17:41 INFO - no previously-included directories found matching '.git*' 17:17:41 INFO - Installing collected packages: jsonschema, functools32 17:17:41 INFO - Running setup.py install for functools32 17:17:41 INFO - warning: no files found matching '*.txt' 17:17:41 INFO - no previously-included directories found matching 'build' 17:17:41 INFO - no previously-included directories found matching 'dist' 17:17:41 INFO - no previously-included directories found matching '.git*' 17:17:41 INFO - Successfully installed jsonschema functools32 17:17:41 INFO - Cleaning up... 17:17:41 INFO - Return code: 0 17:17:41 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:17:41 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')]} 17:17:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:41 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')]} 17:17:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:41 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:41 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 17:17:41 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 17:17:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:41 INFO - 'HOME': '/Users/cltbld', 17:17:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:41 INFO - 'LOGNAME': 'cltbld', 17:17:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:41 INFO - 'MOZ_NO_REMOTE': '1', 17:17:41 INFO - 'NO_EM_RESTART': '1', 17:17:41 INFO - 'PAGER': '/bin/cat', 17:17:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:41 INFO - 'PWD': '/builds/slave/test', 17:17:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:41 INFO - 'SHELL': '/bin/bash', 17:17:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:41 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:41 INFO - 'USER': 'cltbld', 17:17:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:41 INFO - 'XPC_FLAGS': '0x0', 17:17:41 INFO - 'XPC_SERVICE_NAME': '0', 17:17:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:17:42 INFO - Cleaning up... 17:17:42 INFO - Return code: 0 17:17:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:17:42 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')]} 17:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:42 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')]} 17:17:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:42 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:42 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 17:17:42 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 17:17:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:42 INFO - 'HOME': '/Users/cltbld', 17:17:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:42 INFO - 'LOGNAME': 'cltbld', 17:17:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:42 INFO - 'MOZ_NO_REMOTE': '1', 17:17:42 INFO - 'NO_EM_RESTART': '1', 17:17:42 INFO - 'PAGER': '/bin/cat', 17:17:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:42 INFO - 'PWD': '/builds/slave/test', 17:17:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:42 INFO - 'SHELL': '/bin/bash', 17:17:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:42 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:42 INFO - 'USER': 'cltbld', 17:17:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:42 INFO - 'XPC_FLAGS': '0x0', 17:17:42 INFO - 'XPC_SERVICE_NAME': '0', 17:17:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:42 INFO - Downloading/unpacking blobuploader==1.2.4 17:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:43 INFO - Downloading blobuploader-1.2.4.tar.gz 17:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:17:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:17:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:44 INFO - Downloading docopt-0.6.1.tar.gz 17:17:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:17:44 INFO - Installing collected packages: blobuploader, requests, docopt 17:17:44 INFO - Running setup.py install for blobuploader 17:17:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:17:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:17:44 INFO - Running setup.py install for requests 17:17:45 INFO - Running setup.py install for docopt 17:17:45 INFO - Successfully installed blobuploader requests docopt 17:17:45 INFO - Cleaning up... 17:17:45 INFO - Return code: 0 17:17:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:45 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')]} 17:17:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:45 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')]} 17:17:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:45 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:45 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 17:17:45 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 17:17:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:45 INFO - 'HOME': '/Users/cltbld', 17:17:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:45 INFO - 'LOGNAME': 'cltbld', 17:17:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:45 INFO - 'MOZ_NO_REMOTE': '1', 17:17:45 INFO - 'NO_EM_RESTART': '1', 17:17:45 INFO - 'PAGER': '/bin/cat', 17:17:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:45 INFO - 'PWD': '/builds/slave/test', 17:17:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:45 INFO - 'SHELL': '/bin/bash', 17:17:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:45 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:45 INFO - 'USER': 'cltbld', 17:17:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:45 INFO - 'XPC_FLAGS': '0x0', 17:17:45 INFO - 'XPC_SERVICE_NAME': '0', 17:17:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:45 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-nvd6S1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-duUu25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-utTO7F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-o1DmF_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-uf6_Tj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-GH4DW6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-O3hqN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-MIr3tJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0YbN7N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EWpNax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:46 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-5eflJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-OSGkFC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-M3LKzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-WzYCK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-X6cL8e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-PsjqbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:47 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-K_KlsW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:17:47 INFO - Running setup.py install for manifestparser 17:17:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:17:47 INFO - Running setup.py install for mozcrash 17:17:47 INFO - Running setup.py install for mozdebug 17:17:48 INFO - Running setup.py install for mozdevice 17:17:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Running setup.py install for mozfile 17:17:48 INFO - Running setup.py install for mozhttpd 17:17:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Running setup.py install for mozinfo 17:17:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Running setup.py install for mozInstall 17:17:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:17:48 INFO - Running setup.py install for mozleak 17:17:48 INFO - Running setup.py install for mozlog 17:17:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Running setup.py install for moznetwork 17:17:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Running setup.py install for mozprocess 17:17:49 INFO - Running setup.py install for mozprofile 17:17:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Running setup.py install for mozrunner 17:17:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:17:49 INFO - Running setup.py install for mozscreenshot 17:17:49 INFO - Running setup.py install for moztest 17:17:50 INFO - Running setup.py install for mozversion 17:17:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:17:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:17:50 INFO - Cleaning up... 17:17:50 INFO - Return code: 0 17:17:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:50 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')]} 17:17:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:50 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')]} 17:17:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:50 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:50 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 17:17:50 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 17:17:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:50 INFO - 'HOME': '/Users/cltbld', 17:17:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:50 INFO - 'LOGNAME': 'cltbld', 17:17:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:50 INFO - 'MOZ_NO_REMOTE': '1', 17:17:50 INFO - 'NO_EM_RESTART': '1', 17:17:50 INFO - 'PAGER': '/bin/cat', 17:17:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:50 INFO - 'PWD': '/builds/slave/test', 17:17:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:50 INFO - 'SHELL': '/bin/bash', 17:17:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:50 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:50 INFO - 'USER': 'cltbld', 17:17:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:50 INFO - 'XPC_FLAGS': '0x0', 17:17:50 INFO - 'XPC_SERVICE_NAME': '0', 17:17:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-EJapnm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-r1t0Hr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-0Vw7b_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qqDIJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:17:50 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)) 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-TZNPTn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:17:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 17:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:17:50 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Ofb8ik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-2g8s1G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-gWu6bd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-Uo2YDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-SduaEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-A_POm8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9RCWnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-rB41i2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-wvKQhF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:17:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:17:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:51 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-LOPLLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 17:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:17:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-9rHdbm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 17:17:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:17:52 INFO - Running setup.py (path:/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/pip-qy5TpH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:17:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:17:52 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)) 17:17:52 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)) 17:17:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:53 INFO - Downloading blessings-1.6.tar.gz 17:17:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:17:53 INFO - Installing collected packages: blessings 17:17:53 INFO - Running setup.py install for blessings 17:17:53 INFO - Successfully installed blessings 17:17:53 INFO - Cleaning up... 17:17:53 INFO - Return code: 0 17:17:53 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:17:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:54 INFO - Reading from file tmpfile_stdout 17:17:54 INFO - Current package versions: 17:17:54 INFO - blessings == 1.6 17:17:54 INFO - blobuploader == 1.2.4 17:17:54 INFO - docopt == 0.6.1 17:17:54 INFO - functools32 == 3.2.3-2 17:17:54 INFO - jsonschema == 2.5.1 17:17:54 INFO - manifestparser == 1.1 17:17:54 INFO - mozInstall == 1.12 17:17:54 INFO - mozcrash == 1.0 17:17:54 INFO - mozdebug == 0.1 17:17:54 INFO - mozdevice == 0.51 17:17:54 INFO - mozfile == 1.2 17:17:54 INFO - mozhttpd == 0.7 17:17:54 INFO - mozinfo == 0.9 17:17:54 INFO - mozleak == 0.1 17:17:54 INFO - mozlog == 3.4 17:17:54 INFO - moznetwork == 0.27 17:17:54 INFO - mozprocess == 0.25 17:17:54 INFO - mozprofile == 0.28 17:17:54 INFO - mozrunner == 6.13 17:17:54 INFO - mozscreenshot == 0.1 17:17:54 INFO - mozsystemmonitor == 0.3 17:17:54 INFO - moztest == 0.8 17:17:54 INFO - mozversion == 1.4 17:17:54 INFO - psutil == 3.1.1 17:17:54 INFO - requests == 1.2.3 17:17:54 INFO - wsgiref == 0.1.2 17:17:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:17:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:54 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:54 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 17:17:54 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 17:17:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:54 INFO - 'HOME': '/Users/cltbld', 17:17:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:54 INFO - 'LOGNAME': 'cltbld', 17:17:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:54 INFO - 'MOZ_NO_REMOTE': '1', 17:17:54 INFO - 'NO_EM_RESTART': '1', 17:17:54 INFO - 'PAGER': '/bin/cat', 17:17:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:54 INFO - 'PWD': '/builds/slave/test', 17:17:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:54 INFO - 'SHELL': '/bin/bash', 17:17:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:54 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:54 INFO - 'USER': 'cltbld', 17:17:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:54 INFO - 'XPC_FLAGS': '0x0', 17:17:54 INFO - 'XPC_SERVICE_NAME': '0', 17:17:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 17:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 17:17:54 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 17:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:17:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:17:55 INFO - Downloading marionette_driver-1.4.0.tar.gz 17:17:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 17:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 17:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 17:17:56 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)) 17:17:56 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)) 17:17:56 INFO - Installing collected packages: marionette-driver 17:17:56 INFO - Running setup.py install for marionette-driver 17:17:56 INFO - Successfully installed marionette-driver 17:17:56 INFO - Cleaning up... 17:17:56 INFO - Return code: 0 17:17:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:17:56 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')]} 17:17:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:17:56 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')]} 17:17:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:17:56 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 0x104232c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104236768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fda714e5500>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, '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.0XQab04O8o/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.NEl9ukVZhm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 17:17:56 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 17:17:56 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 17:17:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:17:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:17:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:17:56 INFO - 'HOME': '/Users/cltbld', 17:17:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:17:56 INFO - 'LOGNAME': 'cltbld', 17:17:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:17:56 INFO - 'MOZ_NO_REMOTE': '1', 17:17:56 INFO - 'NO_EM_RESTART': '1', 17:17:56 INFO - 'PAGER': '/bin/cat', 17:17:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:17:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:17:56 INFO - 'PWD': '/builds/slave/test', 17:17:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:17:56 INFO - 'SHELL': '/bin/bash', 17:17:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:17:56 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:17:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:17:56 INFO - 'USER': 'cltbld', 17:17:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:17:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:17:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:17:56 INFO - 'XPC_FLAGS': '0x0', 17:17:56 INFO - 'XPC_SERVICE_NAME': '0', 17:17:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:17:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:17:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:17:56 INFO - Cleaning up... 17:17:56 INFO - Return code: 0 17:17:56 INFO - Running post-action listener: _resource_record_post_action 17:17:56 INFO - Running post-action listener: _start_resource_monitoring 17:17:56 INFO - Starting resource monitoring. 17:17:56 INFO - [mozharness: 2017-05-29 00:17:56.563785Z] Finished create-virtualenv step (success) 17:17:56 INFO - [mozharness: 2017-05-29 00:17:56.564929Z] Running install step. 17:17:56 INFO - Running pre-action listener: _resource_record_pre_action 17:17:56 INFO - Running main action method: install 17:17:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:17:56 INFO - Reading from file tmpfile_stdout 17:17:56 INFO - Detecting whether we're running mozinstall >=1.0... 17:17:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:17:56 INFO - Reading from file tmpfile_stdout 17:17:56 INFO - Output received: 17:17:56 INFO - Usage: mozinstall [options] installer 17:17:56 INFO - Options: 17:17:56 INFO - -h, --help show this help message and exit 17:17:56 INFO - -d DEST, --destination=DEST 17:17:56 INFO - Directory to install application into. [default: 17:17:56 INFO - "/builds/slave/test"] 17:17:56 INFO - --app=APP Application being installed. [default: firefox] 17:17:56 INFO - mkdir: /builds/slave/test/build/application 17:17:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:17:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:18:18 INFO - Reading from file tmpfile_stdout 17:18:18 INFO - Output received: 17:18:18 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:18:18 INFO - Running post-action listener: _resource_record_post_action 17:18:18 INFO - [mozharness: 2017-05-29 00:18:18.573866Z] Finished install step (success) 17:18:18 INFO - [mozharness: 2017-05-29 00:18:18.574044Z] Running setup-mitmproxy step. 17:18:18 INFO - Running pre-action listener: _resource_record_pre_action 17:18:18 INFO - Running main action method: setup_mitmproxy 17:18:18 INFO - Skipping: mitmproxy is not required 17:18:18 INFO - Running post-action listener: _resource_record_post_action 17:18:18 INFO - [mozharness: 2017-05-29 00:18:18.574693Z] Finished setup-mitmproxy step (success) 17:18:18 INFO - [mozharness: 2017-05-29 00:18:18.574810Z] Running run-tests step. 17:18:18 INFO - Running pre-action listener: _resource_record_pre_action 17:18:18 INFO - Running main action method: run_tests 17:18:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:18:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:18:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:18:18 INFO - 2017-05-28 17:18:18.589 screenresolution[1637:8094] starting screenresolution argv=screenresolution get 17:18:18 INFO - 2017-05-28 17:18:18.592 screenresolution[1637:8094] Display 0: 1600x1200x32@60 17:18:18 INFO - 2017-05-28 17:18:18.598 screenresolution[1638:8096] starting screenresolution argv=screenresolution list 17:18:18 INFO - Available Modes on Display 0 17:18:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:18:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:18:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:18:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:18:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:18:18 INFO - Intel Iris: 17:18:18 INFO - Chipset Model: Intel Iris 17:18:18 INFO - Type: GPU 17:18:18 INFO - Bus: Built-In 17:18:18 INFO - VRAM (Dynamic, Max): 1536 MB 17:18:18 INFO - Vendor: Intel (0x8086) 17:18:18 INFO - Device ID: 0x0a2e 17:18:18 INFO - Revision ID: 0x0009 17:18:18 INFO - Displays: 17:18:18 INFO - Display: 17:18:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:18:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:18:18 INFO - Display Serial Number: 1600x1200 60 17:18:18 INFO - Main Display: Yes 17:18:18 INFO - Mirror: Off 17:18:18 INFO - Online: Yes 17:18:18 INFO - Rotation: Supported 17:18:18 INFO - Return code: 0 17:18:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:18:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:18:18 INFO - Python 2.7.3 17:18:18 INFO - Return code: 0 17:18:18 INFO - grabbing minidump binary from tooltool 17:18:18 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')]} 17:18:18 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 0x1041bbc60>, '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 0x103f50cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103f79be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:18:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:18:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 17:18:18 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 17:18:18 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:18:18 INFO - Return code: 0 17:18:18 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:18:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:18:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:18:18 INFO - ENV: RUST_BACKTRACE is now 1 17:18:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:18:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:18:18 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/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:18:18 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/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0024 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:18:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0XQab04O8o/Render', 17:18:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:18:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:18:18 INFO - 'HOME': '/Users/cltbld', 17:18:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:18:18 INFO - 'LOGNAME': 'cltbld', 17:18:18 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:18:18 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:18:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:18:18 INFO - 'MOZ_NO_REMOTE': '1', 17:18:18 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:18:18 INFO - 'NO_EM_RESTART': '1', 17:18:18 INFO - 'PAGER': '/bin/cat', 17:18:18 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:18:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:18:18 INFO - 'PWD': '/builds/slave/test', 17:18:18 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:18:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:18:18 INFO - 'RUST_BACKTRACE': '1', 17:18:18 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 17:18:18 INFO - 'SHELL': '/bin/bash', 17:18:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.NEl9ukVZhm/Listeners', 17:18:18 INFO - 'TMPDIR': '/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/', 17:18:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:18:18 INFO - 'USER': 'cltbld', 17:18:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:18:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:18:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:18:18 INFO - 'XPC_FLAGS': '0x0', 17:18:18 INFO - 'XPC_SERVICE_NAME': '0', 17:18:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:18:18 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/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0024', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:18:19 INFO - mozversion application_buildid: 20170528155233 17:18:19 INFO - mozversion application_changeset: 51d220b260d8d52a41b65aae2c5d74be99c2d59e 17:18:19 INFO - mozversion application_display_name: Nightly 17:18:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:18:19 INFO - mozversion application_name: Firefox 17:18:19 INFO - mozversion application_remotingname: firefox 17:18:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:18:19 INFO - mozversion application_vendor: Mozilla 17:18:19 INFO - mozversion application_version: 55.0a1 17:18:19 INFO - mozversion platform_buildid: 20170528155233 17:18:19 INFO - mozversion platform_changeset: 51d220b260d8d52a41b65aae2c5d74be99c2d59e 17:18:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:18:19 INFO - mozversion platform_version: 55.0a1 17:18:19 INFO - using testdate: 1496017099 17:18:19 INFO - actual date: 1496017099 17:18:19 INFO - starting webserver on 'localhost:49230' 17:18:19 INFO - SUITE-START | Running 2 tests 17:18:19 INFO - TEST-START | dromaeo_css 17:18:19 INFO - operating with platform_type : mac_ 17:18:19 INFO - Initialising browser for dromaeo_css test... 17:18:19 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpYlFM66/profile 17:18:19 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 17:18:53 INFO - TEST-INFO | 1648: killed by SIGTERM 17:18:53 INFO - Browser initialized. 17:18:53 INFO - Running cycle 1/1 for dromaeo_css test... 17:18:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpYlFM66/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:18:55 INFO - PID 1656 | 2017-05-28 17:18:55.737 plugin-container[1657:8700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:18:55 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:55 INFO - PID 1656 | 2017-05-28 17:18:55.738 plugin-container[1657:8700] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 17:18:55 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:55 INFO - PID 1656 | 2017-05-28 17:18:55.738 plugin-container[1657:8700] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:18:56 INFO - PID 1656 | 2017-05-28 17:18:56.424 plugin-container[1658:8743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 17:18:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:56 INFO - PID 1656 | 2017-05-28 17:18:56.424 plugin-container[1658:8743] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 17:18:56 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:18:56 INFO - PID 1656 | 2017-05-28 17:18:56.424 plugin-container[1658:8743] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:19:05 INFO - PID 1656 | RSS: Main: 263720960 17:19:05 INFO - PID 1656 | 17:22:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49230/tests/dromaeo/cssquery-ext.html) 17:22:09 INFO - PID 1656 | RSS: Main: 271761408 17:22:09 INFO - PID 1656 | 17:25:11 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-ext.html (next: http://localhost:49230/tests/dromaeo/cssquery-jquery.html) 17:25:12 INFO - PID 1656 | RSS: Main: 283693056 17:25:12 INFO - PID 1656 | 17:28:14 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49230/tests/dromaeo/cssquery-mootools.html) 17:28:14 INFO - PID 1656 | RSS: Main: 283598848 17:28:14 INFO - PID 1656 | 17:31:16 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49230/tests/dromaeo/cssquery-prototype.html) 17:31:17 INFO - PID 1656 | RSS: Main: 287240192 17:31:17 INFO - PID 1656 | 17:34:19 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49230/tests/dromaeo/cssquery-yui.html) 17:34:20 INFO - PID 1656 | RSS: Main: 287518720 17:34:20 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49230/tests/dromaeo/cssquery-yui.html (next: http://localhost:49230/tests/dromaeo/cssquery-dojo.html) 17:37:22 INFO - PID 1656 | RSS: Main: 287342592 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | __start_tp_report 17:37:22 INFO - PID 1656 | _x_x_mozilla_page_load 17:37:22 INFO - PID 1656 | _x_x_mozilla_page_load_details 17:37:22 INFO - PID 1656 | |i|pagename|runs| 17:37:22 INFO - PID 1656 | |0;dojo.html;3206.679332066793;3641.526601541799;3650.142216579105;3673.0266479382967;3676.926461470771;6121.361519882465;6198.752049918004;6232.158658581096;6243.438090571848;6278.6232826030355;5196.688198708081;6128.655903792268;6161.383861613836;6224.097505861648;6274.874502509959;5303.204519322113;5451.809186678477;5462.317210348707;5511.255980442649;5556.361018482886;5559.416261292567;5635.915461268084;5659.585103724069;5661.207430959648;5682.488576028157;5438.401775804658;5483.972580137113;5510.669359838383;5538.861528461801;5540.944590554083;5564.610477266593;5568.749406276727;5577.972110139464;5584.022796010722;5611.242482264924;5491.835244942658;5503.036968530491;5532.363778165482;5563.221149039137;5608.971955140239;5274.143613302729;6149.262088549358;6196.442320191204;6235.844103897393;6285.182926219608;6661.333866613349;6703.966480167616;6705.631190284533;6713.328667133296;6723.865522689568;6768.52620316578;6781.491388145909;6787.457003439713;6797.864042719168;6825.965870170667;5514.503694667459;6231.906521402183;6276.560640755103;6276.748930042792;6309.211348581427;6832.555883867532;6902.275261097613;6903.275156108536;6905.620190889549;6956.304369563003;5315.893682126336;6257.687115644199;6296.716647750863;6444.806655800334;6553.574017688835;6759.729610815611;6767.120274364301;6771.424428923597;6786.3892249697765;6810.557313774589;6458.644774537445;6543.5419520633095;6633.336666333328;6671.13275274221;6688.030235615808;6573.211214654272;6634.203895532567;6643.966780166165;6673.566218195801;6677.699503522354;5368.624196306221;5373.570114390839;5383.757730902119;5390.622656414013;5408.594355423359;4803.879903002453;4812.687175333592;4822.397200349957;4836.105320515716;4850.902981940341;4898.338724272249;6133;6240.500759939194;6272.40412160844;6278.4035516625845;6798.52410331272;6815.795526134224;6818.454523638097;6834.179898412123;6835.384815366541;6206.503479721611;6290.433860952537;6290.748370065237;6303.905441418382;6308.3060863305;5175.896482070338;5374.274472946102;5838.182654428383;5873.295204575393;6094.2077529920825;6926.792196234121;6941.201761797458;6944.131983502163;6956.373926346653;6957.965210174119;6765.051198596906;6805;6816;6818.249992500721;6833.043373927555;6724.260331363644;6823.385895269251;6881.862362752817;6929.203141638776;6960.129983752031;6578.309277525982;6669.199696036507;6691.230508491586;6719.731210751515;6730.158730158731;6785.321467853175;6813;6831.180258369028;6839.829004274933;6847;6371.458426033839;6427.228732552124;6530.47756179495;6558.442532384801;6605.9009114864275;6531.11829902957;6585.3085426029575;6591.604503729792;6599.043138744951;6607.900881486878;6353.745850165941;6437.742490300336;6449.9355006451215;6454.322296158835;6463.062855885964;6388.073729309317;6442.871142577216;6589.703463344065;6611.537192396486;6652.368025037614;6336.524760643062;6388.297287298301;6396.32838551964;6412.647304398303;6437.967810160919 17:37:22 INFO - PID 1656 | |1;ext.html;787.6967367563486;824.3940703582865;844.370943646983;1262;1331.7136815584647;37675;38959.41560876589;39001;39057.21885562287;39098.60901390985;39435.40846887299;39617.60382396175;39783.204335913266;39828.601713982855;39858.40212396816;7866.331361834236;8018.558979256139;8047.798805029863;8331;8577.39958202926;8344.249017588416;8403.789905252386;8421.326293896504;8428.241458268754;8439.324854011664;8372.748817535454;8387.412881098326;8466.872996905051;8473.322134229247;8478.915210847874;8301.29438997686;8390.706325278616;8423.957880210559;8435.95782021092;8437.704680336159;8290.502569845828;8303.501789892625;8315.04376996647;8321.251087402163;8361.540115293656;36958.5216591336;37030;37130.443043354375;37158.8142059288;37161.62838371609;3279.6064472263242;3309.851056702454;3311.9006429807146;3314.6188188358165;3314.685104915029;30739.231519211975;30805.537916931265;30823.845880770452;30825.69174308273;30996.845015775;3058.7247147756807;3119.298157914474;3135.2475405902646;3142.1359126240236;3146.11908665574;247099;249843;284050;300227;301194;47682.28476572854;47702;47815;47870.04259914852;47928;49595;49975.25037124446;50025;50112;50205.74897125491;14157;14184;14295.857041429661;14356.928215359065;14385.928070359581;11543.307401555934;11705.648830534927;11729.530818767154;11731.530738770523;11746.17776755636;13511.459541618422;13516;13529.52646657382;13541.322933853267;13617.182969021891;5168.069319256994;5992.910106348409;6049.516038716892;6068.393160683984;6160.244330365824;990.252359468611;1001.9749506262402;1020.1532727835878;1045.430240518919;1052.4579841381694;19097.618047639247;23711;24116.87941560281;24202.15292464755;24221.757782422304;1106.1758989552834;1108.9279196852178;1136.2841409911869;1137.9374134422687;1146.7247860513417;11833.290002599872;12363.505459781509;12374;12380.62858114258;12397.876021239672;324993;329786;330031;330429;330717;42121;53948.73025634847;54003;54125.45874541204;54154.18768718473;1484.6140003599141;1505.533284681752;1513.402206128573;1514.4850750744579;1533.8849586281074;20537.075831587914;23786;24014.51970960536;24138.75861241365;24238.87880560586;31375.529367059968;37985.810070950574;38056.238875222894;38074.80962595169;38102.618973809906;277335;278905.2109478879;281608;282158;282493;27400.451990960468;32449.51325730163;34250;34287.485687715205;34355.65644343525;39786.403203951384;43344.133117336845;43760.78119609381;45024;45104.872378189495;29173.85413072921;29765.255868603457;30325.545116823705;30923.536146957365;31435.214119646276;27211.455770884866;31256.843715782186;31272.68727312789;31567.21081972969;31611.36777264396;37047.25905481821;37368.81315593405;37853.81073094617;37997.620023799405;38077;30325.848370758005;31075.223119422197;31414.685853141174;32094.358112837144;32141.875034373683;20116.29592964239;21977.120915163214;22442.551148977254;23769.405764856016;24248.51502969966 17:37:22 INFO - PID 1656 | |2;jquery.html;426.3562021347766;432.5523083608466;438.91221755648866;447.9529649386814;453.4377372058647;19166.808331916694;21044;21724.674129888044;21724.91375431226;21819.890900545473;22025.898705064763;22639.660405093935;22826.315210543667;23026.309210723663;23118.65322020171;5970.71629599636;6211.968940155293;6250.999840025597;6254.593451425665;6262.655553944532;5968.79109231177;6082.756689732428;6139.140520327158;6146.446819786218;6185.505159587245;5206;6061.848453788646;6114.3579924108;6122.35715249899;6127.662978536178;5803.274590676166;6035.396460353952;6070.02879539276;6089.817305480843;6116.816495505097;5427.185922111715;5435.891282174374;5444;5444.564434845203;5448.210009548633;22918.77081229183;27305.453890922072;27679.723202767917;27860.606969651435;27876;2084.1975839301826;2127.2022991378235;2155.3103007037753;2157.0832396231694;2161.8919054047233;15628.531144065697;18067.45797626073;18408.63182736351;18410.90794546032;18494.722579161324;2127.851050426471;2146.527763891941;2164.54544545644;2169.8372622053407;2174.662927246279;143576;171575;176366;177369.45261094705;179860;11237.887621123848;11602.491676082056;11735.647930562096;12725.864094169432;12738.363081845871;16437;16618.75071873923;16656.16719164037;16693.666126677643;16722;7295.744648937368;8833.55832208387;9059.728208153765;9278.443293402283;9305.302102342352;8450.098727786199;8506.637883437901;8600.268977136888;8761.518116503654;8766.517841518778;7613.743732284222;7974.840503189904;8115.959420202861;8140.3487720982175;8148.511089334659;1908.3034692337474;2212.834037447166;2245.8091062259564;2263.9773602263767;2266.331432227497;869.8738682890945;900.8288425199191;927.9675211367705;971.7473456901115;979.1628157925014;16920.492385228463;19248.711269330972;20773.688394674093;20909.89545052265;21173.364799055744;987.8146224530449;1014.5840205515703;1033.405791669793;1043.9112675422523;1049.2655141400896;9402.811943761086;9647.649329093932;10552.050315471644;10610.94694526538;10660.520276587573;516968;572993;577067;577657;589198;128134;136200.9569856453;146262;146323;146930;1179.7699448607439;1210.8244304575787;1260.3635164242182;1265.4685032286277;1269.4668239339499;21386.14455421828;21558.353249402542;21763.564728705653;21986.560268794216;22053.11787528481;58834;60362.68005259753;61032;61382;61454.07818882631;79889.60055199687;79927;80466.59766701129;81465;81508.18491815006;7325.7912444446065;7883.526988380716;8857.867131992896;8935.329850261127;9022.413543119676;13923.373448194652;14017.649558761113;14312.569129967198;14340.713185736435;14530.491432799801;11826.467808948446;11836.644900652995;11893.702657433634;12049.096317776026;12137.21108127969;9265.675701350421;9749.853752193581;10023.849642255518;11238.269512481662;11486.540538378371;11889.702757431134;11992.40037998114;12128.029757619546;12230.877691222973;12307.56923507673;8738.519381434082;8912.28701703875;9028.909710902806;9251.352405331561;9364.812703746024;11975.401229938294;11996.940015299868;12188.268703877795;12193.573224937085;12229.816552751894 17:37:22 INFO - PID 1656 | |3;mootools.html;1199.3283761093787;1427.4647007372234;1436.9856301436982;1449.116039216078;1454.9709005819884;11373.374464404455;11498.310101393901;11808.291502509855;11838.289702617838;12252.877471225285;11842;12090;12096.334701591431;12109.697257568543;12118.45466953987;2520.3447103753065;2544.7709706126443;2556.4503631719176;2557.629143774161;2557.744225577446;2153.8815365154987;2489.5892177790734;2501.5497210502103;2520.1053625962795;2525.3434107132184;2458.9754102458924;2496.2012156110054;2502.737212592679;2503.0488414402503;2507.53610582042;2429.149797570854;2443.450223699671;2444.181199298237;2460.6063029915126;2463.6797216361847;2444.4133407982135;2450.7304196538366;2461.5323088613104;2474.208253358926;2545.643609894616;11496.712582185428;11553.942230288794;11566.248193867372;11566.47950842214;11570.305781653127;6359.300476947532;6575.605463672195;6629.038789375513;6636.170478690164;6639.970804525258;4069.857554985591;4252.425922500484;4303.612674859278;4316.6978311518205;4366.061296821199;1256.597888675624;1259.0934527140444;1259.603224984127;1260.5525038611252;1260.9873901260962;15202.46791362308;15620.375184992587;15973.520794376187;16052.03687778737;16121.516354509384;4656.301554766812;5354.330708661418;5394.3796463406425;5424.538914192259;5530.917036244459;11542.480588373543;11676.88323116758;11678.708032299091;11681.299122052535;11682.591109311134;5110.66780659256;6380.106785050097;6390.424861762371;6449.580777249464;6482.319356467598;6030.884286407028;6070.2108725865355;6070.210872586624;6113.816585502442;6177.227846519185;3616.8010759408485;4228.851990180329;4364.08354245612;4377.518472967971;4414.779261036935;1148.3798748675788;1216.8014505711867;1260.6659235302652;1266.468083404972;1287.9549215777404;733.7468573342188;750.7072241825692;757.3448966643814;758.0600055930606;770.595437395372;6042.274927008699;6053.697315134225;6078.300995385587;6196.443632625122;6334.461570766536;595.8619037638091;611.6880391000533;620.7082671144555;620.7579044172776;624.9093881387173;4001.79946016194;4075.409065685459;4480.283154695233;4534.206513860061;4607.239805432133;12869.2921889297;13969;14982.250887455584;15077.698446031238;15171.924140379228;14414.855851441562;14476.782848257284;14931.328090235747;14950;14988.175650339117;8510.48937063778;8653.264472519906;8664.523451210243;8682.044975052866;8682.088380720059;6165.335359452833;7280.453965952681;7441.4418918582405;7457.32884040439;7459.776206713807;12569.93715031419;12728.554500592434;12869.67825804325;12879.034072444792;12907.677308067374;13003.08978371505;13015.828575428257;13133.474661013452;13195.274259915797;13315.334233288104;9177.954110229406;9447.952760236154;9467.431954082776;9476.526173691425;9477.241820654193;10544.472776361305;10558.736031598964;10602.89397106019;10613.20400969915;10684.57261709523;9128.863067054132;9218.44689318643;9224.354295199271;9342.065793420876;9419.011003844771;8637.740867773977;8824.602892869709;8827.823443531222;8841.071687472722;8900.866487002571;8160.795980100307;8250.133735957637;8313.33493320545;8315.916840831756;8321.375896807642;6051;6069.3020302665755;6184.969075154595;6219.191505104308;6283.654399007915;5017.974910125426;5792;5864;5868.3251426086545;5951.672658003678 17:37:22 INFO - PID 1656 | |4;prototype.html;384.68840239406086;390.95504017038047;393.3745344901606;393.70668851705483;394.68030894975055;4996.625253106018;5983.371745966676;6275.403836635513;6301.590396624216;6311.305756366807;5438.700871452079;5873.474406005416;5995.160677505153;6079.908801367983;6083.634981901089;4515.503294637604;4635.351050852883;4676.836310729117;4676.953230467686;4678.859634210962;4671.532846715335;4673.691366417409;4678.976605116986;4680.953190468086;4694.530546945295;3599.3341231872055;4546.249693867851;4588.541145885418;4595.172868883599;4606.355110284563;3799.8480060797533;4532.773361331937;4571.154336447735;4589.724616523019;4623.006053698437;3895.9091454392533;4085.9387109193385;4097.631213190797;4106.733062350968;4122.958770412288;5477.808276710336;5668.546516278729;5881.588288819784;5968.850778730523;6067.969660151715;5451.373092094381;5476.698781567012;5499.1476321170285;5505.430492691057;5532.474414930575;5644.3508996465525;6391.712372943182;6531.595706922987;6543.574667646588;6554.951207806728;3505.684488396031;4287.957120428787;4599.655025873039;4611.654125940568;4617.191991401525;5458.836234912959;6769.323067693241;6835.384815366591;6877.071595334616;6946.756863509803;4073.307537718595;4262.23279809631;4465.910681786346;4710.740909250024;4754.690945088558;4615.699979501322;4778.307145463958;4974.651774375759;4999.950000500021;5022.397312322471;4697.906041879143;5623.6906970116215;5633.549316054706;5655.632383895034;5663.773449062074;6532.771353002717;6681.498887583451;6755.628440435823;6764.661766911635;6771.695273712695;1516.9457227226972;1580.1704105344556;1704.8833014375605;1727.7322015087682;1744.5202569293547;1150.7410832562607;1201.8617858946334;1203.6990752311922;1223.9168336022683;1224.8468941382328;5938.7231745174995;5996.010658241428;6257.499400047986;6261.154744109578;6310.684465776784;6084.239470066242;6419.711112999927;6772.153480814898;7003.404710599554;7016.614086225204;5028.371453568304;5662.518685911744;5713.143028545752;5860.912086318709;6193.327801493571;3140.3827029080044;3189.3621275744945;3317;3631.7457777955287;3646.9817650911577;5787.421257874179;7059.2234854166;7156.821079473055;7165.964170179116;7171.820704482326;7119.679614417364;7176.92823071773;7180.676869540883;7197.532160409557;7300.160481544584;6174.753009879644;6273.62358258506;6304.779332723333;7130.821729456805;7200.387967022862;5825.970870145622;6735.955926831349;6745.662716864039;7016.26329235423;7126.893096603659;5761.5390768739235;5934.584579079423;6689.665516724047;6838.418734407631;6920;7082.114735658043;7162.677679504539;7173.354398103987;7211.459140564374;7215.639218039182;4259.510156332061;4317.633001194934;4363.258246098235;4390.7705842364185;4460.397846290709;6212.006079027334;6449.645269510222;6945.930540694529;6960.303969602999;6999.755008574675;5606.971965140311;5850.040236520813;6465.967670161619;6722.647073524227;6735.393814556714;6052.788152414644;6643.9335606645245;6666.466682665278;6715.697793599397;6747.561408508432;5015;6239.656818875005;6613.775117656997;6625.4037136657935;6640.169978752656;6258.5918168411745;6575.112359831553;6621.5364924454825;6624;6671.733130674718;5729.598928074995;6507.479401647952;6561.704723287559;6567.376099270562;6567.483314016249 17:37:22 INFO - PID 1656 | |5;yui.html;631.2362041929265;687.4672129099948;746.5446077892489;752.9736459223925;773.6982576795051;14773.926130369333;15380.692386152297;16548.503544893658;17495.987961384446;17570.38503652371;16378.590535236624;18051.368202112928;18255.817441825577;18530.44408667742;18686.813131868676;885.8085874498789;948.7390967483929;991.0931497679614;997.3915911294141;1007.098646711193;1012.7771890184148;1015.0712098429965;1016.2530540053056;1017.1760873692295;1017.8422344536609;904.3082042237661;939.8355287824637;1040.9375437473739;1042.7914417116608;1044.3316277582353;1015.751140970467;1033.5090831854884;1036.000259749338;1036.0623635609747;1042.2495803021811;754.4794092076504;756.6103456719794;757.5757575757593;762.4891322813672;765.9927195737587;17408.651826963516;17424.128793560398;17487.912560437242;17659.11704414774;17750.46748597544;17495.12524373789;17552.736708949375;17604.031778252192;18027.909860450745;18122.90938545299;14786.03890747098;14920.477783277642;15079.64283214505;15095.396184152622;15133.167675777828;3450.5341778859784;3476.4090104682264;3479.982600087009;3512.8770493032875;3526.34762568923;124632;159561.20219398828;161822;165898;166345;13651.317434128254;13680.726385472235;13714.862851371558;13840.723185536233;13971.23158226287;21009.579808403745;25914;26142.215733528024;27006.919723210853;27358.589621155697;6572.572782769104;6809.591424514545;7437.665305061285;7735.187805280477;7756.340711039512;3768.5477742671055;3801.6388443097862;3842.212346468967;3849.8652547161273;3850.9037274068373;4275.657947364203;4300.204462174508;4304.375865499485;4375.628071613948;4402.679196241115;951.3055469507299;959.6065613098735;959.9184069354044;966.2849491376331;966.8888077870994;587.9559033072451;590.5334785519477;604.6916072802902;617.5646169450526;631.9698890807954;10617.84073238902;11533.617225454274;11633.0111940486;12493.375331233401;12783.360831958365;561.4273441090065;596.4691424632081;601.1793901324747;602.235161345098;618.8421952402199;5404.567634589223;5614.747336369873;5751.482366587027;5760.3951585083805;5842.328132264759;143530.56469435382;147829;149227.28929589686;149909;150121;111446.32830507548;124072;130487.34756326348;132541.67458325293;133597;76514.6174269125;102305.30404915517;107771.92228077618;107846.99999999843;108105;12028.518859245532;12725.236485810881;12813.590505044624;12849.807252891027;12864.549740759028;41390.34438622421;43287.56712432835;43483.347749784414;44233.89415264644;45111;57342;82263;85026;85953.27257000926;88011;15827.366905323657;16499;17696.469105926844;17921.28314867391;17970.91014544919;16513.752293715363;18399.632007360047;19346.613067738846;19369.4189174325;19413;17565;17819;18213.089345532935;18628.720569191202;18760.437186884414;17189.484315470556;18038.36865709694;18089.909550452165;18654.53363665919;18829.811701882805;17842.107894604957;17880.55298617545;18850;18871.339503117324;18980;11558.42207889619;11889.167758256839;12362.938185309016;12430.440630171845;12445.37773111359;12575.937120314706;13599;14183.716325673222;14264;14312.499062532761 17:37:22 INFO - PID 1656 | __end_tp_report 17:37:22 INFO - PID 1656 | __start_cc_report 17:37:22 INFO - PID 1656 | _x_x_mozilla_cycle_collect,447 17:37:22 INFO - PID 1656 | __end_cc_report 17:37:22 INFO - PID 1656 | __startTimestamp1496018242324__endTimestamp 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | ------- Summary: start ------- 17:37:22 INFO - PID 1656 | Number of tests: 6 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#0] dojo.html Cycles:165 Average:6174.15 Median:6388.30 stddev:735.06 (11.5%) stddev-sans-first:699.48 17:37:22 INFO - PID 1656 | Values: 3206.7 3641.5 3650.1 3673.0 3676.9 6121.4 6198.8 6232.2 6243.4 6278.6 5196.7 6128.7 6161.4 6224.1 6274.9 5303.2 5451.8 5462.3 5511.3 5556.4 5559.4 5635.9 5659.6 5661.2 5682.5 5438.4 5484.0 5510.7 5538.9 5540.9 5564.6 5568.7 5578.0 5584.0 5611.2 5491.8 5503.0 5532.4 5563.2 5609.0 5274.1 6149.3 6196.4 6235.8 6285.2 6661.3 6704.0 6705.6 6713.3 6723.9 6768.5 6781.5 6787.5 6797.9 6826.0 5514.5 6231.9 6276.6 6276.7 6309.2 6832.6 6902.3 6903.3 6905.6 6956.3 5315.9 6257.7 6296.7 6444.8 6553.6 6759.7 6767.1 6771.4 6786.4 6810.6 6458.6 6543.5 6633.3 6671.1 6688.0 6573.2 6634.2 6644.0 6673.6 6677.7 5368.6 5373.6 5383.8 5390.6 5408.6 4803.9 4812.7 4822.4 4836.1 4850.9 4898.3 6133.0 6240.5 6272.4 6278.4 6798.5 6815.8 6818.5 6834.2 6835.4 6206.5 6290.4 6290.7 6303.9 6308.3 5175.9 5374.3 5838.2 5873.3 6094.2 6926.8 6941.2 6944.1 6956.4 6958.0 6765.1 6805.0 6816.0 6818.2 6833.0 6724.3 6823.4 6881.9 6929.2 6960.1 6578.3 6669.2 6691.2 6719.7 6730.2 6785.3 6813.0 6831.2 6839.8 6847.0 6371.5 6427.2 6530.5 6558.4 6605.9 6531.1 6585.3 6591.6 6599.0 6607.9 6353.7 6437.7 6449.9 6454.3 6463.1 6388.1 6442.9 6589.7 6611.5 6652.4 6336.5 6388.3 6396.3 6412.6 6438.0 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#1] ext.html Cycles:180 Average:44558.06 Median:166532.80 stddev:77804.28 (46.7%) stddev-sans-first:77953.14 17:37:22 INFO - PID 1656 | Values: 787.7 824.4 844.4 1262.0 1331.7 37675.0 38959.4 39001.0 39057.2 39098.6 39435.4 39617.6 39783.2 39828.6 39858.4 7866.3 8018.6 8047.8 8331.0 8577.4 8344.2 8403.8 8421.3 8428.2 8439.3 8372.7 8387.4 8466.9 8473.3 8478.9 8301.3 8390.7 8424.0 8436.0 8437.7 8290.5 8303.5 8315.0 8321.3 8361.5 36958.5 37030.0 37130.4 37158.8 37161.6 3279.6 3309.9 3311.9 3314.6 3314.7 30739.2 30805.5 30823.8 30825.7 30996.8 3058.7 3119.3 3135.2 3142.1 3146.1 247099.0 249843.0 284050.0 300227.0 301194.0 47682.3 47702.0 47815.0 47870.0 47928.0 49595.0 49975.3 50025.0 50112.0 50205.7 14157.0 14184.0 14295.9 14356.9 14385.9 11543.3 11705.6 11729.5 11731.5 11746.2 13511.5 13516.0 13529.5 13541.3 13617.2 5168.1 5992.9 6049.5 6068.4 6160.2 990.3 1002.0 1020.2 1045.4 1052.5 19097.6 23711.0 24116.9 24202.2 24221.8 1106.2 1108.9 1136.3 1137.9 1146.7 11833.3 12363.5 12374.0 12380.6 12397.9 324993.0 329786.0 330031.0 330429.0 330717.0 42121.0 53948.7 54003.0 54125.5 54154.2 1484.6 1505.5 1513.4 1514.5 1533.9 20537.1 23786.0 24014.5 24138.8 24238.9 31375.5 37985.8 38056.2 38074.8 38102.6 277335.0 278905.2 281608.0 282158.0 282493.0 27400.5 32449.5 34250.0 34287.5 34355.7 39786.4 43344.1 43760.8 45024.0 45104.9 29173.9 29765.3 30325.5 30923.5 31435.2 27211.5 31256.8 31272.7 31567.2 31611.4 37047.3 37368.8 37853.8 37997.6 38077.0 30325.8 31075.2 31414.7 32094.4 32141.9 20116.3 21977.1 22442.6 23769.4 24248.5 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#2] jquery.html Cycles:180 Average:36962.62 Median:12453.00 stddev:96943.12 (778.5%) stddev-sans-first:97176.25 17:37:22 INFO - PID 1656 | Values: 426.4 432.6 438.9 448.0 453.4 19166.8 21044.0 21724.7 21724.9 21819.9 22025.9 22639.7 22826.3 23026.3 23118.7 5970.7 6212.0 6251.0 6254.6 6262.7 5968.8 6082.8 6139.1 6146.4 6185.5 5206.0 6061.8 6114.4 6122.4 6127.7 5803.3 6035.4 6070.0 6089.8 6116.8 5427.2 5435.9 5444.0 5444.6 5448.2 22918.8 27305.5 27679.7 27860.6 27876.0 2084.2 2127.2 2155.3 2157.1 2161.9 15628.5 18067.5 18408.6 18410.9 18494.7 2127.9 2146.5 2164.5 2169.8 2174.7 143576.0 171575.0 176366.0 177369.5 179860.0 11237.9 11602.5 11735.6 12725.9 12738.4 16437.0 16618.8 16656.2 16693.7 16722.0 7295.7 8833.6 9059.7 9278.4 9305.3 8450.1 8506.6 8600.3 8761.5 8766.5 7613.7 7974.8 8116.0 8140.3 8148.5 1908.3 2212.8 2245.8 2264.0 2266.3 869.9 900.8 928.0 971.7 979.2 16920.5 19248.7 20773.7 20909.9 21173.4 987.8 1014.6 1033.4 1043.9 1049.3 9402.8 9647.6 10552.1 10610.9 10660.5 516968.0 572993.0 577067.0 577657.0 589198.0 128134.0 136201.0 146262.0 146323.0 146930.0 1179.8 1210.8 1260.4 1265.5 1269.5 21386.1 21558.4 21763.6 21986.6 22053.1 58834.0 60362.7 61032.0 61382.0 61454.1 79889.6 79927.0 80466.6 81465.0 81508.2 7325.8 7883.5 8857.9 8935.3 9022.4 13923.4 14017.6 14312.6 14340.7 14530.5 11826.5 11836.6 11893.7 12049.1 12137.2 9265.7 9749.9 10023.8 11238.3 11486.5 11889.7 11992.4 12128.0 12230.9 12307.6 8738.5 8912.3 9028.9 9251.4 9364.8 11975.4 11996.9 12188.3 12193.6 12229.8 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#3] mootools.html Cycles:180 Average:7016.85 Median:3809.30 stddev:4413.67 (115.9%) stddev-sans-first:4404.40 17:37:22 INFO - PID 1656 | Values: 1199.3 1427.5 1437.0 1449.1 1455.0 11373.4 11498.3 11808.3 11838.3 12252.9 11842.0 12090.0 12096.3 12109.7 12118.5 2520.3 2544.8 2556.5 2557.6 2557.7 2153.9 2489.6 2501.5 2520.1 2525.3 2459.0 2496.2 2502.7 2503.0 2507.5 2429.1 2443.5 2444.2 2460.6 2463.7 2444.4 2450.7 2461.5 2474.2 2545.6 11496.7 11553.9 11566.2 11566.5 11570.3 6359.3 6575.6 6629.0 6636.2 6640.0 4069.9 4252.4 4303.6 4316.7 4366.1 1256.6 1259.1 1259.6 1260.6 1261.0 15202.5 15620.4 15973.5 16052.0 16121.5 4656.3 5354.3 5394.4 5424.5 5530.9 11542.5 11676.9 11678.7 11681.3 11682.6 5110.7 6380.1 6390.4 6449.6 6482.3 6030.9 6070.2 6070.2 6113.8 6177.2 3616.8 4228.9 4364.1 4377.5 4414.8 1148.4 1216.8 1260.7 1266.5 1288.0 733.7 750.7 757.3 758.1 770.6 6042.3 6053.7 6078.3 6196.4 6334.5 595.9 611.7 620.7 620.8 624.9 4001.8 4075.4 4480.3 4534.2 4607.2 12869.3 13969.0 14982.3 15077.7 15171.9 14414.9 14476.8 14931.3 14950.0 14988.2 8510.5 8653.3 8664.5 8682.0 8682.1 6165.3 7280.5 7441.4 7457.3 7459.8 12569.9 12728.6 12869.7 12879.0 12907.7 13003.1 13015.8 13133.5 13195.3 13315.3 9178.0 9448.0 9467.4 9476.5 9477.2 10544.5 10558.7 10602.9 10613.2 10684.6 9128.9 9218.4 9224.4 9342.1 9419.0 8637.7 8824.6 8827.8 8841.1 8900.9 8160.8 8250.1 8313.3 8315.9 8321.4 6051.0 6069.3 6185.0 6219.2 6283.7 5018.0 5792.0 5864.0 5868.3 5951.7 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#4] prototype.html Cycles:180 Average:5368.53 Median:5855.48 stddev:1664.88 (28.4%) stddev-sans-first:1626.98 17:37:22 INFO - PID 1656 | Values: 384.7 391.0 393.4 393.7 394.7 4996.6 5983.4 6275.4 6301.6 6311.3 5438.7 5873.5 5995.2 6079.9 6083.6 4515.5 4635.4 4676.8 4677.0 4678.9 4671.5 4673.7 4679.0 4681.0 4694.5 3599.3 4546.2 4588.5 4595.2 4606.4 3799.8 4532.8 4571.2 4589.7 4623.0 3895.9 4085.9 4097.6 4106.7 4123.0 5477.8 5668.5 5881.6 5968.9 6068.0 5451.4 5476.7 5499.1 5505.4 5532.5 5644.4 6391.7 6531.6 6543.6 6555.0 3505.7 4288.0 4599.7 4611.7 4617.2 5458.8 6769.3 6835.4 6877.1 6946.8 4073.3 4262.2 4465.9 4710.7 4754.7 4615.7 4778.3 4974.7 5000.0 5022.4 4697.9 5623.7 5633.5 5655.6 5663.8 6532.8 6681.5 6755.6 6764.7 6771.7 1516.9 1580.2 1704.9 1727.7 1744.5 1150.7 1201.9 1203.7 1223.9 1224.8 5938.7 5996.0 6257.5 6261.2 6310.7 6084.2 6419.7 6772.2 7003.4 7016.6 5028.4 5662.5 5713.1 5860.9 6193.3 3140.4 3189.4 3317.0 3631.7 3647.0 5787.4 7059.2 7156.8 7166.0 7171.8 7119.7 7176.9 7180.7 7197.5 7300.2 6174.8 6273.6 6304.8 7130.8 7200.4 5826.0 6736.0 6745.7 7016.3 7126.9 5761.5 5934.6 6689.7 6838.4 6920.0 7082.1 7162.7 7173.4 7211.5 7215.6 4259.5 4317.6 4363.3 4390.8 4460.4 6212.0 6449.6 6945.9 6960.3 6999.8 5607.0 5850.0 6466.0 6722.6 6735.4 6052.8 6643.9 6666.5 6715.7 6747.6 5015.0 6239.7 6613.8 6625.4 6640.2 6258.6 6575.1 6621.5 6624.0 6671.7 5729.6 6507.5 6561.7 6567.4 6567.5 17:37:22 INFO - PID 1656 | 17:37:22 INFO - PID 1656 | [#5] yui.html Cycles:180 Average:26419.24 Median:18070.64 stddev:41170.66 (227.8%) stddev-sans-first:41240.62 17:37:22 INFO - PID 1656 | Values: 631.2 687.5 746.5 753.0 773.7 14773.9 15380.7 16548.5 17496.0 17570.4 16378.6 18051.4 18255.8 18530.4 18686.8 885.8 948.7 991.1 997.4 1007.1 1012.8 1015.1 1016.3 1017.2 1017.8 904.3 939.8 1040.9 1042.8 1044.3 1015.8 1033.5 1036.0 1036.1 1042.2 754.5 756.6 757.6 762.5 766.0 17408.7 17424.1 17487.9 17659.1 17750.5 17495.1 17552.7 17604.0 18027.9 18122.9 14786.0 14920.5 15079.6 15095.4 15133.2 3450.5 3476.4 3480.0 3512.9 3526.3 124632.0 159561.2 161822.0 165898.0 166345.0 13651.3 13680.7 13714.9 13840.7 13971.2 21009.6 25914.0 26142.2 27006.9 27358.6 6572.6 6809.6 7437.7 7735.2 7756.3 3768.5 3801.6 3842.2 3849.9 3850.9 4275.7 4300.2 4304.4 4375.6 4402.7 951.3 959.6 959.9 966.3 966.9 588.0 590.5 604.7 617.6 632.0 10617.8 11533.6 11633.0 12493.4 12783.4 561.4 596.5 601.2 602.2 618.8 5404.6 5614.7 5751.5 5760.4 5842.3 143530.6 147829.0 149227.3 149909.0 150121.0 111446.3 124072.0 130487.3 132541.7 133597.0 76514.6 102305.3 107771.9 107847.0 108105.0 12028.5 12725.2 12813.6 12849.8 12864.5 41390.3 43287.6 43483.3 44233.9 45111.0 57342.0 82263.0 85026.0 85953.3 88011.0 15827.4 16499.0 17696.5 17921.3 17970.9 16513.8 18399.6 19346.6 19369.4 19413.0 17565.0 17819.0 18213.1 18628.7 18760.4 17189.5 18038.4 18089.9 18654.5 18829.8 17842.1 17880.6 18850.0 18871.3 18980.0 11558.4 11889.2 12362.9 12430.4 12445.4 12575.9 13599.0 14183.7 14264.0 14312.5 17:37:22 INFO - PID 1656 | -------- Summary: end -------- 17:37:22 INFO - PID 1656 | 17:37:22 INFO - TEST-INFO | 1656: exit 0 17:37:22 INFO - TEST-OK | dromaeo_css | took 1143629ms 17:37:22 INFO - TEST-START | kraken 17:37:22 INFO - operating with platform_type : mac_ 17:37:22 INFO - Initialising browser for kraken test... 17:37:22 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpND7TxA/profile 17:37:22 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49230/getInfo.html -foreground) 17:37:56 INFO - TEST-INFO | 1664: killed by SIGTERM 17:37:56 INFO - Browser initialized. 17:37:56 INFO - Running cycle 1/1 for kraken test... 17:37:56 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/T/tmpND7TxA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.096 plugin-container[1669:11251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 17:37:59 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.096 plugin-container[1669:11251] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9637, name = 'com.apple.CFPasteboardClient' 17:37:59 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.097 plugin-container[1669:11251] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.694 plugin-container[1670:11292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 17:37:59 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.695 plugin-container[1670:11292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9433, name = 'com.apple.CFPasteboardClient' 17:37:59 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:37:59 INFO - PID 1668 | 2017-05-28 17:37:59.695 plugin-container[1670:11292] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:38:09 INFO - PID 1668 | RSS: Main: 264200192 17:38:09 INFO - PID 1668 | 17:38:11 INFO - PID 1668 | 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) 17:38:12 INFO - PID 1668 | RSS: Main: 273342464 17:38:12 INFO - PID 1668 | 17:38:15 INFO - PID 1668 | 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) 17:38:15 INFO - PID 1668 | RSS: Main: 281038848 17:38:15 INFO - PID 1668 | 17:38:19 INFO - PID 1668 | 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) 17:38:20 INFO - PID 1668 | RSS: Main: 283295744 17:38:20 INFO - PID 1668 | 17:38:22 INFO - PID 1668 | 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) 17:38:22 INFO - PID 1668 | RSS: Main: 285077504 17:38:22 INFO - PID 1668 | 17:38:24 INFO - PID 1668 | 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) 17:38:25 INFO - PID 1668 | RSS: Main: 285986816 17:38:25 INFO - PID 1668 | 17:38:28 INFO - PID 1668 | 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) 17:38:29 INFO - PID 1668 | RSS: Main: 289718272 17:38:29 INFO - PID 1668 | 17:38:31 INFO - PID 1668 | 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) 17:38:32 INFO - PID 1668 | RSS: Main: 293081088 17:38:32 INFO - PID 1668 | 17:38:35 INFO - PID 1668 | 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) 17:38:36 INFO - PID 1668 | RSS: Main: 291364864 17:38:36 INFO - PID 1668 | 17:38:37 INFO - PID 1668 | 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) 17:38:38 INFO - PID 1668 | RSS: Main: 291659776 17:38:38 INFO - PID 1668 | 17:38:40 INFO - PID 1668 | 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) 17:38:40 INFO - PID 1668 | RSS: Main: 291500032 17:38:40 INFO - PID 1668 | 17:38:43 INFO - PID 1668 | 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) 17:38:43 INFO - PID 1668 | RSS: Main: 291430400 17:38:43 INFO - PID 1668 | 17:38:46 INFO - PID 1668 | 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) 17:38:47 INFO - PID 1668 | RSS: Main: 291262464 17:38:47 INFO - PID 1668 | 17:38:50 INFO - PID 1668 | 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) 17:38:51 INFO - PID 1668 | RSS: Main: 291332096 17:38:51 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | 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) 17:38:53 INFO - PID 1668 | RSS: Main: 293158912 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | __start_tp_report 17:38:53 INFO - PID 1668 | _x_x_mozilla_page_load 17:38:53 INFO - PID 1668 | _x_x_mozilla_page_load_details 17:38:53 INFO - PID 1668 | |i|pagename|runs| 17:38:53 INFO - PID 1668 | |0;ai-astar;103;117;114;113;112;114;114;114;115;114 17:38:53 INFO - PID 1668 | |1;audio-beat-detection;131;147;144;150;110;130;143;116;138;114 17:38:53 INFO - PID 1668 | |2;audio-dft;203;208;206;218;249;276;222;200;258;238 17:38:53 INFO - PID 1668 | |3;audio-fft;94;58;61;85;59;59;63;58;84;80 17:38:53 INFO - PID 1668 | |4;audio-oscillator;86;69;85;74;75;94;95;70;76;91 17:38:53 INFO - PID 1668 | |5;imaging-gaussian-blur;92;93;93;91;97;87;91;82;138;137 17:38:53 INFO - PID 1668 | |6;imaging-darkroom;98;99;99;100;98;97;98;98;98;98 17:38:53 INFO - PID 1668 | |7;imaging-desaturate;92;94;88;90;121;148;91;91;90;94 17:38:53 INFO - PID 1668 | |8;json-parse-financial;53;53;52;54;53;55;53;50;51;53 17:38:53 INFO - PID 1668 | |9;json-stringify-tinderbox;58;65;65;67;64;59;62;58;55;58 17:38:53 INFO - PID 1668 | |10;stanford-crypto-aes;89;87;86;90;87;89;90;112;131;109 17:38:53 INFO - PID 1668 | |11;stanford-crypto-ccm;138;148;155;138;146;149;152;148;155;142 17:38:53 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;189;181;188;169;237;237;191;190;185;185 17:38:53 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;67;68;69;66;71;65;69;65;67;68 17:38:53 INFO - PID 1668 | __end_tp_report 17:38:53 INFO - PID 1668 | __start_cc_report 17:38:53 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1190 17:38:53 INFO - PID 1668 | __end_cc_report 17:38:53 INFO - PID 1668 | __startTimestamp1496018333260__endTimestamp 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | ------- Summary: start ------- 17:38:53 INFO - PID 1668 | Number of tests: 14 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:113.00 Median:114.00 stddev:3.74 (3.3%) stddev-sans-first:1.36 17:38:53 INFO - PID 1668 | Values: 103.0 117.0 114.0 113.0 112.0 114.0 114.0 114.0 115.0 114.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:140.50 stddev:14.60 (10.4%) stddev-sans-first:15.48 17:38:53 INFO - PID 1668 | Values: 131.0 147.0 144.0 150.0 110.0 130.0 143.0 116.0 138.0 114.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:227.80 Median:230.00 stddev:26.19 (11.4%) stddev-sans-first:26.20 17:38:53 INFO - PID 1668 | Values: 203.0 208.0 206.0 218.0 249.0 276.0 222.0 200.0 258.0 238.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:70.10 Median:71.50 stddev:13.97 (19.5%) stddev-sans-first:11.84 17:38:53 INFO - PID 1668 | Values: 94.0 58.0 61.0 85.0 59.0 59.0 63.0 58.0 84.0 80.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:81.50 Median:85.50 stddev:9.88 (11.6%) stddev-sans-first:10.34 17:38:53 INFO - PID 1668 | Values: 86.0 69.0 85.0 74.0 75.0 94.0 95.0 70.0 76.0 91.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:100.10 Median:91.50 stddev:20.11 (22.0%) stddev-sans-first:21.11 17:38:53 INFO - PID 1668 | Values: 92.0 93.0 93.0 91.0 97.0 87.0 91.0 82.0 138.0 137.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:98.30 Median:98.00 stddev:0.82 (0.8%) stddev-sans-first:0.87 17:38:53 INFO - PID 1668 | Values: 98.0 99.0 99.0 100.0 98.0 97.0 98.0 98.0 98.0 98.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:99.90 Median:91.00 stddev:19.40 (21.3%) stddev-sans-first:20.36 17:38:53 INFO - PID 1668 | Values: 92.0 94.0 88.0 90.0 121.0 148.0 91.0 91.0 90.0 94.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:52.70 Median:53.00 stddev:1.42 (2.7%) stddev-sans-first:1.50 17:38:53 INFO - PID 1668 | Values: 53.0 53.0 52.0 54.0 53.0 55.0 53.0 50.0 51.0 53.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:61.10 Median:63.00 stddev:4.01 (6.4%) stddev-sans-first:4.10 17:38:53 INFO - PID 1668 | Values: 58.0 65.0 65.0 67.0 64.0 59.0 62.0 58.0 55.0 58.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:97.00 Median:88.00 stddev:15.17 (17.2%) stddev-sans-first:15.81 17:38:53 INFO - PID 1668 | Values: 89.0 87.0 86.0 90.0 87.0 89.0 90.0 112.0 131.0 109.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:147.10 Median:148.50 stddev:6.21 (4.2%) stddev-sans-first:5.64 17:38:53 INFO - PID 1668 | Values: 138.0 148.0 155.0 138.0 146.0 149.0 152.0 148.0 155.0 142.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.20 Median:189.50 stddev:22.91 (12.1%) stddev-sans-first:24.19 17:38:53 INFO - PID 1668 | Values: 189.0 181.0 188.0 169.0 237.0 237.0 191.0 190.0 185.0 185.0 17:38:53 INFO - PID 1668 | 17:38:53 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:1.90 (2.8%) stddev-sans-first:2.01 17:38:53 INFO - PID 1668 | Values: 67.0 68.0 69.0 66.0 71.0 65.0 69.0 65.0 67.0 68.0 17:38:53 INFO - PID 1668 | -------- Summary: end -------- 17:38:53 INFO - PID 1668 | 17:38:54 INFO - TEST-INFO | 1668: exit 0 17:38:54 INFO - TEST-OK | kraken | took 91219ms 17:38:54 INFO - SUITE-END | took 1234s 17:38:54 INFO - Completed test suite (00:20:35) 17:38:54 INFO - javascript benchmark 17:38:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7953.411405725695, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3206.679332066793, 3641.526601541799, 3650.142216579105, 3673.0266479382967, 3676.926461470771, 6121.361519882465, 6198.752049918004, 6232.158658581096, 6243.438090571848, 6278.6232826030355, 5196.688198708081, 6128.655903792268, 6161.383861613836, 6224.097505861648, 6274.874502509959, 5303.204519322113, 5451.809186678477, 5462.317210348707, 5511.255980442649, 5556.361018482886, 5559.416261292567, 5635.915461268084, 5659.585103724069, 5661.207430959648, 5682.488576028157, 5438.401775804658, 5483.972580137113, 5510.669359838383, 5538.861528461801, 5540.944590554083, 5564.610477266593, 5568.749406276727, 5577.972110139464, 5584.022796010722, 5611.242482264924, 5491.835244942658, 5503.036968530491, 5532.363778165482, 5563.221149039137, 5608.971955140239, 5274.143613302729, 6149.262088549358, 6196.442320191204, 6235.844103897393, 6285.182926219608, 6661.333866613349, 6703.966480167616, 6705.631190284533, 6713.328667133296, 6723.865522689568, 6768.52620316578, 6781.491388145909, 6787.457003439713, 6797.864042719168, 6825.965870170667, 5514.503694667459, 6231.906521402183, 6276.560640755103, 6276.748930042792, 6309.211348581427, 6832.555883867532, 6902.275261097613, 6903.275156108536, 6905.620190889549, 6956.304369563003, 5315.893682126336, 6257.687115644199, 6296.716647750863, 6444.806655800334, 6553.574017688835, 6759.729610815611, 6767.120274364301, 6771.424428923597, 6786.3892249697765, 6810.557313774589, 6458.644774537445, 6543.5419520633095, 6633.336666333328, 6671.13275274221, 6688.030235615808, 6573.211214654272, 6634.203895532567, 6643.966780166165, 6673.566218195801, 6677.699503522354, 5368.624196306221, 5373.570114390839, 5383.757730902119, 5390.622656414013, 5408.594355423359, 4803.879903002453, 4812.687175333592, 4822.397200349957, 4836.105320515716, 4850.902981940341, 4898.338724272249, 6133.0, 6240.500759939194, 6272.40412160844, 6278.4035516625845, 6798.52410331272, 6815.795526134224, 6818.454523638097, 6834.179898412123, 6835.384815366541, 6206.503479721611, 6290.433860952537, 6290.748370065237, 6303.905441418382, 6308.3060863305, 5175.896482070338, 5374.274472946102, 5838.182654428383, 5873.295204575393, 6094.2077529920825, 6926.792196234121, 6941.201761797458, 6944.131983502163, 6956.373926346653, 6957.965210174119, 6765.051198596906, 6805.0, 6816.0, 6818.249992500721, 6833.043373927555, 6724.260331363644, 6823.385895269251, 6881.862362752817, 6929.203141638776, 6960.129983752031, 6578.309277525982, 6669.199696036507, 6691.230508491586, 6719.731210751515, 6730.158730158731, 6785.321467853175, 6813.0, 6831.180258369028, 6839.829004274933, 6847.0, 6371.458426033839, 6427.228732552124, 6530.47756179495, 6558.442532384801, 6605.9009114864275, 6531.11829902957, 6585.3085426029575, 6591.604503729792, 6599.043138744951, 6607.900881486878, 6353.745850165941, 6437.742490300336, 6449.9355006451215, 6454.322296158835, 6463.062855885964, 6388.073729309317, 6442.871142577216, 6589.703463344065, 6611.537192396486, 6652.368025037614, 6336.524760643062, 6388.297287298301, 6396.32838551964, 6412.647304398303, 6437.967810160919], "value": 6126.014270401583, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [787.6967367563486, 824.3940703582865, 844.370943646983, 1262.0, 1331.7136815584647, 37675.0, 38959.41560876589, 39001.0, 39057.21885562287, 39098.60901390985, 39435.40846887299, 39617.60382396175, 39783.204335913266, 39828.601713982855, 39858.40212396816, 7866.331361834236, 8018.558979256139, 8047.798805029863, 8331.0, 8577.39958202926, 8344.249017588416, 8403.789905252386, 8421.326293896504, 8428.241458268754, 8439.324854011664, 8372.748817535454, 8387.412881098326, 8466.872996905051, 8473.322134229247, 8478.915210847874, 8301.29438997686, 8390.706325278616, 8423.957880210559, 8435.95782021092, 8437.704680336159, 8290.502569845828, 8303.501789892625, 8315.04376996647, 8321.251087402163, 8361.540115293656, 36958.5216591336, 37030.0, 37130.443043354375, 37158.8142059288, 37161.62838371609, 3279.6064472263242, 3309.851056702454, 3311.9006429807146, 3314.6188188358165, 3314.685104915029, 30739.231519211975, 30805.537916931265, 30823.845880770452, 30825.69174308273, 30996.845015775, 3058.7247147756807, 3119.298157914474, 3135.2475405902646, 3142.1359126240236, 3146.11908665574, 247099.0, 249843.0, 284050.0, 300227.0, 301194.0, 47682.28476572854, 47702.0, 47815.0, 47870.04259914852, 47928.0, 49595.0, 49975.25037124446, 50025.0, 50112.0, 50205.74897125491, 14157.0, 14184.0, 14295.857041429661, 14356.928215359065, 14385.928070359581, 11543.307401555934, 11705.648830534927, 11729.530818767154, 11731.530738770523, 11746.17776755636, 13511.459541618422, 13516.0, 13529.52646657382, 13541.322933853267, 13617.182969021891, 5168.069319256994, 5992.910106348409, 6049.516038716892, 6068.393160683984, 6160.244330365824, 990.252359468611, 1001.9749506262402, 1020.1532727835878, 1045.430240518919, 1052.4579841381694, 19097.618047639247, 23711.0, 24116.87941560281, 24202.15292464755, 24221.757782422304, 1106.1758989552834, 1108.9279196852178, 1136.2841409911869, 1137.9374134422687, 1146.7247860513417, 11833.290002599872, 12363.505459781509, 12374.0, 12380.62858114258, 12397.876021239672, 324993.0, 329786.0, 330031.0, 330429.0, 330717.0, 42121.0, 53948.73025634847, 54003.0, 54125.45874541204, 54154.18768718473, 1484.6140003599141, 1505.533284681752, 1513.402206128573, 1514.4850750744579, 1533.8849586281074, 20537.075831587914, 23786.0, 24014.51970960536, 24138.75861241365, 24238.87880560586, 31375.529367059968, 37985.810070950574, 38056.238875222894, 38074.80962595169, 38102.618973809906, 277335.0, 278905.2109478879, 281608.0, 282158.0, 282493.0, 27400.451990960468, 32449.51325730163, 34250.0, 34287.485687715205, 34355.65644343525, 39786.403203951384, 43344.133117336845, 43760.78119609381, 45024.0, 45104.872378189495, 29173.85413072921, 29765.255868603457, 30325.545116823705, 30923.536146957365, 31435.214119646276, 27211.455770884866, 31256.843715782186, 31272.68727312789, 31567.21081972969, 31611.36777264396, 37047.25905481821, 37368.81315593405, 37853.81073094617, 37997.620023799405, 38077.0, 30325.848370758005, 31075.223119422197, 31414.685853141174, 32094.358112837144, 32141.875034373683, 20116.29592964239, 21977.120915163214, 22442.551148977254, 23769.405764856016, 24248.51502969966], "value": 17403.67004409727, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.3562021347766, 432.5523083608466, 438.91221755648866, 447.9529649386814, 453.4377372058647, 19166.808331916694, 21044.0, 21724.674129888044, 21724.91375431226, 21819.890900545473, 22025.898705064763, 22639.660405093935, 22826.315210543667, 23026.309210723663, 23118.65322020171, 5970.71629599636, 6211.968940155293, 6250.999840025597, 6254.593451425665, 6262.655553944532, 5968.79109231177, 6082.756689732428, 6139.140520327158, 6146.446819786218, 6185.505159587245, 5206.0, 6061.848453788646, 6114.3579924108, 6122.35715249899, 6127.662978536178, 5803.274590676166, 6035.396460353952, 6070.02879539276, 6089.817305480843, 6116.816495505097, 5427.185922111715, 5435.891282174374, 5444.0, 5444.564434845203, 5448.210009548633, 22918.77081229183, 27305.453890922072, 27679.723202767917, 27860.606969651435, 27876.0, 2084.1975839301826, 2127.2022991378235, 2155.3103007037753, 2157.0832396231694, 2161.8919054047233, 15628.531144065697, 18067.45797626073, 18408.63182736351, 18410.90794546032, 18494.722579161324, 2127.851050426471, 2146.527763891941, 2164.54544545644, 2169.8372622053407, 2174.662927246279, 143576.0, 171575.0, 176366.0, 177369.45261094705, 179860.0, 11237.887621123848, 11602.491676082056, 11735.647930562096, 12725.864094169432, 12738.363081845871, 16437.0, 16618.75071873923, 16656.16719164037, 16693.666126677643, 16722.0, 7295.744648937368, 8833.55832208387, 9059.728208153765, 9278.443293402283, 9305.302102342352, 8450.098727786199, 8506.637883437901, 8600.268977136888, 8761.518116503654, 8766.517841518778, 7613.743732284222, 7974.840503189904, 8115.959420202861, 8140.3487720982175, 8148.511089334659, 1908.3034692337474, 2212.834037447166, 2245.8091062259564, 2263.9773602263767, 2266.331432227497, 869.8738682890945, 900.8288425199191, 927.9675211367705, 971.7473456901115, 979.1628157925014, 16920.492385228463, 19248.711269330972, 20773.688394674093, 20909.89545052265, 21173.364799055744, 987.8146224530449, 1014.5840205515703, 1033.405791669793, 1043.9112675422523, 1049.2655141400896, 9402.811943761086, 9647.649329093932, 10552.050315471644, 10610.94694526538, 10660.520276587573, 516968.0, 572993.0, 577067.0, 577657.0, 589198.0, 128134.0, 136200.9569856453, 146262.0, 146323.0, 146930.0, 1179.7699448607439, 1210.8244304575787, 1260.3635164242182, 1265.4685032286277, 1269.4668239339499, 21386.14455421828, 21558.353249402542, 21763.564728705653, 21986.560268794216, 22053.11787528481, 58834.0, 60362.68005259753, 61032.0, 61382.0, 61454.07818882631, 79889.60055199687, 79927.0, 80466.59766701129, 81465.0, 81508.18491815006, 7325.7912444446065, 7883.526988380716, 8857.867131992896, 8935.329850261127, 9022.413543119676, 13923.373448194652, 14017.649558761113, 14312.569129967198, 14340.713185736435, 14530.491432799801, 11826.467808948446, 11836.644900652995, 11893.702657433634, 12049.096317776026, 12137.21108127969, 9265.675701350421, 9749.853752193581, 10023.849642255518, 11238.269512481662, 11486.540538378371, 11889.702757431134, 11992.40037998114, 12128.029757619546, 12230.877691222973, 12307.56923507673, 8738.519381434082, 8912.28701703875, 9028.909710902806, 9251.352405331561, 9364.812703746024, 11975.401229938294, 11996.940015299868, 12188.268703877795, 12193.573224937085, 12229.816552751894], "value": 10511.843021979616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1199.3283761093787, 1427.4647007372234, 1436.9856301436982, 1449.116039216078, 1454.9709005819884, 11373.374464404455, 11498.310101393901, 11808.291502509855, 11838.289702617838, 12252.877471225285, 11842.0, 12090.0, 12096.334701591431, 12109.697257568543, 12118.45466953987, 2520.3447103753065, 2544.7709706126443, 2556.4503631719176, 2557.629143774161, 2557.744225577446, 2153.8815365154987, 2489.5892177790734, 2501.5497210502103, 2520.1053625962795, 2525.3434107132184, 2458.9754102458924, 2496.2012156110054, 2502.737212592679, 2503.0488414402503, 2507.53610582042, 2429.149797570854, 2443.450223699671, 2444.181199298237, 2460.6063029915126, 2463.6797216361847, 2444.4133407982135, 2450.7304196538366, 2461.5323088613104, 2474.208253358926, 2545.643609894616, 11496.712582185428, 11553.942230288794, 11566.248193867372, 11566.47950842214, 11570.305781653127, 6359.300476947532, 6575.605463672195, 6629.038789375513, 6636.170478690164, 6639.970804525258, 4069.857554985591, 4252.425922500484, 4303.612674859278, 4316.6978311518205, 4366.061296821199, 1256.597888675624, 1259.0934527140444, 1259.603224984127, 1260.5525038611252, 1260.9873901260962, 15202.46791362308, 15620.375184992587, 15973.520794376187, 16052.03687778737, 16121.516354509384, 4656.301554766812, 5354.330708661418, 5394.3796463406425, 5424.538914192259, 5530.917036244459, 11542.480588373543, 11676.88323116758, 11678.708032299091, 11681.299122052535, 11682.591109311134, 5110.66780659256, 6380.106785050097, 6390.424861762371, 6449.580777249464, 6482.319356467598, 6030.884286407028, 6070.2108725865355, 6070.210872586624, 6113.816585502442, 6177.227846519185, 3616.8010759408485, 4228.851990180329, 4364.08354245612, 4377.518472967971, 4414.779261036935, 1148.3798748675788, 1216.8014505711867, 1260.6659235302652, 1266.468083404972, 1287.9549215777404, 733.7468573342188, 750.7072241825692, 757.3448966643814, 758.0600055930606, 770.595437395372, 6042.274927008699, 6053.697315134225, 6078.300995385587, 6196.443632625122, 6334.461570766536, 595.8619037638091, 611.6880391000533, 620.7082671144555, 620.7579044172776, 624.9093881387173, 4001.79946016194, 4075.409065685459, 4480.283154695233, 4534.206513860061, 4607.239805432133, 12869.2921889297, 13969.0, 14982.250887455584, 15077.698446031238, 15171.924140379228, 14414.855851441562, 14476.782848257284, 14931.328090235747, 14950.0, 14988.175650339117, 8510.48937063778, 8653.264472519906, 8664.523451210243, 8682.044975052866, 8682.088380720059, 6165.335359452833, 7280.453965952681, 7441.4418918582405, 7457.32884040439, 7459.776206713807, 12569.93715031419, 12728.554500592434, 12869.67825804325, 12879.034072444792, 12907.677308067374, 13003.08978371505, 13015.828575428257, 13133.474661013452, 13195.274259915797, 13315.334233288104, 9177.954110229406, 9447.952760236154, 9467.431954082776, 9476.526173691425, 9477.241820654193, 10544.472776361305, 10558.736031598964, 10602.89397106019, 10613.20400969915, 10684.57261709523, 9128.863067054132, 9218.44689318643, 9224.354295199271, 9342.065793420876, 9419.011003844771, 8637.740867773977, 8824.602892869709, 8827.823443531222, 8841.071687472722, 8900.866487002571, 8160.795980100307, 8250.133735957637, 8313.33493320545, 8315.916840831756, 8321.375896807642, 6051.0, 6069.3020302665755, 6184.969075154595, 6219.191505104308, 6283.654399007915, 5017.974910125426, 5792.0, 5864.0, 5868.3251426086545, 5951.672658003678], "value": 5263.511871174026, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.68840239406086, 390.95504017038047, 393.3745344901606, 393.70668851705483, 394.68030894975055, 4996.625253106018, 5983.371745966676, 6275.403836635513, 6301.590396624216, 6311.305756366807, 5438.700871452079, 5873.474406005416, 5995.160677505153, 6079.908801367983, 6083.634981901089, 4515.503294637604, 4635.351050852883, 4676.836310729117, 4676.953230467686, 4678.859634210962, 4671.532846715335, 4673.691366417409, 4678.976605116986, 4680.953190468086, 4694.530546945295, 3599.3341231872055, 4546.249693867851, 4588.541145885418, 4595.172868883599, 4606.355110284563, 3799.8480060797533, 4532.773361331937, 4571.154336447735, 4589.724616523019, 4623.006053698437, 3895.9091454392533, 4085.9387109193385, 4097.631213190797, 4106.733062350968, 4122.958770412288, 5477.808276710336, 5668.546516278729, 5881.588288819784, 5968.850778730523, 6067.969660151715, 5451.373092094381, 5476.698781567012, 5499.1476321170285, 5505.430492691057, 5532.474414930575, 5644.3508996465525, 6391.712372943182, 6531.595706922987, 6543.574667646588, 6554.951207806728, 3505.684488396031, 4287.957120428787, 4599.655025873039, 4611.654125940568, 4617.191991401525, 5458.836234912959, 6769.323067693241, 6835.384815366591, 6877.071595334616, 6946.756863509803, 4073.307537718595, 4262.23279809631, 4465.910681786346, 4710.740909250024, 4754.690945088558, 4615.699979501322, 4778.307145463958, 4974.651774375759, 4999.950000500021, 5022.397312322471, 4697.906041879143, 5623.6906970116215, 5633.549316054706, 5655.632383895034, 5663.773449062074, 6532.771353002717, 6681.498887583451, 6755.628440435823, 6764.661766911635, 6771.695273712695, 1516.9457227226972, 1580.1704105344556, 1704.8833014375605, 1727.7322015087682, 1744.5202569293547, 1150.7410832562607, 1201.8617858946334, 1203.6990752311922, 1223.9168336022683, 1224.8468941382328, 5938.7231745174995, 5996.010658241428, 6257.499400047986, 6261.154744109578, 6310.684465776784, 6084.239470066242, 6419.711112999927, 6772.153480814898, 7003.404710599554, 7016.614086225204, 5028.371453568304, 5662.518685911744, 5713.143028545752, 5860.912086318709, 6193.327801493571, 3140.3827029080044, 3189.3621275744945, 3317.0, 3631.7457777955287, 3646.9817650911577, 5787.421257874179, 7059.2234854166, 7156.821079473055, 7165.964170179116, 7171.820704482326, 7119.679614417364, 7176.92823071773, 7180.676869540883, 7197.532160409557, 7300.160481544584, 6174.753009879644, 6273.62358258506, 6304.779332723333, 7130.821729456805, 7200.387967022862, 5825.970870145622, 6735.955926831349, 6745.662716864039, 7016.26329235423, 7126.893096603659, 5761.5390768739235, 5934.584579079423, 6689.665516724047, 6838.418734407631, 6920.0, 7082.114735658043, 7162.677679504539, 7173.354398103987, 7211.459140564374, 7215.639218039182, 4259.510156332061, 4317.633001194934, 4363.258246098235, 4390.7705842364185, 4460.397846290709, 6212.006079027334, 6449.645269510222, 6945.930540694529, 6960.303969602999, 6999.755008574675, 5606.971965140311, 5850.040236520813, 6465.967670161619, 6722.647073524227, 6735.393814556714, 6052.788152414644, 6643.9335606645245, 6666.466682665278, 6715.697793599397, 6747.561408508432, 5015.0, 6239.656818875005, 6613.775117656997, 6625.4037136657935, 6640.169978752656, 6258.5918168411745, 6575.112359831553, 6621.5364924454825, 6624.0, 6671.733130674718, 5729.598928074995, 6507.479401647952, 6561.704723287559, 6567.376099270562, 6567.483314016249], "value": 4863.28754008994, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [631.2362041929265, 687.4672129099948, 746.5446077892489, 752.9736459223925, 773.6982576795051, 14773.926130369333, 15380.692386152297, 16548.503544893658, 17495.987961384446, 17570.38503652371, 16378.590535236624, 18051.368202112928, 18255.817441825577, 18530.44408667742, 18686.813131868676, 885.8085874498789, 948.7390967483929, 991.0931497679614, 997.3915911294141, 1007.098646711193, 1012.7771890184148, 1015.0712098429965, 1016.2530540053056, 1017.1760873692295, 1017.8422344536609, 904.3082042237661, 939.8355287824637, 1040.9375437473739, 1042.7914417116608, 1044.3316277582353, 1015.751140970467, 1033.5090831854884, 1036.000259749338, 1036.0623635609747, 1042.2495803021811, 754.4794092076504, 756.6103456719794, 757.5757575757593, 762.4891322813672, 765.9927195737587, 17408.651826963516, 17424.128793560398, 17487.912560437242, 17659.11704414774, 17750.46748597544, 17495.12524373789, 17552.736708949375, 17604.031778252192, 18027.909860450745, 18122.90938545299, 14786.03890747098, 14920.477783277642, 15079.64283214505, 15095.396184152622, 15133.167675777828, 3450.5341778859784, 3476.4090104682264, 3479.982600087009, 3512.8770493032875, 3526.34762568923, 124632.0, 159561.20219398828, 161822.0, 165898.0, 166345.0, 13651.317434128254, 13680.726385472235, 13714.862851371558, 13840.723185536233, 13971.23158226287, 21009.579808403745, 25914.0, 26142.215733528024, 27006.919723210853, 27358.589621155697, 6572.572782769104, 6809.591424514545, 7437.665305061285, 7735.187805280477, 7756.340711039512, 3768.5477742671055, 3801.6388443097862, 3842.212346468967, 3849.8652547161273, 3850.9037274068373, 4275.657947364203, 4300.204462174508, 4304.375865499485, 4375.628071613948, 4402.679196241115, 951.3055469507299, 959.6065613098735, 959.9184069354044, 966.2849491376331, 966.8888077870994, 587.9559033072451, 590.5334785519477, 604.6916072802902, 617.5646169450526, 631.9698890807954, 10617.84073238902, 11533.617225454274, 11633.0111940486, 12493.375331233401, 12783.360831958365, 561.4273441090065, 596.4691424632081, 601.1793901324747, 602.235161345098, 618.8421952402199, 5404.567634589223, 5614.747336369873, 5751.482366587027, 5760.3951585083805, 5842.328132264759, 143530.56469435382, 147829.0, 149227.28929589686, 149909.0, 150121.0, 111446.32830507548, 124072.0, 130487.34756326348, 132541.67458325293, 133597.0, 76514.6174269125, 102305.30404915517, 107771.92228077618, 107846.99999999843, 108105.0, 12028.518859245532, 12725.236485810881, 12813.590505044624, 12849.807252891027, 12864.549740759028, 41390.34438622421, 43287.56712432835, 43483.347749784414, 44233.89415264644, 45111.0, 57342.0, 82263.0, 85026.0, 85953.27257000926, 88011.0, 15827.366905323657, 16499.0, 17696.469105926844, 17921.28314867391, 17970.91014544919, 16513.752293715363, 18399.632007360047, 19346.613067738846, 19369.4189174325, 19413.0, 17565.0, 17819.0, 18213.089345532935, 18628.720569191202, 18760.437186884414, 17189.484315470556, 18038.36865709694, 18089.909550452165, 18654.53363665919, 18829.811701882805, 17842.107894604957, 17880.55298617545, 18850.0, 18871.339503117324, 18980.0, 11558.42207889619, 11889.167758256839, 12362.938185309016, 12430.440630171845, 12445.37773111359, 12575.937120314706, 13599.0, 14183.716325673222, 14264.0, 14312.499062532761], "value": 8822.40228475585, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1543.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 117.0, 114.0, 113.0, 112.0, 114.0, 114.0, 114.0, 115.0, 114.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 147.0, 144.0, 150.0, 110.0, 130.0, 143.0, 116.0, 138.0, 114.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [203.0, 208.0, 206.0, 218.0, 249.0, 276.0, 222.0, 200.0, 258.0, 238.0], "value": 227.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 58.0, 61.0, 85.0, 59.0, 59.0, 63.0, 58.0, 84.0, 80.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 69.0, 85.0, 74.0, 75.0, 94.0, 95.0, 70.0, 76.0, 91.0], "value": 81.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 91.0, 97.0, 87.0, 91.0, 82.0, 138.0, 137.0], "value": 100.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 99.0, 100.0, 98.0, 97.0, 98.0, 98.0, 98.0, 98.0], "value": 98.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 88.0, 90.0, 121.0, 148.0, 91.0, 91.0, 90.0, 94.0], "value": 99.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 52.0, 54.0, 53.0, 55.0, 53.0, 50.0, 51.0, 53.0], "value": 52.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 65.0, 65.0, 67.0, 64.0, 59.0, 62.0, 58.0, 55.0, 58.0], "value": 61.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 86.0, 90.0, 87.0, 89.0, 90.0, 112.0, 131.0, 109.0], "value": 97.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 148.0, 155.0, 138.0, 146.0, 149.0, 152.0, 148.0, 155.0, 142.0], "value": 147.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 181.0, 188.0, 169.0, 237.0, 237.0, 191.0, 190.0, 185.0, 185.0], "value": 195.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 69.0, 66.0, 71.0, 65.0, 69.0, 65.0, 67.0, 68.0], "value": 67.5, "unit": "score"}]}]} 17:38:54 INFO - Return code: 0 17:38:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:38:54 INFO - # TBPL SUCCESS # 17:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:38:54 INFO - 2017-05-28 17:38:54.602 screenresolution[1675:11679] starting screenresolution argv=screenresolution get 17:38:54 INFO - 2017-05-28 17:38:54.605 screenresolution[1675:11679] Display 0: 1600x1200x32@60 17:38:54 INFO - 2017-05-28 17:38:54.611 screenresolution[1676:11682] starting screenresolution argv=screenresolution list 17:38:54 INFO - Available Modes on Display 0 17:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:38:54 INFO - Intel Iris: 17:38:54 INFO - Chipset Model: Intel Iris 17:38:54 INFO - Type: GPU 17:38:54 INFO - Bus: Built-In 17:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 17:38:54 INFO - Vendor: Intel (0x8086) 17:38:54 INFO - Device ID: 0x0a2e 17:38:54 INFO - Revision ID: 0x0009 17:38:54 INFO - Displays: 17:38:54 INFO - Display: 17:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:38:54 INFO - Display Serial Number: 1600x1200 60 17:38:54 INFO - Main Display: Yes 17:38:54 INFO - Mirror: Off 17:38:54 INFO - Online: Yes 17:38:54 INFO - Rotation: Supported 17:38:54 INFO - Return code: 0 17:38:54 INFO - Running post-action listener: _resource_record_post_action 17:38:54 INFO - [mozharness: 2017-05-29 00:38:54.848555Z] Finished run-tests step (success) 17:38:54 INFO - Running post-run listener: _resource_record_post_run 17:38:54 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 33006592; Write bytes: 572135936; Read time: 328; Write time: 1787 17:38:54 INFO - TinderboxPrint: CPU usage
53.2% 17:38:54 INFO - TinderboxPrint: I/O read bytes / time
33,006,592 / 328 17:38:54 INFO - TinderboxPrint: I/O write bytes / time
572,135,936 / 1,787 17:38:54 INFO - TinderboxPrint: CPU idle
2,353.8 (46.8%) 17:38:54 INFO - TinderboxPrint: CPU system
1,012.7 (20.1%) 17:38:54 INFO - TinderboxPrint: CPU user
1,663.1 (33.1%) 17:38:54 INFO - TinderboxPrint: Swap in / out
275,091,456 / 0 17:38:54 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 224307712; Write bytes: 217698304; Read time: 16399; Write time: 379 17:38:54 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 17:38:54 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 30651392; Write bytes: 323435008; Read time: 297; Write time: 1331 17:38:55 INFO - Running post-run listener: _upload_blobber_files 17:38:55 INFO - Blob upload gear active. 17:38:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:38:55 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:38:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:38:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:38:55 INFO - (blobuploader) - INFO - Open directory for files ... 17:38:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:38:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:38:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:56 INFO - (blobuploader) - INFO - Done attempting. 17:38:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:38:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:38:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:57 INFO - (blobuploader) - INFO - Done attempting. 17:38:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 17:38:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:38:58 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 17:38:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:38:58 INFO - (blobuploader) - INFO - Done attempting. 17:38:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:38:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:38:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:39:00 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:39:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:39:00 INFO - (blobuploader) - INFO - Done attempting. 17:39:00 INFO - (blobuploader) - INFO - Iteration through files over. 17:39:00 INFO - Return code: 0 17:39:00 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:39:00 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:39:00 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d090cefb638c55505847c596698d829d412df3b32d953ad0ab45dac2d1c04b0897f6868684c44ad54153db3a2bc715f1ccff5261aea7169e3d18ced89570ca6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c62d49fdc772381d49e49394dd01e456941ab5a0094a469fef746a8ceb9552648f4edb291f999b9e237d5e07e589582280bbf6e2e1a52dd75099fa4076ff833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bd50b3cfcef6fc4280c425904c4ed999f62335ba68854b0efcb09b35b1c355a69a7b07f4eeadce5dbcbb4d193e7730ee123a9d00dce263c4ebce0e321ec3c0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9ce6df4d7984449843005d229afad03b81b37f8e70e5f98170d4e02425238b5d2dfa18a97681cd0711201ef2e38f26bab86166ba68d3ebace8f99569f64369"} 17:39:00 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:39:00 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:39:00 INFO - Contents: 17:39:00 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d090cefb638c55505847c596698d829d412df3b32d953ad0ab45dac2d1c04b0897f6868684c44ad54153db3a2bc715f1ccff5261aea7169e3d18ced89570ca6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c62d49fdc772381d49e49394dd01e456941ab5a0094a469fef746a8ceb9552648f4edb291f999b9e237d5e07e589582280bbf6e2e1a52dd75099fa4076ff833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bd50b3cfcef6fc4280c425904c4ed999f62335ba68854b0efcb09b35b1c355a69a7b07f4eeadce5dbcbb4d193e7730ee123a9d00dce263c4ebce0e321ec3c0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9ce6df4d7984449843005d229afad03b81b37f8e70e5f98170d4e02425238b5d2dfa18a97681cd0711201ef2e38f26bab86166ba68d3ebace8f99569f64369"} 17:39:00 INFO - Running post-run listener: copy_logs_to_upload_dir 17:39:00 INFO - Copying logs to upload dir... 17:39:00 INFO - mkdir: /builds/slave/test/build/upload/logs 17:39:00 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.849618 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-05-28 17:39:00.590039) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-28 17:39:00.594076) ========= 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.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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/d090cefb638c55505847c596698d829d412df3b32d953ad0ab45dac2d1c04b0897f6868684c44ad54153db3a2bc715f1ccff5261aea7169e3d18ced89570ca6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c62d49fdc772381d49e49394dd01e456941ab5a0094a469fef746a8ceb9552648f4edb291f999b9e237d5e07e589582280bbf6e2e1a52dd75099fa4076ff833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bd50b3cfcef6fc4280c425904c4ed999f62335ba68854b0efcb09b35b1c355a69a7b07f4eeadce5dbcbb4d193e7730ee123a9d00dce263c4ebce0e321ec3c0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9ce6df4d7984449843005d229afad03b81b37f8e70e5f98170d4e02425238b5d2dfa18a97681cd0711201ef2e38f26bab86166ba68d3ebace8f99569f64369"} build_url:https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011187 build_url: 'https://queue.taskcluster.net/v1/task/RRCg1XvrRciWzKC6uCeKig/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/d090cefb638c55505847c596698d829d412df3b32d953ad0ab45dac2d1c04b0897f6868684c44ad54153db3a2bc715f1ccff5261aea7169e3d18ced89570ca6d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6c62d49fdc772381d49e49394dd01e456941ab5a0094a469fef746a8ceb9552648f4edb291f999b9e237d5e07e589582280bbf6e2e1a52dd75099fa4076ff833", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5bd50b3cfcef6fc4280c425904c4ed999f62335ba68854b0efcb09b35b1c355a69a7b07f4eeadce5dbcbb4d193e7730ee123a9d00dce263c4ebce0e321ec3c0a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4b9ce6df4d7984449843005d229afad03b81b37f8e70e5f98170d4e02425238b5d2dfa18a97681cd0711201ef2e38f26bab86166ba68d3ebace8f99569f64369"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-28 17:39:00.627964) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:39:00.628292) ========= 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.0XQab04O8o/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.NEl9ukVZhm/Listeners TMPDIR=/var/folders/cr/txgjfp9j4mnbstqzvq00gdx400000w/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.005240 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-28 17:39:00.676604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-28 17:39:00.676928) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-28 17:39:00.677271) ========= ========= Total master_lag: 0.18 =========