builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0256 starttime: 1496713518.97 results: success (0) revision: 3cfe81678881ada0245215c8863bc9722925bb44 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:18.971772) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:18.972143) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:18.972433) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:18.996304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:18.996607) ========= 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.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-05 18:45:19-- 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% 18.9M=0.001s 2017-06-05 18:45:19 (18.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.164183 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:19.183743) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:19.184053) ========= 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.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.037128 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:19.243079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 18:45:19.243538) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3cfe81678881ada0245215c8863bc9722925bb44 --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 3cfe81678881ada0245215c8863bc9722925bb44 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-05 18:45:19,349 truncating revision to first 12 chars 2017-06-05 18:45:19,349 Setting DEBUG logging. 2017-06-05 18:45:19,349 attempt 1/10 2017-06-05 18:45:19,350 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3cfe81678881?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-05 18:45:20,037 unpacking tar archive at: mozilla-central-3cfe81678881/testing/mozharness/ program finished with exit code 0 elapsedTime=0.971264 ========= master_lag: 1.29 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-05 18:45:21.504368) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:21.504679) ========= script_repo_revision: 3cfe81678881ada0245215c8863bc9722925bb44 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:21.505305) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:21.505583) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-05 18:45:21.563965) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 18:45:21.564244) ========= /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.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:45:21 INFO - MultiFileLogger online at 20170605 18:45:21 in /builds/slave/test 18:45:21 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 18:45:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:45:21 INFO - {'append_to_log': False, 18:45:21 INFO - 'base_work_dir': '/builds/slave/test', 18:45:21 INFO - 'blob_upload_branch': 'Firefox', 18:45:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:45:21 INFO - 'branch': 'Firefox', 18:45:21 INFO - 'buildbot_json_path': 'buildprops.json', 18:45:21 INFO - 'config_files': ('talos/mac_config.py',), 18:45:21 INFO - 'default_actions': ('clobber', 18:45:21 INFO - 'read-buildbot-config', 18:45:21 INFO - 'download-and-extract', 18:45:21 INFO - 'populate-webroot', 18:45:21 INFO - 'create-virtualenv', 18:45:21 INFO - 'install', 18:45:21 INFO - 'setup-mitmproxy', 18:45:21 INFO - 'run-tests'), 18:45:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:45:21 INFO - 'download_minidump_stackwalk': True, 18:45:21 INFO - 'download_symbols': 'ondemand', 18:45:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:45:21 INFO - 'tooltool.py': '/tools/tooltool.py', 18:45:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:45:21 INFO - '/tools/misc-python/virtualenv.py')}, 18:45:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:45:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:45:21 INFO - 'gecko_profile': False, 18:45:21 INFO - 'gecko_profile_interval': 0, 18:45:21 INFO - 'installer_path': 'installer.exe', 18:45:21 INFO - 'log_level': 'info', 18:45:21 INFO - 'log_name': 'talos', 18:45:21 INFO - 'log_to_console': True, 18:45:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:45:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:45:21 INFO - 'opt_config_files': (), 18:45:21 INFO - 'pip_index': False, 18:45:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:45:21 INFO - 'cmd': ('bash', 18:45:21 INFO - '-c', 18:45:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:45:21 INFO - 'enabled': True, 18:45:21 INFO - 'halt_on_failure': False, 18:45:21 INFO - 'name': 'check_screen_resolution'},), 18:45:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:45:21 INFO - 'cmd': ('bash', 18:45:21 INFO - '-c', 18:45:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:45:21 INFO - 'enabled': True, 18:45:21 INFO - 'halt_on_failure': False, 18:45:21 INFO - 'name': 'check_screen_resolution'},), 18:45:21 INFO - 'run_cmd_checks_enabled': True, 18:45:21 INFO - 'suite': 'dromaeojs-e10s', 18:45:21 INFO - 'system_bits': '32', 18:45:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:45:21 INFO - 'title': 't-yosemite-r7-0256', 18:45:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:45:21 INFO - 'use_talos_json': True, 18:45:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:45:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:45:21 INFO - 'work_dir': 'build'} 18:45:21 INFO - [mozharness: 2017-06-06 01:45:21.785250Z] Running clobber step. 18:45:21 INFO - Running pre-action listener: _resource_record_pre_action 18:45:21 INFO - Running main action method: clobber 18:45:21 INFO - rmtree: /builds/slave/test/build 18:45:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:45:24 INFO - Running post-action listener: _resource_record_post_action 18:45:24 INFO - [mozharness: 2017-06-06 01:45:24.219937Z] Finished clobber step (success) 18:45:24 INFO - [mozharness: 2017-06-06 01:45:24.220015Z] Running read-buildbot-config step. 18:45:24 INFO - Running pre-action listener: _resource_record_pre_action 18:45:24 INFO - Running main action method: read_buildbot_config 18:45:24 INFO - Using buildbot properties: 18:45:24 INFO - { 18:45:24 INFO - "project": "", 18:45:24 INFO - "product": "firefox", 18:45:24 INFO - "who": "ryanvm@gmail.com", 18:45:24 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 18:45:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 18:45:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 18:45:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 18:45:24 INFO - "stage_platform": "macosx64", 18:45:24 INFO - "basedir": "/builds/slave/test", 18:45:24 INFO - "buildnumber": 17, 18:45:24 INFO - "platform": "yosemite_r7", 18:45:24 INFO - "parent_task_id": "Ax3PT8M7SJKb79DwvPiVsQ", 18:45:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:45:24 INFO - "slavebuilddir": "test", 18:45:24 INFO - "taskId": "XuF169pnRpy9vg3hvJ25xg", 18:45:24 INFO - "branch": "mozilla-central", 18:45:24 INFO - "script_repo_revision": "production", 18:45:24 INFO - "revision": "3cfe81678881ada0245215c8863bc9722925bb44", 18:45:24 INFO - "slavename": "t-yosemite-r7-0256", 18:45:24 INFO - "repo_path": "mozilla-central" 18:45:24 INFO - } 18:45:24 INFO - Finding installer, test and symbols from parent task. 18:45:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XuF169pnRpy9vg3hvJ25xg'}, attempt #1 18:45:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XuF169pnRpy9vg3hvJ25xg'}, attempt #1 18:45:25 INFO - Task dependencies: SE2FUByASqWKM9t-A4bdZg Ax3PT8M7SJKb79DwvPiVsQ 18:45:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ'}, attempt #1 18:45:26 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:45:26 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/test_packages.json 18:45:26 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/target.crashreporter-symbols.zip 18:45:26 INFO - Running post-action listener: _resource_record_post_action 18:45:26 INFO - [mozharness: 2017-06-06 01:45:26.082755Z] Finished read-buildbot-config step (success) 18:45:26 INFO - [mozharness: 2017-06-06 01:45:26.082854Z] Running download-and-extract step. 18:45:26 INFO - Running pre-action listener: _resource_record_pre_action 18:45:26 INFO - Running main action method: download_and_extract 18:45:26 INFO - mkdir: /builds/slave/test/build/tests 18:45:26 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')]} 18:45:26 INFO - trying https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:45:26 INFO - Downloading https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/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 18:45:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/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 18:45:26 INFO - Downloaded 1328 bytes. 18:45:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:45:26 INFO - Using the following test package requirements: 18:45:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:45:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:45:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:45:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:45:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'jsshell-mac.zip'], 18:45:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:45:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:45:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:45:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:45:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 18:45:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:45:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:45:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:45:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:45:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:45:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:45:28 INFO - Content-Length response header: 57631950 18:45:28 INFO - Bytes received: 57631950 18:45:46 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 18:45:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:45:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:45:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:45:48 INFO - Content-Length response header: 57631950 18:45:48 INFO - Bytes received: 57631950 18:46:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:46:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:46:05 INFO - Fetch https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:46:07 INFO - Content-Length response header: 14447425 18:46:07 INFO - Bytes received: 14447425 18:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:07 INFO - trying https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:46:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/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 18:46:09 INFO - Downloaded 67984444 bytes. 18:46:09 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:09 INFO - mkdir: /builds/slave/test/properties 18:46:09 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:46:09 INFO - Writing to file /builds/slave/test/properties/build_url 18:46:09 INFO - Contents: 18:46:09 INFO - build_url:https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:46:09 INFO - Running post-action listener: _resource_record_post_action 18:46:09 INFO - Running post-action listener: set_extra_try_arguments 18:46:09 INFO - [mozharness: 2017-06-06 01:46:09.748384Z] Finished download-and-extract step (success) 18:46:09 INFO - [mozharness: 2017-06-06 01:46:09.748522Z] Running populate-webroot step. 18:46:09 INFO - Running pre-action listener: _resource_record_pre_action 18:46:09 INFO - Running main action method: populate_webroot 18:46:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:46:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:46:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:46:09 INFO - '--tppagecycles', 18:46:09 INFO - '7'], 18:46:09 INFO - 'tests': ['tsvgm']}}, 18:46:09 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:46:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:46:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:46:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:46:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['damp', 'tps']}, 18:46:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'tests': ['damp', 'tps']}, 18:46:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['dromaeo_dom']}, 18:46:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:46:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:46:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:46:09 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 18:46:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'other-e10s': {'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['a11yr', 18:46:09 INFO - 'ts_paint', 18:46:09 INFO - 'tpaint', 18:46:09 INFO - 'sessionrestore', 18:46:09 INFO - 'sessionrestore_no_auto_restore', 18:46:09 INFO - 'tabpaint']}, 18:46:09 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:46:09 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:46:09 INFO - 'bloom_basic_ref']}, 18:46:09 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 18:46:09 INFO - 'talos_options': ['--mitmproxy', 18:46:09 INFO - 'mitmproxy-recording-1.mp', 18:46:09 INFO - '--firstNonBlankPaint'], 18:46:09 INFO - 'tests': ['Quantum_1']}, 18:46:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['tsvgx', 18:46:09 INFO - 'tsvgr_opacity', 18:46:09 INFO - 'tart', 18:46:09 INFO - 'tscrollx', 18:46:09 INFO - 'cart', 18:46:09 INFO - 'tsvg_static']}, 18:46:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:46:09 INFO - 'tsvgr_opacity', 18:46:09 INFO - 'tart', 18:46:09 INFO - 'tscrollx', 18:46:09 INFO - 'cart', 18:46:09 INFO - 'tsvg_static']}, 18:46:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'talos_options': ['--disable-e10s'], 18:46:09 INFO - 'tests': ['tp5o']}, 18:46:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:46:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'talos_options': ['--disable-e10s', 18:46:09 INFO - '--xperf_path', 18:46:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:09 INFO - 'tests': ['tp5n']}, 18:46:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:46:09 INFO - 'talos_options': ['--xperf_path', 18:46:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:46:09 INFO - 'tests': ['tp5n']}}, 18:46:09 INFO - 'talos.zip': {'path': '', 18:46:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:46:09 INFO - Running post-action listener: _resource_record_post_action 18:46:09 INFO - [mozharness: 2017-06-06 01:46:09.770413Z] Finished populate-webroot step (success) 18:46:09 INFO - [mozharness: 2017-06-06 01:46:09.770550Z] Running create-virtualenv step. 18:46:09 INFO - Running pre-action listener: _resource_record_pre_action 18:46:09 INFO - Running main action method: create_virtualenv 18:46:09 INFO - Creating virtualenv /builds/slave/test/build/venv 18:46:09 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 18:46:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:46:09 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 18:46:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:46:09 INFO - Using real prefix '/tools/python27' 18:46:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:46:10 INFO - Installing distribute.............................................................................................................................................................................................done. 18:46:13 INFO - Installing pip.................done. 18:46:13 INFO - Return code: 0 18:46:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:46:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:46:13 INFO - Reading from file tmpfile_stdout 18:46:13 INFO - Output received: 18:46:13 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) 18:46:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:46:13 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')]} 18:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:13 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')]} 18:46:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:13 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:13 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 18:46:13 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 18:46:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:13 INFO - 'HOME': '/Users/cltbld', 18:46:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:13 INFO - 'LOGNAME': 'cltbld', 18:46:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:13 INFO - 'MOZ_NO_REMOTE': '1', 18:46:13 INFO - 'NO_EM_RESTART': '1', 18:46:13 INFO - 'PAGER': '/bin/cat', 18:46:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:13 INFO - 'PWD': '/builds/slave/test', 18:46:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:13 INFO - 'SHELL': '/bin/bash', 18:46:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:13 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:13 INFO - 'USER': 'cltbld', 18:46:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:13 INFO - 'XPC_FLAGS': '0x0', 18:46:13 INFO - 'XPC_SERVICE_NAME': '0', 18:46:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:13 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 18:46:13 INFO - Cleaning up... 18:46:14 INFO - Return code: 0 18:46:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:14 INFO - 'HOME': '/Users/cltbld', 18:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:14 INFO - 'LOGNAME': 'cltbld', 18:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:14 INFO - 'MOZ_NO_REMOTE': '1', 18:46:14 INFO - 'NO_EM_RESTART': '1', 18:46:14 INFO - 'PAGER': '/bin/cat', 18:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:14 INFO - 'PWD': '/builds/slave/test', 18:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:14 INFO - 'SHELL': '/bin/bash', 18:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:14 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:14 INFO - 'USER': 'cltbld', 18:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:14 INFO - 'XPC_FLAGS': '0x0', 18:46:14 INFO - 'XPC_SERVICE_NAME': '0', 18:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:14 INFO - Downloading/unpacking psutil>=3.1.1 18:46:14 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 18:46:14 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 18:46:14 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 18:46:14 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 18:46:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:46:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:16 INFO - Installing collected packages: psutil 18:46:16 INFO - Running setup.py install for psutil 18:46:16 INFO - building 'psutil._psutil_osx' extension 18:46:16 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 18:46:17 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 18:46:17 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 18:46:17 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 18:46:17 INFO - building 'psutil._psutil_posix' extension 18:46:17 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 18:46:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:46:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:46:17 INFO - ^ 18:46:17 INFO - 1 warning generated. 18:46:17 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 18:46:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:46:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:46:17 INFO - Successfully installed psutil 18:46:17 INFO - Cleaning up... 18:46:17 INFO - Return code: 0 18:46:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:17 INFO - 'HOME': '/Users/cltbld', 18:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:17 INFO - 'LOGNAME': 'cltbld', 18:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:17 INFO - 'MOZ_NO_REMOTE': '1', 18:46:17 INFO - 'NO_EM_RESTART': '1', 18:46:17 INFO - 'PAGER': '/bin/cat', 18:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:17 INFO - 'PWD': '/builds/slave/test', 18:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:17 INFO - 'SHELL': '/bin/bash', 18:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:17 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:17 INFO - 'USER': 'cltbld', 18:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:17 INFO - 'XPC_FLAGS': '0x0', 18:46:17 INFO - 'XPC_SERVICE_NAME': '0', 18:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:19 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:46:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:46:19 INFO - Installing collected packages: mozsystemmonitor 18:46:19 INFO - Running setup.py install for mozsystemmonitor 18:46:19 INFO - Successfully installed mozsystemmonitor 18:46:19 INFO - Cleaning up... 18:46:19 INFO - Return code: 0 18:46:19 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:19 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 18:46:19 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 18:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:19 INFO - 'HOME': '/Users/cltbld', 18:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:19 INFO - 'LOGNAME': 'cltbld', 18:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:19 INFO - 'MOZ_NO_REMOTE': '1', 18:46:19 INFO - 'NO_EM_RESTART': '1', 18:46:19 INFO - 'PAGER': '/bin/cat', 18:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:19 INFO - 'PWD': '/builds/slave/test', 18:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:19 INFO - 'SHELL': '/bin/bash', 18:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:19 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:19 INFO - 'USER': 'cltbld', 18:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:19 INFO - 'XPC_FLAGS': '0x0', 18:46:19 INFO - 'XPC_SERVICE_NAME': '0', 18:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:20 INFO - Downloading/unpacking jsonschema==2.5.1 18:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:46:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:21 INFO - Downloading functools32-3.2.3-2.tar.gz 18:46:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:46:21 INFO - warning: no files found matching '*.txt' 18:46:21 INFO - no previously-included directories found matching 'build' 18:46:21 INFO - no previously-included directories found matching 'dist' 18:46:21 INFO - no previously-included directories found matching '.git*' 18:46:21 INFO - Installing collected packages: jsonschema, functools32 18:46:21 INFO - Running setup.py install for functools32 18:46:21 INFO - warning: no files found matching '*.txt' 18:46:21 INFO - no previously-included directories found matching 'build' 18:46:21 INFO - no previously-included directories found matching 'dist' 18:46:21 INFO - no previously-included directories found matching '.git*' 18:46:22 INFO - Successfully installed jsonschema functools32 18:46:22 INFO - Cleaning up... 18:46:22 INFO - Return code: 0 18:46:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:46:22 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')]} 18:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:22 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')]} 18:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:22 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:22 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 18:46:22 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 18:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:22 INFO - 'HOME': '/Users/cltbld', 18:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:22 INFO - 'LOGNAME': 'cltbld', 18:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:22 INFO - 'MOZ_NO_REMOTE': '1', 18:46:22 INFO - 'NO_EM_RESTART': '1', 18:46:22 INFO - 'PAGER': '/bin/cat', 18:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:22 INFO - 'PWD': '/builds/slave/test', 18:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:22 INFO - 'SHELL': '/bin/bash', 18:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:22 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:22 INFO - 'USER': 'cltbld', 18:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:22 INFO - 'XPC_FLAGS': '0x0', 18:46:22 INFO - 'XPC_SERVICE_NAME': '0', 18:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:46:22 INFO - Cleaning up... 18:46:22 INFO - Return code: 0 18:46:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:46:22 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')]} 18:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:22 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')]} 18:46:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:22 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:22 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 18:46:22 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 18:46:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:22 INFO - 'HOME': '/Users/cltbld', 18:46:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:22 INFO - 'LOGNAME': 'cltbld', 18:46:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:22 INFO - 'MOZ_NO_REMOTE': '1', 18:46:22 INFO - 'NO_EM_RESTART': '1', 18:46:22 INFO - 'PAGER': '/bin/cat', 18:46:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:22 INFO - 'PWD': '/builds/slave/test', 18:46:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:22 INFO - 'SHELL': '/bin/bash', 18:46:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:22 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:22 INFO - 'USER': 'cltbld', 18:46:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:22 INFO - 'XPC_FLAGS': '0x0', 18:46:22 INFO - 'XPC_SERVICE_NAME': '0', 18:46:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:22 INFO - Downloading/unpacking blobuploader==1.2.4 18:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:46:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:46:24 INFO - Downloading blobuploader-1.2.4.tar.gz 18:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:46:24 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:46:24 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 18:46:24 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 18:46:24 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 18:46:24 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 18:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:46:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:46:24 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 18:46:24 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 18:46:24 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 18:46:24 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 18:46:24 INFO - Downloading docopt-0.6.1.tar.gz 18:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:46:25 INFO - Installing collected packages: blobuploader, requests, docopt 18:46:25 INFO - Running setup.py install for blobuploader 18:46:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:46:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:46:25 INFO - Running setup.py install for requests 18:46:25 INFO - Running setup.py install for docopt 18:46:25 INFO - Successfully installed blobuploader requests docopt 18:46:25 INFO - Cleaning up... 18:46:25 INFO - Return code: 0 18:46:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:25 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:25 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 18:46:25 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 18:46:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:25 INFO - 'HOME': '/Users/cltbld', 18:46:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:25 INFO - 'LOGNAME': 'cltbld', 18:46:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:25 INFO - 'MOZ_NO_REMOTE': '1', 18:46:25 INFO - 'NO_EM_RESTART': '1', 18:46:25 INFO - 'PAGER': '/bin/cat', 18:46:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:25 INFO - 'PWD': '/builds/slave/test', 18:46:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:25 INFO - 'SHELL': '/bin/bash', 18:46:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:25 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:25 INFO - 'USER': 'cltbld', 18:46:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:25 INFO - 'XPC_FLAGS': '0x0', 18:46:25 INFO - 'XPC_SERVICE_NAME': '0', 18:46:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-joulNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-ffUCgz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-CObH8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Io0lw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-XVm1uh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-6M3kDd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-g_a4oD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-SFl4CY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:26 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-cxJKfl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-uHubES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-7cUQwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-jH6GA0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-4_5y1M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-11LhNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-48qc2L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-LPwiI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:27 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-rfFaQB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:46:27 INFO - Running setup.py install for manifestparser 18:46:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Running setup.py install for mozcrash 18:46:28 INFO - Running setup.py install for mozdebug 18:46:28 INFO - Running setup.py install for mozdevice 18:46:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Running setup.py install for mozfile 18:46:28 INFO - Running setup.py install for mozhttpd 18:46:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Running setup.py install for mozinfo 18:46:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Running setup.py install for mozInstall 18:46:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:46:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Running setup.py install for mozleak 18:46:29 INFO - Running setup.py install for mozlog 18:46:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Running setup.py install for moznetwork 18:46:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Running setup.py install for mozprocess 18:46:29 INFO - Running setup.py install for mozprofile 18:46:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Running setup.py install for mozrunner 18:46:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:46:29 INFO - Running setup.py install for mozscreenshot 18:46:30 INFO - Running setup.py install for moztest 18:46:30 INFO - Running setup.py install for mozversion 18:46:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:46:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:46:30 INFO - Cleaning up... 18:46:30 INFO - Return code: 0 18:46:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:30 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')]} 18:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:30 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')]} 18:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:30 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:30 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 18:46:30 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 18:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:30 INFO - 'HOME': '/Users/cltbld', 18:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:30 INFO - 'LOGNAME': 'cltbld', 18:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:30 INFO - 'MOZ_NO_REMOTE': '1', 18:46:30 INFO - 'NO_EM_RESTART': '1', 18:46:30 INFO - 'PAGER': '/bin/cat', 18:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:30 INFO - 'PWD': '/builds/slave/test', 18:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:30 INFO - 'SHELL': '/bin/bash', 18:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:30 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:30 INFO - 'USER': 'cltbld', 18:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:30 INFO - 'XPC_FLAGS': '0x0', 18:46:30 INFO - 'XPC_SERVICE_NAME': '0', 18:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:46:30 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-CcOq5d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:46:30 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)) 18:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:46:30 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-Qmryot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:46:30 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)) 18:46:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:46:30 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-9Ol5hi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-T3DaiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-75_pBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-R3C3xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-umXE1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:46:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-SdWaTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-gN82na-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-1jeOko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-IVJ8if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-0sKKwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:46:31 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)) 18:46:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:46:31 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-ITLy8g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:46:32 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)) 18:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:46:32 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-DHR5BK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:46:32 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)) 18:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:32 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-56KQHn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:46:32 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)) 18:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:46:32 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-qkAoWS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:46:32 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)) 18:46:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:46:32 INFO - Running setup.py (path:/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/pip-WtPPaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:46:32 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)) 18:46:32 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)) 18:46:32 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)) 18:46:32 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)) 18:46:32 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)) 18:46:32 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)) 18:46:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:46:32 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 18:46:32 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 18:46:32 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 18:46:32 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 18:46:33 INFO - Downloading blessings-1.6.tar.gz 18:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:46:34 INFO - Installing collected packages: blessings 18:46:34 INFO - Running setup.py install for blessings 18:46:34 INFO - Successfully installed blessings 18:46:34 INFO - Cleaning up... 18:46:34 INFO - Return code: 0 18:46:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:46:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:34 INFO - Reading from file tmpfile_stdout 18:46:34 INFO - Current package versions: 18:46:34 INFO - blessings == 1.6 18:46:34 INFO - blobuploader == 1.2.4 18:46:34 INFO - docopt == 0.6.1 18:46:34 INFO - functools32 == 3.2.3-2 18:46:34 INFO - jsonschema == 2.5.1 18:46:34 INFO - manifestparser == 1.1 18:46:34 INFO - mozInstall == 1.12 18:46:34 INFO - mozcrash == 1.0 18:46:34 INFO - mozdebug == 0.1 18:46:34 INFO - mozdevice == 0.51 18:46:34 INFO - mozfile == 1.2 18:46:34 INFO - mozhttpd == 0.7 18:46:34 INFO - mozinfo == 0.10 18:46:34 INFO - mozleak == 0.1 18:46:34 INFO - mozlog == 3.4 18:46:34 INFO - moznetwork == 0.27 18:46:34 INFO - mozprocess == 0.25 18:46:34 INFO - mozprofile == 0.28 18:46:34 INFO - mozrunner == 6.13 18:46:34 INFO - mozscreenshot == 0.1 18:46:34 INFO - mozsystemmonitor == 0.3 18:46:34 INFO - moztest == 0.8 18:46:34 INFO - mozversion == 1.4 18:46:34 INFO - psutil == 3.1.1 18:46:34 INFO - requests == 1.2.3 18:46:34 INFO - wsgiref == 0.1.2 18:46:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:46:34 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')]} 18:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:34 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')]} 18:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:46:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:34 INFO - 'HOME': '/Users/cltbld', 18:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:34 INFO - 'LOGNAME': 'cltbld', 18:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:34 INFO - 'MOZ_NO_REMOTE': '1', 18:46:34 INFO - 'NO_EM_RESTART': '1', 18:46:34 INFO - 'PAGER': '/bin/cat', 18:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:34 INFO - 'PWD': '/builds/slave/test', 18:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:34 INFO - 'SHELL': '/bin/bash', 18:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:34 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:34 INFO - 'USER': 'cltbld', 18:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:34 INFO - 'XPC_FLAGS': '0x0', 18:46:34 INFO - 'XPC_SERVICE_NAME': '0', 18:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 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)) 18:46:34 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 18:46: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 18:46: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 18:46: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 18:46: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 18:46:36 INFO - Downloading marionette_driver-1.4.0.tar.gz 18:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 18:46:36 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)) 18:46:36 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)) 18:46:36 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)) 18:46:36 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)) 18:46:36 INFO - Installing collected packages: marionette-driver 18:46:36 INFO - Running setup.py install for marionette-driver 18:46:36 INFO - Successfully installed marionette-driver 18:46:36 INFO - Cleaning up... 18:46:36 INFO - Return code: 0 18:46:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:46:36 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')]} 18:46:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:46:36 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')]} 18:46:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:46:36 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 0x1073eec10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073f2768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffccb55ee50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, '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.VUaqjxutO4/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.IkA7JXHwdD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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 18:46:36 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 18:46:36 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 18:46:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:36 INFO - 'HOME': '/Users/cltbld', 18:46:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:36 INFO - 'LOGNAME': 'cltbld', 18:46:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:36 INFO - 'MOZ_NO_REMOTE': '1', 18:46:36 INFO - 'NO_EM_RESTART': '1', 18:46:36 INFO - 'PAGER': '/bin/cat', 18:46:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:36 INFO - 'PWD': '/builds/slave/test', 18:46:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:36 INFO - 'SHELL': '/bin/bash', 18:46:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:36 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:36 INFO - 'USER': 'cltbld', 18:46:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:36 INFO - 'XPC_FLAGS': '0x0', 18:46:36 INFO - 'XPC_SERVICE_NAME': '0', 18:46:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:46:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:46:36 INFO - Cleaning up... 18:46:36 INFO - Return code: 0 18:46:36 INFO - Running post-action listener: _resource_record_post_action 18:46:36 INFO - Running post-action listener: _start_resource_monitoring 18:46:36 INFO - Starting resource monitoring. 18:46:36 INFO - [mozharness: 2017-06-06 01:46:36.799300Z] Finished create-virtualenv step (success) 18:46:36 INFO - [mozharness: 2017-06-06 01:46:36.800266Z] Running install step. 18:46:36 INFO - Running pre-action listener: _resource_record_pre_action 18:46:36 INFO - Running main action method: install 18:46:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:46:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:46:37 INFO - Reading from file tmpfile_stdout 18:46:37 INFO - Detecting whether we're running mozinstall >=1.0... 18:46:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:46:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:46:37 INFO - Reading from file tmpfile_stdout 18:46:37 INFO - Output received: 18:46:37 INFO - Usage: mozinstall [options] installer 18:46:37 INFO - Options: 18:46:37 INFO - -h, --help show this help message and exit 18:46:37 INFO - -d DEST, --destination=DEST 18:46:37 INFO - Directory to install application into. [default: 18:46:37 INFO - "/builds/slave/test"] 18:46:37 INFO - --app=APP Application being installed. [default: firefox] 18:46:37 INFO - mkdir: /builds/slave/test/build/application 18:46:37 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'] 18:46:37 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 18:46:58 INFO - Reading from file tmpfile_stdout 18:46:58 INFO - Output received: 18:46:58 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:46:58 INFO - Running post-action listener: _resource_record_post_action 18:46:58 INFO - [mozharness: 2017-06-06 01:46:58.706945Z] Finished install step (success) 18:46:58 INFO - [mozharness: 2017-06-06 01:46:58.707078Z] Running setup-mitmproxy step. 18:46:58 INFO - Running pre-action listener: _resource_record_pre_action 18:46:58 INFO - Running main action method: setup_mitmproxy 18:46:58 INFO - Skipping: mitmproxy is not required 18:46:58 INFO - Running post-action listener: _resource_record_post_action 18:46:58 INFO - [mozharness: 2017-06-06 01:46:58.707717Z] Finished setup-mitmproxy step (success) 18:46:58 INFO - [mozharness: 2017-06-06 01:46:58.707840Z] Running run-tests step. 18:46:58 INFO - Running pre-action listener: _resource_record_pre_action 18:46:58 INFO - Running main action method: run_tests 18:46:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:46:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:46:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:46:58 INFO - 2017-06-05 18:46:58.722 screenresolution[1636:8061] starting screenresolution argv=screenresolution get 18:46:58 INFO - 2017-06-05 18:46:58.725 screenresolution[1636:8061] Display 0: 1600x1200x32@60 18:46:58 INFO - 2017-06-05 18:46:58.731 screenresolution[1637:8063] starting screenresolution argv=screenresolution list 18:46:58 INFO - Available Modes on Display 0 18:46:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:46:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:46:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:46:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:46:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:46:58 INFO - Intel Iris: 18:46:58 INFO - Chipset Model: Intel Iris 18:46:58 INFO - Type: GPU 18:46:58 INFO - Bus: Built-In 18:46:58 INFO - VRAM (Dynamic, Max): 1536 MB 18:46:58 INFO - Vendor: Intel (0x8086) 18:46:58 INFO - Device ID: 0x0a2e 18:46:58 INFO - Revision ID: 0x0009 18:46:58 INFO - Displays: 18:46:58 INFO - Display: 18:46:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:46:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:46:58 INFO - Display Serial Number: 1600x1200 60 18:46:58 INFO - Main Display: Yes 18:46:58 INFO - Mirror: Off 18:46:58 INFO - Online: Yes 18:46:58 INFO - Rotation: Supported 18:46:58 INFO - Return code: 0 18:46:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:46:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:46:58 INFO - Python 2.7.3 18:46:58 INFO - Return code: 0 18:46:58 INFO - grabbing minidump binary from tooltool 18:46:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:58 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 0x107377c60>, '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 0x10710ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107135be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:46:58 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 18:46:58 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 18:46:58 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 18:46:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:46:59 INFO - Return code: 0 18:46:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:46:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:46:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:46:59 INFO - ENV: RUST_BACKTRACE is now 1 18:46:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:46:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:46:59 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/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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 18:46:59 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/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0256 --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 18:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VUaqjxutO4/Render', 18:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:46:59 INFO - 'HOME': '/Users/cltbld', 18:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:46:59 INFO - 'LOGNAME': 'cltbld', 18:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:46:59 INFO - 'MOZ_NO_REMOTE': '1', 18:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:46:59 INFO - 'NO_EM_RESTART': '1', 18:46:59 INFO - 'PAGER': '/bin/cat', 18:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:46:59 INFO - 'PWD': '/builds/slave/test', 18:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:46:59 INFO - 'RUST_BACKTRACE': '1', 18:46:59 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 18:46:59 INFO - 'SHELL': '/bin/bash', 18:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.IkA7JXHwdD/Listeners', 18:46:59 INFO - 'TMPDIR': '/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/', 18:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:46:59 INFO - 'USER': 'cltbld', 18:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:46:59 INFO - 'XPC_FLAGS': '0x0', 18:46:59 INFO - 'XPC_SERVICE_NAME': '0', 18:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:46:59 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/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0256', '--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 18:46:59 INFO - mozversion application_buildid: 20170605171955 18:46:59 INFO - mozversion application_changeset: 3cfe81678881ada0245215c8863bc9722925bb44 18:46:59 INFO - mozversion application_display_name: Nightly 18:46:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:46:59 INFO - mozversion application_name: Firefox 18:46:59 INFO - mozversion application_remotingname: firefox 18:46:59 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 18:46:59 INFO - mozversion application_vendor: Mozilla 18:46:59 INFO - mozversion application_version: 55.0a1 18:46:59 INFO - mozversion platform_buildid: 20170605171955 18:46:59 INFO - mozversion platform_changeset: 3cfe81678881ada0245215c8863bc9722925bb44 18:46:59 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 18:46:59 INFO - mozversion platform_version: 55.0a1 18:46:59 INFO - using testdate: 1496713619 18:46:59 INFO - actual date: 1496713619 18:46:59 INFO - starting webserver on 'localhost:49227' 18:46:59 INFO - SUITE-START | Running 2 tests 18:46:59 INFO - TEST-START | dromaeo_css 18:46:59 INFO - operating with platform_type : mac_ 18:46:59 INFO - Initialising browser for dromaeo_css test... 18:46:59 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpTzqncg/profile 18:46:59 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 18:47:33 INFO - TEST-INFO | 1647: killed by SIGTERM 18:47:33 INFO - Browser initialized. 18:47:33 INFO - Running cycle 1/1 for dromaeo_css test... 18:47:33 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmpTzqncg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.503 plugin-container[1657:8716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 18:47:35 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.504 plugin-container[1657:8716] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x491f, name = 'com.apple.CFPasteboardClient' 18:47:35 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.504 plugin-container[1657:8716] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.981 plugin-container[1658:8762] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 18:47:35 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.982 plugin-container[1658:8762] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa003, name = 'com.apple.CFPasteboardClient' 18:47:35 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:47:35 INFO - PID 1656 | 2017-06-05 18:47:35.982 plugin-container[1658:8762] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:47:45 INFO - PID 1656 | RSS: Main: 268546048 18:47:45 INFO - PID 1656 | 18:50:47 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 18:50:49 INFO - PID 1656 | RSS: Main: 269643776 18:50:49 INFO - PID 1656 | 18:53:50 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 18:53:51 INFO - PID 1656 | RSS: Main: 284246016 18:53:51 INFO - PID 1656 | 18:56:53 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 18:56:54 INFO - PID 1656 | RSS: Main: 283815936 18:56:54 INFO - PID 1656 | 18:59:55 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 18:59:56 INFO - PID 1656 | RSS: Main: 287535104 18:59:56 INFO - PID 1656 | 19:02:59 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 19:03:00 INFO - PID 1656 | RSS: Main: 287444992 19:03:00 INFO - PID 1656 | 19:06:01 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 19:06:02 INFO - PID 1656 | RSS: Main: 287510528 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | __start_tp_report 19:06:02 INFO - PID 1656 | _x_x_mozilla_page_load 19:06:02 INFO - PID 1656 | _x_x_mozilla_page_load_details 19:06:02 INFO - PID 1656 | |i|pagename|runs| 19:06:02 INFO - PID 1656 | |0;dojo.html;3147.638021627512;3227.48360262358;3407.2844702612433;3505.842237099331;3564.643535646437;5493.368262649797;5913.172155898178;6173.598716083463;6188.845278868025;6223.626582405059;6102.88313068173;6106.725197366118;6134.436587033451;6157.599756015856;6162.938370616293;4303.376010478479;5347.037533244014;5357.357117145948;5361.839144825662;5363.54409875161;4415.712978656393;5370.060239458098;5416.620836541444;5442.319710036246;5497.945020549783;5243.213517972296;5293.602979776532;5364.409914909357;5400.405955344948;5431.429699881515;5336.4129945705945;5359.598030147716;5373.140297552372;5387.757550910218;5411.540019098381;5276.630635855492;5330.413654498002;5332.546733527654;5334.493223143795;5348.732563371855;5230.189320655267;5723.833512057666;6018.3981601839905;6205.968970155165;6228.626282423023;5299.655522391032;6170.2904166021035;6499.350064993463;6540.215174179081;6553.9344606553805;6662.233843108056;6671.366220209073;6707.128073350482;6712.5301228913995;6734.7642832501115;6211.534134939852;6221.9377806222265;6233.937660623335;6238.376162383771;6241.9999999999545;6616.172978377703;6729.932700672931;6772.424343930723;6818.18181818185;6893.620850853151;5464.371597266286;6252.5935814171935;6298.244210694746;6445.613263204223;6460.3216662250725;5412.512873841373;6704.463642908556;6709.7316107355155;6726.091977582963;6735.562188457735;6486.772962946275;6491.642959637265;6493.51298652603;6494.967525162344;6498.415142637185;6331.113644089739;6365.96817015912;6461.773837915651;6491.123698300763;6498.870022599616;4854.077725232195;5131.589472842164;5306.151015837448;5326.147816349443;5332.226827110047;4434.711743736647;4703.365045718852;4716.9292460613115;4728.503507131771;4748.762561871893;6201.627902325875;6247.156633854372;6282.905756413657;6292.528060395489;6301.149344838388;6688.966555167193;6739.494537909676;6751.966240168866;6761.560498567577;6777.356151165632;6307.463865571478;6327.335629758901;6343.207099112611;6346.650934198663;6350.047492876013;5678.176664383641;5720.628159169641;5777.768889244468;5808.622439541501;5898;6835.829104272433;6863.485238607124;6881.724731010703;6917.654117294216;6919.930800691928;6652.567583107082;6672.132622759011;6673.265940746611;6677.592788759395;6691.5315927884585;6593.241777195684;6660.800175994728;6771;6863.897041544481;6866.896996545156;6421.5825971311715;6459;6469.514786390945;6482.773102941374;6527.902081468686;6680.331966803281;6695.296993815579;6703.229128650268;6715.93284067153;6722.462203023844;6275.937240627535;6319.083732858618;6405.775797847053;6429.03564465334;6452.548321617442;6477.611343319416;6501.772437964649;6512.283648798723;6515.250746164252;6533.509986750918;5867.73843623623;6150.261968563801;6359.618422894641;6363.300036996019;6380.649064301322;6283.088952102011;6331.2085989251345;6501.609903405811;6506.02409638558;6514.869702605826;6309.274433440213;6315.747370105144;6325.4939604832425;6330.683465826783;6336.08126821599 19:06:02 INFO - PID 1656 | |1;ext.html;791.1534657916029;825.8719898415745;847.5762118940528;1266.461753754654;1334.5862782537408;37438;38570.61429385712;38611.22777544447;38612.61387386125;38639.80680096599;38017.23965520688;38457.03857403559;38463.42304865422;38473.61526384735;38501.22997540054;7711.2288771123;7853.253940875607;8071.31393831525;8276.503409795432;8338.791530211764;8286.46138001031;8370.790730231733;8377.581120943958;8379.078301386842;8387.077421483631;8252.092269850311;8363.372747043966;8373.706920257791;8393.95075615548;8426.957865210665;8268.875966860502;8316.542590157538;8369.748907532723;8372.58137093143;8408.663653453916;6974.651267436608;7980.840383192303;8044.436889417743;8093.392995525301;8118.10700822909;35291.82354088239;35476.403561839776;35486.64513354885;35692.464613030825;35722.28555428877;3549.716022718176;3558.7864728116397;3588.802615856127;3594.137407022322;3598.118460977051;25626.359341016625;28446.573301400495;29606.70393296061;29843.806247750064;30056.699433005826;2811.859407029661;2978.37454134632;2990.788730564125;3071.4932036214;3113.501839705636;242885;247485;252820;262079.68960155267;289346;47031.76484117557;47162.29256561154;47262;47299;47401.28898066532;48524.51475485271;49020.754896226004;49078;49117;49181;14006.859931400759;14016.369263383172;14052.85947140536;14064.507742228972;14277;11374.94312528432;11427;11510.884891151149;11512.88487115135;11516.30902145874;13656.907447404184;13693.246871421969;13698.246596437291;13795.379207935666;13845.238511881944;6155;6206.286277078104;6213.875722485615;6216.409441103088;6218.378162183836;979.9412035277907;983.4050399508337;1004.6985904228702;1027.3219675014454;1039.6517166749225;23012.30963071077;24633.01467941298;24636.384090397525;24702.382440439134;24834.758262086823;1089.7657003744075;1090.1170052257537;1133.5069244878339;1151.5221183209042;1169.4094482286562;11004.944975275232;11391.120465123327;12814.743705126031;12820.038497112753;12845.871541284654;307534;308552;308733.4563327169;309746;309907;52739.20891186635;53187.734061329444;53434.46565534295;53448;53693.73153134209;1469.4930249064055;1499.2353899511327;1526.0538466151058;1529.6787674588238;1548.1717281254475;22134.557308854055;22466.438339041655;22962.19632312861;23230;24118.63822042706;36432.907012789656;36832.81583592065;36998;48701.512984869696;49447.011059779325;281050;283275;285480;288241;289093;33908.49137262993;35240.82379588085;35266.471002934464;35413.82293088518;35865.744698935414;41243;42508.9372765671;43427.565724342356;43677.344839826794;43703.34444983264;29647.110586682433;30173.09480715582;30945.690543094282;31002.139871607775;31042.223944400666;24525.264242072768;29479.85260073686;29892.850535748054;30684.84657576698;30686.386272273983;37661;37783;37852.81073594614;37895.81052094722;38017.429738553394;29618.703812961594;29902.551461728526;30159.396812063198;31041.379172415975;31663.36673266568;22339.10643574239;23960.760392395852;24179.75820241775;24436;24539.38651533726 19:06:02 INFO - PID 1656 | |2;jquery.html;397.72159488358136;437.33306707271424;445.11200155689403;445.2193851959777;449.98875028124274;21783.45541361463;22399.10403583857;23195.76804231957;23201;23541.646875296883;19019.144138513766;20608.484787880312;24039.39901502463;24691.259262222116;24776.87611561944;6084.787032453876;6167.198264225644;6232.0028795392745;6233.065040243954;6273.7176827042895;6161.630302181861;6172.166757487749;6206.441420272175;6226.283977342596;6231.532635052389;5965.433283838051;6044.667543285118;6084.634921904678;6084.6349219047;6094.299155597118;5576.33084029917;5660.858478538028;5673.23411339468;5707.77168913243;5721.570882183832;4440.911181776379;4985.750712464363;5046.84859454217;5121.564667003309;5134;28524.859005639748;28955.71044289572;29334.559981600294;29365.26586835346;29381.11856644304;2115.989420052905;2172.880491572963;2173.119886445992;2174.8695078295195;2186.387811412807;18483.722744158847;18776;18855.245790168377;18956.146973386232;18972.430827075208;2129.808317251455;2170.533335332911;2172.945676358088;2190.8466407351493;2202.0421116814236;162929.18535407248;170578;171721;178943;178974;12178.878211217952;12688.49246030167;13074.673133171556;13252.469901204036;13281;15771.44799931997;16616.83383166177;16683.582910427336;16692.833071669127;16718.08050557231;8021.478603890729;8275.08974012858;8809.33929955256;9178.954105229564;9232.7691807704;8577.25527831088;8769.429987050822;8825.07336729647;8885.06706795777;8913.108689131035;8254.54599996994;8343.374246931504;8376.623051962677;8382.664693412316;8429.114942930904;2214.1032881682945;2240.551889622082;2241.2603840732518;2244.2257421189665;2407.16952651335;845.4884794699118;944.3749575806979;951.4909523404945;953.8569214617863;985.9161971232382;26151.869240653676;27898.860505697343;28134.43731125366;28197;28232.71767282342;929.4934260827865;1009.3489699144011;1011.5650270383659;1029.3978022856563;1046.706922061824;8872.778180545409;10263.12763415118;10507.159427245802;10573.894261057445;10969.738480074733;499856;545915;574358;575268;584573;121815;140315;142879.2856035713;145747;147639;1190.595197632809;1218.7501562179732;1223.7001934526097;1240.6588188248304;1241.7330273991136;18166.5458363542;20270.695939561214;21804.781952180274;22054.228102016354;23004.07983680701;61883.38116618776;62165;62355.75288494295;63288;63518.36481635125;81593.59203204184;82530.58734706289;82597;82958;82979;10056.29605927578;10132.49337533135;10263.255246608243;10292.919243479328;10300.278980471594;16110.275037623569;16962.830371696124;17814.376496822548;18011.819881801013;18168.818311816714;14085.08446950945;14128.858711412755;14215.857841421453;14322.14067155974;14369.712605748036;12978.80531792005;13165.53920612774;13256.963140267964;13381;13581.728365432438;14435.845132389593;14804.185769782334;14863.628409289855;15051.021683590532;15063.849361506245;10003.849942250727;10058.798824023625;10081.899181008095;10164.64423745195;10165.542550585093;14280.785788212977;14439.566812995627;14495;14522.85477145215;14587.343569539184 19:06:02 INFO - PID 1656 | |3;mootools.html;1426.4793350427094;1456.570311758031;1470.3236511204846;1490.3293517916934;1492.9477468288605;11419.771604567914;11856.407179641026;11919.404029798498;11930.403479826007;12254.203476774026;11834.171607987444;12008.159428839985;12021.879781202208;12036.939815300912;12094.213876098069;2516.6350879122515;2557.3734435063343;2557.693076830783;2563.3976015636404;2570.8586027768465;2459.077845807822;2474.257722683197;2496.613024981131;2505.8496490210646;2508.8871000805007;2472.320111969205;2507.7743003129713;2508.3102146909564;2519.685039370079;2527.721950585434;2428.7571242875747;2459.532688789124;2463.6797216361847;2464.568700477418;2464.6056630939142;2450.656908032877;2481.1812102006293;2484.1553871683714;2509.2095989763347;2530.7595778401205;9713.417194968324;11690.240134391237;11712.882871171265;11716.765664686744;11737.53049878004;6405.5516113872045;6558.573692709959;6628.867422651569;6639.136912201431;6657.1345725055435;3391.270876761508;3852.3451013327804;3998.5801490843332;4067.4712287402745;4069.491313585802;1215.3133479584008;1244.9813252801216;1260.2690439545042;1274.0953922714884;1289.2844471318415;15274.541763747106;15741.134237617041;16342;16355.018698877986;16455.012699237963;5486.61593688438;5497.8075767347955;5685.061964775766;5701.657900525982;5707.571932105108;10363.430011349448;12255.387230638433;12320.75358492825;12351.25892446456;12354.25874447536;6175.814725558241;6221.906671399933;6320.778772742932;6509.837254068686;6760.1549806274215;5975.282966044103;6045.516358691294;6179.16581261533;6201.379862013853;6202.968985155046;4347.282698354736;4399.23013472641;4417.094495628388;4439.045605194899;4453.755043472575;1285.2095960983927;1287.6394609509352;1304.589054447846;1309.3256972659087;1310.1614966421498;814.470594113819;820.4789958376474;843.8945131858518;853.6585365853708;876.8816209811594;5552.861178470571;6254.405831446005;6504.414602685686;6577.375149360803;6724.587836554379;557.9107342825129;587.166223961975;589.3311091910626;599.2509363295881;614.1248720573166;3982.8008599569907;4447.555244475591;4538.410006699174;4551.271796512542;4741.762911854394;12413.503459861686;12808.423620937081;14590.051646642934;15071.623209419633;15080.924595376953;14320.212388318534;14624.853751462562;14727.895407844455;14862.033967792058;14912.70174596502;8130.146334634896;8523.48859068458;8667.089955554577;8674.132586741276;8704.6518139275;6581.440577550962;6926.445884329342;6971.7211311549;7017.508774385744;7259.782206533812;11634.53461861516;12678.619641410773;12687.80968285495;12843.357832108544;13012.349382530647;10245.538950747383;13948.674875886774;14001.22993235382;14328.856711432752;14392.280385980868;7840.803979900548;9578.808423831346;9722.708318750167;9738.513074346396;9739.805203896025;10650.201234907257;10787.568497260021;10820.350778953289;10843.67468975932;10878.40168790724;9175.724728258163;9199.862002070107;9215;9224.077592240723;9258.95370523143;8922.598483068148;8970.147835955573;9027.638894444148;9038.819223615623;9217.262618990599;8075.919240807517;8146.633401496828;8237.135100814328;8243.464174828618;8248.051474080557;6064.878702426015;6184.81445556634;6367.299597044414;6370.458511026614;6522.21733391996;6198;6330;6419.069234960998;6525.119108920235;6551.770688026087 19:06:02 INFO - PID 1656 | |4;prototype.html;341.375283231686;379.57677189933213;384.3178358413815;394.0837552689995;408.7629175078455;5245.580353571715;5258.789648414064;5782.987977104011;6332.145160403345;6449.51628627853;5544.362398324194;6057.575969682124;6277.497800175975;6282.811515654526;6342.968285158579;3439.7936123832587;4294.377315289281;4600.309953506984;4601.6548758843055;4654.557817007396;4612.630989520847;4615.561521655437;4628.074385122989;4636.884077898063;4653.650976176783;3785.413260944544;4578.450585929677;4591.701539399928;4596.471405788327;4598.816047358102;3762.322781899257;3985;4545.11370282795;4593.701409408378;4596.701649175412;3363.1928337199142;3885.6502914737807;3923.4507168996365;3945;3949.5063117110362;5106.668066575698;5388.272583201257;6289;6309.3690630937;6309.684515774193;5487.612888393562;5508.641938274;5510.862228444281;5519.558435325164;5534.169874518814;5546.500814926636;5616.5787565932305;6523.380278873499;6804.863902721967;6830.658467076626;3940.271049855757;4134.751914885116;4191.8532851350365;4471.323253779832;4502.189605870942;6281.309056003836;7030.7539236127;7101.715931362739;7163.319484649002;7185;4515.683902126885;4540.455145382509;4561.612262957619;4563.612092972068;4618.884527886763;3536.9485698294925;4428.933565996513;4615.499962512197;4654.627629789608;4667.386263692343;5016;5017.523335283142;5108.948910510921;5506.32057222543;5614.9157762633595;6230;6231.3145553989025;6416.4225219729515;6834.931650683429;6838.863222735517;1586.365453818482;1716.5536960390389;1768.6551122530982;1786.7587875636882;1792.3278770461077;1092.8633920759905;1128.8814674459227;1227.778360531277;1234.8332975048434;1239.7706424311532;5541.33503979525;6244.469220116341;6399.808005759742;6411.807645770635;6465.515086368541;6675.566088204251;7050.964745176241;7063.752768653072;7074.594637823062;7114.893276600855;5261.763750406139;5343.49236822509;6423.197100362455;6441.355864413615;6461.352355149407;3131.7338026267566;3360.2943381889586;3643.198496330803;3691.57546882112;3700.148965737895;7285.4171666266575;7295.522089857832;7304.306090921354;7313.48805583604;7329.230430804689;6646.6676666166495;7254.5647261164495;7286.052813134364;7288.198298187195;7297.270272972766;6470.708818103197;6903.585784852925;7291.197968223491;7329.680657481598;7365.201223535022;5760.423957604206;6398.264199617103;6584.637844918384;6680.665966701548;7138.643067846483;5451.7001564912725;5512.4211957743855;5756.30924289088;6718.958561422987;6875.865482195381;6101.4544164597655;6344.651044192429;6745.898811517929;7236.891446628199;7288.453365997678;4603.125406172817;4603.470600880942;4608.746518941491;4619.653525985632;4630.3286023525725;7073.292670732885;7111.644417779194;7120.1099862517185;7125.5368401053765;7132.786016419515;5692.48767610917;5742.684152371659;6759.087523184307;6779.423748981392;6864.48516361265;6016.8796624068145;6590.209174899042;6831.282715315019;6849.520533562602;6875.896861547181;6696.727621751852;6715.731370745116;6715.832104197239;6716.630585317659;6730.562513436611;5906.763729450774;6703.497237707289;6843;6873.518853680394;6884.965575172092;6605.306442823435;6636.369544893228;6649.46804255668;6655.168103987002;6677.799666010027 19:06:02 INFO - PID 1656 | |5;yui.html;734.2070563791107;830.1822704635937;840.1388576708869;856.6336692974703;874.3180319350904;16731.916340418295;17154.57113572158;17165.999999999985;17220.741688874677;17285.913570432145;13446.596602101929;15078.623034424145;15540.922295388507;16107.83892161078;16497.83502164978;930.2511478259999;930.878985731854;934.9813003739921;938.2259635800458;953.4041224234836;978.1636700620974;978.5400861595075;980.9313348065639;980.9362391444569;985.23644175764;994.2692121290845;1031.2935639087211;1034.095166729112;1036.6630844975375;1037.8339465685492;1011.8633984412083;1022.1516141602452;1028.912542433894;1029.2640761855273;1032.385730490357;738.4240292571802;756.4780301591884;757.5303311946574;758.7420277105755;762.675862758331;17292.56768580783;17307.913460432745;17344.13279336028;17416.825831741648;17427.5643108922;17193.484195474157;17367.565810854703;17652.646947061115;17683.93896366222;17738.29046838125;14578.489752858599;14809.815214782899;14902.850971490254;14908.254587270701;14914.403423863141;3161.7470602351755;3624.6375362463805;3627.2201476682376;3629.2922880038404;3847.826847791856;117672;144660;147427;149902;150059;13133.408996594984;13583.252921089435;13648;13677.384517696728;13857.099288546213;25620.615690764655;26272.34319142037;26530;26672.933082676474;26716.931322746874;7812.375009999187;7830.804229894185;8007.2793448588745;8099.676012959533;8111.351091912633;3912.0611053346993;4000.979995100006;4003.839846406111;4004.3593025115842;4010.7192496525545;3853.3641949078087;4189.2459357315365;4304.268274393361;4314.094040251583;4421.668374871898;814.9633266503022;933.5472295936465;947.6398968392105;955.4888134847823;957.2963871554402;638.4613082471222;649.28254279021;656.6191608866845;672.9562578432386;689.7930620813736;11120;11270.549178032787;12593.433295501725;13957.511487097903;14099.154050756782;622.7789134857151;656.4092316914815;657.1621182991723;673.3502170405582;675.3887731602907;5310;5482.2182790593215;5748.5688573357165;5797.710114494258;6561.704723287464;140568.07579665838;142975.2851235758;146871;148077.86520561058;152633;92645.90604294982;105247.4737626307;106163;108097.91902081035;108885.91114088758;97885;98110.5094474523;101210;101891;102858;11857.525698971944;12286.017118630309;12452.377381131088;12601.621951341474;12702.618921432371;40425.98935026648;41135;42977.78511107424;44003.559964399945;44157;57990.710046449494;58086;58566.70716646389;59490.40509594849;59797;16680.833191667927;16862.241199146312;16929;16980.74528882043;17061;17424.82575174283;18641;18646.254149833854;18752.812471875106;18826.717599236297;16872;16928.74606880873;17701.38045168413;18240.72638910444;18403.447896563124;17275.7408638868;17894.91052544729;17946.37187698424;18186.36347727823;18250.543736406697;15337.923310383376;15399.230038498254;15446.2276886153;15463.226838658247;15604.843951560339;11662.549012971725;11698;12166.817497737718;12525.123241373016;12533.87466125327;12628.621141365773;13242.735145297233;13875.861241387456;14268.928655356656;14462.132272063709 19:06:02 INFO - PID 1656 | __end_tp_report 19:06:02 INFO - PID 1656 | __start_cc_report 19:06:02 INFO - PID 1656 | _x_x_mozilla_cycle_collect,451 19:06:02 INFO - PID 1656 | __end_cc_report 19:06:02 INFO - PID 1656 | __startTimestamp1496714762123__endTimestamp 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | ------- Summary: start ------- 19:06:02 INFO - PID 1656 | Number of tests: 6 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#0] dojo.html Cycles:165 Average:6048.32 Median:6315.75 stddev:764.12 (12.1%) stddev-sans-first:731.79 19:06:02 INFO - PID 1656 | Values: 3147.6 3227.5 3407.3 3505.8 3564.6 5493.4 5913.2 6173.6 6188.8 6223.6 6102.9 6106.7 6134.4 6157.6 6162.9 4303.4 5347.0 5357.4 5361.8 5363.5 4415.7 5370.1 5416.6 5442.3 5497.9 5243.2 5293.6 5364.4 5400.4 5431.4 5336.4 5359.6 5373.1 5387.8 5411.5 5276.6 5330.4 5332.5 5334.5 5348.7 5230.2 5723.8 6018.4 6206.0 6228.6 5299.7 6170.3 6499.4 6540.2 6553.9 6662.2 6671.4 6707.1 6712.5 6734.8 6211.5 6221.9 6233.9 6238.4 6242.0 6616.2 6729.9 6772.4 6818.2 6893.6 5464.4 6252.6 6298.2 6445.6 6460.3 5412.5 6704.5 6709.7 6726.1 6735.6 6486.8 6491.6 6493.5 6495.0 6498.4 6331.1 6366.0 6461.8 6491.1 6498.9 4854.1 5131.6 5306.2 5326.1 5332.2 4434.7 4703.4 4716.9 4728.5 4748.8 6201.6 6247.2 6282.9 6292.5 6301.1 6689.0 6739.5 6752.0 6761.6 6777.4 6307.5 6327.3 6343.2 6346.7 6350.0 5678.2 5720.6 5777.8 5808.6 5898.0 6835.8 6863.5 6881.7 6917.7 6919.9 6652.6 6672.1 6673.3 6677.6 6691.5 6593.2 6660.8 6771.0 6863.9 6866.9 6421.6 6459.0 6469.5 6482.8 6527.9 6680.3 6695.3 6703.2 6715.9 6722.5 6275.9 6319.1 6405.8 6429.0 6452.5 6477.6 6501.8 6512.3 6515.3 6533.5 5867.7 6150.3 6359.6 6363.3 6380.6 6283.1 6331.2 6501.6 6506.0 6514.9 6309.3 6315.7 6325.5 6330.7 6336.1 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#1] ext.html Cycles:180 Average:43681.55 Median:34574.66 stddev:74703.31 (216.1%) stddev-sans-first:74843.46 19:06:02 INFO - PID 1656 | Values: 791.2 825.9 847.6 1266.5 1334.6 37438.0 38570.6 38611.2 38612.6 38639.8 38017.2 38457.0 38463.4 38473.6 38501.2 7711.2 7853.3 8071.3 8276.5 8338.8 8286.5 8370.8 8377.6 8379.1 8387.1 8252.1 8363.4 8373.7 8394.0 8427.0 8268.9 8316.5 8369.7 8372.6 8408.7 6974.7 7980.8 8044.4 8093.4 8118.1 35291.8 35476.4 35486.6 35692.5 35722.3 3549.7 3558.8 3588.8 3594.1 3598.1 25626.4 28446.6 29606.7 29843.8 30056.7 2811.9 2978.4 2990.8 3071.5 3113.5 242885.0 247485.0 252820.0 262079.7 289346.0 47031.8 47162.3 47262.0 47299.0 47401.3 48524.5 49020.8 49078.0 49117.0 49181.0 14006.9 14016.4 14052.9 14064.5 14277.0 11374.9 11427.0 11510.9 11512.9 11516.3 13656.9 13693.2 13698.2 13795.4 13845.2 6155.0 6206.3 6213.9 6216.4 6218.4 979.9 983.4 1004.7 1027.3 1039.7 23012.3 24633.0 24636.4 24702.4 24834.8 1089.8 1090.1 1133.5 1151.5 1169.4 11004.9 11391.1 12814.7 12820.0 12845.9 307534.0 308552.0 308733.5 309746.0 309907.0 52739.2 53187.7 53434.5 53448.0 53693.7 1469.5 1499.2 1526.1 1529.7 1548.2 22134.6 22466.4 22962.2 23230.0 24118.6 36432.9 36832.8 36998.0 48701.5 49447.0 281050.0 283275.0 285480.0 288241.0 289093.0 33908.5 35240.8 35266.5 35413.8 35865.7 41243.0 42508.9 43427.6 43677.3 43703.3 29647.1 30173.1 30945.7 31002.1 31042.2 24525.3 29479.9 29892.9 30684.8 30686.4 37661.0 37783.0 37852.8 37895.8 38017.4 29618.7 29902.6 30159.4 31041.4 31663.4 22339.1 23960.8 24179.8 24436.0 24539.4 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#2] jquery.html Cycles:180 Average:37636.44 Median:12306.60 stddev:95278.14 (774.2%) stddev-sans-first:95504.40 19:06:02 INFO - PID 1656 | Values: 397.7 437.3 445.1 445.2 450.0 21783.5 22399.1 23195.8 23201.0 23541.6 19019.1 20608.5 24039.4 24691.3 24776.9 6084.8 6167.2 6232.0 6233.1 6273.7 6161.6 6172.2 6206.4 6226.3 6231.5 5965.4 6044.7 6084.6 6084.6 6094.3 5576.3 5660.9 5673.2 5707.8 5721.6 4440.9 4985.8 5046.8 5121.6 5134.0 28524.9 28955.7 29334.6 29365.3 29381.1 2116.0 2172.9 2173.1 2174.9 2186.4 18483.7 18776.0 18855.2 18956.1 18972.4 2129.8 2170.5 2172.9 2190.8 2202.0 162929.2 170578.0 171721.0 178943.0 178974.0 12178.9 12688.5 13074.7 13252.5 13281.0 15771.4 16616.8 16683.6 16692.8 16718.1 8021.5 8275.1 8809.3 9179.0 9232.8 8577.3 8769.4 8825.1 8885.1 8913.1 8254.5 8343.4 8376.6 8382.7 8429.1 2214.1 2240.6 2241.3 2244.2 2407.2 845.5 944.4 951.5 953.9 985.9 26151.9 27898.9 28134.4 28197.0 28232.7 929.5 1009.3 1011.6 1029.4 1046.7 8872.8 10263.1 10507.2 10573.9 10969.7 499856.0 545915.0 574358.0 575268.0 584573.0 121815.0 140315.0 142879.3 145747.0 147639.0 1190.6 1218.8 1223.7 1240.7 1241.7 18166.5 20270.7 21804.8 22054.2 23004.1 61883.4 62165.0 62355.8 63288.0 63518.4 81593.6 82530.6 82597.0 82958.0 82979.0 10056.3 10132.5 10263.3 10292.9 10300.3 16110.3 16962.8 17814.4 18011.8 18168.8 14085.1 14128.9 14215.9 14322.1 14369.7 12978.8 13165.5 13257.0 13381.0 13581.7 14435.8 14804.2 14863.6 15051.0 15063.8 10003.8 10058.8 10081.9 10164.6 10165.5 14280.8 14439.6 14495.0 14522.9 14587.3 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#3] mootools.html Cycles:180 Average:7063.15 Median:3917.57 stddev:4409.86 (112.6%) stddev-sans-first:4401.89 19:06:02 INFO - PID 1656 | Values: 1426.5 1456.6 1470.3 1490.3 1492.9 11419.8 11856.4 11919.4 11930.4 12254.2 11834.2 12008.2 12021.9 12036.9 12094.2 2516.6 2557.4 2557.7 2563.4 2570.9 2459.1 2474.3 2496.6 2505.8 2508.9 2472.3 2507.8 2508.3 2519.7 2527.7 2428.8 2459.5 2463.7 2464.6 2464.6 2450.7 2481.2 2484.2 2509.2 2530.8 9713.4 11690.2 11712.9 11716.8 11737.5 6405.6 6558.6 6628.9 6639.1 6657.1 3391.3 3852.3 3998.6 4067.5 4069.5 1215.3 1245.0 1260.3 1274.1 1289.3 15274.5 15741.1 16342.0 16355.0 16455.0 5486.6 5497.8 5685.1 5701.7 5707.6 10363.4 12255.4 12320.8 12351.3 12354.3 6175.8 6221.9 6320.8 6509.8 6760.2 5975.3 6045.5 6179.2 6201.4 6203.0 4347.3 4399.2 4417.1 4439.0 4453.8 1285.2 1287.6 1304.6 1309.3 1310.2 814.5 820.5 843.9 853.7 876.9 5552.9 6254.4 6504.4 6577.4 6724.6 557.9 587.2 589.3 599.3 614.1 3982.8 4447.6 4538.4 4551.3 4741.8 12413.5 12808.4 14590.1 15071.6 15080.9 14320.2 14624.9 14727.9 14862.0 14912.7 8130.1 8523.5 8667.1 8674.1 8704.7 6581.4 6926.4 6971.7 7017.5 7259.8 11634.5 12678.6 12687.8 12843.4 13012.3 10245.5 13948.7 14001.2 14328.9 14392.3 7840.8 9578.8 9722.7 9738.5 9739.8 10650.2 10787.6 10820.4 10843.7 10878.4 9175.7 9199.9 9215.0 9224.1 9259.0 8922.6 8970.1 9027.6 9038.8 9217.3 8075.9 8146.6 8237.1 8243.5 8248.1 6064.9 6184.8 6367.3 6370.5 6522.2 6198.0 6330.0 6419.1 6525.1 6551.8 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#4] prototype.html Cycles:180 Average:5403.90 Median:5771.71 stddev:1721.99 (29.8%) stddev-sans-first:1684.38 19:06:02 INFO - PID 1656 | Values: 341.4 379.6 384.3 394.1 408.8 5245.6 5258.8 5783.0 6332.1 6449.5 5544.4 6057.6 6277.5 6282.8 6343.0 3439.8 4294.4 4600.3 4601.7 4654.6 4612.6 4615.6 4628.1 4636.9 4653.7 3785.4 4578.5 4591.7 4596.5 4598.8 3762.3 3985.0 4545.1 4593.7 4596.7 3363.2 3885.7 3923.5 3945.0 3949.5 5106.7 5388.3 6289.0 6309.4 6309.7 5487.6 5508.6 5510.9 5519.6 5534.2 5546.5 5616.6 6523.4 6804.9 6830.7 3940.3 4134.8 4191.9 4471.3 4502.2 6281.3 7030.8 7101.7 7163.3 7185.0 4515.7 4540.5 4561.6 4563.6 4618.9 3536.9 4428.9 4615.5 4654.6 4667.4 5016.0 5017.5 5108.9 5506.3 5614.9 6230.0 6231.3 6416.4 6834.9 6838.9 1586.4 1716.6 1768.7 1786.8 1792.3 1092.9 1128.9 1227.8 1234.8 1239.8 5541.3 6244.5 6399.8 6411.8 6465.5 6675.6 7051.0 7063.8 7074.6 7114.9 5261.8 5343.5 6423.2 6441.4 6461.4 3131.7 3360.3 3643.2 3691.6 3700.1 7285.4 7295.5 7304.3 7313.5 7329.2 6646.7 7254.6 7286.1 7288.2 7297.3 6470.7 6903.6 7291.2 7329.7 7365.2 5760.4 6398.3 6584.6 6680.7 7138.6 5451.7 5512.4 5756.3 6719.0 6875.9 6101.5 6344.7 6745.9 7236.9 7288.5 4603.1 4603.5 4608.7 4619.7 4630.3 7073.3 7111.6 7120.1 7125.5 7132.8 5692.5 5742.7 6759.1 6779.4 6864.5 6016.9 6590.2 6831.3 6849.5 6875.9 6696.7 6715.7 6715.8 6716.6 6730.6 5906.8 6703.5 6843.0 6873.5 6885.0 6605.3 6636.4 6649.5 6655.2 6677.8 19:06:02 INFO - PID 1656 | 19:06:02 INFO - PID 1656 | [#5] yui.html Cycles:180 Average:24629.68 Median:17816.60 stddev:37609.96 (211.1%) stddev-sans-first:37672.67 19:06:02 INFO - PID 1656 | Values: 734.2 830.2 840.1 856.6 874.3 16731.9 17154.6 17166.0 17220.7 17285.9 13446.6 15078.6 15540.9 16107.8 16497.8 930.3 930.9 935.0 938.2 953.4 978.2 978.5 980.9 980.9 985.2 994.3 1031.3 1034.1 1036.7 1037.8 1011.9 1022.2 1028.9 1029.3 1032.4 738.4 756.5 757.5 758.7 762.7 17292.6 17307.9 17344.1 17416.8 17427.6 17193.5 17367.6 17652.6 17683.9 17738.3 14578.5 14809.8 14902.9 14908.3 14914.4 3161.7 3624.6 3627.2 3629.3 3847.8 117672.0 144660.0 147427.0 149902.0 150059.0 13133.4 13583.3 13648.0 13677.4 13857.1 25620.6 26272.3 26530.0 26672.9 26716.9 7812.4 7830.8 8007.3 8099.7 8111.4 3912.1 4001.0 4003.8 4004.4 4010.7 3853.4 4189.2 4304.3 4314.1 4421.7 815.0 933.5 947.6 955.5 957.3 638.5 649.3 656.6 673.0 689.8 11120.0 11270.5 12593.4 13957.5 14099.2 622.8 656.4 657.2 673.4 675.4 5310.0 5482.2 5748.6 5797.7 6561.7 140568.1 142975.3 146871.0 148077.9 152633.0 92645.9 105247.5 106163.0 108097.9 108885.9 97885.0 98110.5 101210.0 101891.0 102858.0 11857.5 12286.0 12452.4 12601.6 12702.6 40426.0 41135.0 42977.8 44003.6 44157.0 57990.7 58086.0 58566.7 59490.4 59797.0 16680.8 16862.2 16929.0 16980.7 17061.0 17424.8 18641.0 18646.3 18752.8 18826.7 16872.0 16928.7 17701.4 18240.7 18403.4 17275.7 17894.9 17946.4 18186.4 18250.5 15337.9 15399.2 15446.2 15463.2 15604.8 11662.5 11698.0 12166.8 12525.1 12533.9 12628.6 13242.7 13875.9 14268.9 14462.1 19:06:02 INFO - PID 1656 | -------- Summary: end -------- 19:06:02 INFO - PID 1656 | 19:06:02 INFO - TEST-INFO | 1656: exit 0 19:06:02 INFO - TEST-OK | dromaeo_css | took 1143462ms 19:06:02 INFO - TEST-START | kraken 19:06:02 INFO - operating with platform_type : mac_ 19:06:02 INFO - Initialising browser for kraken test... 19:06:02 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp0uyjDU/profile 19:06:02 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 19:06:36 INFO - TEST-INFO | 1664: killed by SIGTERM 19:06:36 INFO - Browser initialized. 19:06:36 INFO - Running cycle 1/1 for kraken test... 19:06:36 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/T/tmp0uyjDU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:06:38 INFO - PID 1668 | 2017-06-05 19:06:38.607 plugin-container[1669:11287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 19:06:38 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:38 INFO - PID 1668 | 2017-06-05 19:06:38.608 plugin-container[1669:11287] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 19:06:38 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:38 INFO - PID 1668 | 2017-06-05 19:06:38.608 plugin-container[1669:11287] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:06:39 INFO - PID 1668 | 2017-06-05 19:06:39.066 plugin-container[1670:11336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 19:06:39 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:39 INFO - PID 1668 | 2017-06-05 19:06:39.066 plugin-container[1670:11336] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9f0b, name = 'com.apple.CFPasteboardClient' 19:06:39 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:06:39 INFO - PID 1668 | 2017-06-05 19:06:39.066 plugin-container[1670:11336] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:06:48 INFO - PID 1668 | RSS: Main: 268304384 19:06:48 INFO - PID 1668 | 19:06:51 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 19:06:52 INFO - PID 1668 | RSS: Main: 275906560 19:06:52 INFO - PID 1668 | 19:06:54 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 19:06:55 INFO - PID 1668 | RSS: Main: 286044160 19:06:55 INFO - PID 1668 | 19:06:58 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 19:06:59 INFO - PID 1668 | RSS: Main: 283967488 19:06:59 INFO - PID 1668 | 19:07:01 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 19:07:01 INFO - PID 1668 | RSS: Main: 285122560 19:07:01 INFO - PID 1668 | 19:07:03 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:04 INFO - PID 1668 | RSS: Main: 285552640 19:07:04 INFO - PID 1668 | 19:07:07 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:07 INFO - PID 1668 | RSS: Main: 291237888 19:07:07 INFO - PID 1668 | 19:07:10 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:11 INFO - PID 1668 | RSS: Main: 294125568 19:07:11 INFO - PID 1668 | 19:07:14 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 19:07:15 INFO - PID 1668 | RSS: Main: 293773312 19:07:15 INFO - PID 1668 | 19:07:16 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:17 INFO - PID 1668 | RSS: Main: 292888576 19:07:17 INFO - PID 1668 | 19:07:18 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:19 INFO - PID 1668 | RSS: Main: 292798464 19:07:19 INFO - PID 1668 | 19:07:21 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:22 INFO - PID 1668 | RSS: Main: 292638720 19:07:22 INFO - PID 1668 | 19:07:25 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:26 INFO - PID 1668 | RSS: Main: 291966976 19:07:26 INFO - PID 1668 | 19:07:29 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:07:29 INFO - PID 1668 | RSS: Main: 292196352 19:07:29 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 19:07:31 INFO - PID 1668 | RSS: Main: 293855232 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | __start_tp_report 19:07:31 INFO - PID 1668 | _x_x_mozilla_page_load 19:07:31 INFO - PID 1668 | _x_x_mozilla_page_load_details 19:07:31 INFO - PID 1668 | |i|pagename|runs| 19:07:31 INFO - PID 1668 | |0;ai-astar;102;117;114;115;115;116;116;115;117;115 19:07:31 INFO - PID 1668 | |1;audio-beat-detection;147;111;116;131;131;149;141;137;139;110 19:07:31 INFO - PID 1668 | |2;audio-dft;198;198;195;201;223;214;223;196;204;196 19:07:31 INFO - PID 1668 | |3;audio-fft;81;58;61;84;58;60;82;71;80;49 19:07:31 INFO - PID 1668 | |4;audio-oscillator;87;70;80;71;69;68;84;70;72;89 19:07:31 INFO - PID 1668 | |5;imaging-gaussian-blur;89;90;88;89;89;88;89;89;89;94 19:07:31 INFO - PID 1668 | |6;imaging-darkroom;97;96;97;96;100;97;96;96;98;96 19:07:31 INFO - PID 1668 | |7;imaging-desaturate;92;132;144;94;91;91;100;99;139;146 19:07:31 INFO - PID 1668 | |8;json-parse-financial;51;50;49;53;51;49;53;52;51;52 19:07:31 INFO - PID 1668 | |9;json-stringify-tinderbox;57;59;66;66;67;61;56;54;56;65 19:07:31 INFO - PID 1668 | |10;stanford-crypto-aes;88;90;90;111;86;88;89;88;107;92 19:07:31 INFO - PID 1668 | |11;stanford-crypto-ccm;126;173;146;143;145;145;132;137;149;155 19:07:31 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;199;235;178;182;185;184;188;180;231;166 19:07:31 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;68;68;67;62;66;75;74;85;77;69 19:07:31 INFO - PID 1668 | __end_tp_report 19:07:31 INFO - PID 1668 | __start_cc_report 19:07:31 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1206 19:07:31 INFO - PID 1668 | __end_cc_report 19:07:31 INFO - PID 1668 | __startTimestamp1496714851754__endTimestamp 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | ------- Summary: start ------- 19:07:31 INFO - PID 1668 | Number of tests: 14 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:114.20 Median:115.50 stddev:4.39 (3.8%) stddev-sans-first:1.01 19:07:31 INFO - PID 1668 | Values: 102.0 117.0 114.0 115.0 115.0 116.0 116.0 115.0 117.0 115.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:131.20 Median:138.00 stddev:14.32 (10.4%) stddev-sans-first:14.00 19:07:31 INFO - PID 1668 | Values: 147.0 111.0 116.0 131.0 131.0 149.0 141.0 137.0 139.0 110.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:204.80 Median:202.50 stddev:11.08 (5.5%) stddev-sans-first:11.48 19:07:31 INFO - PID 1668 | Values: 198.0 198.0 195.0 201.0 223.0 214.0 223.0 196.0 204.0 196.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:68.40 Median:75.50 stddev:12.68 (16.8%) stddev-sans-first:12.60 19:07:31 INFO - PID 1668 | Values: 81.0 58.0 61.0 84.0 58.0 60.0 82.0 71.0 80.0 49.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:76.00 stddev:8.14 (10.7%) stddev-sans-first:7.60 19:07:31 INFO - PID 1668 | Values: 87.0 70.0 80.0 71.0 69.0 68.0 84.0 70.0 72.0 89.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:89.00 stddev:1.71 (1.9%) stddev-sans-first:1.81 19:07:31 INFO - PID 1668 | Values: 89.0 90.0 88.0 89.0 89.0 88.0 89.0 89.0 89.0 94.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.50 stddev:1.29 (1.3%) stddev-sans-first:1.36 19:07:31 INFO - PID 1668 | Values: 97.0 96.0 97.0 96.0 100.0 97.0 96.0 96.0 98.0 96.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:112.80 Median:91.00 stddev:24.09 (26.5%) stddev-sans-first:24.34 19:07:31 INFO - PID 1668 | Values: 92.0 132.0 144.0 94.0 91.0 91.0 100.0 99.0 139.0 146.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.45 (2.8%) stddev-sans-first:1.54 19:07:31 INFO - PID 1668 | Values: 51.0 50.0 49.0 53.0 51.0 49.0 53.0 52.0 51.0 52.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:60.70 Median:63.00 stddev:4.95 (7.8%) stddev-sans-first:5.06 19:07:31 INFO - PID 1668 | Values: 57.0 59.0 66.0 66.0 67.0 61.0 56.0 54.0 56.0 65.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:92.90 Median:88.50 stddev:8.69 (9.8%) stddev-sans-first:9.03 19:07:31 INFO - PID 1668 | Values: 88.0 90.0 90.0 111.0 86.0 88.0 89.0 88.0 107.0 92.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:145.10 Median:145.50 stddev:12.91 (8.9%) stddev-sans-first:11.69 19:07:31 INFO - PID 1668 | Values: 126.0 173.0 146.0 143.0 145.0 145.0 132.0 137.0 149.0 155.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.80 Median:186.50 stddev:22.75 (12.2%) stddev-sans-first:24.02 19:07:31 INFO - PID 1668 | Values: 199.0 235.0 178.0 182.0 185.0 184.0 188.0 180.0 231.0 166.0 19:07:31 INFO - PID 1668 | 19:07:31 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.10 Median:71.50 stddev:6.67 (9.3%) stddev-sans-first:6.98 19:07:31 INFO - PID 1668 | Values: 68.0 68.0 67.0 62.0 66.0 75.0 74.0 85.0 77.0 69.0 19:07:31 INFO - PID 1668 | -------- Summary: end -------- 19:07:31 INFO - PID 1668 | 19:07:32 INFO - TEST-INFO | 1668: exit 0 19:07:32 INFO - TEST-OK | kraken | took 89800ms 19:07:32 INFO - SUITE-END | took 1233s 19:07:33 INFO - Completed test suite (00:20:33) 19:07:33 INFO - javascript benchmark 19:07:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7990.879991729565, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3147.638021627512, 3227.48360262358, 3407.2844702612433, 3505.842237099331, 3564.643535646437, 5493.368262649797, 5913.172155898178, 6173.598716083463, 6188.845278868025, 6223.626582405059, 6102.88313068173, 6106.725197366118, 6134.436587033451, 6157.599756015856, 6162.938370616293, 4303.376010478479, 5347.037533244014, 5357.357117145948, 5361.839144825662, 5363.54409875161, 4415.712978656393, 5370.060239458098, 5416.620836541444, 5442.319710036246, 5497.945020549783, 5243.213517972296, 5293.602979776532, 5364.409914909357, 5400.405955344948, 5431.429699881515, 5336.4129945705945, 5359.598030147716, 5373.140297552372, 5387.757550910218, 5411.540019098381, 5276.630635855492, 5330.413654498002, 5332.546733527654, 5334.493223143795, 5348.732563371855, 5230.189320655267, 5723.833512057666, 6018.3981601839905, 6205.968970155165, 6228.626282423023, 5299.655522391032, 6170.2904166021035, 6499.350064993463, 6540.215174179081, 6553.9344606553805, 6662.233843108056, 6671.366220209073, 6707.128073350482, 6712.5301228913995, 6734.7642832501115, 6211.534134939852, 6221.9377806222265, 6233.937660623335, 6238.376162383771, 6241.9999999999545, 6616.172978377703, 6729.932700672931, 6772.424343930723, 6818.18181818185, 6893.620850853151, 5464.371597266286, 6252.5935814171935, 6298.244210694746, 6445.613263204223, 6460.3216662250725, 5412.512873841373, 6704.463642908556, 6709.7316107355155, 6726.091977582963, 6735.562188457735, 6486.772962946275, 6491.642959637265, 6493.51298652603, 6494.967525162344, 6498.415142637185, 6331.113644089739, 6365.96817015912, 6461.773837915651, 6491.123698300763, 6498.870022599616, 4854.077725232195, 5131.589472842164, 5306.151015837448, 5326.147816349443, 5332.226827110047, 4434.711743736647, 4703.365045718852, 4716.9292460613115, 4728.503507131771, 4748.762561871893, 6201.627902325875, 6247.156633854372, 6282.905756413657, 6292.528060395489, 6301.149344838388, 6688.966555167193, 6739.494537909676, 6751.966240168866, 6761.560498567577, 6777.356151165632, 6307.463865571478, 6327.335629758901, 6343.207099112611, 6346.650934198663, 6350.047492876013, 5678.176664383641, 5720.628159169641, 5777.768889244468, 5808.622439541501, 5898.0, 6835.829104272433, 6863.485238607124, 6881.724731010703, 6917.654117294216, 6919.930800691928, 6652.567583107082, 6672.132622759011, 6673.265940746611, 6677.592788759395, 6691.5315927884585, 6593.241777195684, 6660.800175994728, 6771.0, 6863.897041544481, 6866.896996545156, 6421.5825971311715, 6459.0, 6469.514786390945, 6482.773102941374, 6527.902081468686, 6680.331966803281, 6695.296993815579, 6703.229128650268, 6715.93284067153, 6722.462203023844, 6275.937240627535, 6319.083732858618, 6405.775797847053, 6429.03564465334, 6452.548321617442, 6477.611343319416, 6501.772437964649, 6512.283648798723, 6515.250746164252, 6533.509986750918, 5867.73843623623, 6150.261968563801, 6359.618422894641, 6363.300036996019, 6380.649064301322, 6283.088952102011, 6331.2085989251345, 6501.609903405811, 6506.02409638558, 6514.869702605826, 6309.274433440213, 6315.747370105144, 6325.4939604832425, 6330.683465826783, 6336.08126821599], "value": 5994.932386319347, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [791.1534657916029, 825.8719898415745, 847.5762118940528, 1266.461753754654, 1334.5862782537408, 37438.0, 38570.61429385712, 38611.22777544447, 38612.61387386125, 38639.80680096599, 38017.23965520688, 38457.03857403559, 38463.42304865422, 38473.61526384735, 38501.22997540054, 7711.2288771123, 7853.253940875607, 8071.31393831525, 8276.503409795432, 8338.791530211764, 8286.46138001031, 8370.790730231733, 8377.581120943958, 8379.078301386842, 8387.077421483631, 8252.092269850311, 8363.372747043966, 8373.706920257791, 8393.95075615548, 8426.957865210665, 8268.875966860502, 8316.542590157538, 8369.748907532723, 8372.58137093143, 8408.663653453916, 6974.651267436608, 7980.840383192303, 8044.436889417743, 8093.392995525301, 8118.10700822909, 35291.82354088239, 35476.403561839776, 35486.64513354885, 35692.464613030825, 35722.28555428877, 3549.716022718176, 3558.7864728116397, 3588.802615856127, 3594.137407022322, 3598.118460977051, 25626.359341016625, 28446.573301400495, 29606.70393296061, 29843.806247750064, 30056.699433005826, 2811.859407029661, 2978.37454134632, 2990.788730564125, 3071.4932036214, 3113.501839705636, 242885.0, 247485.0, 252820.0, 262079.68960155267, 289346.0, 47031.76484117557, 47162.29256561154, 47262.0, 47299.0, 47401.28898066532, 48524.51475485271, 49020.754896226004, 49078.0, 49117.0, 49181.0, 14006.859931400759, 14016.369263383172, 14052.85947140536, 14064.507742228972, 14277.0, 11374.94312528432, 11427.0, 11510.884891151149, 11512.88487115135, 11516.30902145874, 13656.907447404184, 13693.246871421969, 13698.246596437291, 13795.379207935666, 13845.238511881944, 6155.0, 6206.286277078104, 6213.875722485615, 6216.409441103088, 6218.378162183836, 979.9412035277907, 983.4050399508337, 1004.6985904228702, 1027.3219675014454, 1039.6517166749225, 23012.30963071077, 24633.01467941298, 24636.384090397525, 24702.382440439134, 24834.758262086823, 1089.7657003744075, 1090.1170052257537, 1133.5069244878339, 1151.5221183209042, 1169.4094482286562, 11004.944975275232, 11391.120465123327, 12814.743705126031, 12820.038497112753, 12845.871541284654, 307534.0, 308552.0, 308733.4563327169, 309746.0, 309907.0, 52739.20891186635, 53187.734061329444, 53434.46565534295, 53448.0, 53693.73153134209, 1469.4930249064055, 1499.2353899511327, 1526.0538466151058, 1529.6787674588238, 1548.1717281254475, 22134.557308854055, 22466.438339041655, 22962.19632312861, 23230.0, 24118.63822042706, 36432.907012789656, 36832.81583592065, 36998.0, 48701.512984869696, 49447.011059779325, 281050.0, 283275.0, 285480.0, 288241.0, 289093.0, 33908.49137262993, 35240.82379588085, 35266.471002934464, 35413.82293088518, 35865.744698935414, 41243.0, 42508.9372765671, 43427.565724342356, 43677.344839826794, 43703.34444983264, 29647.110586682433, 30173.09480715582, 30945.690543094282, 31002.139871607775, 31042.223944400666, 24525.264242072768, 29479.85260073686, 29892.850535748054, 30684.84657576698, 30686.386272273983, 37661.0, 37783.0, 37852.81073594614, 37895.81052094722, 38017.429738553394, 29618.703812961594, 29902.551461728526, 30159.396812063198, 31041.379172415975, 31663.36673266568, 22339.10643574239, 23960.760392395852, 24179.75820241775, 24436.0, 24539.38651533726], "value": 17360.386536078975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [397.72159488358136, 437.33306707271424, 445.11200155689403, 445.2193851959777, 449.98875028124274, 21783.45541361463, 22399.10403583857, 23195.76804231957, 23201.0, 23541.646875296883, 19019.144138513766, 20608.484787880312, 24039.39901502463, 24691.259262222116, 24776.87611561944, 6084.787032453876, 6167.198264225644, 6232.0028795392745, 6233.065040243954, 6273.7176827042895, 6161.630302181861, 6172.166757487749, 6206.441420272175, 6226.283977342596, 6231.532635052389, 5965.433283838051, 6044.667543285118, 6084.634921904678, 6084.6349219047, 6094.299155597118, 5576.33084029917, 5660.858478538028, 5673.23411339468, 5707.77168913243, 5721.570882183832, 4440.911181776379, 4985.750712464363, 5046.84859454217, 5121.564667003309, 5134.0, 28524.859005639748, 28955.71044289572, 29334.559981600294, 29365.26586835346, 29381.11856644304, 2115.989420052905, 2172.880491572963, 2173.119886445992, 2174.8695078295195, 2186.387811412807, 18483.722744158847, 18776.0, 18855.245790168377, 18956.146973386232, 18972.430827075208, 2129.808317251455, 2170.533335332911, 2172.945676358088, 2190.8466407351493, 2202.0421116814236, 162929.18535407248, 170578.0, 171721.0, 178943.0, 178974.0, 12178.878211217952, 12688.49246030167, 13074.673133171556, 13252.469901204036, 13281.0, 15771.44799931997, 16616.83383166177, 16683.582910427336, 16692.833071669127, 16718.08050557231, 8021.478603890729, 8275.08974012858, 8809.33929955256, 9178.954105229564, 9232.7691807704, 8577.25527831088, 8769.429987050822, 8825.07336729647, 8885.06706795777, 8913.108689131035, 8254.54599996994, 8343.374246931504, 8376.623051962677, 8382.664693412316, 8429.114942930904, 2214.1032881682945, 2240.551889622082, 2241.2603840732518, 2244.2257421189665, 2407.16952651335, 845.4884794699118, 944.3749575806979, 951.4909523404945, 953.8569214617863, 985.9161971232382, 26151.869240653676, 27898.860505697343, 28134.43731125366, 28197.0, 28232.71767282342, 929.4934260827865, 1009.3489699144011, 1011.5650270383659, 1029.3978022856563, 1046.706922061824, 8872.778180545409, 10263.12763415118, 10507.159427245802, 10573.894261057445, 10969.738480074733, 499856.0, 545915.0, 574358.0, 575268.0, 584573.0, 121815.0, 140315.0, 142879.2856035713, 145747.0, 147639.0, 1190.595197632809, 1218.7501562179732, 1223.7001934526097, 1240.6588188248304, 1241.7330273991136, 18166.5458363542, 20270.695939561214, 21804.781952180274, 22054.228102016354, 23004.07983680701, 61883.38116618776, 62165.0, 62355.75288494295, 63288.0, 63518.36481635125, 81593.59203204184, 82530.58734706289, 82597.0, 82958.0, 82979.0, 10056.29605927578, 10132.49337533135, 10263.255246608243, 10292.919243479328, 10300.278980471594, 16110.275037623569, 16962.830371696124, 17814.376496822548, 18011.819881801013, 18168.818311816714, 14085.08446950945, 14128.858711412755, 14215.857841421453, 14322.14067155974, 14369.712605748036, 12978.80531792005, 13165.53920612774, 13256.963140267964, 13381.0, 13581.728365432438, 14435.845132389593, 14804.185769782334, 14863.628409289855, 15051.021683590532, 15063.849361506245, 10003.849942250727, 10058.798824023625, 10081.899181008095, 10164.64423745195, 10165.542550585093, 14280.785788212977, 14439.566812995627, 14495.0, 14522.85477145215, 14587.343569539184], "value": 11084.140351791219, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1426.4793350427094, 1456.570311758031, 1470.3236511204846, 1490.3293517916934, 1492.9477468288605, 11419.771604567914, 11856.407179641026, 11919.404029798498, 11930.403479826007, 12254.203476774026, 11834.171607987444, 12008.159428839985, 12021.879781202208, 12036.939815300912, 12094.213876098069, 2516.6350879122515, 2557.3734435063343, 2557.693076830783, 2563.3976015636404, 2570.8586027768465, 2459.077845807822, 2474.257722683197, 2496.613024981131, 2505.8496490210646, 2508.8871000805007, 2472.320111969205, 2507.7743003129713, 2508.3102146909564, 2519.685039370079, 2527.721950585434, 2428.7571242875747, 2459.532688789124, 2463.6797216361847, 2464.568700477418, 2464.6056630939142, 2450.656908032877, 2481.1812102006293, 2484.1553871683714, 2509.2095989763347, 2530.7595778401205, 9713.417194968324, 11690.240134391237, 11712.882871171265, 11716.765664686744, 11737.53049878004, 6405.5516113872045, 6558.573692709959, 6628.867422651569, 6639.136912201431, 6657.1345725055435, 3391.270876761508, 3852.3451013327804, 3998.5801490843332, 4067.4712287402745, 4069.491313585802, 1215.3133479584008, 1244.9813252801216, 1260.2690439545042, 1274.0953922714884, 1289.2844471318415, 15274.541763747106, 15741.134237617041, 16342.0, 16355.018698877986, 16455.012699237963, 5486.61593688438, 5497.8075767347955, 5685.061964775766, 5701.657900525982, 5707.571932105108, 10363.430011349448, 12255.387230638433, 12320.75358492825, 12351.25892446456, 12354.25874447536, 6175.814725558241, 6221.906671399933, 6320.778772742932, 6509.837254068686, 6760.1549806274215, 5975.282966044103, 6045.516358691294, 6179.16581261533, 6201.379862013853, 6202.968985155046, 4347.282698354736, 4399.23013472641, 4417.094495628388, 4439.045605194899, 4453.755043472575, 1285.2095960983927, 1287.6394609509352, 1304.589054447846, 1309.3256972659087, 1310.1614966421498, 814.470594113819, 820.4789958376474, 843.8945131858518, 853.6585365853708, 876.8816209811594, 5552.861178470571, 6254.405831446005, 6504.414602685686, 6577.375149360803, 6724.587836554379, 557.9107342825129, 587.166223961975, 589.3311091910626, 599.2509363295881, 614.1248720573166, 3982.8008599569907, 4447.555244475591, 4538.410006699174, 4551.271796512542, 4741.762911854394, 12413.503459861686, 12808.423620937081, 14590.051646642934, 15071.623209419633, 15080.924595376953, 14320.212388318534, 14624.853751462562, 14727.895407844455, 14862.033967792058, 14912.70174596502, 8130.146334634896, 8523.48859068458, 8667.089955554577, 8674.132586741276, 8704.6518139275, 6581.440577550962, 6926.445884329342, 6971.7211311549, 7017.508774385744, 7259.782206533812, 11634.53461861516, 12678.619641410773, 12687.80968285495, 12843.357832108544, 13012.349382530647, 10245.538950747383, 13948.674875886774, 14001.22993235382, 14328.856711432752, 14392.280385980868, 7840.803979900548, 9578.808423831346, 9722.708318750167, 9738.513074346396, 9739.805203896025, 10650.201234907257, 10787.568497260021, 10820.350778953289, 10843.67468975932, 10878.40168790724, 9175.724728258163, 9199.862002070107, 9215.0, 9224.077592240723, 9258.95370523143, 8922.598483068148, 8970.147835955573, 9027.638894444148, 9038.819223615623, 9217.262618990599, 8075.919240807517, 8146.633401496828, 8237.135100814328, 8243.464174828618, 8248.051474080557, 6064.878702426015, 6184.81445556634, 6367.299597044414, 6370.458511026614, 6522.21733391996, 6198.0, 6330.0, 6419.069234960998, 6525.119108920235, 6551.770688026087], "value": 5326.752717857332, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.375283231686, 379.57677189933213, 384.3178358413815, 394.0837552689995, 408.7629175078455, 5245.580353571715, 5258.789648414064, 5782.987977104011, 6332.145160403345, 6449.51628627853, 5544.362398324194, 6057.575969682124, 6277.497800175975, 6282.811515654526, 6342.968285158579, 3439.7936123832587, 4294.377315289281, 4600.309953506984, 4601.6548758843055, 4654.557817007396, 4612.630989520847, 4615.561521655437, 4628.074385122989, 4636.884077898063, 4653.650976176783, 3785.413260944544, 4578.450585929677, 4591.701539399928, 4596.471405788327, 4598.816047358102, 3762.322781899257, 3985.0, 4545.11370282795, 4593.701409408378, 4596.701649175412, 3363.1928337199142, 3885.6502914737807, 3923.4507168996365, 3945.0, 3949.5063117110362, 5106.668066575698, 5388.272583201257, 6289.0, 6309.3690630937, 6309.684515774193, 5487.612888393562, 5508.641938274, 5510.862228444281, 5519.558435325164, 5534.169874518814, 5546.500814926636, 5616.5787565932305, 6523.380278873499, 6804.863902721967, 6830.658467076626, 3940.271049855757, 4134.751914885116, 4191.8532851350365, 4471.323253779832, 4502.189605870942, 6281.309056003836, 7030.7539236127, 7101.715931362739, 7163.319484649002, 7185.0, 4515.683902126885, 4540.455145382509, 4561.612262957619, 4563.612092972068, 4618.884527886763, 3536.9485698294925, 4428.933565996513, 4615.499962512197, 4654.627629789608, 4667.386263692343, 5016.0, 5017.523335283142, 5108.948910510921, 5506.32057222543, 5614.9157762633595, 6230.0, 6231.3145553989025, 6416.4225219729515, 6834.931650683429, 6838.863222735517, 1586.365453818482, 1716.5536960390389, 1768.6551122530982, 1786.7587875636882, 1792.3278770461077, 1092.8633920759905, 1128.8814674459227, 1227.778360531277, 1234.8332975048434, 1239.7706424311532, 5541.33503979525, 6244.469220116341, 6399.808005759742, 6411.807645770635, 6465.515086368541, 6675.566088204251, 7050.964745176241, 7063.752768653072, 7074.594637823062, 7114.893276600855, 5261.763750406139, 5343.49236822509, 6423.197100362455, 6441.355864413615, 6461.352355149407, 3131.7338026267566, 3360.2943381889586, 3643.198496330803, 3691.57546882112, 3700.148965737895, 7285.4171666266575, 7295.522089857832, 7304.306090921354, 7313.48805583604, 7329.230430804689, 6646.6676666166495, 7254.5647261164495, 7286.052813134364, 7288.198298187195, 7297.270272972766, 6470.708818103197, 6903.585784852925, 7291.197968223491, 7329.680657481598, 7365.201223535022, 5760.423957604206, 6398.264199617103, 6584.637844918384, 6680.665966701548, 7138.643067846483, 5451.7001564912725, 5512.4211957743855, 5756.30924289088, 6718.958561422987, 6875.865482195381, 6101.4544164597655, 6344.651044192429, 6745.898811517929, 7236.891446628199, 7288.453365997678, 4603.125406172817, 4603.470600880942, 4608.746518941491, 4619.653525985632, 4630.3286023525725, 7073.292670732885, 7111.644417779194, 7120.1099862517185, 7125.5368401053765, 7132.786016419515, 5692.48767610917, 5742.684152371659, 6759.087523184307, 6779.423748981392, 6864.48516361265, 6016.8796624068145, 6590.209174899042, 6831.282715315019, 6849.520533562602, 6875.896861547181, 6696.727621751852, 6715.731370745116, 6715.832104197239, 6716.630585317659, 6730.562513436611, 5906.763729450774, 6703.497237707289, 6843.0, 6873.518853680394, 6884.965575172092, 6605.306442823435, 6636.369544893228, 6649.46804255668, 6655.168103987002, 6677.799666010027], "value": 4880.636388223189, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [734.2070563791107, 830.1822704635937, 840.1388576708869, 856.6336692974703, 874.3180319350904, 16731.916340418295, 17154.57113572158, 17165.999999999985, 17220.741688874677, 17285.913570432145, 13446.596602101929, 15078.623034424145, 15540.922295388507, 16107.83892161078, 16497.83502164978, 930.2511478259999, 930.878985731854, 934.9813003739921, 938.2259635800458, 953.4041224234836, 978.1636700620974, 978.5400861595075, 980.9313348065639, 980.9362391444569, 985.23644175764, 994.2692121290845, 1031.2935639087211, 1034.095166729112, 1036.6630844975375, 1037.8339465685492, 1011.8633984412083, 1022.1516141602452, 1028.912542433894, 1029.2640761855273, 1032.385730490357, 738.4240292571802, 756.4780301591884, 757.5303311946574, 758.7420277105755, 762.675862758331, 17292.56768580783, 17307.913460432745, 17344.13279336028, 17416.825831741648, 17427.5643108922, 17193.484195474157, 17367.565810854703, 17652.646947061115, 17683.93896366222, 17738.29046838125, 14578.489752858599, 14809.815214782899, 14902.850971490254, 14908.254587270701, 14914.403423863141, 3161.7470602351755, 3624.6375362463805, 3627.2201476682376, 3629.2922880038404, 3847.826847791856, 117672.0, 144660.0, 147427.0, 149902.0, 150059.0, 13133.408996594984, 13583.252921089435, 13648.0, 13677.384517696728, 13857.099288546213, 25620.615690764655, 26272.34319142037, 26530.0, 26672.933082676474, 26716.931322746874, 7812.375009999187, 7830.804229894185, 8007.2793448588745, 8099.676012959533, 8111.351091912633, 3912.0611053346993, 4000.979995100006, 4003.839846406111, 4004.3593025115842, 4010.7192496525545, 3853.3641949078087, 4189.2459357315365, 4304.268274393361, 4314.094040251583, 4421.668374871898, 814.9633266503022, 933.5472295936465, 947.6398968392105, 955.4888134847823, 957.2963871554402, 638.4613082471222, 649.28254279021, 656.6191608866845, 672.9562578432386, 689.7930620813736, 11120.0, 11270.549178032787, 12593.433295501725, 13957.511487097903, 14099.154050756782, 622.7789134857151, 656.4092316914815, 657.1621182991723, 673.3502170405582, 675.3887731602907, 5310.0, 5482.2182790593215, 5748.5688573357165, 5797.710114494258, 6561.704723287464, 140568.07579665838, 142975.2851235758, 146871.0, 148077.86520561058, 152633.0, 92645.90604294982, 105247.4737626307, 106163.0, 108097.91902081035, 108885.91114088758, 97885.0, 98110.5094474523, 101210.0, 101891.0, 102858.0, 11857.525698971944, 12286.017118630309, 12452.377381131088, 12601.621951341474, 12702.618921432371, 40425.98935026648, 41135.0, 42977.78511107424, 44003.559964399945, 44157.0, 57990.710046449494, 58086.0, 58566.70716646389, 59490.40509594849, 59797.0, 16680.833191667927, 16862.241199146312, 16929.0, 16980.74528882043, 17061.0, 17424.82575174283, 18641.0, 18646.254149833854, 18752.812471875106, 18826.717599236297, 16872.0, 16928.74606880873, 17701.38045168413, 18240.72638910444, 18403.447896563124, 17275.7408638868, 17894.91052544729, 17946.37187698424, 18186.36347727823, 18250.543736406697, 15337.923310383376, 15399.230038498254, 15446.2276886153, 15463.226838658247, 15604.843951560339, 11662.549012971725, 11698.0, 12166.817497737718, 12525.123241373016, 12533.87466125327, 12628.621141365773, 13242.735145297233, 13875.861241387456, 14268.928655356656, 14462.132272063709], "value": 8680.642665062105, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1507.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 117.0, 114.0, 115.0, 115.0, 116.0, 116.0, 115.0, 117.0, 115.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 111.0, 116.0, 131.0, 131.0, 149.0, 141.0, 137.0, 139.0, 110.0], "value": 131.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 198.0, 195.0, 201.0, 223.0, 214.0, 223.0, 196.0, 204.0, 196.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 61.0, 84.0, 58.0, 60.0, 82.0, 71.0, 80.0, 49.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 80.0, 71.0, 69.0, 68.0, 84.0, 70.0, 72.0, 89.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 88.0, 89.0, 89.0, 88.0, 89.0, 89.0, 89.0, 94.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 100.0, 97.0, 96.0, 96.0, 98.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 132.0, 144.0, 94.0, 91.0, 91.0, 100.0, 99.0, 139.0, 146.0], "value": 112.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 50.0, 49.0, 53.0, 51.0, 49.0, 53.0, 52.0, 51.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 66.0, 66.0, 67.0, 61.0, 56.0, 54.0, 56.0, 65.0], "value": 60.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 90.0, 111.0, 86.0, 88.0, 89.0, 88.0, 107.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 173.0, 146.0, 143.0, 145.0, 145.0, 132.0, 137.0, 149.0, 155.0], "value": 145.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 235.0, 178.0, 182.0, 185.0, 184.0, 188.0, 180.0, 231.0, 166.0], "value": 192.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 62.0, 66.0, 75.0, 74.0, 85.0, 77.0, 69.0], "value": 71.1, "unit": "score"}]}]} 19:07:33 INFO - Return code: 0 19:07:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:33 INFO - # TBPL SUCCESS # 19:07:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:33 INFO - 2017-06-05 19:07:33.182 screenresolution[1676:11725] starting screenresolution argv=screenresolution get 19:07:33 INFO - 2017-06-05 19:07:33.185 screenresolution[1676:11725] Display 0: 1600x1200x32@60 19:07:33 INFO - 2017-06-05 19:07:33.191 screenresolution[1677:11728] starting screenresolution argv=screenresolution list 19:07:33 INFO - Available Modes on Display 0 19:07:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:33 INFO - Intel Iris: 19:07:33 INFO - Chipset Model: Intel Iris 19:07:33 INFO - Type: GPU 19:07:33 INFO - Bus: Built-In 19:07:33 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:33 INFO - Vendor: Intel (0x8086) 19:07:33 INFO - Device ID: 0x0a2e 19:07:33 INFO - Revision ID: 0x0009 19:07:33 INFO - Displays: 19:07:33 INFO - Display: 19:07:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:33 INFO - Display Serial Number: 1600x1200 60 19:07:33 INFO - Main Display: Yes 19:07:33 INFO - Mirror: Off 19:07:33 INFO - Online: Yes 19:07:33 INFO - Rotation: Supported 19:07:33 INFO - Return code: 0 19:07:33 INFO - Running post-action listener: _resource_record_post_action 19:07:33 INFO - [mozharness: 2017-06-06 02:07:33.428560Z] Finished run-tests step (success) 19:07:33 INFO - Running post-run listener: _resource_record_post_run 19:07:33 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 34685952; Write bytes: 573450240; Read time: 218; Write time: 1788 19:07:33 INFO - TinderboxPrint: CPU usage
53.5% 19:07:33 INFO - TinderboxPrint: I/O read bytes / time
34,685,952 / 218 19:07:33 INFO - TinderboxPrint: I/O write bytes / time
573,450,240 / 1,788 19:07:33 INFO - TinderboxPrint: CPU idle
2,339.0 (46.5%) 19:07:33 INFO - TinderboxPrint: CPU system
1,025.1 (20.4%) 19:07:33 INFO - TinderboxPrint: CPU user
1,661.7 (33.1%) 19:07:33 INFO - TinderboxPrint: Swap in / out
291,745,792 / 0 19:07:33 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 229581312; Write bytes: 227321856; Read time: 16790; Write time: 491 19:07:33 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 19:07:33 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 33731584; Write bytes: 323067904; Read time: 206; Write time: 1233 19:07:33 INFO - Running post-run listener: _upload_blobber_files 19:07:33 INFO - Blob upload gear active. 19:07:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:33 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 19:07:33 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'] 19:07:33 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 19:07:34 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:07:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:07:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:35 INFO - (blobuploader) - INFO - Done attempting. 19:07:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:07:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:07:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:37 INFO - (blobuploader) - INFO - Done attempting. 19:07:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:07:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:07:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:38 INFO - (blobuploader) - INFO - Done attempting. 19:07:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:07:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:07:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:40 INFO - (blobuploader) - INFO - Done attempting. 19:07:40 INFO - (blobuploader) - INFO - Iteration through files over. 19:07:40 INFO - Return code: 0 19:07:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:07:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:07:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3979537b84af20717bef705c9986757a6a1fb5330b34938d65dbdc13b7ca802367eed4a619e6388b289e7c18f2bdbf254a65d3952dabff55fc40dc080c9d3da4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d96d0c1a0de5f51b64f363409c9dbbfbf8e7b580cfcc661b8d75cb27425d0eadd9599bb68e2ad581bca2940fd87bfd2729809a0fbe4b78f2ead9bf3db3ba2f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17c199481f06c981467c261027463c68592652afb4cc2c9e49d95861285826e0f5e09771c050e2ad0ea9c3cd333dbcdab41312bf9b5afc1ddd3007d4de0bbad0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a625bd1fe66f2103ae3fa8be198ff41753cdd3a5819c092a1c97a82f07ec0dac54f3748ad8717900f02a5e26293142ce611825097948950c7600f59fcd2a098c"} 19:07:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:07:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:07:40 INFO - Contents: 19:07:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3979537b84af20717bef705c9986757a6a1fb5330b34938d65dbdc13b7ca802367eed4a619e6388b289e7c18f2bdbf254a65d3952dabff55fc40dc080c9d3da4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d96d0c1a0de5f51b64f363409c9dbbfbf8e7b580cfcc661b8d75cb27425d0eadd9599bb68e2ad581bca2940fd87bfd2729809a0fbe4b78f2ead9bf3db3ba2f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17c199481f06c981467c261027463c68592652afb4cc2c9e49d95861285826e0f5e09771c050e2ad0ea9c3cd333dbcdab41312bf9b5afc1ddd3007d4de0bbad0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a625bd1fe66f2103ae3fa8be198ff41753cdd3a5819c092a1c97a82f07ec0dac54f3748ad8717900f02a5e26293142ce611825097948950c7600f59fcd2a098c"} 19:07:40 INFO - Running post-run listener: copy_logs_to_upload_dir 19:07:40 INFO - Copying logs to upload dir... 19:07:40 INFO - mkdir: /builds/slave/test/build/upload/logs 19:07:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.024457 ========= master_lag: 0.38 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-05 19:07:40.965920) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 19:07:40.969820) ========= 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.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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/3979537b84af20717bef705c9986757a6a1fb5330b34938d65dbdc13b7ca802367eed4a619e6388b289e7c18f2bdbf254a65d3952dabff55fc40dc080c9d3da4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d96d0c1a0de5f51b64f363409c9dbbfbf8e7b580cfcc661b8d75cb27425d0eadd9599bb68e2ad581bca2940fd87bfd2729809a0fbe4b78f2ead9bf3db3ba2f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17c199481f06c981467c261027463c68592652afb4cc2c9e49d95861285826e0f5e09771c050e2ad0ea9c3cd333dbcdab41312bf9b5afc1ddd3007d4de0bbad0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a625bd1fe66f2103ae3fa8be198ff41753cdd3a5819c092a1c97a82f07ec0dac54f3748ad8717900f02a5e26293142ce611825097948950c7600f59fcd2a098c"} build_url:https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011025 build_url: 'https://queue.taskcluster.net/v1/task/Ax3PT8M7SJKb79DwvPiVsQ/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/3979537b84af20717bef705c9986757a6a1fb5330b34938d65dbdc13b7ca802367eed4a619e6388b289e7c18f2bdbf254a65d3952dabff55fc40dc080c9d3da4", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8d96d0c1a0de5f51b64f363409c9dbbfbf8e7b580cfcc661b8d75cb27425d0eadd9599bb68e2ad581bca2940fd87bfd2729809a0fbe4b78f2ead9bf3db3ba2f8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/17c199481f06c981467c261027463c68592652afb4cc2c9e49d95861285826e0f5e09771c050e2ad0ea9c3cd333dbcdab41312bf9b5afc1ddd3007d4de0bbad0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a625bd1fe66f2103ae3fa8be198ff41753cdd3a5819c092a1c97a82f07ec0dac54f3748ad8717900f02a5e26293142ce611825097948950c7600f59fcd2a098c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-05 19:07:41.003730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 19:07:41.004117) ========= 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.VUaqjxutO4/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.IkA7JXHwdD/Listeners TMPDIR=/var/folders/87/tdvlw3rj6yb17xk_t0qxk6xw00000w/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.004974 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-05 19:07:41.053676) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 19:07:41.054048) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-05 19:07:41.054442) ========= ========= Total master_lag: 1.78 =========