builder: mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s slave: t-yosemite-r7-0026 starttime: 1497993747.16 results: success (0) buildid: 20170620115250 builduid: ebf1f9f3c634441f808e47495bf37e33 revision: 4a0e45a42904d6aac5716975f30e9e12a2624426 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.162425) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.163025) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.163412) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.190953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.191643) ========= 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.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-20 14:22:27-- 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% 41.2M=0s 2017-06-20 14:22:27 (41.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.153277 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.367642) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.367959) ========= 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.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.029542 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:27.417058) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 14:22:27.417444) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4a0e45a42904d6aac5716975f30e9e12a2624426 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4a0e45a42904d6aac5716975f30e9e12a2624426 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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-20 14:22:27,502 truncating revision to first 12 chars 2017-06-20 14:22:27,502 Setting DEBUG logging. 2017-06-20 14:22:27,502 attempt 1/10 2017-06-20 14:22:27,502 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4a0e45a42904?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-20 14:22:29,206 unpacking tar archive at: mozilla-beta-4a0e45a42904/testing/mozharness/ program finished with exit code 0 elapsedTime=1.986485 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-20 14:22:29.425679) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:29.426079) ========= script_repo_revision: 4a0e45a42904d6aac5716975f30e9e12a2624426 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:29.426488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:29.426775) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-20 14:22:29.444761) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 14:22:29.445181) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:22:29 INFO - MultiFileLogger online at 20170620 14:22:29 in /builds/slave/test 14:22:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 14:22:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:22:29 INFO - {'append_to_log': False, 14:22:29 INFO - 'base_work_dir': '/builds/slave/test', 14:22:29 INFO - 'blob_upload_branch': 'Mozilla-Beta', 14:22:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:22:29 INFO - 'branch': 'Mozilla-Beta', 14:22:29 INFO - 'buildbot_json_path': 'buildprops.json', 14:22:29 INFO - 'config_files': ('talos/mac_config.py',), 14:22:29 INFO - 'default_actions': ('clobber', 14:22:29 INFO - 'read-buildbot-config', 14:22:29 INFO - 'download-and-extract', 14:22:29 INFO - 'populate-webroot', 14:22:29 INFO - 'create-virtualenv', 14:22:29 INFO - 'install', 14:22:29 INFO - 'setup-mitmproxy', 14:22:29 INFO - 'run-tests'), 14:22:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:22:29 INFO - 'download_minidump_stackwalk': True, 14:22:29 INFO - 'download_symbols': 'ondemand', 14:22:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:22:29 INFO - 'tooltool.py': '/tools/tooltool.py', 14:22:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:22:29 INFO - '/tools/misc-python/virtualenv.py')}, 14:22:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:22:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:22:29 INFO - 'gecko_profile': False, 14:22:29 INFO - 'gecko_profile_interval': 0, 14:22:29 INFO - 'installer_path': 'installer.exe', 14:22:29 INFO - 'log_level': 'info', 14:22:29 INFO - 'log_name': 'talos', 14:22:29 INFO - 'log_to_console': True, 14:22:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:22:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:22:29 INFO - 'opt_config_files': (), 14:22:29 INFO - 'pip_index': False, 14:22:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:29 INFO - 'cmd': ('bash', 14:22:29 INFO - '-c', 14:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:29 INFO - 'enabled': True, 14:22:29 INFO - 'halt_on_failure': False, 14:22:29 INFO - 'name': 'check_screen_resolution'},), 14:22:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:29 INFO - 'cmd': ('bash', 14:22:29 INFO - '-c', 14:22:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:29 INFO - 'enabled': True, 14:22:29 INFO - 'halt_on_failure': False, 14:22:29 INFO - 'name': 'check_screen_resolution'},), 14:22:29 INFO - 'run_cmd_checks_enabled': True, 14:22:29 INFO - 'suite': 'dromaeojs-e10s', 14:22:29 INFO - 'system_bits': '32', 14:22:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:22:29 INFO - 'title': 't-yosemite-r7-0026', 14:22:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:22:29 INFO - 'use_talos_json': True, 14:22:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:22:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:22:29 INFO - 'work_dir': 'build'} 14:22:29 INFO - [mozharness: 2017-06-20 21:22:29.646633Z] Running clobber step. 14:22:29 INFO - Running pre-action listener: _resource_record_pre_action 14:22:29 INFO - Running main action method: clobber 14:22:29 INFO - rmtree: /builds/slave/test/build 14:22:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:22:32 INFO - Running post-action listener: _resource_record_post_action 14:22:32 INFO - [mozharness: 2017-06-20 21:22:32.271285Z] Finished clobber step (success) 14:22:32 INFO - [mozharness: 2017-06-20 21:22:32.271412Z] Running read-buildbot-config step. 14:22:32 INFO - Running pre-action listener: _resource_record_pre_action 14:22:32 INFO - Running main action method: read_buildbot_config 14:22:32 INFO - Using buildbot properties: 14:22:32 INFO - { 14:22:32 INFO - "project": "", 14:22:32 INFO - "product": "firefox", 14:22:32 INFO - "script_repo_revision": "production", 14:22:32 INFO - "builddir": "mozilla-beta_yosemite_r7_devedition_test-dromaeojs-e10s", 14:22:32 INFO - "repository": "", 14:22:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 DevEdition mozilla-beta talos dromaeojs-e10s", 14:22:32 INFO - "buildid": "20170620115250", 14:22:32 INFO - "pgo_build": "False", 14:22:32 INFO - "basedir": "/builds/slave/test", 14:22:32 INFO - "buildnumber": 9, 14:22:32 INFO - "slavename": "t-yosemite-r7-0026", 14:22:32 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:22:32 INFO - "platform": "yosemite_r7_devedition", 14:22:32 INFO - "scheduler": "tests-mozilla-beta-macosx64-devedition-talos", 14:22:32 INFO - "branch": "mozilla-beta", 14:22:32 INFO - "repo_path": "releases/mozilla-beta", 14:22:32 INFO - "revision": "4a0e45a42904d6aac5716975f30e9e12a2624426", 14:22:32 INFO - "stage_platform": "macosx64-devedition", 14:22:32 INFO - "builduid": "ebf1f9f3c634441f808e47495bf37e33", 14:22:32 INFO - "slavebuilddir": "test" 14:22:32 INFO - } 14:22:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg. 14:22:32 INFO - Running post-action listener: _resource_record_post_action 14:22:32 INFO - [mozharness: 2017-06-20 21:22:32.274601Z] Finished read-buildbot-config step (success) 14:22:32 INFO - [mozharness: 2017-06-20 21:22:32.274725Z] Running download-and-extract step. 14:22:32 INFO - Running pre-action listener: _resource_record_pre_action 14:22:32 INFO - Running main action method: download_and_extract 14:22:32 INFO - mkdir: /builds/slave/test/build/tests 14:22:32 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:22:32 INFO - trying https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 14:22:32 INFO - Downloading https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 14:22:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 14:22:33 INFO - Downloaded 1290 bytes. 14:22:33 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 14:22:33 INFO - Using the following test package requirements: 14:22:33 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 14:22:33 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 14:22:33 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 14:22:33 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 14:22:33 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 14:22:33 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 14:22:33 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 14:22:33 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 14:22:33 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 14:22:33 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 14:22:33 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 14:22:33 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 14:22:33 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 14:22:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 14:22:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 14:22:33 INFO - Fetch https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 14:22:35 INFO - Content-Length response header: 59547742 14:22:35 INFO - Bytes received: 59547742 14:22:53 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 14:22:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 14:22:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 14:22:53 INFO - Fetch https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 14:22:55 INFO - Content-Length response header: 59547742 14:22:55 INFO - Bytes received: 59547742 14:23:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 14:23:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 14:23:12 INFO - Fetch https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 14:23:14 INFO - Content-Length response header: 14454470 14:23:14 INFO - Bytes received: 14454470 14:23: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:23:14 INFO - trying https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 14:23:14 INFO - Downloading https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 14:23:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 14:23:16 INFO - Downloaded 66841335 bytes. 14:23:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 14:23:16 INFO - mkdir: /builds/slave/test/properties 14:23:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:23:16 INFO - Writing to file /builds/slave/test/properties/build_url 14:23:16 INFO - Contents: 14:23:16 INFO - build_url:https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 14:23:17 INFO - Running post-action listener: _resource_record_post_action 14:23:17 INFO - Running post-action listener: set_extra_try_arguments 14:23:17 INFO - [mozharness: 2017-06-20 21:23:17.015775Z] Finished download-and-extract step (success) 14:23:17 INFO - [mozharness: 2017-06-20 21:23:17.015923Z] Running populate-webroot step. 14:23:17 INFO - Running pre-action listener: _resource_record_pre_action 14:23:17 INFO - Running main action method: populate_webroot 14:23:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:23:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:23:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:23:17 INFO - '--tppagecycles', 14:23:17 INFO - '7'], 14:23:17 INFO - 'tests': ['tsvgm']}}, 14:23:17 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:23:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:23:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:23:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:23:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['damp', 'tps']}, 14:23:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'tests': ['damp', 'tps']}, 14:23:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['dromaeo_dom']}, 14:23:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:23:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 14:23:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 14:23:17 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 14:23:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'other-e10s': {'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['a11yr', 14:23:17 INFO - 'ts_paint', 14:23:17 INFO - 'tpaint', 14:23:17 INFO - 'sessionrestore', 14:23:17 INFO - 'sessionrestore_no_auto_restore', 14:23:17 INFO - 'tabpaint']}, 14:23:17 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 14:23:17 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 14:23:17 INFO - 'bloom_basic_ref']}, 14:23:17 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 14:23:17 INFO - 'talos_options': ['--mitmproxy', 14:23:17 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 14:23:17 INFO - '--firstNonBlankPaint'], 14:23:17 INFO - 'tests': ['quantum_pageload_google', 14:23:17 INFO - 'quantum_pageload_youtube', 14:23:17 INFO - 'quantum_pageload_amazon', 14:23:17 INFO - 'quantum_pageload_facebook']}, 14:23:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['tsvgx', 14:23:17 INFO - 'tsvgr_opacity', 14:23:17 INFO - 'tart', 14:23:17 INFO - 'tscrollx', 14:23:17 INFO - 'cart', 14:23:17 INFO - 'tsvg_static']}, 14:23:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:23:17 INFO - 'tsvgr_opacity', 14:23:17 INFO - 'tart', 14:23:17 INFO - 'tscrollx', 14:23:17 INFO - 'cart', 14:23:17 INFO - 'tsvg_static']}, 14:23:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'talos_options': ['--disable-e10s'], 14:23:17 INFO - 'tests': ['tp5o']}, 14:23:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:23:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'talos_options': ['--disable-e10s', 14:23:17 INFO - '--xperf_path', 14:23:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:17 INFO - 'tests': ['tp5n']}, 14:23:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:23:17 INFO - 'talos_options': ['--xperf_path', 14:23:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:23:17 INFO - 'tests': ['tp5n']}}, 14:23:17 INFO - 'talos.zip': {'path': '', 14:23:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:23:17 INFO - Running post-action listener: _resource_record_post_action 14:23:17 INFO - [mozharness: 2017-06-20 21:23:17.033961Z] Finished populate-webroot step (success) 14:23:17 INFO - [mozharness: 2017-06-20 21:23:17.034069Z] Running create-virtualenv step. 14:23:17 INFO - Running pre-action listener: _resource_record_pre_action 14:23:17 INFO - Running main action method: create_virtualenv 14:23:17 INFO - Creating virtualenv /builds/slave/test/build/venv 14:23:17 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:23:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:23:17 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 14:23:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:23:17 INFO - Using real prefix '/tools/python27' 14:23:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:23:17 INFO - Installing distribute.............................................................................................................................................................................................done. 14:23:20 INFO - Installing pip.................done. 14:23:20 INFO - Return code: 0 14:23:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:23:20 INFO - Reading from file tmpfile_stdout 14:23:20 INFO - Output received: 14:23:20 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:23:20 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:23: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:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23: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:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23: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', 'pip>=1.5'] in /builds/slave/test/build 14:23: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 pip>=1.5 14:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:20 INFO - 'HOME': '/Users/cltbld', 14:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:20 INFO - 'LOGNAME': 'cltbld', 14:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:20 INFO - 'MOZ_NO_REMOTE': '1', 14:23:20 INFO - 'NO_EM_RESTART': '1', 14:23:20 INFO - 'PAGER': '/bin/cat', 14:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:21 INFO - 'PWD': '/builds/slave/test', 14:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:21 INFO - 'SHELL': '/bin/bash', 14:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:21 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:21 INFO - 'USER': 'cltbld', 14:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:21 INFO - 'XPC_FLAGS': '0x0', 14:23:21 INFO - 'XPC_SERVICE_NAME': '0', 14:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:21 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:23:21 INFO - Cleaning up... 14:23:21 INFO - Return code: 0 14:23:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:21 INFO - 'HOME': '/Users/cltbld', 14:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:21 INFO - 'LOGNAME': 'cltbld', 14:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:21 INFO - 'MOZ_NO_REMOTE': '1', 14:23:21 INFO - 'NO_EM_RESTART': '1', 14:23:21 INFO - 'PAGER': '/bin/cat', 14:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:21 INFO - 'PWD': '/builds/slave/test', 14:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:21 INFO - 'SHELL': '/bin/bash', 14:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:21 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:21 INFO - 'USER': 'cltbld', 14:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:21 INFO - 'XPC_FLAGS': '0x0', 14:23:21 INFO - 'XPC_SERVICE_NAME': '0', 14:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:21 INFO - Downloading/unpacking psutil>=3.1.1 14:23: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:23: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:23: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:23: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:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:23 INFO - Installing collected packages: psutil 14:23:23 INFO - Running setup.py install for psutil 14:23:23 INFO - building 'psutil._psutil_osx' extension 14:23:23 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:23:24 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:23:24 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:23:24 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:23:24 INFO - building 'psutil._psutil_posix' extension 14:23:24 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:23:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:23:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:23:24 INFO - ^ 14:23:24 INFO - 1 warning generated. 14:23:24 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:23:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:23:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:23:24 INFO - Successfully installed psutil 14:23:24 INFO - Cleaning up... 14:23:24 INFO - Return code: 0 14:23:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:23:24 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:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23: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:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:25 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:25 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:23:25 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:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:25 INFO - 'HOME': '/Users/cltbld', 14:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:25 INFO - 'LOGNAME': 'cltbld', 14:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:25 INFO - 'MOZ_NO_REMOTE': '1', 14:23:25 INFO - 'NO_EM_RESTART': '1', 14:23:25 INFO - 'PAGER': '/bin/cat', 14:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:25 INFO - 'PWD': '/builds/slave/test', 14:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:25 INFO - 'SHELL': '/bin/bash', 14:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:25 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:25 INFO - 'USER': 'cltbld', 14:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:25 INFO - 'XPC_FLAGS': '0x0', 14:23:25 INFO - 'XPC_SERVICE_NAME': '0', 14:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:23: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:23: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:23: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:23: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:23:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:23:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:23:27 INFO - Installing collected packages: mozsystemmonitor 14:23:27 INFO - Running setup.py install for mozsystemmonitor 14:23:27 INFO - Successfully installed mozsystemmonitor 14:23:27 INFO - Cleaning up... 14:23:27 INFO - Return code: 0 14:23:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:27 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:27 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:23:27 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:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:27 INFO - 'HOME': '/Users/cltbld', 14:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:27 INFO - 'LOGNAME': 'cltbld', 14:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:27 INFO - 'MOZ_NO_REMOTE': '1', 14:23:27 INFO - 'NO_EM_RESTART': '1', 14:23:27 INFO - 'PAGER': '/bin/cat', 14:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:27 INFO - 'PWD': '/builds/slave/test', 14:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:27 INFO - 'SHELL': '/bin/bash', 14:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:27 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:27 INFO - 'USER': 'cltbld', 14:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:27 INFO - 'XPC_FLAGS': '0x0', 14:23:27 INFO - 'XPC_SERVICE_NAME': '0', 14:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:27 INFO - Downloading/unpacking jsonschema==2.5.1 14:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:29 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:29 INFO - warning: no files found matching '*.txt' 14:23:29 INFO - no previously-included directories found matching 'build' 14:23:29 INFO - no previously-included directories found matching 'dist' 14:23:29 INFO - no previously-included directories found matching '.git*' 14:23:29 INFO - Installing collected packages: jsonschema, functools32 14:23:29 INFO - Running setup.py install for functools32 14:23:29 INFO - warning: no files found matching '*.txt' 14:23:29 INFO - no previously-included directories found matching 'build' 14:23:29 INFO - no previously-included directories found matching 'dist' 14:23:29 INFO - no previously-included directories found matching '.git*' 14:23:29 INFO - Successfully installed jsonschema functools32 14:23:29 INFO - Cleaning up... 14:23:29 INFO - Return code: 0 14:23:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:29 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:29 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:23:29 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:23:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:29 INFO - 'HOME': '/Users/cltbld', 14:23:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:29 INFO - 'LOGNAME': 'cltbld', 14:23:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:29 INFO - 'MOZ_NO_REMOTE': '1', 14:23:29 INFO - 'NO_EM_RESTART': '1', 14:23:29 INFO - 'PAGER': '/bin/cat', 14:23:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:29 INFO - 'PWD': '/builds/slave/test', 14:23:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:29 INFO - 'SHELL': '/bin/bash', 14:23:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:29 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:29 INFO - 'USER': 'cltbld', 14:23:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:29 INFO - 'XPC_FLAGS': '0x0', 14:23:29 INFO - 'XPC_SERVICE_NAME': '0', 14:23:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:23:30 INFO - Cleaning up... 14:23:30 INFO - Return code: 0 14:23:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:30 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:30 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:23:30 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:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:30 INFO - 'HOME': '/Users/cltbld', 14:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:30 INFO - 'LOGNAME': 'cltbld', 14:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:30 INFO - 'MOZ_NO_REMOTE': '1', 14:23:30 INFO - 'NO_EM_RESTART': '1', 14:23:30 INFO - 'PAGER': '/bin/cat', 14:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:30 INFO - 'PWD': '/builds/slave/test', 14:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:30 INFO - 'SHELL': '/bin/bash', 14:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:30 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:30 INFO - 'USER': 'cltbld', 14:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:30 INFO - 'XPC_FLAGS': '0x0', 14:23:30 INFO - 'XPC_SERVICE_NAME': '0', 14:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:30 INFO - Downloading/unpacking blobuploader==1.2.4 14:23:30 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:23:30 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:23:30 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:23:30 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:23:31 INFO - Downloading blobuploader-1.2.4.tar.gz 14:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:23:31 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:23:31 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:23:31 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:23:31 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:23:31 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:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:23:32 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:32 INFO - Downloading docopt-0.6.1.tar.gz 14:23:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:23:33 INFO - Installing collected packages: blobuploader, requests, docopt 14:23:33 INFO - Running setup.py install for blobuploader 14:23:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:23:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:23:33 INFO - Running setup.py install for requests 14:23:33 INFO - Running setup.py install for docopt 14:23:33 INFO - Successfully installed blobuploader requests docopt 14:23:33 INFO - Cleaning up... 14:23:33 INFO - Return code: 0 14:23:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:33 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:33 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:23:33 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:23:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:33 INFO - 'HOME': '/Users/cltbld', 14:23:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:33 INFO - 'LOGNAME': 'cltbld', 14:23:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:33 INFO - 'MOZ_NO_REMOTE': '1', 14:23:33 INFO - 'NO_EM_RESTART': '1', 14:23:33 INFO - 'PAGER': '/bin/cat', 14:23:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:33 INFO - 'PWD': '/builds/slave/test', 14:23:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:33 INFO - 'SHELL': '/bin/bash', 14:23:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:33 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:33 INFO - 'USER': 'cltbld', 14:23:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:33 INFO - 'XPC_FLAGS': '0x0', 14:23:33 INFO - 'XPC_SERVICE_NAME': '0', 14:23:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-g9Drwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-H8QLOp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-GGMQFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Me00R7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-T48zZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-uzt4vZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Pas8YT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-b4aZMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:34 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-txxqQj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Lru1kW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-G_I74D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-Etqt4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-L4y_F1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-rr0GpK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-8pcGA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-rsy8Ew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:35 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-jGMI6n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:35 INFO - Running setup.py install for manifestparser 14:23:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Running setup.py install for mozcrash 14:23:36 INFO - Running setup.py install for mozdebug 14:23:36 INFO - Running setup.py install for mozdevice 14:23:36 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Running setup.py install for mozfile 14:23:36 INFO - Running setup.py install for mozhttpd 14:23:36 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Running setup.py install for mozinfo 14:23:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Running setup.py install for mozInstall 14:23:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:36 INFO - Running setup.py install for mozleak 14:23:37 INFO - Running setup.py install for mozlog 14:23:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Running setup.py install for moznetwork 14:23:37 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Running setup.py install for mozprocess 14:23:37 INFO - Running setup.py install for mozprofile 14:23:37 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Running setup.py install for mozrunner 14:23:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:37 INFO - Running setup.py install for mozscreenshot 14:23:38 INFO - Running setup.py install for moztest 14:23:38 INFO - Running setup.py install for mozversion 14:23:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:38 INFO - Cleaning up... 14:23:38 INFO - Return code: 0 14:23:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:38 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:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:38 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:23:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:38 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:38 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:23:38 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:23:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:38 INFO - 'HOME': '/Users/cltbld', 14:23:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:38 INFO - 'LOGNAME': 'cltbld', 14:23:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:38 INFO - 'MOZ_NO_REMOTE': '1', 14:23:38 INFO - 'NO_EM_RESTART': '1', 14:23:38 INFO - 'PAGER': '/bin/cat', 14:23:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:38 INFO - 'PWD': '/builds/slave/test', 14:23:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:38 INFO - 'SHELL': '/bin/bash', 14:23:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:38 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:38 INFO - 'USER': 'cltbld', 14:23:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:38 INFO - 'XPC_FLAGS': '0x0', 14:23:38 INFO - 'XPC_SERVICE_NAME': '0', 14:23:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-neE4UY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:38 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:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-G_Nwce-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:38 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:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:38 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-h6EkUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:38 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:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-X6Je9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-ZT0gnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-tSB_ow-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-vH_ub_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-9iDaZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-4OzrYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-qjP6uC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-oGzx2T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-b5QRnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:39 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:23:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:39 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-xAzVXu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:40 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:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:40 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-caRMYl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:40 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:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:40 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-7DrtKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:40 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:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:40 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-YxAL_o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:40 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:23:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:40 INFO - Running setup.py (path:/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/pip-chZ6ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:40 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:23:40 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:23:40 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:23:40 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:23:40 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:23:40 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:23:40 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:23:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:40 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:23:40 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:23:40 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:23:40 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:23:41 INFO - Downloading blessings-1.6.tar.gz 14:23:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:41 INFO - Installing collected packages: blessings 14:23:41 INFO - Running setup.py install for blessings 14:23:42 INFO - Successfully installed blessings 14:23:42 INFO - Cleaning up... 14:23:42 INFO - Return code: 0 14:23:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:42 INFO - Reading from file tmpfile_stdout 14:23:42 INFO - Current package versions: 14:23:42 INFO - blessings == 1.6 14:23:42 INFO - blobuploader == 1.2.4 14:23:42 INFO - docopt == 0.6.1 14:23:42 INFO - functools32 == 3.2.3-2 14:23:42 INFO - jsonschema == 2.5.1 14:23:42 INFO - manifestparser == 1.1 14:23:42 INFO - mozInstall == 1.13 14:23:42 INFO - mozcrash == 1.0 14:23:42 INFO - mozdebug == 0.1 14:23:42 INFO - mozdevice == 0.51 14:23:42 INFO - mozfile == 1.2 14:23:42 INFO - mozhttpd == 0.7 14:23:42 INFO - mozinfo == 0.10 14:23:42 INFO - mozleak == 0.1 14:23:42 INFO - mozlog == 3.4 14:23:42 INFO - moznetwork == 0.27 14:23:42 INFO - mozprocess == 0.25 14:23:42 INFO - mozprofile == 0.28 14:23:42 INFO - mozrunner == 6.13 14:23:42 INFO - mozscreenshot == 0.1 14:23:42 INFO - mozsystemmonitor == 0.3 14:23:42 INFO - moztest == 0.8 14:23:42 INFO - mozversion == 1.4 14:23:42 INFO - psutil == 3.1.1 14:23:42 INFO - requests == 1.2.3 14:23:42 INFO - wsgiref == 0.1.2 14:23:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:42 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:42 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:23:42 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:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:42 INFO - 'HOME': '/Users/cltbld', 14:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:42 INFO - 'LOGNAME': 'cltbld', 14:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:42 INFO - 'MOZ_NO_REMOTE': '1', 14:23:42 INFO - 'NO_EM_RESTART': '1', 14:23:42 INFO - 'PAGER': '/bin/cat', 14:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:42 INFO - 'PWD': '/builds/slave/test', 14:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:42 INFO - 'SHELL': '/bin/bash', 14:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:42 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:42 INFO - 'USER': 'cltbld', 14:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:42 INFO - 'XPC_FLAGS': '0x0', 14:23:42 INFO - 'XPC_SERVICE_NAME': '0', 14:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:42 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:23:42 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:23:42 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:23:42 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:23:42 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:23:42 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:23:42 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:23:42 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:23:42 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:23:42 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 14:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:44 INFO - Downloading marionette_driver-1.4.0.tar.gz 14:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 14:23:44 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:23:44 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:23:44 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:23:44 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:23:44 INFO - Installing collected packages: marionette-driver 14:23:44 INFO - Running setup.py install for marionette-driver 14:23:44 INFO - Successfully installed marionette-driver 14:23:44 INFO - Cleaning up... 14:23:44 INFO - Return code: 0 14:23:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:44 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 0x104c0bc10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104c0f768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffd6a5b14d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, '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.LmsRVnyaY7/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.6L4G5AXyco/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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:23:44 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:23:44 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:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:44 INFO - 'HOME': '/Users/cltbld', 14:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:44 INFO - 'LOGNAME': 'cltbld', 14:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:44 INFO - 'MOZ_NO_REMOTE': '1', 14:23:44 INFO - 'NO_EM_RESTART': '1', 14:23:44 INFO - 'PAGER': '/bin/cat', 14:23:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:44 INFO - 'PWD': '/builds/slave/test', 14:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:44 INFO - 'SHELL': '/bin/bash', 14:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:23:44 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:44 INFO - 'USER': 'cltbld', 14:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:44 INFO - 'XPC_FLAGS': '0x0', 14:23:44 INFO - 'XPC_SERVICE_NAME': '0', 14:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:44 INFO - Cleaning up... 14:23:44 INFO - Return code: 0 14:23:44 INFO - Running post-action listener: _resource_record_post_action 14:23:44 INFO - Running post-action listener: _start_resource_monitoring 14:23:44 INFO - Starting resource monitoring. 14:23:44 INFO - [mozharness: 2017-06-20 21:23:44.827981Z] Finished create-virtualenv step (success) 14:23:44 INFO - [mozharness: 2017-06-20 21:23:44.829069Z] Running install step. 14:23:44 INFO - Running pre-action listener: _resource_record_pre_action 14:23:44 INFO - Running main action method: install 14:23:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:45 INFO - Reading from file tmpfile_stdout 14:23:45 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:45 INFO - Reading from file tmpfile_stdout 14:23:45 INFO - Output received: 14:23:45 INFO - Usage: mozinstall [options] installer 14:23:45 INFO - Options: 14:23:45 INFO - -h, --help show this help message and exit 14:23:45 INFO - -d DEST, --destination=DEST 14:23:45 INFO - Directory to install application into. [default: 14:23:45 INFO - "/builds/slave/test"] 14:23:45 INFO - --app=APP Application being installed. [default: firefox] 14:23:45 INFO - mkdir: /builds/slave/test/build/application 14:23:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 14:24:06 INFO - Reading from file tmpfile_stdout 14:24:06 INFO - Output received: 14:24:06 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:24:06 INFO - Running post-action listener: _resource_record_post_action 14:24:06 INFO - [mozharness: 2017-06-20 21:24:06.373775Z] Finished install step (success) 14:24:06 INFO - [mozharness: 2017-06-20 21:24:06.373922Z] Running setup-mitmproxy step. 14:24:06 INFO - Running pre-action listener: _resource_record_pre_action 14:24:06 INFO - Running main action method: setup_mitmproxy 14:24:06 INFO - Skipping: mitmproxy is not required 14:24:06 INFO - Running post-action listener: _resource_record_post_action 14:24:06 INFO - [mozharness: 2017-06-20 21:24:06.374688Z] Finished setup-mitmproxy step (success) 14:24:06 INFO - [mozharness: 2017-06-20 21:24:06.374820Z] Running run-tests step. 14:24:06 INFO - Running pre-action listener: _resource_record_pre_action 14:24:06 INFO - Running main action method: run_tests 14:24:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:24:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:24:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:24:06 INFO - 2017-06-20 14:24:06.389 screenresolution[1627:7395] starting screenresolution argv=screenresolution get 14:24:06 INFO - 2017-06-20 14:24:06.392 screenresolution[1627:7395] Display 0: 1600x1200x32@60 14:24:06 INFO - 2017-06-20 14:24:06.398 screenresolution[1628:7398] starting screenresolution argv=screenresolution list 14:24:06 INFO - Available Modes on Display 0 14:24:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:24:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:24:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:24:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:24:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:24:06 INFO - Intel Iris: 14:24:06 INFO - Chipset Model: Intel Iris 14:24:06 INFO - Type: GPU 14:24:06 INFO - Bus: Built-In 14:24:06 INFO - VRAM (Dynamic, Max): 1536 MB 14:24:06 INFO - Vendor: Intel (0x8086) 14:24:06 INFO - Device ID: 0x0a2e 14:24:06 INFO - Revision ID: 0x0009 14:24:06 INFO - Displays: 14:24:06 INFO - Display: 14:24:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:24:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:24:06 INFO - Display Serial Number: 1600x1200 60 14:24:06 INFO - Main Display: Yes 14:24:06 INFO - Mirror: Off 14:24:06 INFO - Online: Yes 14:24:06 INFO - Rotation: Supported 14:24:06 INFO - Return code: 0 14:24:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:24:06 INFO - Python 2.7.3 14:24:06 INFO - Return code: 0 14:24:06 INFO - grabbing minidump binary from tooltool 14:24:06 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:24:06 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 0x104961d30>, '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 0x10492bcd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104952be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:24:06 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:24:06 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:24:06 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:24:06 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:24:06 INFO - Return code: 0 14:24:06 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:24:06 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:24:06 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:24:06 INFO - ENV: RUST_BACKTRACE is now 1 14:24:06 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:24:06 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:24:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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:24:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0026 --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:24:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LmsRVnyaY7/Render', 14:24:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:24:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:24:06 INFO - 'HOME': '/Users/cltbld', 14:24:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:24:06 INFO - 'LOGNAME': 'cltbld', 14:24:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:24:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:24:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:24:06 INFO - 'MOZ_NO_REMOTE': '1', 14:24:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:24:06 INFO - 'NO_EM_RESTART': '1', 14:24:06 INFO - 'PAGER': '/bin/cat', 14:24:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:24:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:24:06 INFO - 'PWD': '/builds/slave/test', 14:24:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:24:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:24:06 INFO - 'RUST_BACKTRACE': '1', 14:24:06 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 14:24:06 INFO - 'SHELL': '/bin/bash', 14:24:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6L4G5AXyco/Listeners', 14:24:06 INFO - 'TMPDIR': '/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/', 14:24:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:24:06 INFO - 'USER': 'cltbld', 14:24:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:24:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:24:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:24:06 INFO - 'XPC_FLAGS': '0x0', 14:24:06 INFO - 'XPC_SERVICE_NAME': '0', 14:24:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:24:06 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0026', '--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:24:07 INFO - mozversion application_buildid: 20170620115250 14:24:07 INFO - mozversion application_changeset: 4a0e45a42904d6aac5716975f30e9e12a2624426 14:24:07 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:24:07 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:24:07 INFO - mozversion application_name: Firefox 14:24:07 INFO - mozversion application_remotingname: firefox-dev 14:24:07 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 14:24:07 INFO - mozversion application_vendor: Mozilla 14:24:07 INFO - mozversion application_version: 55.0 14:24:07 INFO - mozversion platform_buildid: 20170620115250 14:24:07 INFO - mozversion platform_changeset: 4a0e45a42904d6aac5716975f30e9e12a2624426 14:24:07 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 14:24:07 INFO - mozversion platform_version: 55.0 14:24:07 INFO - using testdate: 1497993847 14:24:07 INFO - actual date: 1497993847 14:24:07 INFO - starting webserver on 'localhost:49227' 14:24:07 INFO - SUITE-START | Running 2 tests 14:24:07 INFO - TEST-START | dromaeo_css 14:24:07 INFO - operating with platform_type : mac_ 14:24:07 INFO - Initialising browser for dromaeo_css test... 14:24:07 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpCVWDJm/profile 14:24:07 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 14:24:40 INFO - TEST-INFO | 1638: killed by SIGTERM 14:24:40 INFO - Browser initialized. 14:24:40 INFO - Running cycle 1/1 for dromaeo_css test... 14:24:40 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpCVWDJm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:24:42 INFO - PID 1647 | 2017-06-20 14:24:42.425 plugin-container[1648:7938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 14:24:42 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:24:42 INFO - PID 1647 | 2017-06-20 14:24:42.426 plugin-container[1648:7938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 14:24:42 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:24:42 INFO - PID 1647 | 2017-06-20 14:24:42.426 plugin-container[1648:7938] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:24:43 INFO - PID 1647 | 2017-06-20 14:24:43.172 plugin-container[1649:7987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4743, name = 'com.apple.tsm.portname' 14:24:43 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:24:43 INFO - PID 1647 | 2017-06-20 14:24:43.172 plugin-container[1649:7987] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a03, name = 'com.apple.CFPasteboardClient' 14:24:43 INFO - PID 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:24:43 INFO - PID 1647 | 2017-06-20 14:24:43.172 plugin-container[1649:7987] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:24:52 INFO - PID 1647 | RSS: Main: 214999040 14:24:52 INFO - PID 1647 | 14:27:57 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49227/tests/dromaeo/cssquery-ext.html) 14:28:00 INFO - PID 1647 | RSS: Main: 232484864 14:28:00 INFO - PID 1647 | 14:31:02 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-ext.html (next: http://localhost:49227/tests/dromaeo/cssquery-jquery.html) 14:31:02 INFO - PID 1647 | RSS: Main: 241704960 14:31:02 INFO - PID 1647 | 14:34:04 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49227/tests/dromaeo/cssquery-mootools.html) 14:34:04 INFO - PID 1647 | RSS: Main: 243970048 14:34:04 INFO - PID 1647 | 14:37:06 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49227/tests/dromaeo/cssquery-prototype.html) 14:37:07 INFO - PID 1647 | RSS: Main: 245669888 14:37:07 INFO - PID 1647 | 14:40:09 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49227/tests/dromaeo/cssquery-yui.html) 14:40:10 INFO - PID 1647 | RSS: Main: 246153216 14:40:10 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | Cycle 1(1): loaded http://localhost:49227/tests/dromaeo/cssquery-yui.html (next: http://localhost:49227/tests/dromaeo/cssquery-dojo.html) 14:43:12 INFO - PID 1647 | RSS: Main: 244146176 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | __start_tp_report 14:43:12 INFO - PID 1647 | _x_x_mozilla_page_load 14:43:12 INFO - PID 1647 | _x_x_mozilla_page_load_details 14:43:12 INFO - PID 1647 | |i|pagename|runs| 14:43:12 INFO - PID 1647 | |0;dojo.html;3297.6042874855016;3576.8926932192044;3636.4727114568404;3704.7777133371983;3712.9628703712956;6290.370962903708;6302.2437307523105;6312.495000399968;6317.052442133682;6333.524985626073;6362.5546211765195;6427.035944608311;6443.516736244788;6449.161608990837;6468.320826313228;5601.887962240753;5614.775408983646;5636.887262254752;5767.509761670263;5836.270466191726;5682.829515114553;5764.106563482667;5775.624584402021;5783.566232532556;5794.5943783935145;5512.476314750093;5542.944570554283;5602.663840169603;5617.550595952314;5645.5201307888465;5503.944960550423;5560.582956278296;5604.747786349583;5640.294963129609;5640.858978525529;5540.085885828834;5551.8889622207325;5608.5793565482345;5626.662400255997;5635.267415236034;5793.710314484259;5934.664700442409;6405.615663060231;6407.711652975628;6416.165898433221;6718.6304753238555;6965.199002114771;6988;6988.685509152049;7019.859602807966;6699.866002679968;6862.725490980355;6994.440444764457;7047.682854271518;7074.3633073023675;6500.577462464925;6514.153160089253;6522.249941256721;6588.439982601437;6618.4705223582;7283.162436319891;7284.599347035858;7289.562626242442;7295.088113985752;7298.489105762652;6667.633280169637;6737.3599508046655;6740.561863478858;6740.9662951685905;6754.12196414463;6954.3045695430055;7035.3316434939625;7045.89431158533;7080.433565314763;7083.972823940603;6175.166352542349;6941.965290173618;6965.373116419445;6972.686229119702;7004.789856304319;6735.629540375228;7009.088818453569;7035.296470352924;7035.6482175891;7039.577625342496;5456.045192091368;5480.506754392124;5516.779328826802;5546.861328466821;5577.386487486373;4092.386142078712;4869.488703686062;4999.125153098194;4999.550040496373;5039.596832253411;6427.517936154807;6550.967245163743;6553.574017688835;6564.868702626016;6570.737170513222;7117.4306055515435;7121.643917804089;7126.2161162272105;7135.286471352927;7141.107361579802;6519.119918810995;6554.606723596599;6562.77030303937;6563.474922006228;6573.112629795012;5781.219535362757;6019.939800601938;6072.332043475214;6084.756609735649;6129.233845769279;6282.3089460158435;7086.787396378117;7187.460940429384;7216.9278307216255;7217.278272172741;7088.574685518885;7116.110486189226;7119.003339532574;7151.856962860609;7192.784216473514;7210.2789721027475;7250.419966402781;7267.273272672691;7278.818029549304;7282;6916.066331045245;6936.79189624312;6958.199807022055;6971.372576468142;6972.825679357854;6897.379235868796;6951.270116637883;6961.89557156633;6962.930370696431;7078.504504684623;6683.8997415039785;6738.966305168443;6753.898691519729;6839.350261725128;6882.03651488782;6800.863982720219;6847.554908930903;6890.586564805927;6905.067815844796;6919.37725604698;6424.871502570016;6494.9025764612625;6737.191537015589;6762.932370676427;6793.218779840382;6663.83340416474;6674.098996635392;6693.364130407604;6703.731850726112;6729.293424190585;6429.067785171031;6652.634105124264;6685.364890335411;6687.8328041797395;6696.698648560924 14:43:12 INFO - PID 1647 | |1;ext.html;768.2087449926576;785.2853902948318;818.615250832109;863.7711006583256;865.6148014133713;37478.437823432636;38424.80787596065;38495.80752096243;38514.84455466337;38550.84347469577;31308.53037204438;37181.628183718225;37930.620693793055;38164.85505434834;38329.23341533168;8137.633806478708;8277.255047045765;8277.793055173608;8281.875771863426;8328.66685332586;8133.715319963802;8155.918440815575;8211;8241.340692744567;8250.051244106944;8159.265666090051;8200.26197642212;8235.752927412188;8253.33973282139;8284.13016633254;8177.5502347370875;8216.753497395059;8243.25810677042;8246.463979841292;8273.048599411024;8107.878381824277;8126.228008339198;8168.264856162974;8168.4282100253;8170.67317307307;36284.27431451382;36431.635683643086;36524.81737591322;36600.901972940854;36609.26781464383;3349.8995030149135;3439.3637177122314;3456.4815277708294;3488.372093023255;3492.8777492787885;23121.88439057794;29916.850415748;30033.549496757347;30061;30346.54480182799;2665.8133930624863;3027.3793872256133;3126.0778070469037;3126.7967582107317;3135.012471071605;241707;255395;255557.7222113896;274290.2570974285;313688;38049.04877378033;47637.52362476331;48211.5178848207;48303.516964829905;48869;44413.88965275829;50335.99328013419;50466.49533504618;50516;50633.49366506288;14985.250737463084;15605.921970390076;15777.368905243891;15784.842151578567;15800.92099539495;12821.974242060613;12874.871251287368;12909.612711618667;12925.806112908125;12934.223946563236;12583.056270779729;12595.496180152875;12599.370031498389;12606.495740170474;12692.873071269169;4546.954530454719;5372.650777699437;5395.622306438511;5424.159255315432;5428.728563571805;930.5347326336831;950.6672664567346;1000.6197644894893;1003.9548220330103;1009.369144284822;22033.33899983003;22765;22826.086956521554;23574.528509429714;23591.938362773715;825.5046971816862;921.3219545322937;953.7329547726648;1106.7233191702076;1165.7260543772206;11841;11958.760824783456;12103.213291136224;12171;12277.710840361811;328244;329413.3529332338;330479;330860.3456982748;331968.3401582977;53807.73096134494;54059.18911216335;54198.45801542013;54384.728076359366;54442.72778636082;1533.854283843033;1540.5994441445305;1574.1342261756217;1574.559123445437;1579.0210069756595;22515.77484225137;22767.658485122403;23273;23301.650475242546;23900.402489937194;37907.81046094752;37910.810445947594;37920.05199869915;37940.24119517539;38060.80969595134;279496;281153;282232;282717;283639;34480.79317223885;34615.65384346225;34856.47715284222;35036;35146;34534.30931381408;35582.64417355897;41697;44299.557004429545;44316.33525497056;28442.288942776595;28656.42687146287;29564.556531652473;30015.549766753953;30978.53532196974;28738;29164.125076247747;30952.84523577368;31346.802857413328;31429.685703142677;35214.47178292379;36575.817120915286;36622.633773661924;36915.09696945553;37250.81374593218;25808.354791130372;28658.283542911595;30119.096427107223;31555.52666709955;31986.52020219652;20086.397408077195;24243.393915151555;24653.63019554744;24877.12930047512;24879 14:43:12 INFO - PID 1647 | |2;jquery.html;432.68197874562213;503.8815878268606;510.83019884464557;521.4316395129309;541.039654613062;20651.793482065175;22206.222782202625;22745.090196392146;22825;23036.539269214627;23374.065037398483;23526.823658817077;23654.172103976365;23696.289111326645;23950.40123996901;6201.937980620204;6310.400511951358;6338.841528961767;6360.872782544347;6365.777197798078;6241.625502469844;6363.236411630588;6373.7131829067575;6389.808305750835;6392.073149393335;5704.8859022819515;5921.792737254197;6330.525210609199;6404.9359506405035;6442.194725659293;6313.810585682414;6345.841353966165;6397.712102955332;6404.295527491972;6405.263394709621;5766.616012157094;5790.305163380379;5790.3630600634315;5822.883542329129;5826.242588463472;26455.603165952525;28702.712972870213;28944.855275723698;29219.977300794584;29243.70756292431;2258.3337915315024;2269.8751568663715;2279.099755596547;2301.9424514387106;2302.620067688846;18723.251069957187;19006.429807105807;19265.13306901193;19288.132034058362;19293.517662058563;1362.5980335800962;1713.800339762159;1757.1477833250863;1811.8188181181908;1817.472932849472;140219;169298.30701693072;174089;177696.11151944156;195844;13259.867401326057;13463.932680336535;14734.778978315333;14876.330565124596;14910.329035193443;19683.11425985834;19908.70136947918;19913.402597922086;19923.800761992483;20125.99370031522;8331.916680833236;10054.447005414626;10256;10322.638707645196;10352.844707329396;7908.960455197802;9646.42121472714;9701.175400091071;9735.075167859042;9764.51177441125;7338.70645174187;8841.204291613785;8877.29242581019;8915.509646969478;8938.541900977283;2224.2993457060975;2536.606825941982;2655.269800804794;2670.8531030793106;2790.66512018555;908.8818453601122;955.4649396338073;957.9904200958041;965.888922773876;1007.6422869881234;20369.694454583194;21691.240806571695;22531.43671408228;24334.878325608257;26070.86964565203;1036.7252678040325;1067.9519421626046;1068.3536460441476;1074.4305518075432;1078.5038882114159;10598.04617584421;11256.718582035352;11279;11357.091432685365;11666.034307166869;597489.0125549345;608271.9586402039;617244;629300;631584.8420757867;158569;161870.38129618787;163932;166436.50345244832;167580;1008.7074748323051;1205.2888795610456;1221.9144659873723;1262.9052821038458;1267.987320126787;17668.558286042953;18549.258029678662;21047;23923.04307827737;24696.382590435383;69055.30944690488;71513;72602.73287702171;73042.80871573862;73307;67023;67512;68833;88466;88518.67221991546;9844.556994935101;11824.704382390164;11962.820557691468;12019.338936358585;12039.879601204226;18030.36893708714;18242.72635910489;18257.360992365204;18364.081795910417;18727.157277922255;14425.278736063365;15081.924590376977;15251.542453726406;15263;15269;13060.934695326463;13248.735025299633;13277.867221327662;13718.38267277955;13785.655358616115;15545.222738863238;15636.218189090272;15669.764953525479;15736.449224277096;15799.36802527932;11760.76478470443;11776.4700588472;11780.823287650863;11788.351640659846;11793.997510211728;12440.315782632042;13880.097793643381;14696.265186740835;14696.265186740835;14823.703525929206 14:43:12 INFO - PID 1647 | |3;mootools.html;1423.7152569486104;1485.7845612386197;1506.3823832228782;1506.771980835619;1523.1698724195312;13539.79690304645;14063;14066.226357550331;14077;14100.717985640295;13392.464301427932;14310.35603397847;14341.713165736679;14355.928220358883;14384.640383990405;2811.775057995366;2873.899413520527;2875.108716297944;2882.5820256062857;2893.4068515954177;2131.7015617813518;2776.8333899966065;2813.1279303415904;2819.9436011279763;2824.053941929456;2785.220138361252;2834.4472827798586;2834.943301133976;2836.503611867925;2837.8155419897744;2764.875580598878;2788.344738986337;2794.5808128780845;2796.7762578993834;2803.845788481643;2707.905223317194;2750.7936904732023;2757.462294852505;2806.887724491038;2818.9436211275865;13819.309034548232;13889.749922507022;13919.791203131961;13931.164130152223;13942.302884855717;6594.93405065948;7528.548287102792;7809.882851757228;7812.257835505618;7835.451518393715;4636.976815115937;4873.269009648546;4976.328195693572;4981.327520784684;4989.800407983676;1391.6729568551382;1394.0799072612028;1402.1376853235288;1411.202670491169;1415.391381705866;20864;20974.580508389747;20985.370438886857;21010.789892101187;21041;5679.801206957736;6441.355864413615;6617.636030018395;6756.864862702719;6764.864702705919;14339.211343376215;14520.419183232763;14530.709385812224;14544.636384090483;14550.708985820223;7398.11222653285;7478.2895624915545;7478.990336304638;7484.850302993909;7500.624968751541;5911.615744976649;6223.657698826608;7068.611226382598;7075.186353569406;7085.0435191248525;5082.110630639623;5271.868203294872;5282.603804714585;5288.497592728685;5308.893822123536;1545.1115608525142;1545.7913181720548;1549.3260431712242;1552.3712896277025;1564.0850102689826;811.4968719393895;836.7280633794041;841.5245386356689;848.6817443458702;873.5021038008401;5486.6982315973;6378.744850206032;6404.23149222087;7295.2340004298785;7322.450816188807;634.7875557684893;674.5244602555186;701.992980070203;714.3749219432996;731.2650785960076;3469.427544455187;3959.3269144245546;4980.501949805063;5088.898222035538;5229.660072095302;17419.65160696779;18896.960667163163;18979.620407592047;19129.852208867513;19180.616387672446;18791;19068.61862762737;19123.80876191248;19133.23467061302;19160.71258931117;8043.39674524413;8836.204741573289;9352.67265645699;9615.615375385047;9701.90298097024;6949.583025018515;7276.87208482686;7379.446541509516;7437.698402779383;8336.874946875923;15643.29605167793;16843.073630949926;16884;17216.74174887403;17234.569135771708;17043;17078.48764537066;17198.226079826687;17279.56801079983;17842.91078544599;8648.567571621268;11059.612913547988;11356.716082097682;11373.601923932623;11491.367974761468;12965;13142.671433213864;13146.21122732639;13151.276679782704;13161.210327380388;9967.8758990047;10212.131968782736;10620.946895265473;10663.626773062906;10687.679369618923;9951.810601667621;9951.900480995388;9980.301378903407;10220.846687299547;10404.68785936423;9250.02874698147;9347.626094956398;9356.251499879858;9372.390794598601;9382.95308523453;5615;6647.102641143577;6726.630035347907;6807.217170025312;7096.503244772816;6916.757913473004;6955.721771129098;7160.928390716026;7173.605451700207;7286.599237042014 14:43:12 INFO - PID 1647 | |4;prototype.html;438.45412461485455;452.855086372361;457.5515994325561;473.1884817537924;478.552553362606;6105.1147583600405;6378.958733205376;6410.9999999999945;6545.312742162064;6568.244651865037;5209.453007434222;5456.890862182764;6364.653690899643;6390.3929126733;6404.359564043605;3946.565877753445;4155.958440415588;4590.495045544987;4815.7110573365535;4835.1296766582;4799.064182484417;4807.250344731098;4807.855764327076;4808.629540580935;4823.348847905523;4583.060472603125;4747.192977193886;4758.643101767364;4766.3088852116425;4769.499202583731;4757.762111894392;4760.666753327268;4786.83246086387;4787.305840653085;4791.329213910055;3251.3822373748912;3674.926501469956;4189.49726032875;4209.452771139763;4218.767967761772;6217.316095229521;6219.1915051043525;6233.535119247019;6295.937040629581;6308.2745484269435;4968.950310496885;5664.26364572603;5688.402717714622;5692.5161361284345;5728.398518155617;5907.409259074058;6573.112629794964;6918.654067296666;6924.792256232321;6929.341712537352;4308.289132293168;4546.772661366918;4816.7350795706225;4819.373481447389;4832.371791667096;7285.490015698903;7356.227596102386;7369.742059027962;7371.852562948711;7385.6307184640555;4858.271259311061;4897.338859254026;4905.533974272438;4910.558049775537;4914.508549145057;4372.23485889968;4991.975040124777;5014.824481143142;5018.72022634226;5046.84859454217;5701.059325211377;5702.608991076848;5714.428557144335;5729.195303479474;5747.224124743189;6959.1996920353795;6963.9303606964295;6965.537237180148;6986.54587451814;6990.685419156151;1388.4723804954053;1587.968240635204;1701.1579268262042;1712.3407488117164;1725.9989206260339;1098.6649072033106;1107.8449017137607;1108.6064447121316;1134.9319040857574;1136.9886301137049;5675.8864822704145;5891.293044834647;6145.20112385396;6211.81364559064;6512.2742473244625;7118.288171182944;7187.137543494814;7195.89206161908;7227.602481863548;7249.782506524813;5403.621746477787;6439.22729272481;6464.870702586016;6475.190601174853;6480.287168411471;2841.105051908642;3392.558162780858;3480.91297717554;3717.7955212463035;3773.415120656303;6687.321972914878;6755.49333799967;6992.965035174791;7330.523515971445;7349.8162545936775;5815;6380.776672816429;6638.103855979478;7154.928450715531;7329.706811727578;7151.570905745672;7336.853262934711;7347.88978165328;7369.263073692588;7385.593792341473;7149.9285007149265;7159.498835081495;7161.892571611325;7162.785116446515;7183.784486465414;7035.331643494064;7038.331358520933;7071.762441572601;7072.186698529735;7099.219085900443;6262.74949002053;6908.965455172692;7312.341889229995;7368.889466657897;7388.224236455095;4085.3871919212356;4108.556163083986;4782.952170478251;4825.4933232010135;4827;5964.343922168471;6010.819675409744;6082.452579267887;7201.335505862864;7204.639768011683;6034.366391528826;6234.908890943976;6437.388448097423;6914.757983470555;7081.327273909176;6479.5788273762055;6899.413549848319;6905.516613837184;6918.169819621678;6924.446044316342;6720.294369091175;6745.639026340741;6746.279698676806;6752.218757495818;6758.290379510081;5669.291338582677;6871;6878.518503704692;6898.931010689829;6914.619695916773;6694.698738556679;6702.296258892746;6720.46236301085;6727.360900714424;6737.932620673927 14:43:12 INFO - PID 1647 | |5;yui.html;821.4537332673772;825.8265764189524;843.5065486690286;859.5659192107985;865.6796985115507;16651.75022374664;16744.916275418607;17113.486595402126;17126.229319680613;17129.57176070599;14072.92963535181;14138.57584272471;14143.504977325796;16597.92113512621;17185.57036074099;984.81780870539;1016.532395098252;1022.5449674894675;1022.693192042388;1025.6153942271649;999.5452069308448;1026.338011982271;1031.876174859018;1033.7622346860226;1036.1037702387455;1078.5847448732216;1083.3229231730168;1084.0027174998982;1085.4030283344168;1085.6417382263835;956.2159029595734;1056.5984925728173;1061.5223149582719;1075.67192006438;1079.2499213046935;763.3015790551694;769.3460558525264;770.8342706318168;771.8649236383667;774.2180397798264;17058.23237954295;17127.571810704707;17175.570610734707;17239.31042758288;17249.051302178374;16847.831521684748;17866.464006079837;17959.371422000168;18067.27730890763;18079.909600452043;14467.27663616815;14850.777238341434;14900.552983410515;14956.925215373854;14994.325255363536;3481.547398838135;3649.6350364963555;3676.4117741161285;3685.9262814743824;3695.5772027769235;109441.45279273632;147719;154828;157822.6326605102;158977;12885.871141288655;13788.862111378958;14037.71924561503;14094;14205.147691138565;26102.73897261003;26866;26974.865125674638;26992.730072699414;27077.86461067682;8136.877946830802;8143.877841832377;8157.592120394075;8194.918050819535;8223.21879421454;4104.527979282361;4124.938125928114;4173.353130264813;4176.477940257468;4179.561146079679;4039.7374170678813;4720.575148236607;4801.51984801517;4804.975975120102;4808.927866082012;887.924526415262;901.7915992569988;980.4411485453223;1034.8654674892216;1043.1811028342715;503.51159375405797;615.559874689599;630.5523078614143;642.1038051328696;682.6382017530717;11391.829122563167;11842.874926882105;12254.387280635932;12562.623121306375;12636.11547191706;605.9878802423926;625.4621025917708;627.3318915355133;647.8024202618246;658.9670516474157;5224.895502089937;5637.915431268534;5721;5721.828345149652;5740.167675687086;147919;153421.23289383482;154053;157049;161049;130439;134159;134401;135185;136990.31504842412;107093;113109.43445282885;115574.26638600428;116656;119621;9287.839020122485;9502.85745713801;10988.230823842254;11032.227744057838;11231.887681123084;44880;47317.76341118272;47404.2889356653;48088.519114808405;48128.759356202994;54755.53600088085;61793.69103154456;61942.69028654828;64819;65921.68156636937;14011.509597164459;14794;14933.477328293458;14996.550103496913;15066.623334416727;15854.841451585336;15970.441034563735;15992.92003539975;16025.839741602435;16143.273552689921;14922.328495217524;14961.925190373979;15029.849701502844;15114.244287785346;15261.771073434129;14241.857581424054;14927.477538286108;15314.387424502895;15337.463188788339;15368.615784605474;14653.047551909092;15074.09555426678;15074.924625376803;15178.165200913614;15235.542933712006;10351.223658225512;10572.41851698164;10921.180911431515;10935.28920620157;11432.82850757256;11456.770864582495;11481;11521.423928803693;11882.82175767347;11889.46497407636 14:43:12 INFO - PID 1647 | __end_tp_report 14:43:12 INFO - PID 1647 | __start_cc_report 14:43:12 INFO - PID 1647 | _x_x_mozilla_cycle_collect,406 14:43:12 INFO - PID 1647 | __end_cc_report 14:43:12 INFO - PID 1647 | __startTimestamp1497994992483__endTimestamp 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | ------- Summary: start ------- 14:43:12 INFO - PID 1647 | Number of tests: 6 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#0] dojo.html Cycles:165 Average:6408.41 Median:6667.63 stddev:795.14 (11.9%) stddev-sans-first:759.20 14:43:12 INFO - PID 1647 | Values: 3297.6 3576.9 3636.5 3704.8 3713.0 6290.4 6302.2 6312.5 6317.1 6333.5 6362.6 6427.0 6443.5 6449.2 6468.3 5601.9 5614.8 5636.9 5767.5 5836.3 5682.8 5764.1 5775.6 5783.6 5794.6 5512.5 5542.9 5602.7 5617.6 5645.5 5503.9 5560.6 5604.7 5640.3 5640.9 5540.1 5551.9 5608.6 5626.7 5635.3 5793.7 5934.7 6405.6 6407.7 6416.2 6718.6 6965.2 6988.0 6988.7 7019.9 6699.9 6862.7 6994.4 7047.7 7074.4 6500.6 6514.2 6522.2 6588.4 6618.5 7283.2 7284.6 7289.6 7295.1 7298.5 6667.6 6737.4 6740.6 6741.0 6754.1 6954.3 7035.3 7045.9 7080.4 7084.0 6175.2 6942.0 6965.4 6972.7 7004.8 6735.6 7009.1 7035.3 7035.6 7039.6 5456.0 5480.5 5516.8 5546.9 5577.4 4092.4 4869.5 4999.1 4999.6 5039.6 6427.5 6551.0 6553.6 6564.9 6570.7 7117.4 7121.6 7126.2 7135.3 7141.1 6519.1 6554.6 6562.8 6563.5 6573.1 5781.2 6019.9 6072.3 6084.8 6129.2 6282.3 7086.8 7187.5 7216.9 7217.3 7088.6 7116.1 7119.0 7151.9 7192.8 7210.3 7250.4 7267.3 7278.8 7282.0 6916.1 6936.8 6958.2 6971.4 6972.8 6897.4 6951.3 6961.9 6962.9 7078.5 6683.9 6739.0 6753.9 6839.4 6882.0 6800.9 6847.6 6890.6 6905.1 6919.4 6424.9 6494.9 6737.2 6762.9 6793.2 6663.8 6674.1 6693.4 6703.7 6729.3 6429.1 6652.6 6685.4 6687.8 6696.7 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#1] ext.html Cycles:180 Average:44136.07 Median:19036.07 stddev:77384.08 (406.5%) stddev-sans-first:77532.65 14:43:12 INFO - PID 1647 | Values: 768.2 785.3 818.6 863.8 865.6 37478.4 38424.8 38495.8 38514.8 38550.8 31308.5 37181.6 37930.6 38164.9 38329.2 8137.6 8277.3 8277.8 8281.9 8328.7 8133.7 8155.9 8211.0 8241.3 8250.1 8159.3 8200.3 8235.8 8253.3 8284.1 8177.6 8216.8 8243.3 8246.5 8273.0 8107.9 8126.2 8168.3 8168.4 8170.7 36284.3 36431.6 36524.8 36600.9 36609.3 3349.9 3439.4 3456.5 3488.4 3492.9 23121.9 29916.9 30033.5 30061.0 30346.5 2665.8 3027.4 3126.1 3126.8 3135.0 241707.0 255395.0 255557.7 274290.3 313688.0 38049.0 47637.5 48211.5 48303.5 48869.0 44413.9 50336.0 50466.5 50516.0 50633.5 14985.3 15605.9 15777.4 15784.8 15800.9 12822.0 12874.9 12909.6 12925.8 12934.2 12583.1 12595.5 12599.4 12606.5 12692.9 4547.0 5372.7 5395.6 5424.2 5428.7 930.5 950.7 1000.6 1004.0 1009.4 22033.3 22765.0 22826.1 23574.5 23591.9 825.5 921.3 953.7 1106.7 1165.7 11841.0 11958.8 12103.2 12171.0 12277.7 328244.0 329413.4 330479.0 330860.3 331968.3 53807.7 54059.2 54198.5 54384.7 54442.7 1533.9 1540.6 1574.1 1574.6 1579.0 22515.8 22767.7 23273.0 23301.7 23900.4 37907.8 37910.8 37920.1 37940.2 38060.8 279496.0 281153.0 282232.0 282717.0 283639.0 34480.8 34615.7 34856.5 35036.0 35146.0 34534.3 35582.6 41697.0 44299.6 44316.3 28442.3 28656.4 29564.6 30015.5 30978.5 28738.0 29164.1 30952.8 31346.8 31429.7 35214.5 36575.8 36622.6 36915.1 37250.8 25808.4 28658.3 30119.1 31555.5 31986.5 20086.4 24243.4 24653.6 24877.1 24879.0 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#2] jquery.html Cycles:180 Average:40332.27 Median:12215.26 stddev:105104.05 (860.4%) stddev-sans-first:105356.20 14:43:12 INFO - PID 1647 | Values: 432.7 503.9 510.8 521.4 541.0 20651.8 22206.2 22745.1 22825.0 23036.5 23374.1 23526.8 23654.2 23696.3 23950.4 6201.9 6310.4 6338.8 6360.9 6365.8 6241.6 6363.2 6373.7 6389.8 6392.1 5704.9 5921.8 6330.5 6404.9 6442.2 6313.8 6345.8 6397.7 6404.3 6405.3 5766.6 5790.3 5790.4 5822.9 5826.2 26455.6 28702.7 28944.9 29220.0 29243.7 2258.3 2269.9 2279.1 2301.9 2302.6 18723.3 19006.4 19265.1 19288.1 19293.5 1362.6 1713.8 1757.1 1811.8 1817.5 140219.0 169298.3 174089.0 177696.1 195844.0 13259.9 13463.9 14734.8 14876.3 14910.3 19683.1 19908.7 19913.4 19923.8 20126.0 8331.9 10054.4 10256.0 10322.6 10352.8 7909.0 9646.4 9701.2 9735.1 9764.5 7338.7 8841.2 8877.3 8915.5 8938.5 2224.3 2536.6 2655.3 2670.9 2790.7 908.9 955.5 958.0 965.9 1007.6 20369.7 21691.2 22531.4 24334.9 26070.9 1036.7 1068.0 1068.4 1074.4 1078.5 10598.0 11256.7 11279.0 11357.1 11666.0 597489.0 608272.0 617244.0 629300.0 631584.8 158569.0 161870.4 163932.0 166436.5 167580.0 1008.7 1205.3 1221.9 1262.9 1268.0 17668.6 18549.3 21047.0 23923.0 24696.4 69055.3 71513.0 72602.7 73042.8 73307.0 67023.0 67512.0 68833.0 88466.0 88518.7 9844.6 11824.7 11962.8 12019.3 12039.9 18030.4 18242.7 18257.4 18364.1 18727.2 14425.3 15081.9 15251.5 15263.0 15269.0 13060.9 13248.7 13277.9 13718.4 13785.7 15545.2 15636.2 15669.8 15736.4 15799.4 11760.8 11776.5 11780.8 11788.4 11794.0 12440.3 13880.1 14696.3 14696.3 14823.7 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#3] mootools.html Cycles:180 Average:8406.60 Median:2822.00 stddev:5723.05 (202.8%) stddev-sans-first:5715.06 14:43:12 INFO - PID 1647 | Values: 1423.7 1485.8 1506.4 1506.8 1523.2 13539.8 14063.0 14066.2 14077.0 14100.7 13392.5 14310.4 14341.7 14355.9 14384.6 2811.8 2873.9 2875.1 2882.6 2893.4 2131.7 2776.8 2813.1 2819.9 2824.1 2785.2 2834.4 2834.9 2836.5 2837.8 2764.9 2788.3 2794.6 2796.8 2803.8 2707.9 2750.8 2757.5 2806.9 2818.9 13819.3 13889.7 13919.8 13931.2 13942.3 6594.9 7528.5 7809.9 7812.3 7835.5 4637.0 4873.3 4976.3 4981.3 4989.8 1391.7 1394.1 1402.1 1411.2 1415.4 20864.0 20974.6 20985.4 21010.8 21041.0 5679.8 6441.4 6617.6 6756.9 6764.9 14339.2 14520.4 14530.7 14544.6 14550.7 7398.1 7478.3 7479.0 7484.9 7500.6 5911.6 6223.7 7068.6 7075.2 7085.0 5082.1 5271.9 5282.6 5288.5 5308.9 1545.1 1545.8 1549.3 1552.4 1564.1 811.5 836.7 841.5 848.7 873.5 5486.7 6378.7 6404.2 7295.2 7322.5 634.8 674.5 702.0 714.4 731.3 3469.4 3959.3 4980.5 5088.9 5229.7 17419.7 18897.0 18979.6 19129.9 19180.6 18791.0 19068.6 19123.8 19133.2 19160.7 8043.4 8836.2 9352.7 9615.6 9701.9 6949.6 7276.9 7379.4 7437.7 8336.9 15643.3 16843.1 16884.0 17216.7 17234.6 17043.0 17078.5 17198.2 17279.6 17842.9 8648.6 11059.6 11356.7 11373.6 11491.4 12965.0 13142.7 13146.2 13151.3 13161.2 9967.9 10212.1 10620.9 10663.6 10687.7 9951.8 9951.9 9980.3 10220.8 10404.7 9250.0 9347.6 9356.3 9372.4 9383.0 5615.0 6647.1 6726.6 6807.2 7096.5 6916.8 6955.7 7160.9 7173.6 7286.6 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#4] prototype.html Cycles:180 Average:5582.12 Median:6218.25 stddev:1745.89 (28.1%) stddev-sans-first:1707.56 14:43:12 INFO - PID 1647 | Values: 438.5 452.9 457.6 473.2 478.6 6105.1 6379.0 6411.0 6545.3 6568.2 5209.5 5456.9 6364.7 6390.4 6404.4 3946.6 4156.0 4590.5 4815.7 4835.1 4799.1 4807.3 4807.9 4808.6 4823.3 4583.1 4747.2 4758.6 4766.3 4769.5 4757.8 4760.7 4786.8 4787.3 4791.3 3251.4 3674.9 4189.5 4209.5 4218.8 6217.3 6219.2 6233.5 6295.9 6308.3 4969.0 5664.3 5688.4 5692.5 5728.4 5907.4 6573.1 6918.7 6924.8 6929.3 4308.3 4546.8 4816.7 4819.4 4832.4 7285.5 7356.2 7369.7 7371.9 7385.6 4858.3 4897.3 4905.5 4910.6 4914.5 4372.2 4992.0 5014.8 5018.7 5046.8 5701.1 5702.6 5714.4 5729.2 5747.2 6959.2 6963.9 6965.5 6986.5 6990.7 1388.5 1588.0 1701.2 1712.3 1726.0 1098.7 1107.8 1108.6 1134.9 1137.0 5675.9 5891.3 6145.2 6211.8 6512.3 7118.3 7187.1 7195.9 7227.6 7249.8 5403.6 6439.2 6464.9 6475.2 6480.3 2841.1 3392.6 3480.9 3717.8 3773.4 6687.3 6755.5 6993.0 7330.5 7349.8 5815.0 6380.8 6638.1 7154.9 7329.7 7151.6 7336.9 7347.9 7369.3 7385.6 7149.9 7159.5 7161.9 7162.8 7183.8 7035.3 7038.3 7071.8 7072.2 7099.2 6262.7 6909.0 7312.3 7368.9 7388.2 4085.4 4108.6 4783.0 4825.5 4827.0 5964.3 6010.8 6082.5 7201.3 7204.6 6034.4 6234.9 6437.4 6914.8 7081.3 6479.6 6899.4 6905.5 6918.2 6924.4 6720.3 6745.6 6746.3 6752.2 6758.3 5669.3 6871.0 6878.5 6898.9 6914.6 6694.7 6702.3 6720.5 6727.4 6737.9 14:43:12 INFO - PID 1647 | 14:43:12 INFO - PID 1647 | [#5] yui.html Cycles:180 Average:25994.53 Median:15981.68 stddev:41780.96 (261.4%) stddev-sans-first:41855.41 14:43:12 INFO - PID 1647 | Values: 821.5 825.8 843.5 859.6 865.7 16651.8 16744.9 17113.5 17126.2 17129.6 14072.9 14138.6 14143.5 16597.9 17185.6 984.8 1016.5 1022.5 1022.7 1025.6 999.5 1026.3 1031.9 1033.8 1036.1 1078.6 1083.3 1084.0 1085.4 1085.6 956.2 1056.6 1061.5 1075.7 1079.2 763.3 769.3 770.8 771.9 774.2 17058.2 17127.6 17175.6 17239.3 17249.1 16847.8 17866.5 17959.4 18067.3 18079.9 14467.3 14850.8 14900.6 14956.9 14994.3 3481.5 3649.6 3676.4 3685.9 3695.6 109441.5 147719.0 154828.0 157822.6 158977.0 12885.9 13788.9 14037.7 14094.0 14205.1 26102.7 26866.0 26974.9 26992.7 27077.9 8136.9 8143.9 8157.6 8194.9 8223.2 4104.5 4124.9 4173.4 4176.5 4179.6 4039.7 4720.6 4801.5 4805.0 4808.9 887.9 901.8 980.4 1034.9 1043.2 503.5 615.6 630.6 642.1 682.6 11391.8 11842.9 12254.4 12562.6 12636.1 606.0 625.5 627.3 647.8 659.0 5224.9 5637.9 5721.0 5721.8 5740.2 147919.0 153421.2 154053.0 157049.0 161049.0 130439.0 134159.0 134401.0 135185.0 136990.3 107093.0 113109.4 115574.3 116656.0 119621.0 9287.8 9502.9 10988.2 11032.2 11231.9 44880.0 47317.8 47404.3 48088.5 48128.8 54755.5 61793.7 61942.7 64819.0 65921.7 14011.5 14794.0 14933.5 14996.6 15066.6 15854.8 15970.4 15992.9 16025.8 16143.3 14922.3 14961.9 15029.8 15114.2 15261.8 14241.9 14927.5 15314.4 15337.5 15368.6 14653.0 15074.1 15074.9 15178.2 15235.5 10351.2 10572.4 10921.2 10935.3 11432.8 11456.8 11481.0 11521.4 11882.8 11889.5 14:43:12 INFO - PID 1647 | -------- Summary: end -------- 14:43:12 INFO - PID 1647 | 14:43:12 INFO - TEST-INFO | 1647: exit 0 14:43:13 INFO - TEST-OK | dromaeo_css | took 1145969ms 14:43:13 INFO - TEST-START | kraken 14:43:13 INFO - operating with platform_type : mac_ 14:43:13 INFO - Initialising browser for kraken test... 14:43:13 INFO - Application command: /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpFJRVnP/profile 14:43:13 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox http://localhost:49227/getInfo.html -foreground) 14:43:46 INFO - TEST-INFO | 1658: killed by SIGTERM 14:43:46 INFO - Browser initialized. 14:43:46 INFO - Running cycle 1/1 for kraken test... 14:43:46 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/T/tmpFJRVnP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.101 plugin-container[1663:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9543, name = 'com.apple.tsm.portname' 14:43:48 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.102 plugin-container[1663:10549] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.CFPasteboardClient' 14:43:48 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.102 plugin-container[1663:10549] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.885 plugin-container[1664:10591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 14:43:48 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.885 plugin-container[1664:10591] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 14:43:48 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:43:48 INFO - PID 1662 | 2017-06-20 14:43:48.885 plugin-container[1664:10591] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:43:58 INFO - PID 1662 | RSS: Main: 214994944 14:43:58 INFO - PID 1662 | 14:44:01 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection) 14:44:02 INFO - PID 1662 | RSS: Main: 228237312 14:44:02 INFO - PID 1662 | 14:44:04 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-dft) 14:44:05 INFO - PID 1662 | RSS: Main: 239112192 14:44:05 INFO - PID 1662 | 14:44:08 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-fft) 14:44:09 INFO - PID 1662 | RSS: Main: 243785728 14:44:09 INFO - PID 1662 | 14:44:11 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator) 14:44:11 INFO - PID 1662 | RSS: Main: 245293056 14:44:11 INFO - PID 1662 | 14:44:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:44:14 INFO - PID 1662 | RSS: Main: 244948992 14:44:14 INFO - PID 1662 | 14:44:17 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom) 14:44:17 INFO - PID 1662 | RSS: Main: 249348096 14:44:17 INFO - PID 1662 | 14:44:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate) 14:44:21 INFO - PID 1662 | RSS: Main: 249618432 14:44:21 INFO - PID 1662 | 14:44:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial) 14:44:24 INFO - PID 1662 | RSS: Main: 249634816 14:44:24 INFO - PID 1662 | 14:44:26 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:44:27 INFO - PID 1662 | RSS: Main: 249139200 14:44:27 INFO - PID 1662 | 14:44:28 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:44:29 INFO - PID 1662 | RSS: Main: 249155584 14:44:29 INFO - PID 1662 | 14:44:31 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:44:32 INFO - PID 1662 | RSS: Main: 249577472 14:44:32 INFO - PID 1662 | 14:44:34 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:44:35 INFO - PID 1662 | RSS: Main: 249393152 14:44:35 INFO - PID 1662 | 14:44:38 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:44:39 INFO - PID 1662 | RSS: Main: 250261504 14:44:39 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49227/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49227/tests/kraken/driver.html?testName=ai-astar) 14:44:41 INFO - PID 1662 | RSS: Main: 251346944 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | __start_tp_report 14:44:41 INFO - PID 1662 | _x_x_mozilla_page_load 14:44:41 INFO - PID 1662 | _x_x_mozilla_page_load_details 14:44:41 INFO - PID 1662 | |i|pagename|runs| 14:44:41 INFO - PID 1662 | |0;ai-astar;102;135;150;132;169;169;114;117;117;142 14:44:41 INFO - PID 1662 | |1;audio-beat-detection;144;114;131;153;110;131;117;184;149;105 14:44:41 INFO - PID 1662 | |2;audio-dft;196;202;199;197;195;192;197;197;203;196 14:44:41 INFO - PID 1662 | |3;audio-fft;66;79;84;153;60;58;59;59;132;58 14:44:41 INFO - PID 1662 | |4;audio-oscillator;68;72;122;70;68;71;105;69;71;105 14:44:41 INFO - PID 1662 | |5;imaging-gaussian-blur;89;90;90;90;89;88;90;89;89;93 14:44:41 INFO - PID 1662 | |6;imaging-darkroom;96;99;98;96;149;155;107;97;99;97 14:44:41 INFO - PID 1662 | |7;imaging-desaturate;91;95;92;92;95;91;91;91;98;92 14:44:41 INFO - PID 1662 | |8;json-parse-financial;49;50;49;51;51;53;53;50;53;52 14:44:41 INFO - PID 1662 | |9;json-stringify-tinderbox;52;57;54;55;62;57;54;55;58;55 14:44:41 INFO - PID 1662 | |10;stanford-crypto-aes;80;91;96;108;94;81;85;85;83;85 14:44:41 INFO - PID 1662 | |11;stanford-crypto-ccm;133;133;141;152;149;150;136;146;146;168 14:44:41 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;178;177;196;178;179;184;179;215;220;215 14:44:41 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;68;67;68;67;99;58;56;89;92;58 14:44:41 INFO - PID 1662 | __end_tp_report 14:44:41 INFO - PID 1662 | __start_cc_report 14:44:41 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1021 14:44:41 INFO - PID 1662 | __end_cc_report 14:44:41 INFO - PID 1662 | __startTimestamp1497995081308__endTimestamp 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | ------- Summary: start ------- 14:44:41 INFO - PID 1662 | Number of tests: 14 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:134.70 Median:138.50 stddev:23.03 (16.6%) stddev-sans-first:21.17 14:44:41 INFO - PID 1662 | Values: 102.0 135.0 150.0 132.0 169.0 169.0 114.0 117.0 117.0 142.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:133.80 Median:137.50 stddev:24.29 (17.7%) stddev-sans-first:25.48 14:44:41 INFO - PID 1662 | Values: 144.0 114.0 131.0 153.0 110.0 131.0 117.0 184.0 149.0 105.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:197.40 Median:197.00 stddev:3.24 (1.6%) stddev-sans-first:3.40 14:44:41 INFO - PID 1662 | Values: 196.0 202.0 199.0 197.0 195.0 192.0 197.0 197.0 203.0 196.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:80.80 Median:59.50 stddev:34.14 (57.4%) stddev-sans-first:35.79 14:44:41 INFO - PID 1662 | Values: 66.0 79.0 84.0 153.0 60.0 58.0 59.0 59.0 132.0 58.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:82.10 Median:69.50 stddev:20.29 (29.2%) stddev-sans-first:20.87 14:44:41 INFO - PID 1662 | Values: 68.0 72.0 122.0 70.0 68.0 71.0 105.0 69.0 71.0 105.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:90.00 stddev:1.34 (1.5%) stddev-sans-first:1.39 14:44:41 INFO - PID 1662 | Values: 89.0 90.0 90.0 90.0 89.0 88.0 90.0 89.0 89.0 93.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:109.30 Median:97.00 stddev:22.77 (23.5%) stddev-sans-first:23.64 14:44:41 INFO - PID 1662 | Values: 96.0 99.0 98.0 96.0 149.0 155.0 107.0 97.0 99.0 97.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:92.80 Median:92.00 stddev:2.39 (2.6%) stddev-sans-first:2.45 14:44:41 INFO - PID 1662 | Values: 91.0 95.0 92.0 92.0 95.0 91.0 91.0 91.0 98.0 92.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:51.10 Median:51.50 stddev:1.60 (3.1%) stddev-sans-first:1.50 14:44:41 INFO - PID 1662 | Values: 49.0 50.0 49.0 51.0 51.0 53.0 53.0 50.0 53.0 52.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:55.90 Median:56.00 stddev:2.77 (4.9%) stddev-sans-first:2.55 14:44:41 INFO - PID 1662 | Values: 52.0 57.0 54.0 55.0 62.0 57.0 54.0 55.0 58.0 55.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:88.80 Median:85.00 stddev:8.61 (10.1%) stddev-sans-first:8.53 14:44:41 INFO - PID 1662 | Values: 80.0 91.0 96.0 108.0 94.0 81.0 85.0 85.0 83.0 85.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:147.50 stddev:10.56 (7.2%) stddev-sans-first:10.21 14:44:41 INFO - PID 1662 | Values: 133.0 133.0 141.0 152.0 149.0 150.0 136.0 146.0 146.0 168.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.10 Median:190.00 stddev:17.88 (9.4%) stddev-sans-first:18.22 14:44:41 INFO - PID 1662 | Values: 178.0 177.0 196.0 178.0 179.0 184.0 179.0 215.0 220.0 215.0 14:44:41 INFO - PID 1662 | 14:44:41 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:68.00 stddev:15.45 (22.7%) stddev-sans-first:16.31 14:44:41 INFO - PID 1662 | Values: 68.0 67.0 68.0 67.0 99.0 58.0 56.0 89.0 92.0 58.0 14:44:41 INFO - PID 1662 | -------- Summary: end -------- 14:44:41 INFO - PID 1662 | 14:44:42 INFO - TEST-INFO | 1662: exit 0 14:44:42 INFO - TEST-OK | kraken | took 89105ms 14:44:42 INFO - SUITE-END | took 1235s 14:44:42 INFO - Completed test suite (00:20:35) 14:44:42 INFO - javascript benchmark 14:44:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8339.741823607314, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3297.6042874855016, 3576.8926932192044, 3636.4727114568404, 3704.7777133371983, 3712.9628703712956, 6290.370962903708, 6302.2437307523105, 6312.495000399968, 6317.052442133682, 6333.524985626073, 6362.5546211765195, 6427.035944608311, 6443.516736244788, 6449.161608990837, 6468.320826313228, 5601.887962240753, 5614.775408983646, 5636.887262254752, 5767.509761670263, 5836.270466191726, 5682.829515114553, 5764.106563482667, 5775.624584402021, 5783.566232532556, 5794.5943783935145, 5512.476314750093, 5542.944570554283, 5602.663840169603, 5617.550595952314, 5645.5201307888465, 5503.944960550423, 5560.582956278296, 5604.747786349583, 5640.294963129609, 5640.858978525529, 5540.085885828834, 5551.8889622207325, 5608.5793565482345, 5626.662400255997, 5635.267415236034, 5793.710314484259, 5934.664700442409, 6405.615663060231, 6407.711652975628, 6416.165898433221, 6718.6304753238555, 6965.199002114771, 6988.0, 6988.685509152049, 7019.859602807966, 6699.866002679968, 6862.725490980355, 6994.440444764457, 7047.682854271518, 7074.3633073023675, 6500.577462464925, 6514.153160089253, 6522.249941256721, 6588.439982601437, 6618.4705223582, 7283.162436319891, 7284.599347035858, 7289.562626242442, 7295.088113985752, 7298.489105762652, 6667.633280169637, 6737.3599508046655, 6740.561863478858, 6740.9662951685905, 6754.12196414463, 6954.3045695430055, 7035.3316434939625, 7045.89431158533, 7080.433565314763, 7083.972823940603, 6175.166352542349, 6941.965290173618, 6965.373116419445, 6972.686229119702, 7004.789856304319, 6735.629540375228, 7009.088818453569, 7035.296470352924, 7035.6482175891, 7039.577625342496, 5456.045192091368, 5480.506754392124, 5516.779328826802, 5546.861328466821, 5577.386487486373, 4092.386142078712, 4869.488703686062, 4999.125153098194, 4999.550040496373, 5039.596832253411, 6427.517936154807, 6550.967245163743, 6553.574017688835, 6564.868702626016, 6570.737170513222, 7117.4306055515435, 7121.643917804089, 7126.2161162272105, 7135.286471352927, 7141.107361579802, 6519.119918810995, 6554.606723596599, 6562.77030303937, 6563.474922006228, 6573.112629795012, 5781.219535362757, 6019.939800601938, 6072.332043475214, 6084.756609735649, 6129.233845769279, 6282.3089460158435, 7086.787396378117, 7187.460940429384, 7216.9278307216255, 7217.278272172741, 7088.574685518885, 7116.110486189226, 7119.003339532574, 7151.856962860609, 7192.784216473514, 7210.2789721027475, 7250.419966402781, 7267.273272672691, 7278.818029549304, 7282.0, 6916.066331045245, 6936.79189624312, 6958.199807022055, 6971.372576468142, 6972.825679357854, 6897.379235868796, 6951.270116637883, 6961.89557156633, 6962.930370696431, 7078.504504684623, 6683.8997415039785, 6738.966305168443, 6753.898691519729, 6839.350261725128, 6882.03651488782, 6800.863982720219, 6847.554908930903, 6890.586564805927, 6905.067815844796, 6919.37725604698, 6424.871502570016, 6494.9025764612625, 6737.191537015589, 6762.932370676427, 6793.218779840382, 6663.83340416474, 6674.098996635392, 6693.364130407604, 6703.731850726112, 6729.293424190585, 6429.067785171031, 6652.634105124264, 6685.364890335411, 6687.8328041797395, 6696.698648560924], "value": 6351.394662072625, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [768.2087449926576, 785.2853902948318, 818.615250832109, 863.7711006583256, 865.6148014133713, 37478.437823432636, 38424.80787596065, 38495.80752096243, 38514.84455466337, 38550.84347469577, 31308.53037204438, 37181.628183718225, 37930.620693793055, 38164.85505434834, 38329.23341533168, 8137.633806478708, 8277.255047045765, 8277.793055173608, 8281.875771863426, 8328.66685332586, 8133.715319963802, 8155.918440815575, 8211.0, 8241.340692744567, 8250.051244106944, 8159.265666090051, 8200.26197642212, 8235.752927412188, 8253.33973282139, 8284.13016633254, 8177.5502347370875, 8216.753497395059, 8243.25810677042, 8246.463979841292, 8273.048599411024, 8107.878381824277, 8126.228008339198, 8168.264856162974, 8168.4282100253, 8170.67317307307, 36284.27431451382, 36431.635683643086, 36524.81737591322, 36600.901972940854, 36609.26781464383, 3349.8995030149135, 3439.3637177122314, 3456.4815277708294, 3488.372093023255, 3492.8777492787885, 23121.88439057794, 29916.850415748, 30033.549496757347, 30061.0, 30346.54480182799, 2665.8133930624863, 3027.3793872256133, 3126.0778070469037, 3126.7967582107317, 3135.012471071605, 241707.0, 255395.0, 255557.7222113896, 274290.2570974285, 313688.0, 38049.04877378033, 47637.52362476331, 48211.5178848207, 48303.516964829905, 48869.0, 44413.88965275829, 50335.99328013419, 50466.49533504618, 50516.0, 50633.49366506288, 14985.250737463084, 15605.921970390076, 15777.368905243891, 15784.842151578567, 15800.92099539495, 12821.974242060613, 12874.871251287368, 12909.612711618667, 12925.806112908125, 12934.223946563236, 12583.056270779729, 12595.496180152875, 12599.370031498389, 12606.495740170474, 12692.873071269169, 4546.954530454719, 5372.650777699437, 5395.622306438511, 5424.159255315432, 5428.728563571805, 930.5347326336831, 950.6672664567346, 1000.6197644894893, 1003.9548220330103, 1009.369144284822, 22033.33899983003, 22765.0, 22826.086956521554, 23574.528509429714, 23591.938362773715, 825.5046971816862, 921.3219545322937, 953.7329547726648, 1106.7233191702076, 1165.7260543772206, 11841.0, 11958.760824783456, 12103.213291136224, 12171.0, 12277.710840361811, 328244.0, 329413.3529332338, 330479.0, 330860.3456982748, 331968.3401582977, 53807.73096134494, 54059.18911216335, 54198.45801542013, 54384.728076359366, 54442.72778636082, 1533.854283843033, 1540.5994441445305, 1574.1342261756217, 1574.559123445437, 1579.0210069756595, 22515.77484225137, 22767.658485122403, 23273.0, 23301.650475242546, 23900.402489937194, 37907.81046094752, 37910.810445947594, 37920.05199869915, 37940.24119517539, 38060.80969595134, 279496.0, 281153.0, 282232.0, 282717.0, 283639.0, 34480.79317223885, 34615.65384346225, 34856.47715284222, 35036.0, 35146.0, 34534.30931381408, 35582.64417355897, 41697.0, 44299.557004429545, 44316.33525497056, 28442.288942776595, 28656.42687146287, 29564.556531652473, 30015.549766753953, 30978.53532196974, 28738.0, 29164.125076247747, 30952.84523577368, 31346.802857413328, 31429.685703142677, 35214.47178292379, 36575.817120915286, 36622.633773661924, 36915.09696945553, 37250.81374593218, 25808.354791130372, 28658.283542911595, 30119.096427107223, 31555.52666709955, 31986.52020219652, 20086.397408077195, 24243.393915151555, 24653.63019554744, 24877.12930047512, 24879.0], "value": 17066.823991201123, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [432.68197874562213, 503.8815878268606, 510.83019884464557, 521.4316395129309, 541.039654613062, 20651.793482065175, 22206.222782202625, 22745.090196392146, 22825.0, 23036.539269214627, 23374.065037398483, 23526.823658817077, 23654.172103976365, 23696.289111326645, 23950.40123996901, 6201.937980620204, 6310.400511951358, 6338.841528961767, 6360.872782544347, 6365.777197798078, 6241.625502469844, 6363.236411630588, 6373.7131829067575, 6389.808305750835, 6392.073149393335, 5704.8859022819515, 5921.792737254197, 6330.525210609199, 6404.9359506405035, 6442.194725659293, 6313.810585682414, 6345.841353966165, 6397.712102955332, 6404.295527491972, 6405.263394709621, 5766.616012157094, 5790.305163380379, 5790.3630600634315, 5822.883542329129, 5826.242588463472, 26455.603165952525, 28702.712972870213, 28944.855275723698, 29219.977300794584, 29243.70756292431, 2258.3337915315024, 2269.8751568663715, 2279.099755596547, 2301.9424514387106, 2302.620067688846, 18723.251069957187, 19006.429807105807, 19265.13306901193, 19288.132034058362, 19293.517662058563, 1362.5980335800962, 1713.800339762159, 1757.1477833250863, 1811.8188181181908, 1817.472932849472, 140219.0, 169298.30701693072, 174089.0, 177696.11151944156, 195844.0, 13259.867401326057, 13463.932680336535, 14734.778978315333, 14876.330565124596, 14910.329035193443, 19683.11425985834, 19908.70136947918, 19913.402597922086, 19923.800761992483, 20125.99370031522, 8331.916680833236, 10054.447005414626, 10256.0, 10322.638707645196, 10352.844707329396, 7908.960455197802, 9646.42121472714, 9701.175400091071, 9735.075167859042, 9764.51177441125, 7338.70645174187, 8841.204291613785, 8877.29242581019, 8915.509646969478, 8938.541900977283, 2224.2993457060975, 2536.606825941982, 2655.269800804794, 2670.8531030793106, 2790.66512018555, 908.8818453601122, 955.4649396338073, 957.9904200958041, 965.888922773876, 1007.6422869881234, 20369.694454583194, 21691.240806571695, 22531.43671408228, 24334.878325608257, 26070.86964565203, 1036.7252678040325, 1067.9519421626046, 1068.3536460441476, 1074.4305518075432, 1078.5038882114159, 10598.04617584421, 11256.718582035352, 11279.0, 11357.091432685365, 11666.034307166869, 597489.0125549345, 608271.9586402039, 617244.0, 629300.0, 631584.8420757867, 158569.0, 161870.38129618787, 163932.0, 166436.50345244832, 167580.0, 1008.7074748323051, 1205.2888795610456, 1221.9144659873723, 1262.9052821038458, 1267.987320126787, 17668.558286042953, 18549.258029678662, 21047.0, 23923.04307827737, 24696.382590435383, 69055.30944690488, 71513.0, 72602.73287702171, 73042.80871573862, 73307.0, 67023.0, 67512.0, 68833.0, 88466.0, 88518.67221991546, 9844.556994935101, 11824.704382390164, 11962.820557691468, 12019.338936358585, 12039.879601204226, 18030.36893708714, 18242.72635910489, 18257.360992365204, 18364.081795910417, 18727.157277922255, 14425.278736063365, 15081.924590376977, 15251.542453726406, 15263.0, 15269.0, 13060.934695326463, 13248.735025299633, 13277.867221327662, 13718.38267277955, 13785.655358616115, 15545.222738863238, 15636.218189090272, 15669.764953525479, 15736.449224277096, 15799.36802527932, 11760.76478470443, 11776.4700588472, 11780.823287650863, 11788.351640659846, 11793.997510211728, 12440.315782632042, 13880.097793643381, 14696.265186740835, 14696.265186740835, 14823.703525929206], "value": 11598.088069675465, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1423.7152569486104, 1485.7845612386197, 1506.3823832228782, 1506.771980835619, 1523.1698724195312, 13539.79690304645, 14063.0, 14066.226357550331, 14077.0, 14100.717985640295, 13392.464301427932, 14310.35603397847, 14341.713165736679, 14355.928220358883, 14384.640383990405, 2811.775057995366, 2873.899413520527, 2875.108716297944, 2882.5820256062857, 2893.4068515954177, 2131.7015617813518, 2776.8333899966065, 2813.1279303415904, 2819.9436011279763, 2824.053941929456, 2785.220138361252, 2834.4472827798586, 2834.943301133976, 2836.503611867925, 2837.8155419897744, 2764.875580598878, 2788.344738986337, 2794.5808128780845, 2796.7762578993834, 2803.845788481643, 2707.905223317194, 2750.7936904732023, 2757.462294852505, 2806.887724491038, 2818.9436211275865, 13819.309034548232, 13889.749922507022, 13919.791203131961, 13931.164130152223, 13942.302884855717, 6594.93405065948, 7528.548287102792, 7809.882851757228, 7812.257835505618, 7835.451518393715, 4636.976815115937, 4873.269009648546, 4976.328195693572, 4981.327520784684, 4989.800407983676, 1391.6729568551382, 1394.0799072612028, 1402.1376853235288, 1411.202670491169, 1415.391381705866, 20864.0, 20974.580508389747, 20985.370438886857, 21010.789892101187, 21041.0, 5679.801206957736, 6441.355864413615, 6617.636030018395, 6756.864862702719, 6764.864702705919, 14339.211343376215, 14520.419183232763, 14530.709385812224, 14544.636384090483, 14550.708985820223, 7398.11222653285, 7478.2895624915545, 7478.990336304638, 7484.850302993909, 7500.624968751541, 5911.615744976649, 6223.657698826608, 7068.611226382598, 7075.186353569406, 7085.0435191248525, 5082.110630639623, 5271.868203294872, 5282.603804714585, 5288.497592728685, 5308.893822123536, 1545.1115608525142, 1545.7913181720548, 1549.3260431712242, 1552.3712896277025, 1564.0850102689826, 811.4968719393895, 836.7280633794041, 841.5245386356689, 848.6817443458702, 873.5021038008401, 5486.6982315973, 6378.744850206032, 6404.23149222087, 7295.2340004298785, 7322.450816188807, 634.7875557684893, 674.5244602555186, 701.992980070203, 714.3749219432996, 731.2650785960076, 3469.427544455187, 3959.3269144245546, 4980.501949805063, 5088.898222035538, 5229.660072095302, 17419.65160696779, 18896.960667163163, 18979.620407592047, 19129.852208867513, 19180.616387672446, 18791.0, 19068.61862762737, 19123.80876191248, 19133.23467061302, 19160.71258931117, 8043.39674524413, 8836.204741573289, 9352.67265645699, 9615.615375385047, 9701.90298097024, 6949.583025018515, 7276.87208482686, 7379.446541509516, 7437.698402779383, 8336.874946875923, 15643.29605167793, 16843.073630949926, 16884.0, 17216.74174887403, 17234.569135771708, 17043.0, 17078.48764537066, 17198.226079826687, 17279.56801079983, 17842.91078544599, 8648.567571621268, 11059.612913547988, 11356.716082097682, 11373.601923932623, 11491.367974761468, 12965.0, 13142.671433213864, 13146.21122732639, 13151.276679782704, 13161.210327380388, 9967.8758990047, 10212.131968782736, 10620.946895265473, 10663.626773062906, 10687.679369618923, 9951.810601667621, 9951.900480995388, 9980.301378903407, 10220.846687299547, 10404.68785936423, 9250.02874698147, 9347.626094956398, 9356.251499879858, 9372.390794598601, 9382.95308523453, 5615.0, 6647.102641143577, 6726.630035347907, 6807.217170025312, 7096.503244772816, 6916.757913473004, 6955.721771129098, 7160.928390716026, 7173.605451700207, 7286.599237042014], "value": 6131.314376153604, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [438.45412461485455, 452.855086372361, 457.5515994325561, 473.1884817537924, 478.552553362606, 6105.1147583600405, 6378.958733205376, 6410.9999999999945, 6545.312742162064, 6568.244651865037, 5209.453007434222, 5456.890862182764, 6364.653690899643, 6390.3929126733, 6404.359564043605, 3946.565877753445, 4155.958440415588, 4590.495045544987, 4815.7110573365535, 4835.1296766582, 4799.064182484417, 4807.250344731098, 4807.855764327076, 4808.629540580935, 4823.348847905523, 4583.060472603125, 4747.192977193886, 4758.643101767364, 4766.3088852116425, 4769.499202583731, 4757.762111894392, 4760.666753327268, 4786.83246086387, 4787.305840653085, 4791.329213910055, 3251.3822373748912, 3674.926501469956, 4189.49726032875, 4209.452771139763, 4218.767967761772, 6217.316095229521, 6219.1915051043525, 6233.535119247019, 6295.937040629581, 6308.2745484269435, 4968.950310496885, 5664.26364572603, 5688.402717714622, 5692.5161361284345, 5728.398518155617, 5907.409259074058, 6573.112629794964, 6918.654067296666, 6924.792256232321, 6929.341712537352, 4308.289132293168, 4546.772661366918, 4816.7350795706225, 4819.373481447389, 4832.371791667096, 7285.490015698903, 7356.227596102386, 7369.742059027962, 7371.852562948711, 7385.6307184640555, 4858.271259311061, 4897.338859254026, 4905.533974272438, 4910.558049775537, 4914.508549145057, 4372.23485889968, 4991.975040124777, 5014.824481143142, 5018.72022634226, 5046.84859454217, 5701.059325211377, 5702.608991076848, 5714.428557144335, 5729.195303479474, 5747.224124743189, 6959.1996920353795, 6963.9303606964295, 6965.537237180148, 6986.54587451814, 6990.685419156151, 1388.4723804954053, 1587.968240635204, 1701.1579268262042, 1712.3407488117164, 1725.9989206260339, 1098.6649072033106, 1107.8449017137607, 1108.6064447121316, 1134.9319040857574, 1136.9886301137049, 5675.8864822704145, 5891.293044834647, 6145.20112385396, 6211.81364559064, 6512.2742473244625, 7118.288171182944, 7187.137543494814, 7195.89206161908, 7227.602481863548, 7249.782506524813, 5403.621746477787, 6439.22729272481, 6464.870702586016, 6475.190601174853, 6480.287168411471, 2841.105051908642, 3392.558162780858, 3480.91297717554, 3717.7955212463035, 3773.415120656303, 6687.321972914878, 6755.49333799967, 6992.965035174791, 7330.523515971445, 7349.8162545936775, 5815.0, 6380.776672816429, 6638.103855979478, 7154.928450715531, 7329.706811727578, 7151.570905745672, 7336.853262934711, 7347.88978165328, 7369.263073692588, 7385.593792341473, 7149.9285007149265, 7159.498835081495, 7161.892571611325, 7162.785116446515, 7183.784486465414, 7035.331643494064, 7038.331358520933, 7071.762441572601, 7072.186698529735, 7099.219085900443, 6262.74949002053, 6908.965455172692, 7312.341889229995, 7368.889466657897, 7388.224236455095, 4085.3871919212356, 4108.556163083986, 4782.952170478251, 4825.4933232010135, 4827.0, 5964.343922168471, 6010.819675409744, 6082.452579267887, 7201.335505862864, 7204.639768011683, 6034.366391528826, 6234.908890943976, 6437.388448097423, 6914.757983470555, 7081.327273909176, 6479.5788273762055, 6899.413549848319, 6905.516613837184, 6918.169819621678, 6924.446044316342, 6720.294369091175, 6745.639026340741, 6746.279698676806, 6752.218757495818, 6758.290379510081, 5669.291338582677, 6871.0, 6878.518503704692, 6898.931010689829, 6914.619695916773, 6694.698738556679, 6702.296258892746, 6720.46236301085, 6727.360900714424, 6737.932620673927], "value": 5049.975390645009, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [821.4537332673772, 825.8265764189524, 843.5065486690286, 859.5659192107985, 865.6796985115507, 16651.75022374664, 16744.916275418607, 17113.486595402126, 17126.229319680613, 17129.57176070599, 14072.92963535181, 14138.57584272471, 14143.504977325796, 16597.92113512621, 17185.57036074099, 984.81780870539, 1016.532395098252, 1022.5449674894675, 1022.693192042388, 1025.6153942271649, 999.5452069308448, 1026.338011982271, 1031.876174859018, 1033.7622346860226, 1036.1037702387455, 1078.5847448732216, 1083.3229231730168, 1084.0027174998982, 1085.4030283344168, 1085.6417382263835, 956.2159029595734, 1056.5984925728173, 1061.5223149582719, 1075.67192006438, 1079.2499213046935, 763.3015790551694, 769.3460558525264, 770.8342706318168, 771.8649236383667, 774.2180397798264, 17058.23237954295, 17127.571810704707, 17175.570610734707, 17239.31042758288, 17249.051302178374, 16847.831521684748, 17866.464006079837, 17959.371422000168, 18067.27730890763, 18079.909600452043, 14467.27663616815, 14850.777238341434, 14900.552983410515, 14956.925215373854, 14994.325255363536, 3481.547398838135, 3649.6350364963555, 3676.4117741161285, 3685.9262814743824, 3695.5772027769235, 109441.45279273632, 147719.0, 154828.0, 157822.6326605102, 158977.0, 12885.871141288655, 13788.862111378958, 14037.71924561503, 14094.0, 14205.147691138565, 26102.73897261003, 26866.0, 26974.865125674638, 26992.730072699414, 27077.86461067682, 8136.877946830802, 8143.877841832377, 8157.592120394075, 8194.918050819535, 8223.21879421454, 4104.527979282361, 4124.938125928114, 4173.353130264813, 4176.477940257468, 4179.561146079679, 4039.7374170678813, 4720.575148236607, 4801.51984801517, 4804.975975120102, 4808.927866082012, 887.924526415262, 901.7915992569988, 980.4411485453223, 1034.8654674892216, 1043.1811028342715, 503.51159375405797, 615.559874689599, 630.5523078614143, 642.1038051328696, 682.6382017530717, 11391.829122563167, 11842.874926882105, 12254.387280635932, 12562.623121306375, 12636.11547191706, 605.9878802423926, 625.4621025917708, 627.3318915355133, 647.8024202618246, 658.9670516474157, 5224.895502089937, 5637.915431268534, 5721.0, 5721.828345149652, 5740.167675687086, 147919.0, 153421.23289383482, 154053.0, 157049.0, 161049.0, 130439.0, 134159.0, 134401.0, 135185.0, 136990.31504842412, 107093.0, 113109.43445282885, 115574.26638600428, 116656.0, 119621.0, 9287.839020122485, 9502.85745713801, 10988.230823842254, 11032.227744057838, 11231.887681123084, 44880.0, 47317.76341118272, 47404.2889356653, 48088.519114808405, 48128.759356202994, 54755.53600088085, 61793.69103154456, 61942.69028654828, 64819.0, 65921.68156636937, 14011.509597164459, 14794.0, 14933.477328293458, 14996.550103496913, 15066.623334416727, 15854.841451585336, 15970.441034563735, 15992.92003539975, 16025.839741602435, 16143.273552689921, 14922.328495217524, 14961.925190373979, 15029.849701502844, 15114.244287785346, 15261.771073434129, 14241.857581424054, 14927.477538286108, 15314.387424502895, 15337.463188788339, 15368.615784605474, 14653.047551909092, 15074.09555426678, 15074.924625376803, 15178.165200913614, 15235.542933712006, 10351.223658225512, 10572.41851698164, 10921.180911431515, 10935.28920620157, 11432.82850757256, 11456.770864582495, 11481.0, 11521.423928803693, 11882.82175767347, 11889.46497407636], "value": 8642.486003435277, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1526.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 135.0, 150.0, 132.0, 169.0, 169.0, 114.0, 117.0, 117.0, 142.0], "value": 134.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 114.0, 131.0, 153.0, 110.0, 131.0, 117.0, 184.0, 149.0, 105.0], "value": 133.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 202.0, 199.0, 197.0, 195.0, 192.0, 197.0, 197.0, 203.0, 196.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 79.0, 84.0, 153.0, 60.0, 58.0, 59.0, 59.0, 132.0, 58.0], "value": 80.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 72.0, 122.0, 70.0, 68.0, 71.0, 105.0, 69.0, 71.0, 105.0], "value": 82.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 90.0, 90.0, 89.0, 88.0, 90.0, 89.0, 89.0, 93.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 99.0, 98.0, 96.0, 149.0, 155.0, 107.0, 97.0, 99.0, 97.0], "value": 109.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 92.0, 92.0, 95.0, 91.0, 91.0, 91.0, 98.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 50.0, 49.0, 51.0, 51.0, 53.0, 53.0, 50.0, 53.0, 52.0], "value": 51.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 57.0, 54.0, 55.0, 62.0, 57.0, 54.0, 55.0, 58.0, 55.0], "value": 55.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 91.0, 96.0, 108.0, 94.0, 81.0, 85.0, 85.0, 83.0, 85.0], "value": 88.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 133.0, 141.0, 152.0, 149.0, 150.0, 136.0, 146.0, 146.0, 168.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 177.0, 196.0, 178.0, 179.0, 184.0, 179.0, 215.0, 220.0, 215.0], "value": 192.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 67.0, 99.0, 58.0, 56.0, 89.0, 92.0, 58.0], "value": 72.2, "unit": "score"}]}]} 14:44:42 INFO - Return code: 0 14:44:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:44:42 INFO - # TBPL SUCCESS # 14:44:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:44:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:44:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:44:42 INFO - 2017-06-20 14:44:42.683 screenresolution[1670:10972] starting screenresolution argv=screenresolution get 14:44:42 INFO - 2017-06-20 14:44:42.686 screenresolution[1670:10972] Display 0: 1600x1200x32@60 14:44:42 INFO - 2017-06-20 14:44:42.692 screenresolution[1671:10975] starting screenresolution argv=screenresolution list 14:44:42 INFO - Available Modes on Display 0 14:44:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:44:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:44:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:44:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:44:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:44:42 INFO - Intel Iris: 14:44:42 INFO - Chipset Model: Intel Iris 14:44:42 INFO - Type: GPU 14:44:42 INFO - Bus: Built-In 14:44:42 INFO - VRAM (Dynamic, Max): 1536 MB 14:44:42 INFO - Vendor: Intel (0x8086) 14:44:42 INFO - Device ID: 0x0a2e 14:44:42 INFO - Revision ID: 0x0009 14:44:42 INFO - Displays: 14:44:42 INFO - Display: 14:44:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:44:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:44:42 INFO - Display Serial Number: 1600x1200 60 14:44:42 INFO - Main Display: Yes 14:44:42 INFO - Mirror: Off 14:44:42 INFO - Online: Yes 14:44:42 INFO - Rotation: Supported 14:44:42 INFO - Return code: 0 14:44:42 INFO - Running post-action listener: _resource_record_post_action 14:44:42 INFO - [mozharness: 2017-06-20 21:44:42.926831Z] Finished run-tests step (success) 14:44:42 INFO - Running post-run listener: _resource_record_post_run 14:44:42 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 43852800; Write bytes: 556783104; Read time: 509; Write time: 1754 14:44:42 INFO - TinderboxPrint: CPU usage
53.1% 14:44:42 INFO - TinderboxPrint: I/O read bytes / time
43,852,800 / 509 14:44:42 INFO - TinderboxPrint: I/O write bytes / time
556,783,104 / 1,754 14:44:42 INFO - TinderboxPrint: CPU idle
2,361.0 (46.9%) 14:44:42 INFO - TinderboxPrint: CPU system
1,020.6 (20.3%) 14:44:42 INFO - TinderboxPrint: CPU user
1,648.0 (32.8%) 14:44:42 INFO - TinderboxPrint: Swap in / out
281,862,144 / 0 14:44:42 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 232100352; Write bytes: 227158016; Read time: 16876; Write time: 477 14:44:42 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:44:43 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 41845760; Write bytes: 310312448; Read time: 482; Write time: 1236 14:44:43 INFO - Running post-run listener: _upload_blobber_files 14:44:43 INFO - Blob upload gear active. 14:44:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:44:43 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:44:43 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', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:44:43 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 Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:44:43 INFO - (blobuploader) - INFO - Open directory for files ... 14:44:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:44:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:44:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:44 INFO - (blobuploader) - INFO - Done attempting. 14:44:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:44:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:44:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:45 INFO - (blobuploader) - INFO - Done attempting. 14:44:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:44:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:46 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:44:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:46 INFO - (blobuploader) - INFO - Done attempting. 14:44:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:44:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:44:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:44:48 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:44:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:44:48 INFO - (blobuploader) - INFO - Done attempting. 14:44:48 INFO - (blobuploader) - INFO - Iteration through files over. 14:44:48 INFO - Return code: 0 14:44:48 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:44:48 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:44:48 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9183f0cb965e5f01f626ead51765705e8b8ef5908cb9b727ac007e0362ce4d85e248465a0e1f38d6bd68f95e19f66b5c0e3a587ab1eba4b506e953e763fbf99a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6a6032b5149df55d6010a72be5110ebe3a2a500d4edbf1863f8df909dcb168e6855a59bf6f16c3e9e48684a49a884f45b2efd07476d9fbb21769c82c8abc731", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a671b31ef15e36767c2176066e4bcabe8b1803a83b0f232062e58aeb5eb7c24cd071ca49d5038bc4f645abed9a932ab0fac89b04ad1efcab0dcf07ffa4b136f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ae058858798d11faf2f2dde50d0b5765d04de40d79f3e4aebd8ac161c11922265e2868dadd9c13245e892ec2136c32a8329a616ae4e3966ed9ca37a57a728fd"} 14:44:48 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:44:48 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:44:48 INFO - Contents: 14:44:48 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9183f0cb965e5f01f626ead51765705e8b8ef5908cb9b727ac007e0362ce4d85e248465a0e1f38d6bd68f95e19f66b5c0e3a587ab1eba4b506e953e763fbf99a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6a6032b5149df55d6010a72be5110ebe3a2a500d4edbf1863f8df909dcb168e6855a59bf6f16c3e9e48684a49a884f45b2efd07476d9fbb21769c82c8abc731", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a671b31ef15e36767c2176066e4bcabe8b1803a83b0f232062e58aeb5eb7c24cd071ca49d5038bc4f645abed9a932ab0fac89b04ad1efcab0dcf07ffa4b136f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ae058858798d11faf2f2dde50d0b5765d04de40d79f3e4aebd8ac161c11922265e2868dadd9c13245e892ec2136c32a8329a616ae4e3966ed9ca37a57a728fd"} 14:44:48 INFO - Running post-run listener: copy_logs_to_upload_dir 14:44:48 INFO - Copying logs to upload dir... 14:44:48 INFO - mkdir: /builds/slave/test/build/upload/logs 14:44:48 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.996239 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-06-20 14:44:48.478507) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 14:44:48.481802) ========= 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.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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/Mozilla-Beta/sha512/9183f0cb965e5f01f626ead51765705e8b8ef5908cb9b727ac007e0362ce4d85e248465a0e1f38d6bd68f95e19f66b5c0e3a587ab1eba4b506e953e763fbf99a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6a6032b5149df55d6010a72be5110ebe3a2a500d4edbf1863f8df909dcb168e6855a59bf6f16c3e9e48684a49a884f45b2efd07476d9fbb21769c82c8abc731", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a671b31ef15e36767c2176066e4bcabe8b1803a83b0f232062e58aeb5eb7c24cd071ca49d5038bc4f645abed9a932ab0fac89b04ad1efcab0dcf07ffa4b136f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ae058858798d11faf2f2dde50d0b5765d04de40d79f3e4aebd8ac161c11922265e2868dadd9c13245e892ec2136c32a8329a616ae4e3966ed9ca37a57a728fd"} build_url:https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010587 build_url: 'https://queue.taskcluster.net/v1/task/BaZ_9XmqTnqy7HJf0JC5Bw/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9183f0cb965e5f01f626ead51765705e8b8ef5908cb9b727ac007e0362ce4d85e248465a0e1f38d6bd68f95e19f66b5c0e3a587ab1eba4b506e953e763fbf99a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f6a6032b5149df55d6010a72be5110ebe3a2a500d4edbf1863f8df909dcb168e6855a59bf6f16c3e9e48684a49a884f45b2efd07476d9fbb21769c82c8abc731", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a671b31ef15e36767c2176066e4bcabe8b1803a83b0f232062e58aeb5eb7c24cd071ca49d5038bc4f645abed9a932ab0fac89b04ad1efcab0dcf07ffa4b136f7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/3ae058858798d11faf2f2dde50d0b5765d04de40d79f3e4aebd8ac161c11922265e2868dadd9c13245e892ec2136c32a8329a616ae4e3966ed9ca37a57a728fd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-20 14:44:48.514044) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:44:48.514411) ========= 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.LmsRVnyaY7/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.6L4G5AXyco/Listeners TMPDIR=/var/folders/2_/5bvqc7_51034r2hmy_t0wjpm00000w/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.005251 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-20 14:44:48.563602) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 14:44:48.563927) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-20 14:44:48.564233) ========= ========= Total master_lag: 0.17 =========