builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0306 starttime: 1497044239.38 results: success (0) revision: d0e38542a05b17dd87586281b5d324112d38f6ca ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.376613) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.377523) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.377845) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.432129) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.432497) ========= 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.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-09 14:37:19-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.1M=0.001s 2017-06-09 14:37:19 (19.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.132700 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.589479) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.589769) ========= 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.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.035582 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:19.648222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 14:37:19.648620) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev d0e38542a05b17dd87586281b5d324112d38f6ca --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 d0e38542a05b17dd87586281b5d324112d38f6ca --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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-09 14:37:19,744 truncating revision to first 12 chars 2017-06-09 14:37:19,744 Setting DEBUG logging. 2017-06-09 14:37:19,744 attempt 1/10 2017-06-09 14:37:19,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/d0e38542a05b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-09 14:37:20,945 unpacking tar archive at: mozilla-central-d0e38542a05b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.684741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-09 14:37:21.356656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:21.357008) ========= script_repo_revision: d0e38542a05b17dd87586281b5d324112d38f6ca ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:21.357426) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:21.357701) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-09 14:37:21.376168) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-09 14:37:21.376449) ========= /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.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:37:21 INFO - MultiFileLogger online at 20170609 14:37:21 in /builds/slave/test 14:37: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 14:37:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:37:21 INFO - {'append_to_log': False, 14:37:21 INFO - 'base_work_dir': '/builds/slave/test', 14:37:21 INFO - 'blob_upload_branch': 'Firefox', 14:37:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:37:21 INFO - 'branch': 'Firefox', 14:37:21 INFO - 'buildbot_json_path': 'buildprops.json', 14:37:21 INFO - 'config_files': ('talos/mac_config.py',), 14:37:21 INFO - 'default_actions': ('clobber', 14:37:21 INFO - 'read-buildbot-config', 14:37:21 INFO - 'download-and-extract', 14:37:21 INFO - 'populate-webroot', 14:37:21 INFO - 'create-virtualenv', 14:37:21 INFO - 'install', 14:37:21 INFO - 'setup-mitmproxy', 14:37:21 INFO - 'run-tests'), 14:37:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:37:21 INFO - 'download_minidump_stackwalk': True, 14:37:21 INFO - 'download_symbols': 'ondemand', 14:37:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:37:21 INFO - 'tooltool.py': '/tools/tooltool.py', 14:37:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:37:21 INFO - '/tools/misc-python/virtualenv.py')}, 14:37:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:37:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:37:21 INFO - 'gecko_profile': False, 14:37:21 INFO - 'gecko_profile_interval': 0, 14:37:21 INFO - 'installer_path': 'installer.exe', 14:37:21 INFO - 'log_level': 'info', 14:37:21 INFO - 'log_name': 'talos', 14:37:21 INFO - 'log_to_console': True, 14:37:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:37:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:37:21 INFO - 'opt_config_files': (), 14:37:21 INFO - 'pip_index': False, 14:37:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:37:21 INFO - 'cmd': ('bash', 14:37:21 INFO - '-c', 14:37:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:37:21 INFO - 'enabled': True, 14:37:21 INFO - 'halt_on_failure': False, 14:37:21 INFO - 'name': 'check_screen_resolution'},), 14:37:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:37:21 INFO - 'cmd': ('bash', 14:37:21 INFO - '-c', 14:37:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:37:21 INFO - 'enabled': True, 14:37:21 INFO - 'halt_on_failure': False, 14:37:21 INFO - 'name': 'check_screen_resolution'},), 14:37:21 INFO - 'run_cmd_checks_enabled': True, 14:37:21 INFO - 'suite': 'dromaeojs-e10s', 14:37:21 INFO - 'system_bits': '32', 14:37:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:37:21 INFO - 'title': 't-yosemite-r7-0306', 14:37:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:37:21 INFO - 'use_talos_json': True, 14:37:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:37:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:37:21 INFO - 'work_dir': 'build'} 14:37:21 INFO - [mozharness: 2017-06-09 21:37:21.578525Z] Running clobber step. 14:37:21 INFO - Running pre-action listener: _resource_record_pre_action 14:37:21 INFO - Running main action method: clobber 14:37:21 INFO - rmtree: /builds/slave/test/build 14:37:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:37:24 INFO - Running post-action listener: _resource_record_post_action 14:37:24 INFO - [mozharness: 2017-06-09 21:37:24.050941Z] Finished clobber step (success) 14:37:24 INFO - [mozharness: 2017-06-09 21:37:24.051063Z] Running read-buildbot-config step. 14:37:24 INFO - Running pre-action listener: _resource_record_pre_action 14:37:24 INFO - Running main action method: read_buildbot_config 14:37:24 INFO - Using buildbot properties: 14:37:24 INFO - { 14:37:24 INFO - "project": "", 14:37:24 INFO - "product": "firefox", 14:37:24 INFO - "who": "kwierso@gmail.com", 14:37:24 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 14:37:24 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:37:24 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 14:37:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:37:24 INFO - "stage_platform": "macosx64", 14:37:24 INFO - "basedir": "/builds/slave/test", 14:37:24 INFO - "buildnumber": 90, 14:37:24 INFO - "platform": "yosemite_r7", 14:37:24 INFO - "parent_task_id": "Naaq1xLeReWGadCkb5pBAQ", 14:37:24 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:37:24 INFO - "slavebuilddir": "test", 14:37:24 INFO - "taskId": "C5kZxXnjTzKIt6MsDOnLHw", 14:37:24 INFO - "branch": "mozilla-central", 14:37:24 INFO - "script_repo_revision": "production", 14:37:24 INFO - "revision": "d0e38542a05b17dd87586281b5d324112d38f6ca", 14:37:24 INFO - "slavename": "t-yosemite-r7-0306", 14:37:24 INFO - "repo_path": "mozilla-central" 14:37:24 INFO - } 14:37:24 INFO - Finding installer, test and symbols from parent task. 14:37:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5kZxXnjTzKIt6MsDOnLHw'}, attempt #1 14:37:24 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/C5kZxXnjTzKIt6MsDOnLHw'}, attempt #1 14:37:25 INFO - Task dependencies: Ve-49A6wRAiPUP6ts9ulRQ Naaq1xLeReWGadCkb5pBAQ 14:37:25 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ'}, attempt #1 14:37:25 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:37:25 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/test_packages.json 14:37:25 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/target.crashreporter-symbols.zip 14:37:25 INFO - Running post-action listener: _resource_record_post_action 14:37:25 INFO - [mozharness: 2017-06-09 21:37:25.808926Z] Finished read-buildbot-config step (success) 14:37:25 INFO - [mozharness: 2017-06-09 21:37:25.809039Z] Running download-and-extract step. 14:37:25 INFO - Running pre-action listener: _resource_record_pre_action 14:37:25 INFO - Running main action method: download_and_extract 14:37:25 INFO - mkdir: /builds/slave/test/build/tests 14:37: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')]} 14:37:25 INFO - trying https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 14:37:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/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 14:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/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 14:37:26 INFO - Downloaded 1328 bytes. 14:37:26 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 14:37:26 INFO - Using the following test package requirements: 14:37:26 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 14:37:26 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:37:26 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 14:37:26 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 14:37:26 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'jsshell-mac.zip'], 14:37:26 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 14:37:26 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 14:37:26 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 14:37:26 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 14:37:26 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.tar.gz'], 14:37:26 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 14:37:26 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 14:37:26 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:37:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:37:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:37:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:37:28 INFO - Content-Length response header: 59541416 14:37:28 INFO - Bytes received: 59541416 14:37: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 14:37:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 14:37:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:37:46 INFO - Fetch https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 14:37:49 INFO - Content-Length response header: 59541416 14:37:49 INFO - Bytes received: 59541416 14:38:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 14:38:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:38:07 INFO - Fetch https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 14:38:07 INFO - Content-Length response header: 14449999 14:38:07 INFO - Bytes received: 14449999 14:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:08 INFO - trying https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:38:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 14:38:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/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 14:38:10 INFO - Downloaded 69300615 bytes. 14:38:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:38:10 INFO - mkdir: /builds/slave/test/properties 14:38:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:38:10 INFO - Writing to file /builds/slave/test/properties/build_url 14:38:10 INFO - Contents: 14:38:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 14:38:10 INFO - Running post-action listener: _resource_record_post_action 14:38:10 INFO - Running post-action listener: set_extra_try_arguments 14:38:10 INFO - [mozharness: 2017-06-09 21:38:10.197271Z] Finished download-and-extract step (success) 14:38:10 INFO - [mozharness: 2017-06-09 21:38:10.197423Z] Running populate-webroot step. 14:38:10 INFO - Running pre-action listener: _resource_record_pre_action 14:38:10 INFO - Running main action method: populate_webroot 14:38:10 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:38:10 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:38:10 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:38:10 INFO - '--tppagecycles', 14:38:10 INFO - '7'], 14:38:10 INFO - 'tests': ['tsvgm']}}, 14:38:10 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:38:10 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:38:10 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:38:10 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:38:10 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:38:10 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['damp', 'tps']}, 14:38:10 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'tests': ['damp', 'tps']}, 14:38:10 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['dromaeo_dom']}, 14:38:10 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:38:10 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:38:10 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:38:10 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:38:10 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'other-e10s': {'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['a11yr', 14:38:10 INFO - 'ts_paint', 14:38:10 INFO - 'tpaint', 14:38:10 INFO - 'sessionrestore', 14:38:10 INFO - 'sessionrestore_no_auto_restore', 14:38:10 INFO - 'tabpaint']}, 14:38:10 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:38:10 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:38:10 INFO - 'bloom_basic_ref']}, 14:38:10 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:38:10 INFO - 'talos_options': ['--mitmproxy', 14:38:10 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:38:10 INFO - '--firstNonBlankPaint'], 14:38:10 INFO - 'tests': ['quantum_pageload_google', 14:38:10 INFO - 'quantum_pageload_youtube', 14:38:10 INFO - 'quantum_pageload_amazon', 14:38:10 INFO - 'quantum_pageload_facebook']}, 14:38:10 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['tsvgx', 14:38:10 INFO - 'tsvgr_opacity', 14:38:10 INFO - 'tart', 14:38:10 INFO - 'tscrollx', 14:38:10 INFO - 'cart', 14:38:10 INFO - 'tsvg_static']}, 14:38:10 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:38:10 INFO - 'tsvgr_opacity', 14:38:10 INFO - 'tart', 14:38:10 INFO - 'tscrollx', 14:38:10 INFO - 'cart', 14:38:10 INFO - 'tsvg_static']}, 14:38:10 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'talos_options': ['--disable-e10s'], 14:38:10 INFO - 'tests': ['tp5o']}, 14:38:10 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:38:10 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'talos_options': ['--disable-e10s', 14:38:10 INFO - '--xperf_path', 14:38:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:38:10 INFO - 'tests': ['tp5n']}, 14:38:10 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:38:10 INFO - 'talos_options': ['--xperf_path', 14:38:10 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:38:10 INFO - 'tests': ['tp5n']}}, 14:38:10 INFO - 'talos.zip': {'path': '', 14:38:10 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:38:10 INFO - Running post-action listener: _resource_record_post_action 14:38:10 INFO - [mozharness: 2017-06-09 21:38:10.220163Z] Finished populate-webroot step (success) 14:38:10 INFO - [mozharness: 2017-06-09 21:38:10.220299Z] Running create-virtualenv step. 14:38:10 INFO - Running pre-action listener: _resource_record_pre_action 14:38:10 INFO - Running main action method: create_virtualenv 14:38:10 INFO - Creating virtualenv /builds/slave/test/build/venv 14:38:10 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 14:38:10 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:38:10 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:38:10 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:38:10 INFO - Using real prefix '/tools/python27' 14:38:10 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:38:11 INFO - Installing distribute.............................................................................................................................................................................................done. 14:38:13 INFO - Installing pip.................done. 14:38:13 INFO - Return code: 0 14:38:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:38:14 INFO - Reading from file tmpfile_stdout 14:38:14 INFO - Output received: 14:38:14 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) 14:38:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:38: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')]} 14:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38: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')]} 14:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38: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', 'pip>=1.5'] in /builds/slave/test/build 14:38: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 pip>=1.5 14:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:14 INFO - 'HOME': '/Users/cltbld', 14:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:14 INFO - 'LOGNAME': 'cltbld', 14:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:14 INFO - 'MOZ_NO_REMOTE': '1', 14:38:14 INFO - 'NO_EM_RESTART': '1', 14:38:14 INFO - 'PAGER': '/bin/cat', 14:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:14 INFO - 'PWD': '/builds/slave/test', 14:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:14 INFO - 'SHELL': '/bin/bash', 14:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:14 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:14 INFO - 'USER': 'cltbld', 14:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:14 INFO - 'XPC_FLAGS': '0x0', 14:38:14 INFO - 'XPC_SERVICE_NAME': '0', 14:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:14 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 14:38:14 INFO - Cleaning up... 14:38:14 INFO - Return code: 0 14:38:14 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:38: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')]} 14:38:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38: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')]} 14:38:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38: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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38: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 14:38: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 14:38:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:14 INFO - 'HOME': '/Users/cltbld', 14:38:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:14 INFO - 'LOGNAME': 'cltbld', 14:38:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:14 INFO - 'MOZ_NO_REMOTE': '1', 14:38:14 INFO - 'NO_EM_RESTART': '1', 14:38:14 INFO - 'PAGER': '/bin/cat', 14:38:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:14 INFO - 'PWD': '/builds/slave/test', 14:38:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:14 INFO - 'SHELL': '/bin/bash', 14:38:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:14 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:14 INFO - 'USER': 'cltbld', 14:38:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:14 INFO - 'XPC_FLAGS': '0x0', 14:38:14 INFO - 'XPC_SERVICE_NAME': '0', 14:38:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:14 INFO - Downloading/unpacking psutil>=3.1.1 14:38: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 14:38: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 14:38: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 14:38: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 14:38:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:38:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:16 INFO - Installing collected packages: psutil 14:38:16 INFO - Running setup.py install for psutil 14:38:16 INFO - building 'psutil._psutil_osx' extension 14:38: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 14:38: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 14:38: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 14:38: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 14:38:17 INFO - building 'psutil._psutil_posix' extension 14:38: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 14:38:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:38:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:38:17 INFO - ^ 14:38:17 INFO - 1 warning generated. 14:38: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 14:38:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:38:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:38:17 INFO - Successfully installed psutil 14:38:17 INFO - Cleaning up... 14:38:17 INFO - Return code: 0 14:38:17 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:38: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')]} 14:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:18 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 14:38:18 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 14:38:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:18 INFO - 'HOME': '/Users/cltbld', 14:38:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:18 INFO - 'LOGNAME': 'cltbld', 14:38:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:18 INFO - 'MOZ_NO_REMOTE': '1', 14:38:18 INFO - 'NO_EM_RESTART': '1', 14:38:18 INFO - 'PAGER': '/bin/cat', 14:38:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:18 INFO - 'PWD': '/builds/slave/test', 14:38:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:18 INFO - 'SHELL': '/bin/bash', 14:38:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:18 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:18 INFO - 'USER': 'cltbld', 14:38:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:18 INFO - 'XPC_FLAGS': '0x0', 14:38:18 INFO - 'XPC_SERVICE_NAME': '0', 14:38:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:38: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 14:38: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 14:38: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 14:38: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 14:38:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:38:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:38:20 INFO - Installing collected packages: mozsystemmonitor 14:38:20 INFO - Running setup.py install for mozsystemmonitor 14:38:20 INFO - Successfully installed mozsystemmonitor 14:38:20 INFO - Cleaning up... 14:38:20 INFO - Return code: 0 14:38:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:20 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:20 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 14:38:20 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 14:38:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:20 INFO - 'HOME': '/Users/cltbld', 14:38:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:20 INFO - 'LOGNAME': 'cltbld', 14:38:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:20 INFO - 'MOZ_NO_REMOTE': '1', 14:38:20 INFO - 'NO_EM_RESTART': '1', 14:38:20 INFO - 'PAGER': '/bin/cat', 14:38:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:20 INFO - 'PWD': '/builds/slave/test', 14:38:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:20 INFO - 'SHELL': '/bin/bash', 14:38:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:20 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:20 INFO - 'USER': 'cltbld', 14:38:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:20 INFO - 'XPC_FLAGS': '0x0', 14:38:20 INFO - 'XPC_SERVICE_NAME': '0', 14:38:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:21 INFO - Downloading/unpacking jsonschema==2.5.1 14:38: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 14:38: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 14:38: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 14:38: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 14:38:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:38:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:38: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 14:38: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 14:38: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 14:38: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 14:38:22 INFO - Downloading functools32-3.2.3-2.tar.gz 14:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:38:22 INFO - warning: no files found matching '*.txt' 14:38:22 INFO - no previously-included directories found matching 'build' 14:38:22 INFO - no previously-included directories found matching 'dist' 14:38:22 INFO - no previously-included directories found matching '.git*' 14:38:22 INFO - Installing collected packages: jsonschema, functools32 14:38:22 INFO - Running setup.py install for functools32 14:38:23 INFO - warning: no files found matching '*.txt' 14:38:23 INFO - no previously-included directories found matching 'build' 14:38:23 INFO - no previously-included directories found matching 'dist' 14:38:23 INFO - no previously-included directories found matching '.git*' 14:38:23 INFO - Successfully installed jsonschema functools32 14:38:23 INFO - Cleaning up... 14:38:23 INFO - Return code: 0 14:38:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:38:23 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')]} 14:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:23 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')]} 14:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:23 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:23 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 14:38:23 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 14:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:23 INFO - 'HOME': '/Users/cltbld', 14:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:23 INFO - 'LOGNAME': 'cltbld', 14:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:23 INFO - 'MOZ_NO_REMOTE': '1', 14:38:23 INFO - 'NO_EM_RESTART': '1', 14:38:23 INFO - 'PAGER': '/bin/cat', 14:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:23 INFO - 'PWD': '/builds/slave/test', 14:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:23 INFO - 'SHELL': '/bin/bash', 14:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:23 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:23 INFO - 'USER': 'cltbld', 14:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:23 INFO - 'XPC_FLAGS': '0x0', 14:38:23 INFO - 'XPC_SERVICE_NAME': '0', 14:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:38:23 INFO - Cleaning up... 14:38:23 INFO - Return code: 0 14:38:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:38:23 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')]} 14:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:23 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')]} 14:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:23 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:23 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 14:38:23 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 14:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:23 INFO - 'HOME': '/Users/cltbld', 14:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:23 INFO - 'LOGNAME': 'cltbld', 14:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:23 INFO - 'MOZ_NO_REMOTE': '1', 14:38:23 INFO - 'NO_EM_RESTART': '1', 14:38:23 INFO - 'PAGER': '/bin/cat', 14:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:23 INFO - 'PWD': '/builds/slave/test', 14:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:23 INFO - 'SHELL': '/bin/bash', 14:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:23 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:23 INFO - 'USER': 'cltbld', 14:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:23 INFO - 'XPC_FLAGS': '0x0', 14:38:23 INFO - 'XPC_SERVICE_NAME': '0', 14:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:23 INFO - Downloading/unpacking blobuploader==1.2.4 14:38:23 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 14:38:23 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 14:38:23 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 14:38:23 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 14:38:24 INFO - Downloading blobuploader-1.2.4.tar.gz 14:38:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:38:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:38:25 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 14:38:25 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 14:38:25 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 14:38:25 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 14:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:38:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:38:25 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 14:38:25 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 14:38:25 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 14:38:25 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 14:38:25 INFO - Downloading docopt-0.6.1.tar.gz 14:38:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:38:25 INFO - Installing collected packages: blobuploader, requests, docopt 14:38:25 INFO - Running setup.py install for blobuploader 14:38:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:38:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:38:26 INFO - Running setup.py install for requests 14:38:26 INFO - Running setup.py install for docopt 14:38:26 INFO - Successfully installed blobuploader requests docopt 14:38:26 INFO - Cleaning up... 14:38:26 INFO - Return code: 0 14:38:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38: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')]} 14:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38: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')]} 14:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:26 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:26 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 14:38:26 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 14:38:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:26 INFO - 'HOME': '/Users/cltbld', 14:38:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:26 INFO - 'LOGNAME': 'cltbld', 14:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:26 INFO - 'MOZ_NO_REMOTE': '1', 14:38:26 INFO - 'NO_EM_RESTART': '1', 14:38:26 INFO - 'PAGER': '/bin/cat', 14:38:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:26 INFO - 'PWD': '/builds/slave/test', 14:38:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:26 INFO - 'SHELL': '/bin/bash', 14:38:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:26 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:26 INFO - 'USER': 'cltbld', 14:38:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:26 INFO - 'XPC_FLAGS': '0x0', 14:38:26 INFO - 'XPC_SERVICE_NAME': '0', 14:38:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-BXLObI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-CwjWku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-VQiyEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-3h_e4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-sY8IsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-hx3lPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-Yaz2XD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-XzwW9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:38:27 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4qF5qf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-3NE0fC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-AjlVQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-REnHa3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-EbO2Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rFgFdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-sNCxkU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-yGEqkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:38:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:38:28 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gtMpNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:38:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:38:28 INFO - Running setup.py install for manifestparser 14:38:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:38:28 INFO - Running setup.py install for mozcrash 14:38:29 INFO - Running setup.py install for mozdebug 14:38:29 INFO - Running setup.py install for mozdevice 14:38:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Running setup.py install for mozfile 14:38:29 INFO - Running setup.py install for mozhttpd 14:38:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Running setup.py install for mozinfo 14:38:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Running setup.py install for mozInstall 14:38:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:38:29 INFO - Running setup.py install for mozleak 14:38:30 INFO - Running setup.py install for mozlog 14:38:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Running setup.py install for moznetwork 14:38:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Running setup.py install for mozprocess 14:38:30 INFO - Running setup.py install for mozprofile 14:38:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Running setup.py install for mozrunner 14:38:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:38:30 INFO - Running setup.py install for mozscreenshot 14:38:31 INFO - Running setup.py install for moztest 14:38:31 INFO - Running setup.py install for mozversion 14:38:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:38:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:38:31 INFO - Cleaning up... 14:38:31 INFO - Return code: 0 14:38:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:31 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:31 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 14:38:31 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 14:38:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:31 INFO - 'HOME': '/Users/cltbld', 14:38:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:31 INFO - 'LOGNAME': 'cltbld', 14:38:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:31 INFO - 'MOZ_NO_REMOTE': '1', 14:38:31 INFO - 'NO_EM_RESTART': '1', 14:38:31 INFO - 'PAGER': '/bin/cat', 14:38:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:31 INFO - 'PWD': '/builds/slave/test', 14:38:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:31 INFO - 'SHELL': '/bin/bash', 14:38:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:31 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:31 INFO - 'USER': 'cltbld', 14:38:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:31 INFO - 'XPC_FLAGS': '0x0', 14:38:31 INFO - 'XPC_SERVICE_NAME': '0', 14:38:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:38:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-hl8Wci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:38:31 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)) 14:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:38:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-jJ2v2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:38:31 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)) 14:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:38:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-nq5e4f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:38: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)) 14:38:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:38:31 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-nVYxtU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-gbHsR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-R743Zt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-SEZBuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-rdkUpB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-6IJ__g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-tpYdF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-fvbLEx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-s4BYWU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:38:32 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)) 14:38:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:38:32 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-4LZSJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:38:33 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)) 14:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:38:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-GmMhyH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:38:33 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)) 14:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-69faE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:38:33 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)) 14:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:38:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-HCcXSe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:38:33 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)) 14:38:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:38:33 INFO - Running setup.py (path:/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/pip-cHRZGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:38:33 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)) 14:38:33 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)) 14:38:33 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)) 14:38:33 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)) 14:38:33 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)) 14:38:33 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)) 14:38:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:38:33 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 14:38:33 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 14:38:33 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 14:38:33 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 14:38:34 INFO - Downloading blessings-1.6.tar.gz 14:38:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:38:34 INFO - Installing collected packages: blessings 14:38:34 INFO - Running setup.py install for blessings 14:38:35 INFO - Successfully installed blessings 14:38:35 INFO - Cleaning up... 14:38:35 INFO - Return code: 0 14:38:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:38:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:38:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:38:35 INFO - Reading from file tmpfile_stdout 14:38:35 INFO - Current package versions: 14:38:35 INFO - blessings == 1.6 14:38:35 INFO - blobuploader == 1.2.4 14:38:35 INFO - docopt == 0.6.1 14:38:35 INFO - functools32 == 3.2.3-2 14:38:35 INFO - jsonschema == 2.5.1 14:38:35 INFO - manifestparser == 1.1 14:38:35 INFO - mozInstall == 1.12 14:38:35 INFO - mozcrash == 1.0 14:38:35 INFO - mozdebug == 0.1 14:38:35 INFO - mozdevice == 0.51 14:38:35 INFO - mozfile == 1.2 14:38:35 INFO - mozhttpd == 0.7 14:38:35 INFO - mozinfo == 0.10 14:38:35 INFO - mozleak == 0.1 14:38:35 INFO - mozlog == 3.4 14:38:35 INFO - moznetwork == 0.27 14:38:35 INFO - mozprocess == 0.25 14:38:35 INFO - mozprofile == 0.28 14:38:35 INFO - mozrunner == 6.13 14:38:35 INFO - mozscreenshot == 0.1 14:38:35 INFO - mozsystemmonitor == 0.3 14:38:35 INFO - moztest == 0.8 14:38:35 INFO - mozversion == 1.4 14:38:35 INFO - psutil == 3.1.1 14:38:35 INFO - requests == 1.2.3 14:38:35 INFO - wsgiref == 0.1.2 14:38:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:38:35 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')]} 14:38:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:35 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')]} 14:38:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:35 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 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:35 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 14:38:35 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 14:38:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:35 INFO - 'HOME': '/Users/cltbld', 14:38:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:35 INFO - 'LOGNAME': 'cltbld', 14:38:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:35 INFO - 'MOZ_NO_REMOTE': '1', 14:38:35 INFO - 'NO_EM_RESTART': '1', 14:38:35 INFO - 'PAGER': '/bin/cat', 14:38:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:35 INFO - 'PWD': '/builds/slave/test', 14:38:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:35 INFO - 'SHELL': '/bin/bash', 14:38:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:35 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:35 INFO - 'USER': 'cltbld', 14:38:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:35 INFO - 'XPC_FLAGS': '0x0', 14:38:35 INFO - 'XPC_SERVICE_NAME': '0', 14:38:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:38:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:38:35 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:38:35 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 14:38:35 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 14:38:35 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 14:38:35 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 14:38:37 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:38:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:38:37 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)) 14:38:37 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)) 14:38:37 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)) 14:38:37 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)) 14:38:37 INFO - Installing collected packages: marionette-driver 14:38:37 INFO - Running setup.py install for marionette-driver 14:38:37 INFO - Successfully installed marionette-driver 14:38:37 INFO - Cleaning up... 14:38:37 INFO - Return code: 0 14:38:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:38:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:38:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:38:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f9fcc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa00768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9f33db0810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, '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.7cZMACNyki/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.9TYTW6ZdeN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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 14:38:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:38:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:38:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:38:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:38:37 INFO - 'HOME': '/Users/cltbld', 14:38:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:38:37 INFO - 'LOGNAME': 'cltbld', 14:38:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:38:37 INFO - 'MOZ_NO_REMOTE': '1', 14:38:37 INFO - 'NO_EM_RESTART': '1', 14:38:37 INFO - 'PAGER': '/bin/cat', 14:38:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:38:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:38:37 INFO - 'PWD': '/builds/slave/test', 14:38:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:38:37 INFO - 'SHELL': '/bin/bash', 14:38:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:38:37 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:38:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:38:37 INFO - 'USER': 'cltbld', 14:38:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:38:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:38:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:38:37 INFO - 'XPC_FLAGS': '0x0', 14:38:37 INFO - 'XPC_SERVICE_NAME': '0', 14:38:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:38:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:38:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:38:37 INFO - Cleaning up... 14:38:37 INFO - Return code: 0 14:38:37 INFO - Running post-action listener: _resource_record_post_action 14:38:37 INFO - Running post-action listener: _start_resource_monitoring 14:38:37 INFO - Starting resource monitoring. 14:38:37 INFO - [mozharness: 2017-06-09 21:38:37.706976Z] Finished create-virtualenv step (success) 14:38:37 INFO - [mozharness: 2017-06-09 21:38:37.707963Z] Running install step. 14:38:37 INFO - Running pre-action listener: _resource_record_pre_action 14:38:37 INFO - Running main action method: install 14:38:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:38:37 INFO - Reading from file tmpfile_stdout 14:38:37 INFO - Detecting whether we're running mozinstall >=1.0... 14:38:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:38:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:38:38 INFO - Reading from file tmpfile_stdout 14:38:38 INFO - Output received: 14:38:38 INFO - Usage: mozinstall [options] installer 14:38:38 INFO - Options: 14:38:38 INFO - -h, --help show this help message and exit 14:38:38 INFO - -d DEST, --destination=DEST 14:38:38 INFO - Directory to install application into. [default: 14:38:38 INFO - "/builds/slave/test"] 14:38:38 INFO - --app=APP Application being installed. [default: firefox] 14:38:38 INFO - mkdir: /builds/slave/test/build/application 14:38:38 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'] 14:38:38 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 14:38:59 INFO - Reading from file tmpfile_stdout 14:38:59 INFO - Output received: 14:38:59 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:38:59 INFO - Running post-action listener: _resource_record_post_action 14:38:59 INFO - [mozharness: 2017-06-09 21:38:59.972910Z] Finished install step (success) 14:38:59 INFO - [mozharness: 2017-06-09 21:38:59.973092Z] Running setup-mitmproxy step. 14:38:59 INFO - Running pre-action listener: _resource_record_pre_action 14:38:59 INFO - Running main action method: setup_mitmproxy 14:38:59 INFO - Skipping: mitmproxy is not required 14:38:59 INFO - Running post-action listener: _resource_record_post_action 14:38:59 INFO - [mozharness: 2017-06-09 21:38:59.973698Z] Finished setup-mitmproxy step (success) 14:38:59 INFO - [mozharness: 2017-06-09 21:38:59.973840Z] Running run-tests step. 14:38:59 INFO - Running pre-action listener: _resource_record_pre_action 14:38:59 INFO - Running main action method: run_tests 14:38:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:38:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:38:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:38:59 INFO - 2017-06-09 14:38:59.988 screenresolution[1632:7671] starting screenresolution argv=screenresolution get 14:38:59 INFO - 2017-06-09 14:38:59.990 screenresolution[1632:7671] Display 0: 1600x1200x32@60 14:38:59 INFO - 2017-06-09 14:38:59.996 screenresolution[1633:7674] starting screenresolution argv=screenresolution list 14:38:59 INFO - Available Modes on Display 0 14:38:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:38:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:00 INFO - Intel Iris: 14:39:00 INFO - Chipset Model: Intel Iris 14:39:00 INFO - Type: GPU 14:39:00 INFO - Bus: Built-In 14:39:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:00 INFO - Vendor: Intel (0x8086) 14:39:00 INFO - Device ID: 0x0a2e 14:39:00 INFO - Revision ID: 0x0009 14:39:00 INFO - Displays: 14:39:00 INFO - Display: 14:39:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:00 INFO - Display Serial Number: 1600x1200 60 14:39:00 INFO - Main Display: Yes 14:39:00 INFO - Mirror: Off 14:39:00 INFO - Online: Yes 14:39:00 INFO - Rotation: Supported 14:39:00 INFO - Return code: 0 14:39:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:39:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:39:00 INFO - Python 2.7.3 14:39:00 INFO - Return code: 0 14:39:00 INFO - grabbing minidump binary from tooltool 14:39:00 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')]} 14:39:00 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 0x10f752d30>, '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 0x10f71ccd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f743be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:39:00 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 14:39:00 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 14:39:00 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 14:39:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:39:00 INFO - Return code: 0 14:39:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:39:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:39:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:39:00 INFO - ENV: RUST_BACKTRACE is now 1 14:39:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:39:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:39:00 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/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 14:39:00 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/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0306 --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 14:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7cZMACNyki/Render', 14:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:39:00 INFO - 'HOME': '/Users/cltbld', 14:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:39:00 INFO - 'LOGNAME': 'cltbld', 14:39:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:39:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:39:00 INFO - 'MOZ_NO_REMOTE': '1', 14:39:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:39:00 INFO - 'NO_EM_RESTART': '1', 14:39:00 INFO - 'PAGER': '/bin/cat', 14:39:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:39:00 INFO - 'PWD': '/builds/slave/test', 14:39:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:39:00 INFO - 'RUST_BACKTRACE': '1', 14:39:00 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:39:00 INFO - 'SHELL': '/bin/bash', 14:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9TYTW6ZdeN/Listeners', 14:39:00 INFO - 'TMPDIR': '/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/', 14:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:39:00 INFO - 'USER': 'cltbld', 14:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:39:00 INFO - 'XPC_FLAGS': '0x0', 14:39:00 INFO - 'XPC_SERVICE_NAME': '0', 14:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:39:00 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/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0306', '--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 14:39:00 INFO - mozversion application_buildid: 20170609133223 14:39:00 INFO - mozversion application_changeset: d0e38542a05b17dd87586281b5d324112d38f6ca 14:39:00 INFO - mozversion application_display_name: Nightly 14:39:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:39:00 INFO - mozversion application_name: Firefox 14:39:00 INFO - mozversion application_remotingname: firefox 14:39:00 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:39:00 INFO - mozversion application_vendor: Mozilla 14:39:00 INFO - mozversion application_version: 55.0a1 14:39:00 INFO - mozversion platform_buildid: 20170609133223 14:39:00 INFO - mozversion platform_changeset: d0e38542a05b17dd87586281b5d324112d38f6ca 14:39:00 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:39:00 INFO - mozversion platform_version: 55.0a1 14:39:00 INFO - using testdate: 1497044340 14:39:00 INFO - actual date: 1497044340 14:39:00 INFO - starting webserver on 'localhost:49237' 14:39:00 INFO - SUITE-START | Running 2 tests 14:39:00 INFO - TEST-START | dromaeo_css 14:39:00 INFO - operating with platform_type : mac_ 14:39:00 INFO - Initialising browser for dromaeo_css test... 14:39:00 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49237/getInfo.html -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmp0ooSgZ/profile 14:39:00 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49237/getInfo.html -foreground) 14:39:34 INFO - TEST-INFO | 1643: killed by SIGTERM 14:39:34 INFO - Browser initialized. 14:39:34 INFO - Running cycle 1/1 for dromaeo_css test... 14:39:34 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmp0ooSgZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:39:36 INFO - PID 1653 | 2017-06-09 14:39:36.484 plugin-container[1654:8421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9843, name = 'com.apple.tsm.portname' 14:39:36 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:36 INFO - PID 1653 | 2017-06-09 14:39:36.485 plugin-container[1654:8421] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 14:39:36 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:36 INFO - PID 1653 | 2017-06-09 14:39:36.485 plugin-container[1654:8421] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:37 INFO - PID 1653 | 2017-06-09 14:39:37.002 plugin-container[1655:8468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 14:39:37 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:37 INFO - PID 1653 | 2017-06-09 14:39:37.003 plugin-container[1655:8468] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x990b, name = 'com.apple.CFPasteboardClient' 14:39:37 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:39:37 INFO - PID 1653 | 2017-06-09 14:39:37.003 plugin-container[1655:8468] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:39:46 INFO - PID 1653 | RSS: Main: 255451136 14:39:46 INFO - PID 1653 | 14:42:48 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 14:42:50 INFO - PID 1653 | RSS: Main: 271163392 14:42:50 INFO - PID 1653 | 14:45:52 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 14:45:52 INFO - PID 1653 | RSS: Main: 282058752 14:45:52 INFO - PID 1653 | 14:48:54 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 14:48:55 INFO - PID 1653 | RSS: Main: 282095616 14:48:55 INFO - PID 1653 | 14:51:57 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 14:51:57 INFO - PID 1653 | RSS: Main: 287522816 14:51:57 INFO - PID 1653 | 14:55:00 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 14:55:01 INFO - PID 1653 | RSS: Main: 286605312 14:55:01 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 14:58:03 INFO - PID 1653 | RSS: Main: 285589504 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | __start_tp_report 14:58:03 INFO - PID 1653 | _x_x_mozilla_page_load 14:58:03 INFO - PID 1653 | _x_x_mozilla_page_load_details 14:58:03 INFO - PID 1653 | |i|pagename|runs| 14:58:03 INFO - PID 1653 | |0;dojo.html;3134.514150306702;3640.908977275568;3653.2328211075674;3664.7434679572425;3666.981665091674;6160.322364539897;6172.660503672302;6207.937920620792;6261.812145635627;6280.623162610246;6161.753529858812;6211.254649442062;6229.345918678541;6257.561970662044;6271.905921411182;4283.807228674702;4601.987562736193;5460;5469.371022332443;5479.589030822694;5523.751431185607;5561.5272701820395;5580.414056524068;5584.497395234428;5624.465675760796;5359.303290572239;5420.105682562373;5471.480209380103;5484.8354549363585;5500;5525.585581081435;5534.529564986981;5541.889162216733;5556.333240011184;5561.555075593983;5481.780728770845;5483.479069488392;5505.614606977513;5506.981208476444;5510.724463776835;6138.84652883677;6172.290186628506;6224.657643829588;6258.561900666955;6267.185265915447;6735.461163106939;6747.763828266036;6762.966185169043;6781.813182693009;6793.354631310018;5424.620276580641;5820.272465941757;6441.645709485976;6879.690413931385;6903.792886213421;6234.251889773165;6249.6562689051625;6302.590331628429;6336.3980421860315;6348.17473728417;6971.3028697129885;7020.859582808315;7021.087258656444;7034.2262351141335;7044.647767611599;6351.587146835441;6504.577202481823;6560.967195163994;6639.203295604461;6662.5003124765835;6843;6854.485913556503;6875.106236189264;6877.2091209510545;6886.208086070166;6510.44661203803;6587.242467116247;6703.195616526048;6752.155980502437;6786.694598743068;6648.501362397839;6669.599824010575;6747.426468750211;6769.593824370553;6827.897581536281;5404.054290499147;5428.565714742811;5429.239906413105;5452.182172674131;5464.644798088111;4744.478107408182;4814.542618451242;4816.903661926742;4849.515048495122;4871.391076115486;6134.2332208473945;6224.657643829542;6266.561340706198;6290.276618188876;6312;6865.588064716033;6872.965635171792;6873.175218973755;6877.3810357068105;6886.69009894556;4469.150861336336;5263.473652634706;6194.318624951342;6198.473129783929;6357.586756860789;4937.802487900515;5920.2895652521975;6054.182685337423;6077.392260773887;6083.11794789753;7017.228104908456;7017.649117544204;7023.29767023314;7029.753958611424;7030.121234845645;6854.451643868379;6858.279880612464;6888.896666550106;6896.103506544117;6917.9308206917285;6233.812985610439;6517.120188774455;6757.155355580552;6866.485013623898;7017.122859642544;5965.806838632204;6243.750249989949;6646.335366463315;6764.391204791593;6777.864442711019;6671.666416679243;6885.655717214019;6903.551269167488;6917.550359226634;6923.515353925177;5793.536517078539;6333.081703152925;6610.8016759497295;6628.337166283526;6649.667516624053;6619;6645.700943457653;6646.667666616747;6650.900236496554;6657.1345725055435;6403.615783053032;6472.449841763503;6491.123698300669;6495.0906873036865;6527;5502.58730595215;6405.19935008124;6468.935310646833;6477.190351206099;6665.50008749336;5126.974605079074;6390.840228994312;6416.48668106541;6435.839104022437;6455.548111632141 14:58:03 INFO - PID 1653 | |1;ext.html;783.3733013589131;818.6152508321089;844.6494704697549;1251.4618713952996;1310.9737805243901;38630.61369386305;40182.397264041065;40205.79897100518;40222.39666405003;40319.59680403195;40553.391699124535;40765.59234407655;40846.795766021125;41034.38448423276;41074.17851642965;7847.489913155655;8004.839903201932;8179.79550511236;8378.120297368749;8398.496090234605;8275.92412986311;8379.162083791603;8428.747137585882;8451.746447606582;8454.746357609281;8401.201885820838;8444.197801208875;8457.746267611981;8479.364047696417;8491.490510569354;8405.41162118652;8407.03319118303;8445.071042185355;8451.661933522651;8461.323094152453;8252.628631711588;8278.503289802571;8327.167283271685;8331;8334.833303333899;33186;34751.826240868635;37867.62132378668;37875.43186852199;37893.86318410452;3541.13242255649;3586.964130358689;3594.6045934947133;3595.9101022474388;3604.7116230701745;30302.393952120834;30585.847070764725;30685.846570767226;30739;30745.385092298253;3016.8793248270044;3038.4075105354464;3061.4336347775734;3082.660907300195;3090.3355778507503;248989;252033;271786.28213717806;302445;305773;47440.7627961858;47664;47836.5216347839;47844.28233576499;47867.760661197164;50855.23717144246;50952.74523627432;51043.744781275855;51084.48915510871;51101.744491277306;13698.45206191741;14083.366248518842;14156.716865662629;14177.14937103777;14264.500742473963;10000.79998400028;10548.630797922036;10689.518971646294;11577.536898523964;11578.247413918069;13181.011424143036;14285.214313212666;14308;14405.78391324131;14534.636634084232;6280.183576135165;6339.841503962346;6367.808965731036;6392.936070639327;6400.583962042452;980.4166520920041;1004.7789486313145;1010.3937637417491;1043.5982146873357;1048.580567772897;19096.140673669717;19467.221311147678;22674.546509069725;24745.25764227076;24856.875715621307;1087.8912108789057;1090.3893819461127;1126.2285334545863;1129.6498085593328;1136.664683918246;12005.459754310903;12686.111972162045;12720.491180352683;12752.489900404065;12771.042171836963;326064;328075.35962320515;328124;328562;328846;55368.72315638396;55598.72200638971;55646.44353556493;55736.72131639316;55840;1450.6663467402345;1479.216015511802;1506.8041154650045;1514.863662270401;1525.9237038148049;20677.793222068187;21769.673454898508;23473;24041.398965026016;24047.27858164258;37982;38122.809385952896;38129.23741525209;50658.74670626771;52281.7385913068;295133;295637;296383;299219;299944.00055999163;35110.64889351074;37407.251854963295;37449.81275093607;37688.43467348047;37812.43281350727;45099;46022.76988615035;46862.06275874395;47339.52660473351;47506.52493475159;23812.642810358204;26454.14820962591;30504.84747576248;30815.746086142444;31836.204094896886;24922.875385622956;30243.546346804374;30635.693643063285;31190.688093118777;31990.52014219835;37239.069023274635;38199.80900095482;38232.235355292185;38273.4258986121;38343;31251.53122703116;31615.841920790248;31998.72005119769;32004;32445.675543244266;23747.603068072895;24904.875475622506;24929.750702492744;25039.624405634295;25401.237962861145 14:58:03 INFO - PID 1653 | |2;jquery.html;451.4672686230248;486.1832122034982;493.69390977593895;500.78466259508417;511.74668539073167;22203.22288719895;22751.658725119138;23556.29331120067;23656.053757849666;25044;25221;25586.48827023463;25858.96564137437;25876.870615646894;25904.870475647644;6456.96771516143;6564.179477565304;6586.538942274043;6613.074169616233;6621.6027038377615;6097.847553811146;6378.936210637904;6493.383128602799;6535.0524173994745;6542.41118299353;6361.045843123546;6386.712597933104;6388.977763557832;6413.070104834819;6413.198350206224;6381.074744162093;6393.9360606394275;6401.103845461639;6411.0383442483535;6425.1968503937005;5798.797042103548;5813.47678708914;5847.502962248214;5858.853528661775;5860.033094539396;30449;30510.694893051008;30744.8462757687;30787.076387708406;31344.21639459009;2178.9649916289795;2211.845170838042;2222.344408399526;2222.7777222277805;2225.0765932138142;18962.71555926662;19543.609127817504;19550.022498874998;19555.315563955337;19562.728422652483;1958.608278344337;2180.073468775764;2228.186711850163;2255.8082562982004;2267.3764714703425;154599.22700386538;161303.193484033;202717;206581;211314.94342528342;13744.106633068819;14588.832893368504;14779.37905213208;15344.462943796914;15463.072215667096;20155.899220503805;20169.49576260576;20192;20230;20397.592048158953;9839.212862970946;9845.966173551817;9860.408375497493;9864.753381165385;9953.253505986935;8875.600597973107;9036.32227582934;9056.003839577641;9111.225545828545;9129.224015958585;7536.698532058656;9268.7682807929;9279;9308.301877359225;9312.674056408128;2588.589218875717;2596.40282734972;2597.2727636261875;2603.388203772112;2709.498742732601;815.8939337886037;823.069930977991;971.0968799016982;1001.7195185348114;1010.1363334349172;22103.557928841656;22377.88811055967;22411.32766017022;22649.773502265096;22879;984.247051005981;985.4038306824411;1015.4618052432223;1019.7195771162841;1031.7523794289466;9886.604535818487;10700.892991070144;11100.833487497694;11490;13723.656908577366;569189;570219;570598;582615;585468;154927.22536387245;154949;155669;155826;157112;1029.897010298964;1041.95832166714;1094.2395035450286;1160.002397937773;1228.8402507673945;21777;22235.66646500336;22521.43696407603;23163.536729265656;23342.533149337258;68202.63594727978;68449;69482.65258673675;70107.64946175441;71142.64428678031;94714;95157;95429.5228523853;96059.03940960781;96252;11405.543778248777;12039.518419263131;12097.81853272184;12125.636230913087;12162;15335.846641533442;18783.060846957324;18854.717179242598;18855;18950.43148705541;12374.505019799468;15133.243337833284;15159.772603411178;15265.084094954338;15328.770068448759;13584.592462226148;13658.180509169482;13772;13810.447582096604;13866.584002479942;15384.84615153834;15659.217039147768;15799.526014219591;15817.604559886095;15838.9208053959;11920.940395297968;11952;12030.939845300718;12033.63899083029;12057.336846473529;15053.924730376717;15116.622084447537;15153.621159471102;15185;15352.385904563693 14:58:03 INFO - PID 1653 | |3;mootools.html;1425.629336372543;1479.3269062576514;1492.7311784982767;1505.3000354835006;1517.4688858899383;11464.885351146486;13461.663458413545;13482.932585337072;13509.256990865499;13904.443822247124;13453.461861525528;13518.188908665486;13660.248686322248;13665.726685466285;13704.794428083562;2335.8787781864717;2694.393761403683;2758.668959724836;2759.489494443524;2770.265879541923;2187.8796666183352;2514.3211332940095;2639.9604005939927;2649.2846931328627;2650.615660729193;2681.5441374966204;2690.4350086481863;2703.2430919342614;2703.7296270373004;2705.702372739007;2649.589313656377;2661.9866900665374;2662.4408874136457;2670;2672.906448274301;2626.2909014566153;2636.3409147713073;2654.588538776493;2673.1178711025314;2718.8504632245217;13004.609861704164;13072.542461013818;13074.607761767162;13075.54235601759;13176.407061682152;5694.9715251423895;7275.454340924452;7299.598522081284;7582.886256706154;7588.658510367047;4565.269556870918;4727.858164255078;4742.264948932921;4766.976165119187;4829.227323628238;1173.5305877648923;1398.7412328719117;1405.3675845869298;1413.3851774478135;1429.5425463851573;18420.447386578424;18641.254349826126;19475.707864382046;19638;19639.214431422726;5456.672599644034;6236.407541283571;6362.141110949962;6385.680715964183;6557.508186885907;13830.031897767261;14002.369893354824;14006.929965350108;14012.439502419993;14029.298535073205;6178.938210617926;7141.428685705131;7307;7415.59214243211;7690.461667683208;6672.366125218096;6805.523613347117;6816.977453382033;6825.624590647562;6836.794896153124;4399.098184872158;4438.822447102144;4440.622547083534;4465.732056076646;4490.034642551867;1244.9355800790195;1437.7052704195614;1492.7536956402073;1499.415228061057;1510.7960425342658;834.4993004197433;843.1357858195277;866.7486428935538;875.8554838451585;905.4205308602368;5817.621854579439;5911.3201981772645;6417.454516366068;6792.72829086831;7113.857722845618;687.8417963868338;690.5442408010689;693.8308949420243;704.7145905908116;708.2174197511779;4259.467566554181;4334.263175260214;4405.801738921692;4765.690230135099;5065.164247899129;16396;16993.320267189174;17009.404670836462;17032.148392580322;17209.65580688379;16830.83169168317;17017.149142542825;17056.914715426345;17090.743638845426;17091.97448153115;7429.33136017761;9012.954935225413;9110.316726245557;9169.037251088674;9189.172974432333;7012.298770122947;7624.5425274483705;7642.89178069188;7667.961660191663;8084.434089613906;15161.469348572748;15264.23678816077;15451;15495.380184792482;15703.371865125268;14598.635034124232;15666.608334791721;15763.527094187193;15853.048817071012;16070.35718571244;8718.346124040596;10704.571817127538;10779.946100269448;10803.837942431028;10806.405647689453;10881.673549793519;11036.558537658724;11996.64010079699;12161.513539458323;12222.633321000385;9976.700698979042;9982.650607228712;10014.3490673106;10024.899751002396;10032.949835250776;8015.438919275594;9589;9663.661771838233;9735.561899714388;9855.35940163887;8836.381453298207;8864.157904999014;8925.062868398725;8968.372213944962;8984.326175536991;6352.618842869442;6376.617402955838;6395.456386207225;6730.259671436236;6790;5588.972055139699;5825.912611310749;6843.794686159423;6990.650467476504;7032.578045317297 14:58:03 INFO - PID 1653 | |4;prototype.html;427.9358096285555;435.69936743646883;442.85828534868847;451.6341763171829;463.64994429205933;5080.542751152396;6346.492280617551;6538.084668146457;6557.573757705752;6604.900926486101;6192.411720886508;6344.302126766052;6358.300586935434;6376.649284289362;6382.521310901678;4081.897952551195;4277.2087163874785;4856.6357523185725;5032.018756342514;5040.3951525816865;4986.850394488171;5026.623003274769;5039.31969184161;5049.3940727112795;5054.898902021976;4238.766867822269;4706.858794236178;5007.724575148365;5015;5029.723365214912;4668.043051174501;4668.229742092568;4981.003799240167;4990;5019.573336266421;4561.498235194126;4568.1548913451115;4568.725876447391;4569.13186494565;4576.633869290449;5121.513456221653;6354.87290254197;6379.819733349345;6379.872402551923;6416.839579010562;5178.611604129667;6069.696515174223;6106.236720409949;6107.938920610826;6129.264488261392;6977.197622273453;7013.33373329538;7018.227994920555;7024.648767561602;7069.787906362817;5242.370915490165;5294.602904782157;5301.840944771663;5302.549283310923;5340.385855626614;6828.2147553031045;7322.997570995919;7337.963310183415;7340.4127669786285;7351.889721654179;4868.367112275452;4873.4882837301575;4884.413870335582;4908.263760435963;4910.386201724785;4422.535633758409;4929.926051109236;4958.925616115761;4985.775640096204;5002.424721157114;4985.90028199434;5528.364238112668;5668.489835914787;5682.176084467812;5710.514606258432;6059.454649081604;6926.619035953071;6926.722931082701;6933.965330173418;6968.3728464438445;1701.9916498531604;1704.8977061376356;1709.444430560073;1710.1962077823403;1716.4678949525687;1116.151724689242;1127.3574062784135;1157.311399717167;1157.4155051699006;1158.7161145519233;6506.25178104524;6511.906844248944;6522.445592124628;6529.640869752115;6537.607743535308;7122.28777122294;7127.60798156096;7141.321574450419;7157.778999450268;7170.960210769409;6462.32145624712;6474.805755827333;6481.384268494486;6505.934940650627;6509.251436084911;3691.9077023074638;3733.5893051764283;3749.9062523436587;3757.3988161893967;3776.773393596338;5858.619189752738;6708.3291670833505;7114.395565985981;7362.312755844078;7424.740134095281;6133.877322453526;7360.521566098187;7362.079740032496;7450.455222314384;7460.776176714708;6451.516136289797;6851.725930962705;7205.747798827121;7324.340809327186;7429.9257007430315;7107.573545587281;7125.188024406917;7140.642967851691;7147.46394020461;7153.35619794221;6411.743530258737;6782.0505129280955;6967.024616553788;6969.233384327821;7015.543989640657;7101;7249.891251631124;7282.453815963718;7282.563046217244;7300.671469783979;4552.840650577214;4834.250691142878;4862.440819305683;4881;4886.947482057473;5868.3251426086545;5926.348101708722;7171.780797264373;7176.277693353662;7178.748743793943;6281.309056003744;6336.936630633634;6832.726690932307;6914.688839002358;6955.269696681776;6805.795826125224;6813.727450901908;6830.677569626152;6882.483813714091;6891.862162756616;6342.841428964312;6729.149483891969;6747.987801829765;6767.492438067066;6781.38967492928;6214;6914.688839002157;6926.757563485456;6935.583864968118;6978.895316570154;5522.558195344443;6674.466042716668;6704.832379190364;6723.7646682365885;6735.562188457735 14:58:03 INFO - PID 1653 | |5;yui.html;828.0849661124457;829.0880031964844;841.7895526118471;865.9567021648916;871.8779370888077;11960.863717946802;14912.925435372836;17211.22549485273;17588;17658;15174.393024279043;16693.33226670935;18136.36522721705;18168.09159542021;18204.635907281845;973.8636590877283;981.0875885426533;1001.5843424978625;1002.4236064263041;1002.9247806414512;1013.2198207380312;1024.6106479537766;1026.471367245869;1028.2956175020136;1028.4908970059794;1017.8778546574384;1059.4808543813554;1069.6951368859877;1073.4847273308817;1075.166745772025;1056.6354607660344;1058.9894101059006;1066.9970227985668;1067.2369255981932;1070.0850772589401;772.3164998975891;775.1395950494946;778.6768491076197;779.3764988009626;782.7025730222479;17154.914225428794;17438;17499.387521436816;17658.823411765847;18068;17853.018084005376;18014.459566212903;18120.90939545307;18163.63672726538;18399.72400413995;14636.780448293284;14754;14774.70450590993;14911.254437278201;14920.104793712304;3584.444411116255;3585.94621080684;3589.5333606631234;3623.058004918714;3639.4358874374516;112119.22748111413;130148.6985130146;135749.96375054383;136892.946605801;140777;13918.652033699036;14024.859751402559;14251;14397.42410303576;14417.92791036038;26782;26945.461090778073;27097.593536096974;27211.591826122625;27230.319242019108;7816.452848300678;8015.318697910627;8046.195380461907;8066.11272759996;8101.068377136587;3487.3897068013;3797;3965.1078507336197;4029.435878976946;4030.879073627796;3939.5272567291536;4570.428696412949;4682.876109733707;4688.296755486705;4698.600618947359;870.7823044238941;1004.9145822605014;1022.7903279827617;1027.7893031928436;1033.705393962717;644.8678021005683;653.9967300163564;667.742918976188;671.5802623360399;685.7839780469136;9390.34267601275;11095.944520277348;11566.94216528912;12422.440990155465;12687.80968285458;620.8230654263604;630.5964182922933;648.7145655911384;671.633959492078;681.0907438569466;4387.3857659927635;5471.261379713767;5590.5248053915875;5663.490285874291;5879.764809407661;115473;128073;136284;142789;143162.85255721174;113813;115787;120515.39742301233;123555;124193;92419;96937.51531242298;104067;104321;110280;10692.037716605542;11253.49359278818;11454.942725286654;11982.700432489257;13053.216806991612;37235.44146837745;41108.35566577304;43139.784301078296;43175.35236971385;46924.530754692016;62154.37845621486;62166.68916655388;62185;62224.68887655533;62542.687286563276;16835.747463787808;16966.660666786844;17213.56966075858;17320.82679173192;17378.47864564065;18276.634467310312;18752.6249475007;18916;18988.810111898703;19036.71444928355;17401.47795566135;17404.477865664052;17465.476035718948;17563.121843908008;17730.91134544319;16911.408100716908;16994.575135621708;17531.912340438215;17649.294028238728;17679.73480397821;16534.421295254608;16710.331586736393;16724;16871.493855184362;16951.745723813907;11643.12676549279;11685.415729213675;11790.705232369259;12315.876841231473;12338.259704417405;12068.657250134207;12529.684383140004;12976.870231297944;13158.868411315765;14275.785863212268 14:58:03 INFO - PID 1653 | __end_tp_report 14:58:03 INFO - PID 1653 | __start_cc_report 14:58:03 INFO - PID 1653 | _x_x_mozilla_cycle_collect,454 14:58:03 INFO - PID 1653 | __end_cc_report 14:58:03 INFO - PID 1653 | __startTimestamp1497045483359__endTimestamp 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | ------- Summary: start ------- 14:58:03 INFO - PID 1653 | Number of tests: 6 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#0] dojo.html Cycles:165 Average:6161.17 Median:6348.17 stddev:779.66 (12.3%) stddev-sans-first:745.03 14:58:03 INFO - PID 1653 | Values: 3134.5 3640.9 3653.2 3664.7 3667.0 6160.3 6172.7 6207.9 6261.8 6280.6 6161.8 6211.3 6229.3 6257.6 6271.9 4283.8 4602.0 5460.0 5469.4 5479.6 5523.8 5561.5 5580.4 5584.5 5624.5 5359.3 5420.1 5471.5 5484.8 5500.0 5525.6 5534.5 5541.9 5556.3 5561.6 5481.8 5483.5 5505.6 5507.0 5510.7 6138.8 6172.3 6224.7 6258.6 6267.2 6735.5 6747.8 6763.0 6781.8 6793.4 5424.6 5820.3 6441.6 6879.7 6903.8 6234.3 6249.7 6302.6 6336.4 6348.2 6971.3 7020.9 7021.1 7034.2 7044.6 6351.6 6504.6 6561.0 6639.2 6662.5 6843.0 6854.5 6875.1 6877.2 6886.2 6510.4 6587.2 6703.2 6752.2 6786.7 6648.5 6669.6 6747.4 6769.6 6827.9 5404.1 5428.6 5429.2 5452.2 5464.6 4744.5 4814.5 4816.9 4849.5 4871.4 6134.2 6224.7 6266.6 6290.3 6312.0 6865.6 6873.0 6873.2 6877.4 6886.7 4469.2 5263.5 6194.3 6198.5 6357.6 4937.8 5920.3 6054.2 6077.4 6083.1 7017.2 7017.6 7023.3 7029.8 7030.1 6854.5 6858.3 6888.9 6896.1 6917.9 6233.8 6517.1 6757.2 6866.5 7017.1 5965.8 6243.8 6646.3 6764.4 6777.9 6671.7 6885.7 6903.6 6917.6 6923.5 5793.5 6333.1 6610.8 6628.3 6649.7 6619.0 6645.7 6646.7 6650.9 6657.1 6403.6 6472.4 6491.1 6495.1 6527.0 5502.6 6405.2 6468.9 6477.2 6665.5 5127.0 6390.8 6416.5 6435.8 6455.5 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#1] ext.html Cycles:180 Average:45550.29 Median:181016.00 stddev:79097.53 (43.7%) stddev-sans-first:79248.01 14:58:03 INFO - PID 1653 | Values: 783.4 818.6 844.6 1251.5 1311.0 38630.6 40182.4 40205.8 40222.4 40319.6 40553.4 40765.6 40846.8 41034.4 41074.2 7847.5 8004.8 8179.8 8378.1 8398.5 8275.9 8379.2 8428.7 8451.7 8454.7 8401.2 8444.2 8457.7 8479.4 8491.5 8405.4 8407.0 8445.1 8451.7 8461.3 8252.6 8278.5 8327.2 8331.0 8334.8 33186.0 34751.8 37867.6 37875.4 37893.9 3541.1 3587.0 3594.6 3595.9 3604.7 30302.4 30585.8 30685.8 30739.0 30745.4 3016.9 3038.4 3061.4 3082.7 3090.3 248989.0 252033.0 271786.3 302445.0 305773.0 47440.8 47664.0 47836.5 47844.3 47867.8 50855.2 50952.7 51043.7 51084.5 51101.7 13698.5 14083.4 14156.7 14177.1 14264.5 10000.8 10548.6 10689.5 11577.5 11578.2 13181.0 14285.2 14308.0 14405.8 14534.6 6280.2 6339.8 6367.8 6392.9 6400.6 980.4 1004.8 1010.4 1043.6 1048.6 19096.1 19467.2 22674.5 24745.3 24856.9 1087.9 1090.4 1126.2 1129.6 1136.7 12005.5 12686.1 12720.5 12752.5 12771.0 326064.0 328075.4 328124.0 328562.0 328846.0 55368.7 55598.7 55646.4 55736.7 55840.0 1450.7 1479.2 1506.8 1514.9 1525.9 20677.8 21769.7 23473.0 24041.4 24047.3 37982.0 38122.8 38129.2 50658.7 52281.7 295133.0 295637.0 296383.0 299219.0 299944.0 35110.6 37407.3 37449.8 37688.4 37812.4 45099.0 46022.8 46862.1 47339.5 47506.5 23812.6 26454.1 30504.8 30815.7 31836.2 24922.9 30243.5 30635.7 31190.7 31990.5 37239.1 38199.8 38232.2 38273.4 38343.0 31251.5 31615.8 31998.7 32004.0 32445.7 23747.6 24904.9 24929.8 25039.6 25401.2 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#2] jquery.html Cycles:180 Average:40161.81 Median:12458.57 stddev:99365.66 (797.6%) stddev-sans-first:99599.67 14:58:03 INFO - PID 1653 | Values: 451.5 486.2 493.7 500.8 511.7 22203.2 22751.7 23556.3 23656.1 25044.0 25221.0 25586.5 25859.0 25876.9 25904.9 6457.0 6564.2 6586.5 6613.1 6621.6 6097.8 6378.9 6493.4 6535.1 6542.4 6361.0 6386.7 6389.0 6413.1 6413.2 6381.1 6393.9 6401.1 6411.0 6425.2 5798.8 5813.5 5847.5 5858.9 5860.0 30449.0 30510.7 30744.8 30787.1 31344.2 2179.0 2211.8 2222.3 2222.8 2225.1 18962.7 19543.6 19550.0 19555.3 19562.7 1958.6 2180.1 2228.2 2255.8 2267.4 154599.2 161303.2 202717.0 206581.0 211314.9 13744.1 14588.8 14779.4 15344.5 15463.1 20155.9 20169.5 20192.0 20230.0 20397.6 9839.2 9846.0 9860.4 9864.8 9953.3 8875.6 9036.3 9056.0 9111.2 9129.2 7536.7 9268.8 9279.0 9308.3 9312.7 2588.6 2596.4 2597.3 2603.4 2709.5 815.9 823.1 971.1 1001.7 1010.1 22103.6 22377.9 22411.3 22649.8 22879.0 984.2 985.4 1015.5 1019.7 1031.8 9886.6 10700.9 11100.8 11490.0 13723.7 569189.0 570219.0 570598.0 582615.0 585468.0 154927.2 154949.0 155669.0 155826.0 157112.0 1029.9 1042.0 1094.2 1160.0 1228.8 21777.0 22235.7 22521.4 23163.5 23342.5 68202.6 68449.0 69482.7 70107.6 71142.6 94714.0 95157.0 95429.5 96059.0 96252.0 11405.5 12039.5 12097.8 12125.6 12162.0 15335.8 18783.1 18854.7 18855.0 18950.4 12374.5 15133.2 15159.8 15265.1 15328.8 13584.6 13658.2 13772.0 13810.4 13866.6 15384.8 15659.2 15799.5 15817.6 15838.9 11920.9 11952.0 12030.9 12033.6 12057.3 15053.9 15116.6 15153.6 15185.0 15352.4 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#3] mootools.html Cycles:180 Average:7890.67 Median:2703.49 stddev:5218.43 (193.0%) stddev-sans-first:5210.46 14:58:03 INFO - PID 1653 | Values: 1425.6 1479.3 1492.7 1505.3 1517.5 11464.9 13461.7 13482.9 13509.3 13904.4 13453.5 13518.2 13660.2 13665.7 13704.8 2335.9 2694.4 2758.7 2759.5 2770.3 2187.9 2514.3 2640.0 2649.3 2650.6 2681.5 2690.4 2703.2 2703.7 2705.7 2649.6 2662.0 2662.4 2670.0 2672.9 2626.3 2636.3 2654.6 2673.1 2718.9 13004.6 13072.5 13074.6 13075.5 13176.4 5695.0 7275.5 7299.6 7582.9 7588.7 4565.3 4727.9 4742.3 4767.0 4829.2 1173.5 1398.7 1405.4 1413.4 1429.5 18420.4 18641.3 19475.7 19638.0 19639.2 5456.7 6236.4 6362.1 6385.7 6557.5 13830.0 14002.4 14006.9 14012.4 14029.3 6178.9 7141.4 7307.0 7415.6 7690.5 6672.4 6805.5 6817.0 6825.6 6836.8 4399.1 4438.8 4440.6 4465.7 4490.0 1244.9 1437.7 1492.8 1499.4 1510.8 834.5 843.1 866.7 875.9 905.4 5817.6 5911.3 6417.5 6792.7 7113.9 687.8 690.5 693.8 704.7 708.2 4259.5 4334.3 4405.8 4765.7 5065.2 16396.0 16993.3 17009.4 17032.1 17209.7 16830.8 17017.1 17056.9 17090.7 17092.0 7429.3 9013.0 9110.3 9169.0 9189.2 7012.3 7624.5 7642.9 7668.0 8084.4 15161.5 15264.2 15451.0 15495.4 15703.4 14598.6 15666.6 15763.5 15853.0 16070.4 8718.3 10704.6 10779.9 10803.8 10806.4 10881.7 11036.6 11996.6 12161.5 12222.6 9976.7 9982.7 10014.3 10024.9 10032.9 8015.4 9589.0 9663.7 9735.6 9855.4 8836.4 8864.2 8925.1 8968.4 8984.3 6352.6 6376.6 6395.5 6730.3 6790.0 5589.0 5825.9 6843.8 6990.7 7032.6 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#4] prototype.html Cycles:180 Average:5681.20 Median:6356.59 stddev:1706.49 (26.8%) stddev-sans-first:1665.10 14:58:03 INFO - PID 1653 | Values: 427.9 435.7 442.9 451.6 463.6 5080.5 6346.5 6538.1 6557.6 6604.9 6192.4 6344.3 6358.3 6376.6 6382.5 4081.9 4277.2 4856.6 5032.0 5040.4 4986.9 5026.6 5039.3 5049.4 5054.9 4238.8 4706.9 5007.7 5015.0 5029.7 4668.0 4668.2 4981.0 4990.0 5019.6 4561.5 4568.2 4568.7 4569.1 4576.6 5121.5 6354.9 6379.8 6379.9 6416.8 5178.6 6069.7 6106.2 6107.9 6129.3 6977.2 7013.3 7018.2 7024.6 7069.8 5242.4 5294.6 5301.8 5302.5 5340.4 6828.2 7323.0 7338.0 7340.4 7351.9 4868.4 4873.5 4884.4 4908.3 4910.4 4422.5 4929.9 4958.9 4985.8 5002.4 4985.9 5528.4 5668.5 5682.2 5710.5 6059.5 6926.6 6926.7 6934.0 6968.4 1702.0 1704.9 1709.4 1710.2 1716.5 1116.2 1127.4 1157.3 1157.4 1158.7 6506.3 6511.9 6522.4 6529.6 6537.6 7122.3 7127.6 7141.3 7157.8 7171.0 6462.3 6474.8 6481.4 6505.9 6509.3 3691.9 3733.6 3749.9 3757.4 3776.8 5858.6 6708.3 7114.4 7362.3 7424.7 6133.9 7360.5 7362.1 7450.5 7460.8 6451.5 6851.7 7205.7 7324.3 7429.9 7107.6 7125.2 7140.6 7147.5 7153.4 6411.7 6782.1 6967.0 6969.2 7015.5 7101.0 7249.9 7282.5 7282.6 7300.7 4552.8 4834.3 4862.4 4881.0 4886.9 5868.3 5926.3 7171.8 7176.3 7178.7 6281.3 6336.9 6832.7 6914.7 6955.3 6805.8 6813.7 6830.7 6882.5 6891.9 6342.8 6729.1 6748.0 6767.5 6781.4 6214.0 6914.7 6926.8 6935.6 6978.9 5522.6 6674.5 6704.8 6723.8 6735.6 14:58:03 INFO - PID 1653 | 14:58:03 INFO - PID 1653 | [#5] yui.html Cycles:180 Average:24555.95 Median:17482.43 stddev:36767.77 (210.3%) stddev-sans-first:36827.75 14:58:03 INFO - PID 1653 | Values: 828.1 829.1 841.8 866.0 871.9 11960.9 14912.9 17211.2 17588.0 17658.0 15174.4 16693.3 18136.4 18168.1 18204.6 973.9 981.1 1001.6 1002.4 1002.9 1013.2 1024.6 1026.5 1028.3 1028.5 1017.9 1059.5 1069.7 1073.5 1075.2 1056.6 1059.0 1067.0 1067.2 1070.1 772.3 775.1 778.7 779.4 782.7 17154.9 17438.0 17499.4 17658.8 18068.0 17853.0 18014.5 18120.9 18163.6 18399.7 14636.8 14754.0 14774.7 14911.3 14920.1 3584.4 3585.9 3589.5 3623.1 3639.4 112119.2 130148.7 135750.0 136892.9 140777.0 13918.7 14024.9 14251.0 14397.4 14417.9 26782.0 26945.5 27097.6 27211.6 27230.3 7816.5 8015.3 8046.2 8066.1 8101.1 3487.4 3797.0 3965.1 4029.4 4030.9 3939.5 4570.4 4682.9 4688.3 4698.6 870.8 1004.9 1022.8 1027.8 1033.7 644.9 654.0 667.7 671.6 685.8 9390.3 11095.9 11566.9 12422.4 12687.8 620.8 630.6 648.7 671.6 681.1 4387.4 5471.3 5590.5 5663.5 5879.8 115473.0 128073.0 136284.0 142789.0 143162.9 113813.0 115787.0 120515.4 123555.0 124193.0 92419.0 96937.5 104067.0 104321.0 110280.0 10692.0 11253.5 11454.9 11982.7 13053.2 37235.4 41108.4 43139.8 43175.4 46924.5 62154.4 62166.7 62185.0 62224.7 62542.7 16835.7 16966.7 17213.6 17320.8 17378.5 18276.6 18752.6 18916.0 18988.8 19036.7 17401.5 17404.5 17465.5 17563.1 17730.9 16911.4 16994.6 17531.9 17649.3 17679.7 16534.4 16710.3 16724.0 16871.5 16951.7 11643.1 11685.4 11790.7 12315.9 12338.3 12068.7 12529.7 12976.9 13158.9 14275.8 14:58:03 INFO - PID 1653 | -------- Summary: end -------- 14:58:03 INFO - PID 1653 | 14:58:03 INFO - TEST-INFO | 1653: exit 0 14:58:03 INFO - TEST-OK | dromaeo_css | took 1143353ms 14:58:03 INFO - TEST-START | kraken 14:58:03 INFO - operating with platform_type : mac_ 14:58:03 INFO - Initialising browser for kraken test... 14:58:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49237/getInfo.html -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpYywtAQ/profile 14:58:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49237/getInfo.html -foreground) 14:58:37 INFO - TEST-INFO | 1664: killed by SIGTERM 14:58:37 INFO - Browser initialized. 14:58:37 INFO - Running cycle 1/1 for kraken test... 14:58:37 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/T/tmpYywtAQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:58:39 INFO - PID 1668 | 2017-06-09 14:58:39.742 plugin-container[1669:11450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c43, name = 'com.apple.tsm.portname' 14:58:39 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:39 INFO - PID 1668 | 2017-06-09 14:58:39.743 plugin-container[1669:11450] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0xa10f, name = 'com.apple.CFPasteboardClient' 14:58:39 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:39 INFO - PID 1668 | 2017-06-09 14:58:39.743 plugin-container[1669:11450] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:58:40 INFO - PID 1668 | 2017-06-09 14:58:40.318 plugin-container[1670:11500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 14:58:40 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:40 INFO - PID 1668 | 2017-06-09 14:58:40.319 plugin-container[1670:11500] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9723, name = 'com.apple.CFPasteboardClient' 14:58:40 INFO - PID 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:58:40 INFO - PID 1668 | 2017-06-09 14:58:40.319 plugin-container[1670:11500] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:58:49 INFO - PID 1668 | RSS: Main: 254042112 14:58:49 INFO - PID 1668 | 14:58:52 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 14:58:53 INFO - PID 1668 | RSS: Main: 267075584 14:58:53 INFO - PID 1668 | 14:58:55 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 14:58:56 INFO - PID 1668 | RSS: Main: 278138880 14:58:56 INFO - PID 1668 | 14:59:00 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 14:59:00 INFO - PID 1668 | RSS: Main: 280481792 14:59:00 INFO - PID 1668 | 14:59:02 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 14:59:03 INFO - PID 1668 | RSS: Main: 282660864 14:59:03 INFO - PID 1668 | 14:59:05 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:59:05 INFO - PID 1668 | RSS: Main: 283422720 14:59:05 INFO - PID 1668 | 14:59:08 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 14:59:09 INFO - PID 1668 | RSS: Main: 287772672 14:59:09 INFO - PID 1668 | 14:59:12 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 14:59:13 INFO - PID 1668 | RSS: Main: 290062336 14:59:13 INFO - PID 1668 | 14:59:15 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 14:59:16 INFO - PID 1668 | RSS: Main: 288870400 14:59:16 INFO - PID 1668 | 14:59:18 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:59:18 INFO - PID 1668 | RSS: Main: 289103872 14:59:18 INFO - PID 1668 | 14:59:20 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:59:21 INFO - PID 1668 | RSS: Main: 288649216 14:59:21 INFO - PID 1668 | 14:59:23 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:59:24 INFO - PID 1668 | RSS: Main: 288436224 14:59:24 INFO - PID 1668 | 14:59:27 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:59:27 INFO - PID 1668 | RSS: Main: 288374784 14:59:27 INFO - PID 1668 | 14:59:30 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:59:31 INFO - PID 1668 | RSS: Main: 288104448 14:59:31 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 14:59:33 INFO - PID 1668 | RSS: Main: 290254848 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | __start_tp_report 14:59:33 INFO - PID 1668 | _x_x_mozilla_page_load 14:59:33 INFO - PID 1668 | _x_x_mozilla_page_load_details 14:59:33 INFO - PID 1668 | |i|pagename|runs| 14:59:33 INFO - PID 1668 | |0;ai-astar;109;116;98;112;116;115;116;113;114;115 14:59:33 INFO - PID 1668 | |1;audio-beat-detection;149;115;135;190;140;132;129;108;167;111 14:59:33 INFO - PID 1668 | |2;audio-dft;200;200;201;211;233;263;283;263;200;199 14:59:33 INFO - PID 1668 | |3;audio-fft;73;82;75;150;62;63;61;64;125;62 14:59:33 INFO - PID 1668 | |4;audio-oscillator;70;70;114;70;70;78;116;81;103;107 14:59:33 INFO - PID 1668 | |5;imaging-gaussian-blur;89;91;89;90;90;90;97;90;101;106 14:59:33 INFO - PID 1668 | |6;imaging-darkroom;98;97;97;97;97;96;97;97;99;97 14:59:33 INFO - PID 1668 | |7;imaging-desaturate;92;91;93;89;94;92;90;94;92;90 14:59:33 INFO - PID 1668 | |8;json-parse-financial;50;50;52;55;51;53;54;74;78;79 14:59:33 INFO - PID 1668 | |9;json-stringify-tinderbox;58;56;56;62;70;93;84;78;56;57 14:59:33 INFO - PID 1668 | |10;stanford-crypto-aes;85;110;84;84;86;88;111;114;111;92 14:59:33 INFO - PID 1668 | |11;stanford-crypto-ccm;141;172;159;148;136;134;136;134;132;165 14:59:33 INFO - PID 1668 | |12;stanford-crypto-pbkdf2;184;185;207;177;232;235;177;187;195;180 14:59:33 INFO - PID 1668 | |13;stanford-crypto-sha256-iterative;64;69;67;64;66;69;63;67;65;66 14:59:33 INFO - PID 1668 | __end_tp_report 14:59:33 INFO - PID 1668 | __start_cc_report 14:59:33 INFO - PID 1668 | _x_x_mozilla_cycle_collect,1196 14:59:33 INFO - PID 1668 | __end_cc_report 14:59:33 INFO - PID 1668 | __startTimestamp1497045573519__endTimestamp 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | ------- Summary: start ------- 14:59:33 INFO - PID 1668 | Number of tests: 14 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#0] ai-astar Cycles:10 Average:112.40 Median:115.50 stddev:5.52 (4.8%) stddev-sans-first:5.72 14:59:33 INFO - PID 1668 | Values: 109.0 116.0 98.0 112.0 116.0 115.0 116.0 113.0 114.0 115.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#1] audio-beat-detection Cycles:10 Average:137.60 Median:137.50 stddev:25.72 (18.7%) stddev-sans-first:26.94 14:59:33 INFO - PID 1668 | Values: 149.0 115.0 135.0 190.0 140.0 132.0 129.0 108.0 167.0 111.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#2] audio-dft Cycles:10 Average:225.30 Median:222.00 stddev:32.72 (14.7%) stddev-sans-first:33.40 14:59:33 INFO - PID 1668 | Values: 200.0 200.0 201.0 211.0 233.0 263.0 283.0 263.0 200.0 199.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#3] audio-fft Cycles:10 Average:81.70 Median:63.50 stddev:30.78 (48.5%) stddev-sans-first:32.49 14:59:33 INFO - PID 1668 | Values: 73.0 82.0 75.0 150.0 62.0 63.0 61.0 64.0 125.0 62.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#4] audio-oscillator Cycles:10 Average:87.90 Median:70.00 stddev:19.69 (28.1%) stddev-sans-first:19.80 14:59:33 INFO - PID 1668 | Values: 70.0 70.0 114.0 70.0 70.0 78.0 116.0 81.0 103.0 107.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:90.00 stddev:5.96 (6.6%) stddev-sans-first:6.12 14:59:33 INFO - PID 1668 | Values: 89.0 91.0 89.0 90.0 90.0 90.0 97.0 90.0 101.0 106.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:97.00 stddev:0.79 (0.8%) stddev-sans-first:0.78 14:59:33 INFO - PID 1668 | Values: 98.0 97.0 97.0 97.0 97.0 96.0 97.0 97.0 99.0 97.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#7] imaging-desaturate Cycles:10 Average:91.70 Median:92.00 stddev:1.70 (1.9%) stddev-sans-first:1.80 14:59:33 INFO - PID 1668 | Values: 92.0 91.0 93.0 89.0 94.0 92.0 90.0 94.0 92.0 90.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:54.50 stddev:12.18 (22.3%) stddev-sans-first:12.41 14:59:33 INFO - PID 1668 | Values: 50.0 50.0 52.0 55.0 51.0 53.0 54.0 74.0 78.0 79.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:66.00 stddev:13.60 (20.6%) stddev-sans-first:14.03 14:59:33 INFO - PID 1668 | Values: 58.0 56.0 56.0 62.0 70.0 93.0 84.0 78.0 56.0 57.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:84.50 stddev:13.15 (15.6%) stddev-sans-first:13.27 14:59:33 INFO - PID 1668 | Values: 85.0 110.0 84.0 84.0 86.0 88.0 111.0 114.0 111.0 92.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:144.50 stddev:14.60 (10.1%) stddev-sans-first:15.38 14:59:33 INFO - PID 1668 | Values: 141.0 172.0 159.0 148.0 136.0 134.0 136.0 134.0 132.0 165.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.90 Median:191.00 stddev:21.76 (11.4%) stddev-sans-first:22.65 14:59:33 INFO - PID 1668 | Values: 184.0 185.0 207.0 177.0 232.0 235.0 177.0 187.0 195.0 180.0 14:59:33 INFO - PID 1668 | 14:59:33 INFO - PID 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.00 Median:66.50 stddev:2.05 (3.1%) stddev-sans-first:2.05 14:59:33 INFO - PID 1668 | Values: 64.0 69.0 67.0 64.0 66.0 69.0 63.0 67.0 65.0 66.0 14:59:33 INFO - PID 1668 | -------- Summary: end -------- 14:59:33 INFO - PID 1668 | 14:59:34 INFO - TEST-INFO | 1668: exit 0 14:59:34 INFO - TEST-OK | kraken | took 90397ms 14:59:34 INFO - SUITE-END | took 1233s 14:59:34 INFO - Completed test suite (00:20:34) 14:59:34 INFO - javascript benchmark 14:59:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8343.339467999227, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.514150306702, 3640.908977275568, 3653.2328211075674, 3664.7434679572425, 3666.981665091674, 6160.322364539897, 6172.660503672302, 6207.937920620792, 6261.812145635627, 6280.623162610246, 6161.753529858812, 6211.254649442062, 6229.345918678541, 6257.561970662044, 6271.905921411182, 4283.807228674702, 4601.987562736193, 5460.0, 5469.371022332443, 5479.589030822694, 5523.751431185607, 5561.5272701820395, 5580.414056524068, 5584.497395234428, 5624.465675760796, 5359.303290572239, 5420.105682562373, 5471.480209380103, 5484.8354549363585, 5500.0, 5525.585581081435, 5534.529564986981, 5541.889162216733, 5556.333240011184, 5561.555075593983, 5481.780728770845, 5483.479069488392, 5505.614606977513, 5506.981208476444, 5510.724463776835, 6138.84652883677, 6172.290186628506, 6224.657643829588, 6258.561900666955, 6267.185265915447, 6735.461163106939, 6747.763828266036, 6762.966185169043, 6781.813182693009, 6793.354631310018, 5424.620276580641, 5820.272465941757, 6441.645709485976, 6879.690413931385, 6903.792886213421, 6234.251889773165, 6249.6562689051625, 6302.590331628429, 6336.3980421860315, 6348.17473728417, 6971.3028697129885, 7020.859582808315, 7021.087258656444, 7034.2262351141335, 7044.647767611599, 6351.587146835441, 6504.577202481823, 6560.967195163994, 6639.203295604461, 6662.5003124765835, 6843.0, 6854.485913556503, 6875.106236189264, 6877.2091209510545, 6886.208086070166, 6510.44661203803, 6587.242467116247, 6703.195616526048, 6752.155980502437, 6786.694598743068, 6648.501362397839, 6669.599824010575, 6747.426468750211, 6769.593824370553, 6827.897581536281, 5404.054290499147, 5428.565714742811, 5429.239906413105, 5452.182172674131, 5464.644798088111, 4744.478107408182, 4814.542618451242, 4816.903661926742, 4849.515048495122, 4871.391076115486, 6134.2332208473945, 6224.657643829542, 6266.561340706198, 6290.276618188876, 6312.0, 6865.588064716033, 6872.965635171792, 6873.175218973755, 6877.3810357068105, 6886.69009894556, 4469.150861336336, 5263.473652634706, 6194.318624951342, 6198.473129783929, 6357.586756860789, 4937.802487900515, 5920.2895652521975, 6054.182685337423, 6077.392260773887, 6083.11794789753, 7017.228104908456, 7017.649117544204, 7023.29767023314, 7029.753958611424, 7030.121234845645, 6854.451643868379, 6858.279880612464, 6888.896666550106, 6896.103506544117, 6917.9308206917285, 6233.812985610439, 6517.120188774455, 6757.155355580552, 6866.485013623898, 7017.122859642544, 5965.806838632204, 6243.750249989949, 6646.335366463315, 6764.391204791593, 6777.864442711019, 6671.666416679243, 6885.655717214019, 6903.551269167488, 6917.550359226634, 6923.515353925177, 5793.536517078539, 6333.081703152925, 6610.8016759497295, 6628.337166283526, 6649.667516624053, 6619.0, 6645.700943457653, 6646.667666616747, 6650.900236496554, 6657.1345725055435, 6403.615783053032, 6472.449841763503, 6491.123698300669, 6495.0906873036865, 6527.0, 5502.58730595215, 6405.19935008124, 6468.935310646833, 6477.190351206099, 6665.50008749336, 5126.974605079074, 6390.840228994312, 6416.48668106541, 6435.839104022437, 6455.548111632141], "value": 6109.879561480637, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [783.3733013589131, 818.6152508321089, 844.6494704697549, 1251.4618713952996, 1310.9737805243901, 38630.61369386305, 40182.397264041065, 40205.79897100518, 40222.39666405003, 40319.59680403195, 40553.391699124535, 40765.59234407655, 40846.795766021125, 41034.38448423276, 41074.17851642965, 7847.489913155655, 8004.839903201932, 8179.79550511236, 8378.120297368749, 8398.496090234605, 8275.92412986311, 8379.162083791603, 8428.747137585882, 8451.746447606582, 8454.746357609281, 8401.201885820838, 8444.197801208875, 8457.746267611981, 8479.364047696417, 8491.490510569354, 8405.41162118652, 8407.03319118303, 8445.071042185355, 8451.661933522651, 8461.323094152453, 8252.628631711588, 8278.503289802571, 8327.167283271685, 8331.0, 8334.833303333899, 33186.0, 34751.826240868635, 37867.62132378668, 37875.43186852199, 37893.86318410452, 3541.13242255649, 3586.964130358689, 3594.6045934947133, 3595.9101022474388, 3604.7116230701745, 30302.393952120834, 30585.847070764725, 30685.846570767226, 30739.0, 30745.385092298253, 3016.8793248270044, 3038.4075105354464, 3061.4336347775734, 3082.660907300195, 3090.3355778507503, 248989.0, 252033.0, 271786.28213717806, 302445.0, 305773.0, 47440.7627961858, 47664.0, 47836.5216347839, 47844.28233576499, 47867.760661197164, 50855.23717144246, 50952.74523627432, 51043.744781275855, 51084.48915510871, 51101.744491277306, 13698.45206191741, 14083.366248518842, 14156.716865662629, 14177.14937103777, 14264.500742473963, 10000.79998400028, 10548.630797922036, 10689.518971646294, 11577.536898523964, 11578.247413918069, 13181.011424143036, 14285.214313212666, 14308.0, 14405.78391324131, 14534.636634084232, 6280.183576135165, 6339.841503962346, 6367.808965731036, 6392.936070639327, 6400.583962042452, 980.4166520920041, 1004.7789486313145, 1010.3937637417491, 1043.5982146873357, 1048.580567772897, 19096.140673669717, 19467.221311147678, 22674.546509069725, 24745.25764227076, 24856.875715621307, 1087.8912108789057, 1090.3893819461127, 1126.2285334545863, 1129.6498085593328, 1136.664683918246, 12005.459754310903, 12686.111972162045, 12720.491180352683, 12752.489900404065, 12771.042171836963, 326064.0, 328075.35962320515, 328124.0, 328562.0, 328846.0, 55368.72315638396, 55598.72200638971, 55646.44353556493, 55736.72131639316, 55840.0, 1450.6663467402345, 1479.216015511802, 1506.8041154650045, 1514.863662270401, 1525.9237038148049, 20677.793222068187, 21769.673454898508, 23473.0, 24041.398965026016, 24047.27858164258, 37982.0, 38122.809385952896, 38129.23741525209, 50658.74670626771, 52281.7385913068, 295133.0, 295637.0, 296383.0, 299219.0, 299944.00055999163, 35110.64889351074, 37407.251854963295, 37449.81275093607, 37688.43467348047, 37812.43281350727, 45099.0, 46022.76988615035, 46862.06275874395, 47339.52660473351, 47506.52493475159, 23812.642810358204, 26454.14820962591, 30504.84747576248, 30815.746086142444, 31836.204094896886, 24922.875385622956, 30243.546346804374, 30635.693643063285, 31190.688093118777, 31990.52014219835, 37239.069023274635, 38199.80900095482, 38232.235355292185, 38273.4258986121, 38343.0, 31251.53122703116, 31615.841920790248, 31998.72005119769, 32004.0, 32445.675543244266, 23747.603068072895, 24904.875475622506, 24929.750702492744, 25039.624405634295, 25401.237962861145], "value": 17701.091419926197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [451.4672686230248, 486.1832122034982, 493.69390977593895, 500.78466259508417, 511.74668539073167, 22203.22288719895, 22751.658725119138, 23556.29331120067, 23656.053757849666, 25044.0, 25221.0, 25586.48827023463, 25858.96564137437, 25876.870615646894, 25904.870475647644, 6456.96771516143, 6564.179477565304, 6586.538942274043, 6613.074169616233, 6621.6027038377615, 6097.847553811146, 6378.936210637904, 6493.383128602799, 6535.0524173994745, 6542.41118299353, 6361.045843123546, 6386.712597933104, 6388.977763557832, 6413.070104834819, 6413.198350206224, 6381.074744162093, 6393.9360606394275, 6401.103845461639, 6411.0383442483535, 6425.1968503937005, 5798.797042103548, 5813.47678708914, 5847.502962248214, 5858.853528661775, 5860.033094539396, 30449.0, 30510.694893051008, 30744.8462757687, 30787.076387708406, 31344.21639459009, 2178.9649916289795, 2211.845170838042, 2222.344408399526, 2222.7777222277805, 2225.0765932138142, 18962.71555926662, 19543.609127817504, 19550.022498874998, 19555.315563955337, 19562.728422652483, 1958.608278344337, 2180.073468775764, 2228.186711850163, 2255.8082562982004, 2267.3764714703425, 154599.22700386538, 161303.193484033, 202717.0, 206581.0, 211314.94342528342, 13744.106633068819, 14588.832893368504, 14779.37905213208, 15344.462943796914, 15463.072215667096, 20155.899220503805, 20169.49576260576, 20192.0, 20230.0, 20397.592048158953, 9839.212862970946, 9845.966173551817, 9860.408375497493, 9864.753381165385, 9953.253505986935, 8875.600597973107, 9036.32227582934, 9056.003839577641, 9111.225545828545, 9129.224015958585, 7536.698532058656, 9268.7682807929, 9279.0, 9308.301877359225, 9312.674056408128, 2588.589218875717, 2596.40282734972, 2597.2727636261875, 2603.388203772112, 2709.498742732601, 815.8939337886037, 823.069930977991, 971.0968799016982, 1001.7195185348114, 1010.1363334349172, 22103.557928841656, 22377.88811055967, 22411.32766017022, 22649.773502265096, 22879.0, 984.247051005981, 985.4038306824411, 1015.4618052432223, 1019.7195771162841, 1031.7523794289466, 9886.604535818487, 10700.892991070144, 11100.833487497694, 11490.0, 13723.656908577366, 569189.0, 570219.0, 570598.0, 582615.0, 585468.0, 154927.22536387245, 154949.0, 155669.0, 155826.0, 157112.0, 1029.897010298964, 1041.95832166714, 1094.2395035450286, 1160.002397937773, 1228.8402507673945, 21777.0, 22235.66646500336, 22521.43696407603, 23163.536729265656, 23342.533149337258, 68202.63594727978, 68449.0, 69482.65258673675, 70107.64946175441, 71142.64428678031, 94714.0, 95157.0, 95429.5228523853, 96059.03940960781, 96252.0, 11405.543778248777, 12039.518419263131, 12097.81853272184, 12125.636230913087, 12162.0, 15335.846641533442, 18783.060846957324, 18854.717179242598, 18855.0, 18950.43148705541, 12374.505019799468, 15133.243337833284, 15159.772603411178, 15265.084094954338, 15328.770068448759, 13584.592462226148, 13658.180509169482, 13772.0, 13810.447582096604, 13866.584002479942, 15384.84615153834, 15659.217039147768, 15799.526014219591, 15817.604559886095, 15838.9208053959, 11920.940395297968, 11952.0, 12030.939845300718, 12033.63899083029, 12057.336846473529, 15053.924730376717, 15116.622084447537, 15153.621159471102, 15185.0, 15352.385904563693], "value": 11847.06208830923, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1425.629336372543, 1479.3269062576514, 1492.7311784982767, 1505.3000354835006, 1517.4688858899383, 11464.885351146486, 13461.663458413545, 13482.932585337072, 13509.256990865499, 13904.443822247124, 13453.461861525528, 13518.188908665486, 13660.248686322248, 13665.726685466285, 13704.794428083562, 2335.8787781864717, 2694.393761403683, 2758.668959724836, 2759.489494443524, 2770.265879541923, 2187.8796666183352, 2514.3211332940095, 2639.9604005939927, 2649.2846931328627, 2650.615660729193, 2681.5441374966204, 2690.4350086481863, 2703.2430919342614, 2703.7296270373004, 2705.702372739007, 2649.589313656377, 2661.9866900665374, 2662.4408874136457, 2670.0, 2672.906448274301, 2626.2909014566153, 2636.3409147713073, 2654.588538776493, 2673.1178711025314, 2718.8504632245217, 13004.609861704164, 13072.542461013818, 13074.607761767162, 13075.54235601759, 13176.407061682152, 5694.9715251423895, 7275.454340924452, 7299.598522081284, 7582.886256706154, 7588.658510367047, 4565.269556870918, 4727.858164255078, 4742.264948932921, 4766.976165119187, 4829.227323628238, 1173.5305877648923, 1398.7412328719117, 1405.3675845869298, 1413.3851774478135, 1429.5425463851573, 18420.447386578424, 18641.254349826126, 19475.707864382046, 19638.0, 19639.214431422726, 5456.672599644034, 6236.407541283571, 6362.141110949962, 6385.680715964183, 6557.508186885907, 13830.031897767261, 14002.369893354824, 14006.929965350108, 14012.439502419993, 14029.298535073205, 6178.938210617926, 7141.428685705131, 7307.0, 7415.59214243211, 7690.461667683208, 6672.366125218096, 6805.523613347117, 6816.977453382033, 6825.624590647562, 6836.794896153124, 4399.098184872158, 4438.822447102144, 4440.622547083534, 4465.732056076646, 4490.034642551867, 1244.9355800790195, 1437.7052704195614, 1492.7536956402073, 1499.415228061057, 1510.7960425342658, 834.4993004197433, 843.1357858195277, 866.7486428935538, 875.8554838451585, 905.4205308602368, 5817.621854579439, 5911.3201981772645, 6417.454516366068, 6792.72829086831, 7113.857722845618, 687.8417963868338, 690.5442408010689, 693.8308949420243, 704.7145905908116, 708.2174197511779, 4259.467566554181, 4334.263175260214, 4405.801738921692, 4765.690230135099, 5065.164247899129, 16396.0, 16993.320267189174, 17009.404670836462, 17032.148392580322, 17209.65580688379, 16830.83169168317, 17017.149142542825, 17056.914715426345, 17090.743638845426, 17091.97448153115, 7429.33136017761, 9012.954935225413, 9110.316726245557, 9169.037251088674, 9189.172974432333, 7012.298770122947, 7624.5425274483705, 7642.89178069188, 7667.961660191663, 8084.434089613906, 15161.469348572748, 15264.23678816077, 15451.0, 15495.380184792482, 15703.371865125268, 14598.635034124232, 15666.608334791721, 15763.527094187193, 15853.048817071012, 16070.35718571244, 8718.346124040596, 10704.571817127538, 10779.946100269448, 10803.837942431028, 10806.405647689453, 10881.673549793519, 11036.558537658724, 11996.64010079699, 12161.513539458323, 12222.633321000385, 9976.700698979042, 9982.650607228712, 10014.3490673106, 10024.899751002396, 10032.949835250776, 8015.438919275594, 9589.0, 9663.661771838233, 9735.561899714388, 9855.35940163887, 8836.381453298207, 8864.157904999014, 8925.062868398725, 8968.372213944962, 8984.326175536991, 6352.618842869442, 6376.617402955838, 6395.456386207225, 6730.259671436236, 6790.0, 5588.972055139699, 5825.912611310749, 6843.794686159423, 6990.650467476504, 7032.578045317297], "value": 5830.440580326361, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [427.9358096285555, 435.69936743646883, 442.85828534868847, 451.6341763171829, 463.64994429205933, 5080.542751152396, 6346.492280617551, 6538.084668146457, 6557.573757705752, 6604.900926486101, 6192.411720886508, 6344.302126766052, 6358.300586935434, 6376.649284289362, 6382.521310901678, 4081.897952551195, 4277.2087163874785, 4856.6357523185725, 5032.018756342514, 5040.3951525816865, 4986.850394488171, 5026.623003274769, 5039.31969184161, 5049.3940727112795, 5054.898902021976, 4238.766867822269, 4706.858794236178, 5007.724575148365, 5015.0, 5029.723365214912, 4668.043051174501, 4668.229742092568, 4981.003799240167, 4990.0, 5019.573336266421, 4561.498235194126, 4568.1548913451115, 4568.725876447391, 4569.13186494565, 4576.633869290449, 5121.513456221653, 6354.87290254197, 6379.819733349345, 6379.872402551923, 6416.839579010562, 5178.611604129667, 6069.696515174223, 6106.236720409949, 6107.938920610826, 6129.264488261392, 6977.197622273453, 7013.33373329538, 7018.227994920555, 7024.648767561602, 7069.787906362817, 5242.370915490165, 5294.602904782157, 5301.840944771663, 5302.549283310923, 5340.385855626614, 6828.2147553031045, 7322.997570995919, 7337.963310183415, 7340.4127669786285, 7351.889721654179, 4868.367112275452, 4873.4882837301575, 4884.413870335582, 4908.263760435963, 4910.386201724785, 4422.535633758409, 4929.926051109236, 4958.925616115761, 4985.775640096204, 5002.424721157114, 4985.90028199434, 5528.364238112668, 5668.489835914787, 5682.176084467812, 5710.514606258432, 6059.454649081604, 6926.619035953071, 6926.722931082701, 6933.965330173418, 6968.3728464438445, 1701.9916498531604, 1704.8977061376356, 1709.444430560073, 1710.1962077823403, 1716.4678949525687, 1116.151724689242, 1127.3574062784135, 1157.311399717167, 1157.4155051699006, 1158.7161145519233, 6506.25178104524, 6511.906844248944, 6522.445592124628, 6529.640869752115, 6537.607743535308, 7122.28777122294, 7127.60798156096, 7141.321574450419, 7157.778999450268, 7170.960210769409, 6462.32145624712, 6474.805755827333, 6481.384268494486, 6505.934940650627, 6509.251436084911, 3691.9077023074638, 3733.5893051764283, 3749.9062523436587, 3757.3988161893967, 3776.773393596338, 5858.619189752738, 6708.3291670833505, 7114.395565985981, 7362.312755844078, 7424.740134095281, 6133.877322453526, 7360.521566098187, 7362.079740032496, 7450.455222314384, 7460.776176714708, 6451.516136289797, 6851.725930962705, 7205.747798827121, 7324.340809327186, 7429.9257007430315, 7107.573545587281, 7125.188024406917, 7140.642967851691, 7147.46394020461, 7153.35619794221, 6411.743530258737, 6782.0505129280955, 6967.024616553788, 6969.233384327821, 7015.543989640657, 7101.0, 7249.891251631124, 7282.453815963718, 7282.563046217244, 7300.671469783979, 4552.840650577214, 4834.250691142878, 4862.440819305683, 4881.0, 4886.947482057473, 5868.3251426086545, 5926.348101708722, 7171.780797264373, 7176.277693353662, 7178.748743793943, 6281.309056003744, 6336.936630633634, 6832.726690932307, 6914.688839002358, 6955.269696681776, 6805.795826125224, 6813.727450901908, 6830.677569626152, 6882.483813714091, 6891.862162756616, 6342.841428964312, 6729.149483891969, 6747.987801829765, 6767.492438067066, 6781.38967492928, 6214.0, 6914.688839002157, 6926.757563485456, 6935.583864968118, 6978.895316570154, 5522.558195344443, 6674.466042716668, 6704.832379190364, 6723.7646682365885, 6735.562188457735], "value": 5156.473369881037, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [828.0849661124457, 829.0880031964844, 841.7895526118471, 865.9567021648916, 871.8779370888077, 11960.863717946802, 14912.925435372836, 17211.22549485273, 17588.0, 17658.0, 15174.393024279043, 16693.33226670935, 18136.36522721705, 18168.09159542021, 18204.635907281845, 973.8636590877283, 981.0875885426533, 1001.5843424978625, 1002.4236064263041, 1002.9247806414512, 1013.2198207380312, 1024.6106479537766, 1026.471367245869, 1028.2956175020136, 1028.4908970059794, 1017.8778546574384, 1059.4808543813554, 1069.6951368859877, 1073.4847273308817, 1075.166745772025, 1056.6354607660344, 1058.9894101059006, 1066.9970227985668, 1067.2369255981932, 1070.0850772589401, 772.3164998975891, 775.1395950494946, 778.6768491076197, 779.3764988009626, 782.7025730222479, 17154.914225428794, 17438.0, 17499.387521436816, 17658.823411765847, 18068.0, 17853.018084005376, 18014.459566212903, 18120.90939545307, 18163.63672726538, 18399.72400413995, 14636.780448293284, 14754.0, 14774.70450590993, 14911.254437278201, 14920.104793712304, 3584.444411116255, 3585.94621080684, 3589.5333606631234, 3623.058004918714, 3639.4358874374516, 112119.22748111413, 130148.6985130146, 135749.96375054383, 136892.946605801, 140777.0, 13918.652033699036, 14024.859751402559, 14251.0, 14397.42410303576, 14417.92791036038, 26782.0, 26945.461090778073, 27097.593536096974, 27211.591826122625, 27230.319242019108, 7816.452848300678, 8015.318697910627, 8046.195380461907, 8066.11272759996, 8101.068377136587, 3487.3897068013, 3797.0, 3965.1078507336197, 4029.435878976946, 4030.879073627796, 3939.5272567291536, 4570.428696412949, 4682.876109733707, 4688.296755486705, 4698.600618947359, 870.7823044238941, 1004.9145822605014, 1022.7903279827617, 1027.7893031928436, 1033.705393962717, 644.8678021005683, 653.9967300163564, 667.742918976188, 671.5802623360399, 685.7839780469136, 9390.34267601275, 11095.944520277348, 11566.94216528912, 12422.440990155465, 12687.80968285458, 620.8230654263604, 630.5964182922933, 648.7145655911384, 671.633959492078, 681.0907438569466, 4387.3857659927635, 5471.261379713767, 5590.5248053915875, 5663.490285874291, 5879.764809407661, 115473.0, 128073.0, 136284.0, 142789.0, 143162.85255721174, 113813.0, 115787.0, 120515.39742301233, 123555.0, 124193.0, 92419.0, 96937.51531242298, 104067.0, 104321.0, 110280.0, 10692.037716605542, 11253.49359278818, 11454.942725286654, 11982.700432489257, 13053.216806991612, 37235.44146837745, 41108.35566577304, 43139.784301078296, 43175.35236971385, 46924.530754692016, 62154.37845621486, 62166.68916655388, 62185.0, 62224.68887655533, 62542.687286563276, 16835.747463787808, 16966.660666786844, 17213.56966075858, 17320.82679173192, 17378.47864564065, 18276.634467310312, 18752.6249475007, 18916.0, 18988.810111898703, 19036.71444928355, 17401.47795566135, 17404.477865664052, 17465.476035718948, 17563.121843908008, 17730.91134544319, 16911.408100716908, 16994.575135621708, 17531.912340438215, 17649.294028238728, 17679.73480397821, 16534.421295254608, 16710.331586736393, 16724.0, 16871.493855184362, 16951.745723813907, 11643.12676549279, 11685.415729213675, 11790.705232369259, 12315.876841231473, 12338.259704417405, 12068.657250134207, 12529.684383140004, 12976.870231297944, 13158.868411315765, 14275.785863212268], "value": 8756.16050363126, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1557.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 116.0, 98.0, 112.0, 116.0, 115.0, 116.0, 113.0, 114.0, 115.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 135.0, 190.0, 140.0, 132.0, 129.0, 108.0, 167.0, 111.0], "value": 137.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 201.0, 211.0, 233.0, 263.0, 283.0, 263.0, 200.0, 199.0], "value": 225.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 82.0, 75.0, 150.0, 62.0, 63.0, 61.0, 64.0, 125.0, 62.0], "value": 81.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 114.0, 70.0, 70.0, 78.0, 116.0, 81.0, 103.0, 107.0], "value": 87.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 91.0, 89.0, 90.0, 90.0, 90.0, 97.0, 90.0, 101.0, 106.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 97.0, 97.0, 97.0, 96.0, 97.0, 97.0, 99.0, 97.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 93.0, 89.0, 94.0, 92.0, 90.0, 94.0, 92.0, 90.0], "value": 91.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 50.0, 52.0, 55.0, 51.0, 53.0, 54.0, 74.0, 78.0, 79.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 62.0, 70.0, 93.0, 84.0, 78.0, 56.0, 57.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 110.0, 84.0, 84.0, 86.0, 88.0, 111.0, 114.0, 111.0, 92.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 172.0, 159.0, 148.0, 136.0, 134.0, 136.0, 134.0, 132.0, 165.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 185.0, 207.0, 177.0, 232.0, 235.0, 177.0, 187.0, 195.0, 180.0], "value": 195.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 67.0, 64.0, 66.0, 69.0, 63.0, 67.0, 65.0, 66.0], "value": 66.0, "unit": "score"}]}]} 14:59:34 INFO - Return code: 0 14:59:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:59:34 INFO - # TBPL SUCCESS # 14:59:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:59:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:59:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:59:34 INFO - 2017-06-09 14:59:34.939 screenresolution[1676:11883] starting screenresolution argv=screenresolution get 14:59:34 INFO - 2017-06-09 14:59:34.941 screenresolution[1676:11883] Display 0: 1600x1200x32@60 14:59:34 INFO - 2017-06-09 14:59:34.947 screenresolution[1677:11886] starting screenresolution argv=screenresolution list 14:59:34 INFO - Available Modes on Display 0 14:59:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:59:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:59:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:59:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:59:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:59:35 INFO - Intel Iris: 14:59:35 INFO - Chipset Model: Intel Iris 14:59:35 INFO - Type: GPU 14:59:35 INFO - Bus: Built-In 14:59:35 INFO - VRAM (Dynamic, Max): 1536 MB 14:59:35 INFO - Vendor: Intel (0x8086) 14:59:35 INFO - Device ID: 0x0a2e 14:59:35 INFO - Revision ID: 0x0009 14:59:35 INFO - Displays: 14:59:35 INFO - Display: 14:59:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:59:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:59:35 INFO - Display Serial Number: 1600x1200 60 14:59:35 INFO - Main Display: Yes 14:59:35 INFO - Mirror: Off 14:59:35 INFO - Online: Yes 14:59:35 INFO - Rotation: Supported 14:59:35 INFO - Return code: 0 14:59:35 INFO - Running post-action listener: _resource_record_post_action 14:59:35 INFO - [mozharness: 2017-06-09 21:59:35.181905Z] Finished run-tests step (success) 14:59:35 INFO - Running post-run listener: _resource_record_post_run 14:59:35 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40950784; Write bytes: 574521856; Read time: 278; Write time: 1781 14:59:35 INFO - TinderboxPrint: CPU usage
53.3% 14:59:35 INFO - TinderboxPrint: I/O read bytes / time
40,950,784 / 278 14:59:35 INFO - TinderboxPrint: I/O write bytes / time
574,521,856 / 1,781 14:59:35 INFO - TinderboxPrint: CPU idle
2,345.1 (46.7%) 14:59:35 INFO - TinderboxPrint: CPU system
1,022.1 (20.3%) 14:59:35 INFO - TinderboxPrint: CPU user
1,658.6 (33.0%) 14:59:35 INFO - TinderboxPrint: Swap in / out
292,073,472 / 0 14:59:35 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249481728; Write bytes: 244447232; Read time: 17961; Write time: 535 14:59:35 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:59:35 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 38779904; Write bytes: 317774336; Read time: 254; Write time: 1207 14:59:35 INFO - Running post-run listener: _upload_blobber_files 14:59:35 INFO - Blob upload gear active. 14:59:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:59:35 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 14:59:35 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'] 14:59:35 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 14:59:36 INFO - (blobuploader) - INFO - Open directory for files ... 14:59:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:59:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:59:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:37 INFO - (blobuploader) - INFO - Done attempting. 14:59:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:59:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:59:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:38 INFO - (blobuploader) - INFO - Done attempting. 14:59:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:59:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:59:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:39 INFO - (blobuploader) - INFO - Done attempting. 14:59:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:59:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:59:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:59:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:59:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:59:41 INFO - (blobuploader) - INFO - Done attempting. 14:59:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:59:41 INFO - Return code: 0 14:59:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:59:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:59:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382009911b412ce85478d9875bf2e207383f0bad7b028ea7be1753a2a75c86371a4aba4e31a8c23628601a53f75b369eed8c8af933203860de1d1e991775570", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d499afb7ca7e662528b217dc859245c1770b8ddc995413a96a712d84754a087c535a5a6ecd04674c1f61aa09bf2a213ea2980c250d0efd53c2321c3ac43f2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44cd76ce4e6040e4f4a7c64a40f55ac57cc241553619c277ce91235a245660ff80bf0636451c619be354c2c27cc5a2767dc80f73cea2ec97531892689c69daa3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd27d82b5298972536d147dcef03d5d8a5441093428b934028bcf06d03a9850751f28344b7793d10d5fa3c38c7f3edb2620f9918c521af9ee161dfc432fbb2f"} 14:59:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:59:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:59:41 INFO - Contents: 14:59:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0382009911b412ce85478d9875bf2e207383f0bad7b028ea7be1753a2a75c86371a4aba4e31a8c23628601a53f75b369eed8c8af933203860de1d1e991775570", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d499afb7ca7e662528b217dc859245c1770b8ddc995413a96a712d84754a087c535a5a6ecd04674c1f61aa09bf2a213ea2980c250d0efd53c2321c3ac43f2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44cd76ce4e6040e4f4a7c64a40f55ac57cc241553619c277ce91235a245660ff80bf0636451c619be354c2c27cc5a2767dc80f73cea2ec97531892689c69daa3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd27d82b5298972536d147dcef03d5d8a5441093428b934028bcf06d03a9850751f28344b7793d10d5fa3c38c7f3edb2620f9918c521af9ee161dfc432fbb2f"} 14:59:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:59:41 INFO - Copying logs to upload dir... 14:59:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:59:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1340.105360 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 20 secs) (at 2017-06-09 14:59:41.518853) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 14:59:41.522204) ========= 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.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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/0382009911b412ce85478d9875bf2e207383f0bad7b028ea7be1753a2a75c86371a4aba4e31a8c23628601a53f75b369eed8c8af933203860de1d1e991775570", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d499afb7ca7e662528b217dc859245c1770b8ddc995413a96a712d84754a087c535a5a6ecd04674c1f61aa09bf2a213ea2980c250d0efd53c2321c3ac43f2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44cd76ce4e6040e4f4a7c64a40f55ac57cc241553619c277ce91235a245660ff80bf0636451c619be354c2c27cc5a2767dc80f73cea2ec97531892689c69daa3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd27d82b5298972536d147dcef03d5d8a5441093428b934028bcf06d03a9850751f28344b7793d10d5fa3c38c7f3edb2620f9918c521af9ee161dfc432fbb2f"} build_url:https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011356 build_url: 'https://queue.taskcluster.net/v1/task/Naaq1xLeReWGadCkb5pBAQ/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/0382009911b412ce85478d9875bf2e207383f0bad7b028ea7be1753a2a75c86371a4aba4e31a8c23628601a53f75b369eed8c8af933203860de1d1e991775570", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6d499afb7ca7e662528b217dc859245c1770b8ddc995413a96a712d84754a087c535a5a6ecd04674c1f61aa09bf2a213ea2980c250d0efd53c2321c3ac43f2e7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/44cd76ce4e6040e4f4a7c64a40f55ac57cc241553619c277ce91235a245660ff80bf0636451c619be354c2c27cc5a2767dc80f73cea2ec97531892689c69daa3", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5fd27d82b5298972536d147dcef03d5d8a5441093428b934028bcf06d03a9850751f28344b7793d10d5fa3c38c7f3edb2620f9918c521af9ee161dfc432fbb2f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-09 14:59:41.555594) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:59:41.555924) ========= 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.7cZMACNyki/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.9TYTW6ZdeN/Listeners TMPDIR=/var/folders/tn/81tmcd2n6_38yjsnzhmnyzmm00000w/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.005267 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-09 14:59:41.604606) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 14:59:41.604924) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-09 14:59:41.605250) ========= ========= Total master_lag: 0.17 =========