builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0362 starttime: 1496257726.85 results: success (0) revision: 2645d067b1e3f4064f18a7d1241ec96b33402d07 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.848064) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.848499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.848783) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.875602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.875940) ========= 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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-31 12:08:46-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 7.89M=0.001s 2017-05-31 12:08:46 (7.89 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.096661 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.997533) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:46.997844) ========= 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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.021646 ========= master_lag: 0.36 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:47.384284) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 12:08:47.384615) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 2645d067b1e3f4064f18a7d1241ec96b33402d07 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 2645d067b1e3f4064f18a7d1241ec96b33402d07 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-31 12:08:47,469 truncating revision to first 12 chars 2017-05-31 12:08:47,469 Setting DEBUG logging. 2017-05-31 12:08:47,469 attempt 1/10 2017-05-31 12:08:47,469 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/2645d067b1e3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-31 12:08:49,069 unpacking tar archive at: mozilla-central-2645d067b1e3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.892223 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-31 12:08:49.301741) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:49.302077) ========= script_repo_revision: 2645d067b1e3f4064f18a7d1241ec96b33402d07 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:49.302453) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:49.302718) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-31 12:08:49.321293) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-31 12:08:49.321567) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:08:49 INFO - MultiFileLogger online at 20170531 12:08:49 in /builds/slave/test 12:08:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 12:08:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:08:49 INFO - {'append_to_log': False, 12:08:49 INFO - 'base_work_dir': '/builds/slave/test', 12:08:49 INFO - 'blob_upload_branch': 'Firefox', 12:08:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:08:49 INFO - 'branch': 'Firefox', 12:08:49 INFO - 'buildbot_json_path': 'buildprops.json', 12:08:49 INFO - 'config_files': ('talos/mac_config.py',), 12:08:49 INFO - 'default_actions': ('clobber', 12:08:49 INFO - 'read-buildbot-config', 12:08:49 INFO - 'download-and-extract', 12:08:49 INFO - 'populate-webroot', 12:08:49 INFO - 'create-virtualenv', 12:08:49 INFO - 'install', 12:08:49 INFO - 'setup-mitmproxy', 12:08:49 INFO - 'run-tests'), 12:08:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:08:49 INFO - 'download_minidump_stackwalk': True, 12:08:49 INFO - 'download_symbols': 'ondemand', 12:08:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:08:49 INFO - 'tooltool.py': '/tools/tooltool.py', 12:08:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:08:49 INFO - '/tools/misc-python/virtualenv.py')}, 12:08:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:08:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:08:49 INFO - 'gecko_profile': False, 12:08:49 INFO - 'gecko_profile_interval': 0, 12:08:49 INFO - 'installer_path': 'installer.exe', 12:08:49 INFO - 'log_level': 'info', 12:08:49 INFO - 'log_name': 'talos', 12:08:49 INFO - 'log_to_console': True, 12:08:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:08:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:08:49 INFO - 'opt_config_files': (), 12:08:49 INFO - 'pip_index': False, 12:08:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:49 INFO - 'cmd': ('bash', 12:08:49 INFO - '-c', 12:08:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:49 INFO - 'enabled': True, 12:08:49 INFO - 'halt_on_failure': False, 12:08:49 INFO - 'name': 'check_screen_resolution'},), 12:08:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:08:49 INFO - 'cmd': ('bash', 12:08:49 INFO - '-c', 12:08:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:08:49 INFO - 'enabled': True, 12:08:49 INFO - 'halt_on_failure': False, 12:08:49 INFO - 'name': 'check_screen_resolution'},), 12:08:49 INFO - 'run_cmd_checks_enabled': True, 12:08:49 INFO - 'suite': 'dromaeojs-e10s', 12:08:49 INFO - 'system_bits': '32', 12:08:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:08:49 INFO - 'title': 't-yosemite-r7-0362', 12:08:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:08:49 INFO - 'use_talos_json': True, 12:08:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:08:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:08:49 INFO - 'work_dir': 'build'} 12:08:49 INFO - [mozharness: 2017-05-31 19:08:49.529385Z] Running clobber step. 12:08:49 INFO - Running pre-action listener: _resource_record_pre_action 12:08:49 INFO - Running main action method: clobber 12:08:49 INFO - rmtree: /builds/slave/test/build 12:08:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:08:49 INFO - Running post-action listener: _resource_record_post_action 12:08:49 INFO - [mozharness: 2017-05-31 19:08:49.895892Z] Finished clobber step (success) 12:08:49 INFO - [mozharness: 2017-05-31 19:08:49.896018Z] Running read-buildbot-config step. 12:08:49 INFO - Running pre-action listener: _resource_record_pre_action 12:08:49 INFO - Running main action method: read_buildbot_config 12:08:49 INFO - Using buildbot properties: 12:08:49 INFO - { 12:08:49 INFO - "project": "", 12:08:49 INFO - "product": "firefox", 12:08:49 INFO - "who": "ryanvm@gmail.com", 12:08:49 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 12:08:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:08:49 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 12:08:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:08:49 INFO - "stage_platform": "macosx64", 12:08:49 INFO - "basedir": "/builds/slave/test", 12:08:49 INFO - "buildnumber": 91, 12:08:49 INFO - "platform": "yosemite_r7", 12:08:49 INFO - "parent_task_id": "T9Vi3kDnSqiuIz9p6KxXwQ", 12:08:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 12:08:49 INFO - "slavebuilddir": "test", 12:08:49 INFO - "taskId": "Skr_uv23T-2Z4ZXAfg1Nmw", 12:08:49 INFO - "branch": "mozilla-central", 12:08:49 INFO - "script_repo_revision": "production", 12:08:49 INFO - "revision": "2645d067b1e3f4064f18a7d1241ec96b33402d07", 12:08:49 INFO - "slavename": "t-yosemite-r7-0362", 12:08:49 INFO - "repo_path": "mozilla-central" 12:08:49 INFO - } 12:08:49 INFO - Finding installer, test and symbols from parent task. 12:08:49 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Skr_uv23T-2Z4ZXAfg1Nmw'}, attempt #1 12:08:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Skr_uv23T-2Z4ZXAfg1Nmw'}, attempt #1 12:08:50 INFO - Task dependencies: GwhZB4lHQY6uVbGOBVh-4w T9Vi3kDnSqiuIz9p6KxXwQ 12:08:50 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ'}, attempt #1 12:08:51 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:08:51 INFO - Set test_url: https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/test_packages.json 12:08:51 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/target.crashreporter-symbols.zip 12:08:51 INFO - Running post-action listener: _resource_record_post_action 12:08:51 INFO - [mozharness: 2017-05-31 19:08:51.332220Z] Finished read-buildbot-config step (success) 12:08:51 INFO - [mozharness: 2017-05-31 19:08:51.332364Z] Running download-and-extract step. 12:08:51 INFO - Running pre-action listener: _resource_record_pre_action 12:08:51 INFO - Running main action method: download_and_extract 12:08:51 INFO - mkdir: /builds/slave/test/build/tests 12:08:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:08:51 INFO - trying https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 12:08:51 INFO - Downloading https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:08:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 12:08:52 INFO - Downloaded 1325 bytes. 12:08:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 12:08:52 INFO - Using the following test package requirements: 12:08:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 12:08:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:08:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 12:08:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 12:08:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'jsshell-mac.zip'], 12:08:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 12:08:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 12:08:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 12:08:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 12:08:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 12:08:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 12:08:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 12:08:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:08:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:08:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:08:52 INFO - Fetch https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:08:53 INFO - Content-Length response header: 57634804 12:08:53 INFO - Bytes received: 57634804 12:09:11 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:09:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 12:09:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:09:11 INFO - Fetch https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 12:09:13 INFO - Content-Length response header: 57634804 12:09:13 INFO - Bytes received: 57634804 12:09:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 12:09:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:09:31 INFO - Fetch https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 12:09:32 INFO - Content-Length response header: 14447425 12:09:32 INFO - Bytes received: 14447425 12:09: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')]} 12:09:32 INFO - trying https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:09:32 INFO - Downloading https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 12:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 12:09:34 INFO - Downloaded 67814661 bytes. 12:09:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:09:34 INFO - mkdir: /builds/slave/test/properties 12:09:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:09:34 INFO - Writing to file /builds/slave/test/properties/build_url 12:09:34 INFO - Contents: 12:09:34 INFO - build_url:https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 12:09:34 INFO - Running post-action listener: _resource_record_post_action 12:09:34 INFO - Running post-action listener: set_extra_try_arguments 12:09:34 INFO - [mozharness: 2017-05-31 19:09:34.958690Z] Finished download-and-extract step (success) 12:09:34 INFO - [mozharness: 2017-05-31 19:09:34.958839Z] Running populate-webroot step. 12:09:34 INFO - Running pre-action listener: _resource_record_pre_action 12:09:34 INFO - Running main action method: populate_webroot 12:09:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:09:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:09:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:09:34 INFO - '--tppagecycles', 12:09:34 INFO - '7'], 12:09:34 INFO - 'tests': ['tsvgm']}}, 12:09:34 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:09:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:09:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:09:34 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:34 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:09:34 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['damp', 'tps']}, 12:09:34 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'tests': ['damp', 'tps']}, 12:09:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['dromaeo_dom']}, 12:09:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:09:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 12:09:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 12:09:34 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 12:09:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'other-e10s': {'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['a11yr', 12:09:34 INFO - 'ts_paint', 12:09:34 INFO - 'tpaint', 12:09:34 INFO - 'sessionrestore', 12:09:34 INFO - 'sessionrestore_no_auto_restore', 12:09:34 INFO - 'tabpaint']}, 12:09:34 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 12:09:34 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 12:09:34 INFO - 'bloom_basic_ref']}, 12:09:34 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 12:09:34 INFO - 'talos_options': ['--mitmproxy', 12:09:34 INFO - 'mitmproxy-recording-1.mp', 12:09:34 INFO - '--firstNonBlankPaint'], 12:09:34 INFO - 'tests': ['Quantum_1']}, 12:09:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['tsvgx', 12:09:34 INFO - 'tsvgr_opacity', 12:09:34 INFO - 'tart', 12:09:34 INFO - 'tscrollx', 12:09:34 INFO - 'cart', 12:09:34 INFO - 'tsvg_static']}, 12:09:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:09:34 INFO - 'tsvgr_opacity', 12:09:34 INFO - 'tart', 12:09:34 INFO - 'tscrollx', 12:09:34 INFO - 'cart', 12:09:34 INFO - 'tsvg_static']}, 12:09:34 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'talos_options': ['--disable-e10s'], 12:09:34 INFO - 'tests': ['tp5o']}, 12:09:34 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:09:34 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'talos_options': ['--disable-e10s', 12:09:34 INFO - '--xperf_path', 12:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:34 INFO - 'tests': ['tp5n']}, 12:09:34 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:09:34 INFO - 'talos_options': ['--xperf_path', 12:09:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:09:34 INFO - 'tests': ['tp5n']}}, 12:09:34 INFO - 'talos.zip': {'path': '', 12:09:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:09:34 INFO - Running post-action listener: _resource_record_post_action 12:09:34 INFO - [mozharness: 2017-05-31 19:09:34.980653Z] Finished populate-webroot step (success) 12:09:34 INFO - [mozharness: 2017-05-31 19:09:34.980796Z] Running create-virtualenv step. 12:09:34 INFO - Running pre-action listener: _resource_record_pre_action 12:09:34 INFO - Running main action method: create_virtualenv 12:09:34 INFO - Creating virtualenv /builds/slave/test/build/venv 12:09:34 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 12:09:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:09:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:09:35 INFO - Using real prefix '/tools/python27' 12:09:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:09:35 INFO - Installing distribute.............................................................................................................................................................................................done. 12:09:38 INFO - Installing pip.................done. 12:09:38 INFO - Return code: 0 12:09:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:09:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:09:39 INFO - Reading from file tmpfile_stdout 12:09:39 INFO - Output received: 12:09:39 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) 12:09:39 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:39 INFO - 'HOME': '/Users/cltbld', 12:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:39 INFO - 'LOGNAME': 'cltbld', 12:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:39 INFO - 'MOZ_NO_REMOTE': '1', 12:09:39 INFO - 'NO_EM_RESTART': '1', 12:09:39 INFO - 'PAGER': '/bin/cat', 12:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:39 INFO - 'PWD': '/builds/slave/test', 12:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:39 INFO - 'SHELL': '/bin/bash', 12:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:39 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:39 INFO - 'USER': 'cltbld', 12:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:39 INFO - 'XPC_FLAGS': '0x0', 12:09:39 INFO - 'XPC_SERVICE_NAME': '0', 12:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:39 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 12:09:39 INFO - Cleaning up... 12:09:39 INFO - Return code: 0 12:09:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:09:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:39 INFO - 'HOME': '/Users/cltbld', 12:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:39 INFO - 'LOGNAME': 'cltbld', 12:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:39 INFO - 'MOZ_NO_REMOTE': '1', 12:09:39 INFO - 'NO_EM_RESTART': '1', 12:09:39 INFO - 'PAGER': '/bin/cat', 12:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:39 INFO - 'PWD': '/builds/slave/test', 12:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:39 INFO - 'SHELL': '/bin/bash', 12:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:39 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:39 INFO - 'USER': 'cltbld', 12:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:39 INFO - 'XPC_FLAGS': '0x0', 12:09:39 INFO - 'XPC_SERVICE_NAME': '0', 12:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:39 INFO - Downloading/unpacking psutil>=3.1.1 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:09:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:41 INFO - Installing collected packages: psutil 12:09:41 INFO - Running setup.py install for psutil 12:09:41 INFO - building 'psutil._psutil_osx' extension 12:09:41 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 12:09:41 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 12:09:41 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 12:09:42 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 12:09:42 INFO - building 'psutil._psutil_posix' extension 12:09:42 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 12:09:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:09:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:09:42 INFO - ^ 12:09:42 INFO - 1 warning generated. 12:09:42 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 12:09:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:09:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:09:42 INFO - Successfully installed psutil 12:09:42 INFO - Cleaning up... 12:09:42 INFO - Return code: 0 12:09:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:09: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')]} 12:09:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09: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')]} 12:09:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:43 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:43 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 12:09:43 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 12:09:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:43 INFO - 'HOME': '/Users/cltbld', 12:09:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:43 INFO - 'LOGNAME': 'cltbld', 12:09:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:43 INFO - 'MOZ_NO_REMOTE': '1', 12:09:43 INFO - 'NO_EM_RESTART': '1', 12:09:43 INFO - 'PAGER': '/bin/cat', 12:09:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:43 INFO - 'PWD': '/builds/slave/test', 12:09:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:43 INFO - 'SHELL': '/bin/bash', 12:09:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:43 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:43 INFO - 'USER': 'cltbld', 12:09:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:43 INFO - 'XPC_FLAGS': '0x0', 12:09:43 INFO - 'XPC_SERVICE_NAME': '0', 12:09:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:09:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:09:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:09:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:09:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:09:45 INFO - Installing collected packages: mozsystemmonitor 12:09:45 INFO - Running setup.py install for mozsystemmonitor 12:09:45 INFO - Successfully installed mozsystemmonitor 12:09:45 INFO - Cleaning up... 12:09:45 INFO - Return code: 0 12:09:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:45 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:45 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 12:09:45 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 12:09:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:45 INFO - 'HOME': '/Users/cltbld', 12:09:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:45 INFO - 'LOGNAME': 'cltbld', 12:09:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:45 INFO - 'MOZ_NO_REMOTE': '1', 12:09:45 INFO - 'NO_EM_RESTART': '1', 12:09:45 INFO - 'PAGER': '/bin/cat', 12:09:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:45 INFO - 'PWD': '/builds/slave/test', 12:09:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:45 INFO - 'SHELL': '/bin/bash', 12:09:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:45 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:45 INFO - 'USER': 'cltbld', 12:09:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:45 INFO - 'XPC_FLAGS': '0x0', 12:09:45 INFO - 'XPC_SERVICE_NAME': '0', 12:09:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:45 INFO - Downloading/unpacking jsonschema==2.5.1 12:09:45 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 12:09:45 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 12:09:45 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 12:09:45 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 12:09:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:09:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:09:46 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 12:09:46 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 12:09:46 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 12:09:46 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 12:09:47 INFO - Downloading functools32-3.2.3-2.tar.gz 12:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:09:47 INFO - warning: no files found matching '*.txt' 12:09:47 INFO - no previously-included directories found matching 'build' 12:09:47 INFO - no previously-included directories found matching 'dist' 12:09:47 INFO - no previously-included directories found matching '.git*' 12:09:47 INFO - Installing collected packages: jsonschema, functools32 12:09:47 INFO - Running setup.py install for functools32 12:09:47 INFO - warning: no files found matching '*.txt' 12:09:47 INFO - no previously-included directories found matching 'build' 12:09:47 INFO - no previously-included directories found matching 'dist' 12:09:47 INFO - no previously-included directories found matching '.git*' 12:09:47 INFO - Successfully installed jsonschema functools32 12:09:47 INFO - Cleaning up... 12:09:47 INFO - Return code: 0 12:09:47 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:09:47 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')]} 12:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:47 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')]} 12:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:47 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:47 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 12:09:47 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 12:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:47 INFO - 'HOME': '/Users/cltbld', 12:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:47 INFO - 'LOGNAME': 'cltbld', 12:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:47 INFO - 'MOZ_NO_REMOTE': '1', 12:09:47 INFO - 'NO_EM_RESTART': '1', 12:09:47 INFO - 'PAGER': '/bin/cat', 12:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:47 INFO - 'PWD': '/builds/slave/test', 12:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:47 INFO - 'SHELL': '/bin/bash', 12:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:47 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:47 INFO - 'USER': 'cltbld', 12:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:47 INFO - 'XPC_FLAGS': '0x0', 12:09:47 INFO - 'XPC_SERVICE_NAME': '0', 12:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:09:47 INFO - Cleaning up... 12:09:47 INFO - Return code: 0 12:09:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:09:47 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')]} 12:09:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:47 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')]} 12:09:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:47 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:47 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 12:09:47 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 12:09:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:47 INFO - 'HOME': '/Users/cltbld', 12:09:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:47 INFO - 'LOGNAME': 'cltbld', 12:09:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:47 INFO - 'MOZ_NO_REMOTE': '1', 12:09:47 INFO - 'NO_EM_RESTART': '1', 12:09:47 INFO - 'PAGER': '/bin/cat', 12:09:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:47 INFO - 'PWD': '/builds/slave/test', 12:09:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:47 INFO - 'SHELL': '/bin/bash', 12:09:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:47 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:47 INFO - 'USER': 'cltbld', 12:09:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:47 INFO - 'XPC_FLAGS': '0x0', 12:09:47 INFO - 'XPC_SERVICE_NAME': '0', 12:09:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:47 INFO - Downloading/unpacking blobuploader==1.2.4 12:09:47 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 12:09:47 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 12:09:47 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 12:09:47 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 12:09:49 INFO - Downloading blobuploader-1.2.4.tar.gz 12:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:09:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:09:49 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 12:09:49 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 12:09:49 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 12:09:49 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 12:09:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:09:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:09:50 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 12:09:50 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 12:09:50 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 12:09:50 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 12:09:50 INFO - Downloading docopt-0.6.1.tar.gz 12:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:09:50 INFO - Installing collected packages: blobuploader, requests, docopt 12:09:50 INFO - Running setup.py install for blobuploader 12:09:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:09:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:09:50 INFO - Running setup.py install for requests 12:09:51 INFO - Running setup.py install for docopt 12:09:51 INFO - Successfully installed blobuploader requests docopt 12:09:51 INFO - Cleaning up... 12:09:51 INFO - Return code: 0 12:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:51 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:51 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 12:09:51 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 12:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:51 INFO - 'HOME': '/Users/cltbld', 12:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:51 INFO - 'LOGNAME': 'cltbld', 12:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:51 INFO - 'MOZ_NO_REMOTE': '1', 12:09:51 INFO - 'NO_EM_RESTART': '1', 12:09:51 INFO - 'PAGER': '/bin/cat', 12:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:51 INFO - 'PWD': '/builds/slave/test', 12:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:51 INFO - 'SHELL': '/bin/bash', 12:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:51 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:51 INFO - 'USER': 'cltbld', 12:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:51 INFO - 'XPC_FLAGS': '0x0', 12:09:51 INFO - 'XPC_SERVICE_NAME': '0', 12:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-g4aCxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-uwy6D8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-iOBjgc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-SuBcNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-s3OWyR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:51 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-IdbQOc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-AOkoBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-8haJl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-_X8FkG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-1BHbkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CINNhM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-3ZQIry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-f70EOk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-96OzA2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:52 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-vRHov2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-_PrJQ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:53 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oXV1SZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:09:53 INFO - Running setup.py install for manifestparser 12:09:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:09:53 INFO - Running setup.py install for mozcrash 12:09:53 INFO - Running setup.py install for mozdebug 12:09:53 INFO - Running setup.py install for mozdevice 12:09:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:09:53 INFO - Running setup.py install for mozfile 12:09:53 INFO - Running setup.py install for mozhttpd 12:09:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Running setup.py install for mozinfo 12:09:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Running setup.py install for mozInstall 12:09:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Running setup.py install for mozleak 12:09:54 INFO - Running setup.py install for mozlog 12:09:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Running setup.py install for moznetwork 12:09:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:09:54 INFO - Running setup.py install for mozprocess 12:09:55 INFO - Running setup.py install for mozprofile 12:09:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:09:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:09:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:09:55 INFO - Running setup.py install for mozrunner 12:09:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:09:55 INFO - Running setup.py install for mozscreenshot 12:09:55 INFO - Running setup.py install for moztest 12:09:55 INFO - Running setup.py install for mozversion 12:09:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:09:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:09:55 INFO - Cleaning up... 12:09:55 INFO - Return code: 0 12:09:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:55 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')]} 12:09:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:55 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')]} 12:09:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:55 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:55 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 12:09:55 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 12:09:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:55 INFO - 'HOME': '/Users/cltbld', 12:09:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:55 INFO - 'LOGNAME': 'cltbld', 12:09:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:55 INFO - 'MOZ_NO_REMOTE': '1', 12:09:55 INFO - 'NO_EM_RESTART': '1', 12:09:55 INFO - 'PAGER': '/bin/cat', 12:09:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:55 INFO - 'PWD': '/builds/slave/test', 12:09:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:55 INFO - 'SHELL': '/bin/bash', 12:09:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:55 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:55 INFO - 'USER': 'cltbld', 12:09:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:55 INFO - 'XPC_FLAGS': '0x0', 12:09:55 INFO - 'XPC_SERVICE_NAME': '0', 12:09:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-yENcGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-RGHM5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-0qSary-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-CEV3Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-oqmHdA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-yJ__fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-UGJUgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-O4bPLt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:09:56 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-K1lHHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:09:56 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)) 12:09:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-ZP6pwY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-vlj0CT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-GYy74B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-MK4mC6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-kKM3Qh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-h6Zk0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-zmpYgW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:09:57 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)) 12:09:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:09:57 INFO - Running setup.py (path:/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/pip-m8vmzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:09:57 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)) 12:09:57 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)) 12:09:57 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)) 12:09:57 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)) 12:09:57 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)) 12:09:57 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)) 12:09:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:09:57 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 12:09:57 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 12:09:57 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 12:09:57 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 12:09:59 INFO - Downloading blessings-1.6.tar.gz 12:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:09:59 INFO - Installing collected packages: blessings 12:09:59 INFO - Running setup.py install for blessings 12:09:59 INFO - Successfully installed blessings 12:09:59 INFO - Cleaning up... 12:09:59 INFO - Return code: 0 12:09:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:09:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:09:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:09:59 INFO - Reading from file tmpfile_stdout 12:09:59 INFO - Current package versions: 12:09:59 INFO - blessings == 1.6 12:09:59 INFO - blobuploader == 1.2.4 12:09:59 INFO - docopt == 0.6.1 12:09:59 INFO - functools32 == 3.2.3-2 12:09:59 INFO - jsonschema == 2.5.1 12:09:59 INFO - manifestparser == 1.1 12:09:59 INFO - mozInstall == 1.12 12:09:59 INFO - mozcrash == 1.0 12:09:59 INFO - mozdebug == 0.1 12:09:59 INFO - mozdevice == 0.51 12:09:59 INFO - mozfile == 1.2 12:09:59 INFO - mozhttpd == 0.7 12:09:59 INFO - mozinfo == 0.9 12:09:59 INFO - mozleak == 0.1 12:09:59 INFO - mozlog == 3.4 12:09:59 INFO - moznetwork == 0.27 12:09:59 INFO - mozprocess == 0.25 12:09:59 INFO - mozprofile == 0.28 12:09:59 INFO - mozrunner == 6.13 12:09:59 INFO - mozscreenshot == 0.1 12:09:59 INFO - mozsystemmonitor == 0.3 12:09:59 INFO - moztest == 0.8 12:09:59 INFO - mozversion == 1.4 12:09:59 INFO - psutil == 3.1.1 12:09:59 INFO - requests == 1.2.3 12:09:59 INFO - wsgiref == 0.1.2 12:09:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:09:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:09:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:09:59 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:09:59 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 12:09:59 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 12:09:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:09:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:09:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:09:59 INFO - 'HOME': '/Users/cltbld', 12:09:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:09:59 INFO - 'LOGNAME': 'cltbld', 12:09:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:09:59 INFO - 'MOZ_NO_REMOTE': '1', 12:09:59 INFO - 'NO_EM_RESTART': '1', 12:09:59 INFO - 'PAGER': '/bin/cat', 12:09:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:09:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:09:59 INFO - 'PWD': '/builds/slave/test', 12:09:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:09:59 INFO - 'SHELL': '/bin/bash', 12:09:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:09:59 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:09:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:09:59 INFO - 'USER': 'cltbld', 12:09:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:09:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:09:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:09:59 INFO - 'XPC_FLAGS': '0x0', 12:09:59 INFO - 'XPC_SERVICE_NAME': '0', 12:09:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 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)) 12:10:00 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 12:10:00 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 12:10:00 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 12:10:00 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 12:10:00 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 12:10:01 INFO - Downloading marionette_driver-1.4.0.tar.gz 12:10:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 12:10:01 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)) 12:10:01 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)) 12:10:01 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)) 12:10:01 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)) 12:10:01 INFO - Installing collected packages: marionette-driver 12:10:01 INFO - Running setup.py install for marionette-driver 12:10:01 INFO - Successfully installed marionette-driver 12:10:01 INFO - Cleaning up... 12:10:01 INFO - Return code: 0 12:10:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:10:01 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')]} 12:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:10:01 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')]} 12:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:10:01 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 0x107b02c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107b06768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde935c87b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, '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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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 12:10:01 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 12:10:01 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 12:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:01 INFO - 'HOME': '/Users/cltbld', 12:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:01 INFO - 'LOGNAME': 'cltbld', 12:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:01 INFO - 'MOZ_NO_REMOTE': '1', 12:10:01 INFO - 'NO_EM_RESTART': '1', 12:10:01 INFO - 'PAGER': '/bin/cat', 12:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:01 INFO - 'PWD': '/builds/slave/test', 12:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:01 INFO - 'SHELL': '/bin/bash', 12:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:10:01 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:01 INFO - 'USER': 'cltbld', 12:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:01 INFO - 'XPC_FLAGS': '0x0', 12:10:01 INFO - 'XPC_SERVICE_NAME': '0', 12:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:10:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:10:02 INFO - Cleaning up... 12:10:02 INFO - Return code: 0 12:10:02 INFO - Running post-action listener: _resource_record_post_action 12:10:02 INFO - Running post-action listener: _start_resource_monitoring 12:10:02 INFO - Starting resource monitoring. 12:10:02 INFO - [mozharness: 2017-05-31 19:10:02.219212Z] Finished create-virtualenv step (success) 12:10:02 INFO - [mozharness: 2017-05-31 19:10:02.220222Z] Running install step. 12:10:02 INFO - Running pre-action listener: _resource_record_pre_action 12:10:02 INFO - Running main action method: install 12:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:10:02 INFO - Reading from file tmpfile_stdout 12:10:02 INFO - Detecting whether we're running mozinstall >=1.0... 12:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:10:02 INFO - Reading from file tmpfile_stdout 12:10:02 INFO - Output received: 12:10:02 INFO - Usage: mozinstall [options] installer 12:10:02 INFO - Options: 12:10:02 INFO - -h, --help show this help message and exit 12:10:02 INFO - -d DEST, --destination=DEST 12:10:02 INFO - Directory to install application into. [default: 12:10:02 INFO - "/builds/slave/test"] 12:10:02 INFO - --app=APP Application being installed. [default: firefox] 12:10:02 INFO - mkdir: /builds/slave/test/build/application 12:10:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:10:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:10:23 INFO - Reading from file tmpfile_stdout 12:10:23 INFO - Output received: 12:10:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:10:23 INFO - Running post-action listener: _resource_record_post_action 12:10:23 INFO - [mozharness: 2017-05-31 19:10:23.970200Z] Finished install step (success) 12:10:23 INFO - [mozharness: 2017-05-31 19:10:23.970341Z] Running setup-mitmproxy step. 12:10:23 INFO - Running pre-action listener: _resource_record_pre_action 12:10:23 INFO - Running main action method: setup_mitmproxy 12:10:23 INFO - Skipping: mitmproxy is not required 12:10:23 INFO - Running post-action listener: _resource_record_post_action 12:10:23 INFO - [mozharness: 2017-05-31 19:10:23.970924Z] Finished setup-mitmproxy step (success) 12:10:23 INFO - [mozharness: 2017-05-31 19:10:23.971064Z] Running run-tests step. 12:10:23 INFO - Running pre-action listener: _resource_record_pre_action 12:10:23 INFO - Running main action method: run_tests 12:10:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:10:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:10:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:10:23 INFO - 2017-05-31 12:10:23.985 screenresolution[2023:20498] starting screenresolution argv=screenresolution get 12:10:23 INFO - 2017-05-31 12:10:23.988 screenresolution[2023:20498] Display 0: 1600x1200x32@60 12:10:23 INFO - 2017-05-31 12:10:23.994 screenresolution[2024:20500] starting screenresolution argv=screenresolution list 12:10:23 INFO - Available Modes on Display 0 12:10:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:10:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:10:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:10:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:10:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:10:24 INFO - Intel Iris: 12:10:24 INFO - Chipset Model: Intel Iris 12:10:24 INFO - Type: GPU 12:10:24 INFO - Bus: Built-In 12:10:24 INFO - VRAM (Dynamic, Max): 1536 MB 12:10:24 INFO - Vendor: Intel (0x8086) 12:10:24 INFO - Device ID: 0x0a2e 12:10:24 INFO - Revision ID: 0x0009 12:10:24 INFO - Displays: 12:10:24 INFO - Display: 12:10:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:10:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:10:24 INFO - Display Serial Number: 1600x1200 60 12:10:24 INFO - Main Display: Yes 12:10:24 INFO - Mirror: Off 12:10:24 INFO - Online: Yes 12:10:24 INFO - Rotation: Supported 12:10:24 INFO - Return code: 0 12:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:10:24 INFO - Python 2.7.3 12:10:24 INFO - Return code: 0 12:10:24 INFO - grabbing minidump binary from tooltool 12:10: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')]} 12:10:24 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 0x107a8bc60>, '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 0x107820cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107849be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:10:24 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 12:10:24 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 12:10:24 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 12:10:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:10:24 INFO - Return code: 0 12:10:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:10:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:10:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:10:24 INFO - ENV: RUST_BACKTRACE is now 1 12:10:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:10:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:10:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 12:10:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0362 --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 12:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UCD0O1kXHl/Render', 12:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:10:24 INFO - 'HOME': '/Users/cltbld', 12:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:10:24 INFO - 'LOGNAME': 'cltbld', 12:10:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:10:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:10:24 INFO - 'MOZ_NO_REMOTE': '1', 12:10:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:10:24 INFO - 'NO_EM_RESTART': '1', 12:10:24 INFO - 'PAGER': '/bin/cat', 12:10:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:10:24 INFO - 'PWD': '/builds/slave/test', 12:10:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:10:24 INFO - 'RUST_BACKTRACE': '1', 12:10:24 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 12:10:24 INFO - 'SHELL': '/bin/bash', 12:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JnfFXfRIUx/Listeners', 12:10:24 INFO - 'TMPDIR': '/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/', 12:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:10:24 INFO - 'USER': 'cltbld', 12:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:10:24 INFO - 'XPC_FLAGS': '0x0', 12:10:24 INFO - 'XPC_SERVICE_NAME': '0', 12:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:10:24 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0362', '--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 12:10:24 INFO - mozversion application_buildid: 20170531112345 12:10:24 INFO - mozversion application_changeset: 2645d067b1e3f4064f18a7d1241ec96b33402d07 12:10:24 INFO - mozversion application_display_name: Nightly 12:10:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:10:24 INFO - mozversion application_name: Firefox 12:10:24 INFO - mozversion application_remotingname: firefox 12:10:24 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:10:24 INFO - mozversion application_vendor: Mozilla 12:10:24 INFO - mozversion application_version: 55.0a1 12:10:24 INFO - mozversion platform_buildid: 20170531112345 12:10:24 INFO - mozversion platform_changeset: 2645d067b1e3f4064f18a7d1241ec96b33402d07 12:10:24 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:10:24 INFO - mozversion platform_version: 55.0a1 12:10:24 INFO - using testdate: 1496257824 12:10:24 INFO - actual date: 1496257824 12:10:24 INFO - starting webserver on 'localhost:49277' 12:10:24 INFO - SUITE-START | Running 2 tests 12:10:24 INFO - TEST-START | dromaeo_css 12:10:24 INFO - operating with platform_type : mac_ 12:10:24 INFO - Initialising browser for dromaeo_css test... 12:10:24 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpeZKhfe/profile 12:10:24 INFO - TEST-INFO | started process 2034 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground) 12:10:58 INFO - TEST-INFO | 2034: killed by SIGTERM 12:10:58 INFO - Browser initialized. 12:10:58 INFO - Running cycle 1/1 for dromaeo_css test... 12:10:58 INFO - TEST-INFO | started process 2041 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmpeZKhfe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:11:00 INFO - PID 2041 | 2017-05-31 12:11:00.301 plugin-container[2042:20997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 12:11:00 INFO - PID 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:11:00 INFO - PID 2041 | 2017-05-31 12:11:00.301 plugin-container[2042:20997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 12:11:00 INFO - PID 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:11:00 INFO - PID 2041 | 2017-05-31 12:11:00.301 plugin-container[2042:20997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:11:01 INFO - PID 2041 | 2017-05-31 12:11:01.030 plugin-container[2043:21045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x464f, name = 'com.apple.tsm.portname' 12:11:01 INFO - PID 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:11:01 INFO - PID 2041 | 2017-05-31 12:11:01.030 plugin-container[2043:21045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 12:11:01 INFO - PID 2041 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:11:01 INFO - PID 2041 | 2017-05-31 12:11:01.030 plugin-container[2043:21045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:11:10 INFO - PID 2041 | RSS: Main: 260968448 12:11:10 INFO - PID 2041 | 12:14:12 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 12:14:14 INFO - PID 2041 | RSS: Main: 270946304 12:14:14 INFO - PID 2041 | 12:17:15 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 12:17:16 INFO - PID 2041 | RSS: Main: 283492352 12:17:16 INFO - PID 2041 | 12:20:18 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 12:20:18 INFO - PID 2041 | RSS: Main: 283914240 12:20:18 INFO - PID 2041 | 12:23:20 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 12:23:21 INFO - PID 2041 | RSS: Main: 287588352 12:23:21 INFO - PID 2041 | 12:26:24 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 12:26:24 INFO - PID 2041 | RSS: Main: 287760384 12:26:24 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 12:29:26 INFO - PID 2041 | RSS: Main: 289222656 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | __start_tp_report 12:29:26 INFO - PID 2041 | _x_x_mozilla_page_load 12:29:26 INFO - PID 2041 | _x_x_mozilla_page_load_details 12:29:26 INFO - PID 2041 | |i|pagename|runs| 12:29:26 INFO - PID 2041 | |0;dojo.html;3509.701675357594;3524.7356448266387;3531.50558921751;3561.2343346180564;3575.4279315309554;6117.66352850593;6135.171751813504;6236.532260080495;6246.156768836206;6255.530835187368;6136.549713441306;6174.722137503812;6190.442860142586;6214.03682429223;6268.498520118391;4377.715448495837;5328.307320048408;5342.91985620216;5391.191321301816;5400.216968539549;4944.035912996967;5157.555884352388;5435.918461223085;5488.368837583689;5528.889422211553;5286.841394758163;5427.62006659534;5436.483534064297;5477.726113694319;5501.4223506531835;5402.108652072443;5407.215953686733;5443.319585051869;5474.91787623186;5476.479734425223;5388.91916621251;5403.621746477748;5406.432324605899;5438.184272359138;5452.073147564924;6159.907601385938;6249.750009999594;6260.280067792217;6273.7176827042895;6302.212223472066;5628.155776633497;6397.808065758035;6476.222853257592;6652.135222421102;6696.2299335576545;6765;6774.762883299061;6782.355676210801;6784.287649796799;6800.6939687713675;6339.587926784744;6341.460975817061;6347.5556711029785;6359.491240700733;6374.362563743589;6635.635040072746;6725.764598239038;6778.966105169443;6893.896591551131;6895.241523432418;6344.365563443619;6433.678316084177;6463.15978922737;6500.739970401226;6530.379613936669;5664.150377443334;6690.899636505456;6706.463482921355;6751.864962700718;6753.3246675332075;6528.780968815481;6638.070670106189;6639.834004149935;6648.53460257787;6653.401193892573;6470.9029364559565;6527.053577231275;6651.16860392451;6656.4009239167735;6662.200535935719;4437.556244375537;4765.380500534978;5293.073712100367;5342.145256758978;5386.784528618889;4747.691400058985;4764.14245435818;4782.258749893772;4807.18277892759;4844.75776211188;6279.340669229757;6292.4966002719675;6348.301686814447;6352.904706429407;6364.586301890362;6567.2447668518735;6654.467642588678;6712;6746.257911629717;6759.864802703918;6327.082573026885;6329.303776584572;6344.841378965562;6368.458681012074;6371.904421433682;5706.229658996065;5844.590878638454;5883.382244864313;5888.5289176865745;5909.881802364014;6708.463322934153;6812.046313516112;6877.759278425231;6951.270116637883;6998.650067496503;6761.22245941723;6779.8983015253825;6784.253732089565;6807.965960170168;6821.2155602106395;6844.110265665631;6920.169579650475;6943.132108486439;6963.1296087989;6982.79051628452;6451.41937225652;6494.318096599789;6515.869682606227;6520.739170433129;6607.702653380706;6794.500832071389;6808.38724514796;6822.351876571718;6826.146731658543;6832.111825462658;6333.96833015832;6490.123833282447;6547.836304092245;6555.770548030796;6575.63833989116;6381.074744162163;6429.678516074271;6508.186476690414;6530.640814755234;6537.607743535403;6325.525585581007;6403.039544068427;6415.166028416276;6417.96791016042;6419.935800642121;6518.837029074311;6531.706073226621;6532.902006470002;6552.180977377828;6563.0811686364905;6236.376362363727;6340.587861788969;6342.048692696132;6365.872682546231;6377.107204991212 12:29:26 INFO - PID 2041 | |1;ext.html;766.1610536462574;799.9000124984377;855.3841234311295;1253.6991122130692;1331.5539294336397;40124.79937600312;40676.389854152214;40679.186416271696;40734.38898416526;40769.59230407695;32393.352132957327;35566.93299201022;39056.63301784438;40449;40861.38707919384;7910.327622152109;8077.474964127313;8340.874886876702;8588.699395521158;8602.87095693565;8520.531370774605;8597;8605.139486051406;8608.268297194745;8620.655173793071;8571.100034496381;8631.482111073356;8636.438631488965;8642.870356944652;8647.610857511427;8547.74356769298;8603.56982150893;8612.052674205832;8613.181747733955;8617.310615150836;8362.665493380318;8369.790755231106;8408;8431.873521897176;8449.831003379959;38082.61917380818;38294.42558361627;38308.55223029913;38385;38480.422793658116;3586.5158203642695;3611.0972256935765;3629.310431018098;3635.073056370635;3635.94546081809;30996.690033099607;31059.84470077635;31274.687253127406;31364.37271254585;31425.37149257002;3082.3372974810522;3125.484295091307;3133.122725636825;3145.716885480295;3145.968540314613;251777;255543.4445655557;286362.9999999979;309252;310088;37713.43429848555;48329.75835120802;48371.5162848367;48455.515444845805;48491;50123.74938125286;50878.74560627173;51875.48124518707;51999.48000520022;52045.2193217102;14503.637409064857;14631.780523292158;14756.188409637572;14774;14779.113253204627;11726.120540959462;11824.349660768741;11827.822582661267;11838;11853.88146118545;14165.362558684885;14200.573982780534;14222.715545689029;14264.930130240273;14318.284085795876;6288.339724328879;6288.748450062038;6334.429901308904;6339.968300158562;6365.777197798055;996.153269720746;1032.4734385463319;1032.9018743219383;1060.3955745225148;1064.499685147979;17259.482215533553;19778.50553736168;24374.025038998243;24521.264362069167;24569.262922112364;954.3891909178132;1121.747606788466;1125.2179735084037;1156.9074474042056;1161.7618388230392;11515.25150865191;11761.705957351134;12258.693532661577;12477.00183985279;12580.81128783069;340180;341453;342942;343849;345035;55396;55563;55742;55974.88050239054;56002.71998639981;1509.8414666460083;1530.99234503829;1561.695469383482;1565.7886185365057;1569.0272031340414;23855.880720596288;23861.880690596437;23935.76064239405;24047.398815029763;24121.879390602935;38685;38876;38899.80550097232;38911.41632875452;38996.415053774785;238767;238914;240830;245318;246018.76990614933;31412.37175256528;33964.83017584896;35012.29975400529;35017.64982350144;35147.82426087853;42495.78752106219;43761.68714938557;43959;44003.559964401225;44032;28607.570886437137;29779.255518612208;30570.84714576502;30722.385552288382;30752;25739.871300643375;26165.607515886895;29844.403111937205;30669.53995690022;30800.229994250323;36046.819765901004;36142.73500427473;36159.27681446304;36173.638263617024;36214;27987.300317492227;29033;29628.407431851672;29932.401351972403;30733.846330769098;18137.637247254715;23779.881100594386;23873.761262387154;24224.51550969006;24248.272551823473 12:29:26 INFO - PID 2041 | |2;jquery.html;380.6897378636409;427.0058825285914;431.86828017454684;442.1863770661983;450.25033319523;12452.377381130953;15077;21331.466713332175;22139.335819925407;22208;20800.47998800031;23202.535949281006;23218.767812321872;23296.06815727369;23453.413664658394;6022.698865056752;6205.0692396140485;6223.813285601439;6234.968825155868;6241.906371404432;5328.307320048389;6086.726097325631;6127.509799216052;6173.135760993465;6208.161898143738;5689.2319536862615;5926.970365148169;5983.970080149615;6128.050152226412;6158.384161583851;5901.498372638331;5904.439078287556;5945.346011938705;5964.254468191476;5969.611975221596;5285.969235998982;5306.575473962073;5320.547753440924;5322.946770532284;5323.494268044529;27850.721492785015;27898.860505697543;28267.85866070677;28271;28309.57535636967;2067.1627990663806;2146.9355919322447;2170.7829217078324;2172.56548690261;2175.9782402176093;18191;18249;18495;18511.629767404713;18605;2110.799474049963;2142.646463333548;2167.9241226556996;2171.5873983943;2194.5391467791783;178498;186392;200680;202718;204673.9766301159;10312;10353.327033742782;12608.550016748008;12897.41961611711;12963.611091667077;13902.443902243796;15853.286602102933;16284.674306513803;16323.673526529403;16421.58946026359;7123.465740069516;7422;8891.688790892287;9014.053524379977;9115.270778337717;7242.23956484572;8710;8735.475871447734;8782.253508451853;8850;7658.693652253847;7960.40296977729;8029.397795165386;8071.677132914735;8099.716509922125;2279.259240746731;2280.201929324723;2286.8627882327114;2303.5623231585946;2454;889.4485419040236;928.8235235305406;941.4727752458645;965.9130678238993;973.563993110171;19870.70193947092;20322.08550615226;20381.694274585894;20464.897675511824;20466.590668186553;995.7801692926107;1017.6845177994845;1019.6125472320471;1033.1476531861244;1041.1410586266209;10129.139023183112;10332.535035923402;10604.416757078281;12551.93724031374;13700.588982330546;550775;565405;567789;568044.4793328021;569367;153252;154602.6809597857;155826;156426;156600.43399566086;1180.7638472305573;1216.0636310041218;1216.0940099625836;1228.6129869091208;1230.5816022552197;16811.91594042022;17756.28974840992;17795.46613601594;21740.021699023266;22054.77945220527;59973.30120144517;68050;71814.28185718076;73076;73273.63363183363;78564.60717696375;87654;88143;88464;88502.11497885197;9920.652777152765;10169.644062457779;10194.031567001122;10194.643187488402;10204.13264872494;10663.89336106629;13619.523316683868;16162.757558636395;17632.294708211528;17678.381256655954;13962.511312104027;14323.355449005028;14458.132512049311;14492.492762753252;14495.275236237934;12334.321612311409;12598.118131731058;12706.047046471736;12793.168444051107;12985.870141298845;14240.430382784572;14556.199409032604;14615.269236537917;14684.265786710408;14722.337494812546;9721.368111072758;9807.852882206915;9835.754106147117;9861.704148875546;9876.950615246878;13997.020208585302;14150.78773818414;14157.929210353883;14197.716045679235;14272.429102835771 12:29:26 INFO - PID 2041 | |3;mootools.html;1284.8693150027977;1329.800529920512;1383.8583168885673;1413.2651021468844;1422.352829462594;11591.956723894858;11716.355600441972;12023.639290821278;12044.337561434118;12376.50493980242;12061.035117190626;12244.938775306133;12331.876681233185;12341.197822141554;12342.13605047647;2516.2702816183332;2573.858437785921;2573.8841752121107;2574.5751950928075;2578.729233430491;2447.302518782147;2508.9372765680823;2541.0471073347494;2545.592705167174;2546.541622507948;2456.115798312606;2488.7884529814987;2495.513374891897;2496.8127390445698;2504.536660717764;2293.6352870042297;2386.642003699442;2431.914882979096;2453.129139155601;2470.110760126362;2100.789921007902;2440.7925326347104;2489.166129346671;2493.4888347888823;2504.7370026147173;10135.493225338703;11124.443777811077;11517.424128793611;11626;11636.127290453165;6339.619622822599;6516.022596610499;6569.375909288658;6588.439982601484;6591.571547849375;3995.720299579031;4152.294110001307;4217.177650358181;4231.026863821338;4241.172971270604;990.0149351395336;1158.739283661178;1216.6046035038648;1228.2753175626333;1244.1788419643083;15158.772618410732;15589.610259743597;16110.677786444323;16196.75704864428;16297.999999999882;4831.323614693945;5510.421405752419;5563.554915606742;5692.629979051431;5728.112142617818;9942.254330925065;10173.54219060144;12058.638240852788;12205.877941220651;12277.508899643914;6351.841203969938;6371.426571608577;6436.8390790230615;6443;6449.355064493607;5878.177055212273;6000.909986350208;6012.188354572077;6022.247219097613;6047.12316714074;4386.8025938832825;4426.845060422934;4472.552744725566;4499.122671079108;4509.413776209138;1260.7226410189744;1296.7471343088007;1311.5344052861287;1312.6390242683337;1326.8009798530297;739.2939742545856;773.3774311679152;794.5113755040609;799.2646764976236;803.0604193094094;6811.182658081062;7079.929200707927;7087.342246657503;7159.498835081598;7178.38983686382;756.5158298688843;906.2025417632441;928.6285485805597;934.5654270763991;941.3504681769557;3211.6948889855894;3469.37551240774;3960.2673505401594;4581.496035436099;4673.088747694167;14526.983111182115;14609.78085328721;14941.477048303257;15055.171965542;15072.171030593423;14606.707865842836;14888.330025148894;14963.775543366859;15015.474458393905;15063.54809355721;8087.3125784308995;8398.992120945404;8458.111898250596;8503.681929300968;8504.149585041447;6466.644334561644;6842.863142737017;6981.720731170696;7143.321384468467;7207.495475316678;12380.62858114258;12429;12549.99600031977;12761.234325940473;12778;12847.421866015864;13079.215247085205;13117.803232951323;13134.671633209246;13187;8700.477971321741;8750.168733970262;8842.93884733836;8873.068327825486;8885.111488851322;8737.519436431057;8745.562721863755;10760.569577217137;10762.784744305227;10777.838332424863;7460.962695186707;8818.5590720465;8999.280057595508;9004.549772511216;9013.774655633662;8536.810627580278;8593.82812343762;8688.695895643623;8694.434861733967;8764.079771624143;8168.346532277523;8320.459170153921;8371.16288371158;8382.41323107401;8387.706430275155;6022.337542870368;6039;6193.84515387119;6199.938000620116;6255.562110652393;6110.297315808739;6171.382861713792;6286.402791734778;6378.936210637834;6393.073004414427 12:29:26 INFO - PID 2041 | |4;prototype.html;320.74981514418755;391.2995737629643;403.65487508180485;403.7920470957457;408.1551189038691;6110.786122485714;6114.296855861578;6160.353162917896;6262.403087212754;6547.181602299713;5062.873428164288;5233.4243233244315;5460.5085542301185;6215.689215539228;6346.30190679025;4453.621442177411;4482.910341793162;4545.704529205591;4643.16423043852;4673.009274064601;3822.57951625321;4641.984683248999;4670.93628543168;4677.74272415017;4695.201815691324;4545.977270113644;4594.264917613183;4598.724076555401;4612.331211974279;4626.745528995921;4608.8386906458445;4621.2837010263465;4629.421322334708;4637.863723387758;4640.5127461616385;3999.7000224982953;4022.1955608878047;4028.4762980812307;4029.0531725044593;4030.1133750574827;4777.95222047782;6277.560570760049;6277.829696003535;6284;6319.3680631936895;4848.430331924086;5570.86072848178;5590.524805391506;5596.916046259309;5604.346717718283;5743.827685169452;5961.731722072517;6211.9068213976825;6852.862942741167;6870.416014638762;4567.406237189144;4584.954150458486;4594.379758732562;4597.195490789099;4610.285405762079;6497.3827486388245;6903;7259;7278.781636550912;7296.6716497757725;3706.1647839402876;4350.651947844165;4599.08018396322;4622.399088118523;4651.023315091073;4731.776065249396;4733.668643195012;4748.572628463455;4750.764801151726;4763.928116173903;5508.173773933942;5515.889682206333;5550.58370622205;5559.082751346064;5560.833175004756;5951.315598706117;6830.795076147723;6840.0765896603325;6865.176178858569;6882.139732533434;1605.8795590330772;1607.4615003973565;1609.6056466165865;1618.7490938904489;1621.4353149211067;998.2762497189625;1066.3228849680509;1067.354250678344;1076.8815430302661;1081.5889961814617;5763.971180144156;6414.358564143548;6537.248216455168;6569.835754106186;6585.1439312889015;7102.360787529147;7108.649356622329;7130.180029296594;7143.107111611049;7196.568205907663;5302.939412117592;6486.643234622141;6486.772962946275;6488.416042556192;6511.413972742476;3493.615702272748;3629.8366573504254;3641.471986561987;3690.3946783149418;3731.6193008586997;6328;6605.735770569124;6764.985252212207;7308.159561650478;7310.195878453366;6679.532432729759;6686.5653732507235;7249.528780629243;7302.890456643155;7318.194998550155;6084.817455476165;6548.70530826114;6697.430718388991;7295.270472952662;7350.558966461923;7090.822729431599;7102.431805455654;7107.289271072851;7109.893351599627;7119.466040047121;5783.450572195635;6933.1333583302085;6938.167419909643;6979.860402792017;7012.211885969045;6643.867122657423;7278.053852999076;7324.560526368222;7341.302576255247;7349.669264882986;3740.8690695825517;4517.706349087299;4622.976885115553;4623.329617205419;4651.837185698484;6812.965935170292;6995.370416662321;7006.404455621329;7132.429405647639;7202.423806095395;5802.854928626818;6829.965850170717;6872.793816185523;6881.522651845232;6910.274421185703;5765.4811067004175;5800.622959507618;6863.588184708933;6914.585124892523;6962.164540255202;5449.291592093003;6740.8651826962205;6746.460283177236;6756.729730810713;6763.357481039294;6336.7148478319505;6921.062102611277;6929.930700692928;6933.591798397259;6938.479614029069;5730.742116604846;5921.864153694702;6700.5309628327495;6729.5625784323865;6736.551641397123 12:29:26 INFO - PID 2041 | |5;yui.html;737.7823542055095;814.8085199978004;818.3616778912443;853.9573021348934;861.7418568889422;14582.927085364585;15198.468053618126;16736.748948765762;17294.13529323535;17396.56508587286;15156.696866062672;16290.185490725446;18177.36379226724;18282.360117395892;18293.451196464095;964.1997142371849;999.7850462150636;1008.6721815409984;1014.3862962907446;1018.8573599696049;1033.633060263607;1040.5473618975732;1044.7231483656838;1046.539522610053;1048.6591857646258;965.6330594374128;1079.4656644960755;1080.7622323088908;1082.5994382078643;1083.1876092930302;1066.3761699405818;1067.8932106789337;1069.7379142110155;1073.2755390111643;1083.6261489786011;775.5889378629336;776.2354081229984;777.3936329662872;781.2773184804033;787.0594639405912;16756.581085472837;17578.38475653359;17756.822431775647;17791.555211119696;17902.552436189068;17264.56838579033;17509;17653.38213162533;17892.821071789247;17918.372856949944;14682.045667031609;14993.100413975197;15000.099994000395;15051.473198438112;15064.548063558112;3517.050396392985;3547.0954906498678;3577.5349204603494;3578.0160455874584;3634.727395445326;126582;138397.3080134593;142875.55062778993;145033;145801;13538.45846166162;13594.93202533981;13679.521216757366;13850.030497865053;13868.653283667989;25136.12023579166;25360.492790144464;25805.225843224733;25877.741222587534;25892.093776718102;6705.899411508832;6929.029935808991;7588.392928565701;7759.185285544936;7957.681692732226;3892.5718171001167;3977.164795392942;3982.900427489336;3985.262726395626;3988.0029992501873;4227.788610569459;4245.61789438952;4315.287977483743;4315.697901146889;4330.805113769888;930.5533343994921;947.4978261521404;952.1240458777941;952.2524818017823;962.1965658674944;572.3703925681717;621.4438077920236;648.1541588227409;670.2560158224367;684.8118514377551;11452.370119643332;12181.39093045362;12357.135000549875;12447.626571202878;12466.87533124657;632.686820024091;636.6975686548944;639.4788247578209;651.4234902111571;669.3072669786748;5173.4826517347965;5640.407757185519;5760.6255593386295;5766.826995190151;5857.970710146422;140856;147676;147933.52066479198;150685;153257;115748;124230.13654795186;124808;125382.3730881358;127801.3609931963;95236.52381738185;96019;97688.51155744318;101409.49295253328;103113.48443257736;9823;11430.257033293143;11837.763244734884;12263.08026898004;12420.565280215149;33370;37331.440028399054;44912.8771780695;45066.87332816706;45527.40654077091;62534;70425.64787176032;85098.87252818729;85407.14592853992;86127;15108.924455377653;16882.493525194262;17007.639388848616;17415.39046133379;17759.378421755177;14262.57212283633;16173.67652646917;18157.09214539294;18986.810131899056;19108.426747197605;17434.041127738095;17753.644927101126;18144.364947226783;18279.26882924668;18291.54271143232;17718.11409429549;17841.73237401466;17942.282308707505;18114.456566303033;18294.359697410528;13483.662908426975;15341.386344546094;16155.515334539983;16471.176441178133;17987.820121798613;11126.49930753102;11731.88268117342;12067.336296503425;12287.894089531985;12733.745325093261;12325.260484370967;13273.469061237442;13395.464181432633;13418.686084914782;13452.596422107352 12:29:26 INFO - PID 2041 | __end_tp_report 12:29:26 INFO - PID 2041 | __start_cc_report 12:29:26 INFO - PID 2041 | _x_x_mozilla_cycle_collect,452 12:29:26 INFO - PID 2041 | __end_cc_report 12:29:26 INFO - PID 2041 | __startTimestamp1496258966953__endTimestamp 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | ------- Summary: start ------- 12:29:26 INFO - PID 2041 | Number of tests: 6 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#0] dojo.html Cycles:165 Average:6140.56 Median:6371.90 stddev:750.44 (11.8%) stddev-sans-first:723.80 12:29:26 INFO - PID 2041 | Values: 3509.7 3524.7 3531.5 3561.2 3575.4 6117.7 6135.2 6236.5 6246.2 6255.5 6136.5 6174.7 6190.4 6214.0 6268.5 4377.7 5328.3 5342.9 5391.2 5400.2 4944.0 5157.6 5435.9 5488.4 5528.9 5286.8 5427.6 5436.5 5477.7 5501.4 5402.1 5407.2 5443.3 5474.9 5476.5 5388.9 5403.6 5406.4 5438.2 5452.1 6159.9 6249.8 6260.3 6273.7 6302.2 5628.2 6397.8 6476.2 6652.1 6696.2 6765.0 6774.8 6782.4 6784.3 6800.7 6339.6 6341.5 6347.6 6359.5 6374.4 6635.6 6725.8 6779.0 6893.9 6895.2 6344.4 6433.7 6463.2 6500.7 6530.4 5664.2 6690.9 6706.5 6751.9 6753.3 6528.8 6638.1 6639.8 6648.5 6653.4 6470.9 6527.1 6651.2 6656.4 6662.2 4437.6 4765.4 5293.1 5342.1 5386.8 4747.7 4764.1 4782.3 4807.2 4844.8 6279.3 6292.5 6348.3 6352.9 6364.6 6567.2 6654.5 6712.0 6746.3 6759.9 6327.1 6329.3 6344.8 6368.5 6371.9 5706.2 5844.6 5883.4 5888.5 5909.9 6708.5 6812.0 6877.8 6951.3 6998.7 6761.2 6779.9 6784.3 6808.0 6821.2 6844.1 6920.2 6943.1 6963.1 6982.8 6451.4 6494.3 6515.9 6520.7 6607.7 6794.5 6808.4 6822.4 6826.1 6832.1 6334.0 6490.1 6547.8 6555.8 6575.6 6381.1 6429.7 6508.2 6530.6 6537.6 6325.5 6403.0 6415.2 6418.0 6419.9 6518.8 6531.7 6532.9 6552.2 6563.1 6236.4 6340.6 6342.0 6365.9 6377.1 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#1] ext.html Cycles:180 Average:44208.00 Median:343395.50 stddev:76733.64 (22.3%) stddev-sans-first:76879.58 12:29:26 INFO - PID 2041 | Values: 766.2 799.9 855.4 1253.7 1331.6 40124.8 40676.4 40679.2 40734.4 40769.6 32393.4 35566.9 39056.6 40449.0 40861.4 7910.3 8077.5 8340.9 8588.7 8602.9 8520.5 8597.0 8605.1 8608.3 8620.7 8571.1 8631.5 8636.4 8642.9 8647.6 8547.7 8603.6 8612.1 8613.2 8617.3 8362.7 8369.8 8408.0 8431.9 8449.8 38082.6 38294.4 38308.6 38385.0 38480.4 3586.5 3611.1 3629.3 3635.1 3635.9 30996.7 31059.8 31274.7 31364.4 31425.4 3082.3 3125.5 3133.1 3145.7 3146.0 251777.0 255543.4 286363.0 309252.0 310088.0 37713.4 48329.8 48371.5 48455.5 48491.0 50123.7 50878.7 51875.5 51999.5 52045.2 14503.6 14631.8 14756.2 14774.0 14779.1 11726.1 11824.3 11827.8 11838.0 11853.9 14165.4 14200.6 14222.7 14264.9 14318.3 6288.3 6288.7 6334.4 6340.0 6365.8 996.2 1032.5 1032.9 1060.4 1064.5 17259.5 19778.5 24374.0 24521.3 24569.3 954.4 1121.7 1125.2 1156.9 1161.8 11515.3 11761.7 12258.7 12477.0 12580.8 340180.0 341453.0 342942.0 343849.0 345035.0 55396.0 55563.0 55742.0 55974.9 56002.7 1509.8 1531.0 1561.7 1565.8 1569.0 23855.9 23861.9 23935.8 24047.4 24121.9 38685.0 38876.0 38899.8 38911.4 38996.4 238767.0 238914.0 240830.0 245318.0 246018.8 31412.4 33964.8 35012.3 35017.6 35147.8 42495.8 43761.7 43959.0 44003.6 44032.0 28607.6 29779.3 30570.8 30722.4 30752.0 25739.9 26165.6 29844.4 30669.5 30800.2 36046.8 36142.7 36159.3 36173.6 36214.0 27987.3 29033.0 29628.4 29932.4 30733.8 18137.6 23779.9 23873.8 24224.5 24248.3 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#2] jquery.html Cycles:180 Average:38652.28 Median:12124.66 stddev:98023.59 (808.5%) stddev-sans-first:98256.46 12:29:26 INFO - PID 2041 | Values: 380.7 427.0 431.9 442.2 450.3 12452.4 15077.0 21331.5 22139.3 22208.0 20800.5 23202.5 23218.8 23296.1 23453.4 6022.7 6205.1 6223.8 6235.0 6241.9 5328.3 6086.7 6127.5 6173.1 6208.2 5689.2 5927.0 5984.0 6128.1 6158.4 5901.5 5904.4 5945.3 5964.3 5969.6 5286.0 5306.6 5320.5 5322.9 5323.5 27850.7 27898.9 28267.9 28271.0 28309.6 2067.2 2146.9 2170.8 2172.6 2176.0 18191.0 18249.0 18495.0 18511.6 18605.0 2110.8 2142.6 2167.9 2171.6 2194.5 178498.0 186392.0 200680.0 202718.0 204674.0 10312.0 10353.3 12608.6 12897.4 12963.6 13902.4 15853.3 16284.7 16323.7 16421.6 7123.5 7422.0 8891.7 9014.1 9115.3 7242.2 8710.0 8735.5 8782.3 8850.0 7658.7 7960.4 8029.4 8071.7 8099.7 2279.3 2280.2 2286.9 2303.6 2454.0 889.4 928.8 941.5 965.9 973.6 19870.7 20322.1 20381.7 20464.9 20466.6 995.8 1017.7 1019.6 1033.1 1041.1 10129.1 10332.5 10604.4 12551.9 13700.6 550775.0 565405.0 567789.0 568044.5 569367.0 153252.0 154602.7 155826.0 156426.0 156600.4 1180.8 1216.1 1216.1 1228.6 1230.6 16811.9 17756.3 17795.5 21740.0 22054.8 59973.3 68050.0 71814.3 73076.0 73273.6 78564.6 87654.0 88143.0 88464.0 88502.1 9920.7 10169.6 10194.0 10194.6 10204.1 10663.9 13619.5 16162.8 17632.3 17678.4 13962.5 14323.4 14458.1 14492.5 14495.3 12334.3 12598.1 12706.0 12793.2 12985.9 14240.4 14556.2 14615.3 14684.3 14722.3 9721.4 9807.9 9835.8 9861.7 9877.0 13997.0 14150.8 14157.9 14197.7 14272.4 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#3] mootools.html Cycles:180 Average:7003.21 Median:4184.74 stddev:4389.49 (104.9%) stddev-sans-first:4380.77 12:29:26 INFO - PID 2041 | Values: 1284.9 1329.8 1383.9 1413.3 1422.4 11592.0 11716.4 12023.6 12044.3 12376.5 12061.0 12244.9 12331.9 12341.2 12342.1 2516.3 2573.9 2573.9 2574.6 2578.7 2447.3 2508.9 2541.0 2545.6 2546.5 2456.1 2488.8 2495.5 2496.8 2504.5 2293.6 2386.6 2431.9 2453.1 2470.1 2100.8 2440.8 2489.2 2493.5 2504.7 10135.5 11124.4 11517.4 11626.0 11636.1 6339.6 6516.0 6569.4 6588.4 6591.6 3995.7 4152.3 4217.2 4231.0 4241.2 990.0 1158.7 1216.6 1228.3 1244.2 15158.8 15589.6 16110.7 16196.8 16298.0 4831.3 5510.4 5563.6 5692.6 5728.1 9942.3 10173.5 12058.6 12205.9 12277.5 6351.8 6371.4 6436.8 6443.0 6449.4 5878.2 6000.9 6012.2 6022.2 6047.1 4386.8 4426.8 4472.6 4499.1 4509.4 1260.7 1296.7 1311.5 1312.6 1326.8 739.3 773.4 794.5 799.3 803.1 6811.2 7079.9 7087.3 7159.5 7178.4 756.5 906.2 928.6 934.6 941.4 3211.7 3469.4 3960.3 4581.5 4673.1 14527.0 14609.8 14941.5 15055.2 15072.2 14606.7 14888.3 14963.8 15015.5 15063.5 8087.3 8399.0 8458.1 8503.7 8504.1 6466.6 6842.9 6981.7 7143.3 7207.5 12380.6 12429.0 12550.0 12761.2 12778.0 12847.4 13079.2 13117.8 13134.7 13187.0 8700.5 8750.2 8842.9 8873.1 8885.1 8737.5 8745.6 10760.6 10762.8 10777.8 7461.0 8818.6 8999.3 9004.5 9013.8 8536.8 8593.8 8688.7 8694.4 8764.1 8168.3 8320.5 8371.2 8382.4 8387.7 6022.3 6039.0 6193.8 6199.9 6255.6 6110.3 6171.4 6286.4 6378.9 6393.1 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#4] prototype.html Cycles:180 Average:5482.96 Median:6097.80 stddev:1735.22 (28.5%) stddev-sans-first:1696.28 12:29:26 INFO - PID 2041 | Values: 320.7 391.3 403.7 403.8 408.2 6110.8 6114.3 6160.4 6262.4 6547.2 5062.9 5233.4 5460.5 6215.7 6346.3 4453.6 4482.9 4545.7 4643.2 4673.0 3822.6 4642.0 4670.9 4677.7 4695.2 4546.0 4594.3 4598.7 4612.3 4626.7 4608.8 4621.3 4629.4 4637.9 4640.5 3999.7 4022.2 4028.5 4029.1 4030.1 4778.0 6277.6 6277.8 6284.0 6319.4 4848.4 5570.9 5590.5 5596.9 5604.3 5743.8 5961.7 6211.9 6852.9 6870.4 4567.4 4585.0 4594.4 4597.2 4610.3 6497.4 6903.0 7259.0 7278.8 7296.7 3706.2 4350.7 4599.1 4622.4 4651.0 4731.8 4733.7 4748.6 4750.8 4763.9 5508.2 5515.9 5550.6 5559.1 5560.8 5951.3 6830.8 6840.1 6865.2 6882.1 1605.9 1607.5 1609.6 1618.7 1621.4 998.3 1066.3 1067.4 1076.9 1081.6 5764.0 6414.4 6537.2 6569.8 6585.1 7102.4 7108.6 7130.2 7143.1 7196.6 5302.9 6486.6 6486.8 6488.4 6511.4 3493.6 3629.8 3641.5 3690.4 3731.6 6328.0 6605.7 6765.0 7308.2 7310.2 6679.5 6686.6 7249.5 7302.9 7318.2 6084.8 6548.7 6697.4 7295.3 7350.6 7090.8 7102.4 7107.3 7109.9 7119.5 5783.5 6933.1 6938.2 6979.9 7012.2 6643.9 7278.1 7324.6 7341.3 7349.7 3740.9 4517.7 4623.0 4623.3 4651.8 6813.0 6995.4 7006.4 7132.4 7202.4 5802.9 6830.0 6872.8 6881.5 6910.3 5765.5 5800.6 6863.6 6914.6 6962.2 5449.3 6740.9 6746.5 6756.7 6763.4 6336.7 6921.1 6929.9 6933.6 6938.5 5730.7 5921.9 6700.5 6729.6 6736.6 12:29:26 INFO - PID 2041 | 12:29:26 INFO - PID 2041 | [#5] yui.html Cycles:180 Average:25555.85 Median:17816.64 stddev:39347.29 (220.8%) stddev-sans-first:39413.54 12:29:26 INFO - PID 2041 | Values: 737.8 814.8 818.4 854.0 861.7 14582.9 15198.5 16736.7 17294.1 17396.6 15156.7 16290.2 18177.4 18282.4 18293.5 964.2 999.8 1008.7 1014.4 1018.9 1033.6 1040.5 1044.7 1046.5 1048.7 965.6 1079.5 1080.8 1082.6 1083.2 1066.4 1067.9 1069.7 1073.3 1083.6 775.6 776.2 777.4 781.3 787.1 16756.6 17578.4 17756.8 17791.6 17902.6 17264.6 17509.0 17653.4 17892.8 17918.4 14682.0 14993.1 15000.1 15051.5 15064.5 3517.1 3547.1 3577.5 3578.0 3634.7 126582.0 138397.3 142875.6 145033.0 145801.0 13538.5 13594.9 13679.5 13850.0 13868.7 25136.1 25360.5 25805.2 25877.7 25892.1 6705.9 6929.0 7588.4 7759.2 7957.7 3892.6 3977.2 3982.9 3985.3 3988.0 4227.8 4245.6 4315.3 4315.7 4330.8 930.6 947.5 952.1 952.3 962.2 572.4 621.4 648.2 670.3 684.8 11452.4 12181.4 12357.1 12447.6 12466.9 632.7 636.7 639.5 651.4 669.3 5173.5 5640.4 5760.6 5766.8 5858.0 140856.0 147676.0 147933.5 150685.0 153257.0 115748.0 124230.1 124808.0 125382.4 127801.4 95236.5 96019.0 97688.5 101409.5 103113.5 9823.0 11430.3 11837.8 12263.1 12420.6 33370.0 37331.4 44912.9 45066.9 45527.4 62534.0 70425.6 85098.9 85407.1 86127.0 15108.9 16882.5 17007.6 17415.4 17759.4 14262.6 16173.7 18157.1 18986.8 19108.4 17434.0 17753.6 18144.4 18279.3 18291.5 17718.1 17841.7 17942.3 18114.5 18294.4 13483.7 15341.4 16155.5 16471.2 17987.8 11126.5 11731.9 12067.3 12287.9 12733.7 12325.3 13273.5 13395.5 13418.7 13452.6 12:29:26 INFO - PID 2041 | -------- Summary: end -------- 12:29:26 INFO - PID 2041 | 12:29:27 INFO - TEST-INFO | 2041: exit 0 12:29:27 INFO - TEST-OK | dromaeo_css | took 1142913ms 12:29:27 INFO - TEST-START | kraken 12:29:27 INFO - operating with platform_type : mac_ 12:29:27 INFO - Initialising browser for kraken test... 12:29:27 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmppFZZF0/profile 12:29:27 INFO - TEST-INFO | started process 2049 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49277/getInfo.html -foreground) 12:30:01 INFO - TEST-INFO | 2049: killed by SIGTERM 12:30:01 INFO - Browser initialized. 12:30:01 INFO - Running cycle 1/1 for kraken test... 12:30:01 INFO - TEST-INFO | started process 2054 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/tmppFZZF0/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:30:03 INFO - PID 2054 | 2017-05-31 12:30:03.593 plugin-container[2055:23479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x484b, name = 'com.apple.tsm.portname' 12:30:03 INFO - PID 2054 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:03 INFO - PID 2054 | 2017-05-31 12:30:03.594 plugin-container[2055:23479] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 12:30:03 INFO - PID 2054 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:03 INFO - PID 2054 | 2017-05-31 12:30:03.594 plugin-container[2055:23479] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:04 INFO - PID 2054 | 2017-05-31 12:30:04.011 plugin-container[2056:23524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 12:30:04 INFO - PID 2054 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:04 INFO - PID 2054 | 2017-05-31 12:30:04.012 plugin-container[2056:23524] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 12:30:04 INFO - PID 2054 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:30:04 INFO - PID 2054 | 2017-05-31 12:30:04.012 plugin-container[2056:23524] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:30:13 INFO - PID 2054 | RSS: Main: 268627968 12:30:13 INFO - PID 2054 | 12:30:16 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 12:30:17 INFO - PID 2054 | RSS: Main: 277049344 12:30:17 INFO - PID 2054 | 12:30:19 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 12:30:20 INFO - PID 2054 | RSS: Main: 284889088 12:30:20 INFO - PID 2054 | 12:30:23 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 12:30:24 INFO - PID 2054 | RSS: Main: 282918912 12:30:24 INFO - PID 2054 | 12:30:26 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 12:30:27 INFO - PID 2054 | RSS: Main: 283586560 12:30:27 INFO - PID 2054 | 12:30:29 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:30:29 INFO - PID 2054 | RSS: Main: 285405184 12:30:29 INFO - PID 2054 | 12:30:32 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 12:30:33 INFO - PID 2054 | RSS: Main: 289579008 12:30:33 INFO - PID 2054 | 12:30:36 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 12:30:36 INFO - PID 2054 | RSS: Main: 293068800 12:30:36 INFO - PID 2054 | 12:30:39 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 12:30:40 INFO - PID 2054 | RSS: Main: 291815424 12:30:40 INFO - PID 2054 | 12:30:42 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:30:42 INFO - PID 2054 | RSS: Main: 291446784 12:30:42 INFO - PID 2054 | 12:30:44 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:30:45 INFO - PID 2054 | RSS: Main: 291774464 12:30:45 INFO - PID 2054 | 12:30:47 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:30:48 INFO - PID 2054 | RSS: Main: 291213312 12:30:48 INFO - PID 2054 | 12:30:51 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:30:51 INFO - PID 2054 | RSS: Main: 291078144 12:30:51 INFO - PID 2054 | 12:30:55 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:30:55 INFO - PID 2054 | RSS: Main: 291106816 12:30:55 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 12:30:57 INFO - PID 2054 | RSS: Main: 292921344 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | __start_tp_report 12:30:57 INFO - PID 2054 | _x_x_mozilla_page_load 12:30:57 INFO - PID 2054 | _x_x_mozilla_page_load_details 12:30:57 INFO - PID 2054 | |i|pagename|runs| 12:30:57 INFO - PID 2054 | |0;ai-astar;112;166;160;115;112;96;152;166;111;106 12:30:57 INFO - PID 2054 | |1;audio-beat-detection;187;149;116;147;111;112;109;113;156;141 12:30:57 INFO - PID 2054 | |2;audio-dft;198;204;210;221;229;207;196;205;195;199 12:30:57 INFO - PID 2054 | |3;audio-fft;79;58;57;84;59;63;68;90;96;49 12:30:57 INFO - PID 2054 | |4;audio-oscillator;80;71;84;75;69;69;87;69;69;98 12:30:57 INFO - PID 2054 | |5;imaging-gaussian-blur;92;90;89;89;90;90;94;96;89;91 12:30:57 INFO - PID 2054 | |6;imaging-darkroom;97;97;96;96;97;96;96;97;96;96 12:30:57 INFO - PID 2054 | |7;imaging-desaturate;111;97;141;100;103;134;140;92;94;91 12:30:57 INFO - PID 2054 | |8;json-parse-financial;52;50;52;59;75;75;52;50;51;51 12:30:57 INFO - PID 2054 | |9;json-stringify-tinderbox;56;89;71;67;64;61;58;54;56;74 12:30:57 INFO - PID 2054 | |10;stanford-crypto-aes;87;88;91;79;96;119;112;106;144;91 12:30:57 INFO - PID 2054 | |11;stanford-crypto-ccm;136;138;138;131;177;160;151;146;142;153 12:30:57 INFO - PID 2054 | |12;stanford-crypto-pbkdf2;188;191;209;241;195;185;191;239;245;230 12:30:57 INFO - PID 2054 | |13;stanford-crypto-sha256-iterative;66;68;69;69;65;67;65;68;65;73 12:30:57 INFO - PID 2054 | __end_tp_report 12:30:57 INFO - PID 2054 | __start_cc_report 12:30:57 INFO - PID 2054 | _x_x_mozilla_cycle_collect,1186 12:30:57 INFO - PID 2054 | __end_cc_report 12:30:57 INFO - PID 2054 | __startTimestamp1496259057704__endTimestamp 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | ------- Summary: start ------- 12:30:57 INFO - PID 2054 | Number of tests: 14 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#0] ai-astar Cycles:10 Average:129.60 Median:156.00 stddev:27.77 (17.8%) stddev-sans-first:28.71 12:30:57 INFO - PID 2054 | Values: 112.0 166.0 160.0 115.0 112.0 96.0 152.0 166.0 111.0 106.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#1] audio-beat-detection Cycles:10 Average:134.10 Median:144.00 stddev:26.12 (18.1%) stddev-sans-first:19.46 12:30:57 INFO - PID 2054 | Values: 187.0 149.0 116.0 147.0 111.0 112.0 109.0 113.0 156.0 141.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#2] audio-dft Cycles:10 Average:206.40 Median:206.00 stddev:11.10 (5.4%) stddev-sans-first:11.35 12:30:57 INFO - PID 2054 | Values: 198.0 204.0 210.0 221.0 229.0 207.0 196.0 205.0 195.0 199.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#3] audio-fft Cycles:10 Average:70.30 Median:73.50 stddev:15.92 (21.7%) stddev-sans-first:16.57 12:30:57 INFO - PID 2054 | Values: 79.0 58.0 57.0 84.0 59.0 63.0 68.0 90.0 96.0 49.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:9.97 (12.9%) stddev-sans-first:10.52 12:30:57 INFO - PID 2054 | Values: 80.0 71.0 84.0 75.0 69.0 69.0 87.0 69.0 69.0 98.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:90.50 stddev:2.36 (2.6%) stddev-sans-first:2.47 12:30:57 INFO - PID 2054 | Values: 92.0 90.0 89.0 89.0 90.0 90.0 94.0 96.0 89.0 91.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:96.50 stddev:0.52 (0.5%) stddev-sans-first:0.50 12:30:57 INFO - PID 2054 | Values: 97.0 97.0 96.0 96.0 97.0 96.0 96.0 97.0 96.0 96.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#7] imaging-desaturate Cycles:10 Average:110.30 Median:116.00 stddev:20.27 (17.5%) stddev-sans-first:21.49 12:30:57 INFO - PID 2054 | Values: 111.0 97.0 141.0 100.0 103.0 134.0 140.0 92.0 94.0 91.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#8] json-parse-financial Cycles:10 Average:56.70 Median:52.00 stddev:9.98 (19.2%) stddev-sans-first:10.44 12:30:57 INFO - PID 2054 | Values: 52.0 50.0 52.0 59.0 75.0 75.0 52.0 50.0 51.0 51.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#9] json-stringify-tinderbox Cycles:10 Average:65.00 Median:65.50 stddev:10.78 (16.5%) stddev-sans-first:10.93 12:30:57 INFO - PID 2054 | Values: 56.0 89.0 71.0 67.0 64.0 61.0 58.0 54.0 56.0 74.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#10] stanford-crypto-aes Cycles:10 Average:101.30 Median:87.50 stddev:19.41 (22.2%) stddev-sans-first:19.89 12:30:57 INFO - PID 2054 | Values: 87.0 88.0 91.0 79.0 96.0 119.0 112.0 106.0 144.0 91.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#11] stanford-crypto-ccm Cycles:10 Average:147.20 Median:148.50 stddev:13.69 (9.2%) stddev-sans-first:13.90 12:30:57 INFO - PID 2054 | Values: 136.0 138.0 138.0 131.0 177.0 160.0 151.0 146.0 142.0 153.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:211.40 Median:219.50 stddev:24.64 (11.2%) stddev-sans-first:24.64 12:30:57 INFO - PID 2054 | Values: 188.0 191.0 209.0 241.0 195.0 185.0 191.0 239.0 245.0 230.0 12:30:57 INFO - PID 2054 | 12:30:57 INFO - PID 2054 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:2.51 (3.7%) stddev-sans-first:2.60 12:30:57 INFO - PID 2054 | Values: 66.0 68.0 69.0 69.0 65.0 67.0 65.0 68.0 65.0 73.0 12:30:57 INFO - PID 2054 | -------- Summary: end -------- 12:30:57 INFO - PID 2054 | 12:30:58 INFO - TEST-INFO | 2054: exit 0 12:30:58 INFO - TEST-OK | kraken | took 90955ms 12:30:58 INFO - SUITE-END | took 1233s 12:30:58 INFO - Completed test suite (00:20:34) 12:30:58 INFO - javascript benchmark 12:30:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8030.447835641381, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3509.701675357594, 3524.7356448266387, 3531.50558921751, 3561.2343346180564, 3575.4279315309554, 6117.66352850593, 6135.171751813504, 6236.532260080495, 6246.156768836206, 6255.530835187368, 6136.549713441306, 6174.722137503812, 6190.442860142586, 6214.03682429223, 6268.498520118391, 4377.715448495837, 5328.307320048408, 5342.91985620216, 5391.191321301816, 5400.216968539549, 4944.035912996967, 5157.555884352388, 5435.918461223085, 5488.368837583689, 5528.889422211553, 5286.841394758163, 5427.62006659534, 5436.483534064297, 5477.726113694319, 5501.4223506531835, 5402.108652072443, 5407.215953686733, 5443.319585051869, 5474.91787623186, 5476.479734425223, 5388.91916621251, 5403.621746477748, 5406.432324605899, 5438.184272359138, 5452.073147564924, 6159.907601385938, 6249.750009999594, 6260.280067792217, 6273.7176827042895, 6302.212223472066, 5628.155776633497, 6397.808065758035, 6476.222853257592, 6652.135222421102, 6696.2299335576545, 6765.0, 6774.762883299061, 6782.355676210801, 6784.287649796799, 6800.6939687713675, 6339.587926784744, 6341.460975817061, 6347.5556711029785, 6359.491240700733, 6374.362563743589, 6635.635040072746, 6725.764598239038, 6778.966105169443, 6893.896591551131, 6895.241523432418, 6344.365563443619, 6433.678316084177, 6463.15978922737, 6500.739970401226, 6530.379613936669, 5664.150377443334, 6690.899636505456, 6706.463482921355, 6751.864962700718, 6753.3246675332075, 6528.780968815481, 6638.070670106189, 6639.834004149935, 6648.53460257787, 6653.401193892573, 6470.9029364559565, 6527.053577231275, 6651.16860392451, 6656.4009239167735, 6662.200535935719, 4437.556244375537, 4765.380500534978, 5293.073712100367, 5342.145256758978, 5386.784528618889, 4747.691400058985, 4764.14245435818, 4782.258749893772, 4807.18277892759, 4844.75776211188, 6279.340669229757, 6292.4966002719675, 6348.301686814447, 6352.904706429407, 6364.586301890362, 6567.2447668518735, 6654.467642588678, 6712.0, 6746.257911629717, 6759.864802703918, 6327.082573026885, 6329.303776584572, 6344.841378965562, 6368.458681012074, 6371.904421433682, 5706.229658996065, 5844.590878638454, 5883.382244864313, 5888.5289176865745, 5909.881802364014, 6708.463322934153, 6812.046313516112, 6877.759278425231, 6951.270116637883, 6998.650067496503, 6761.22245941723, 6779.8983015253825, 6784.253732089565, 6807.965960170168, 6821.2155602106395, 6844.110265665631, 6920.169579650475, 6943.132108486439, 6963.1296087989, 6982.79051628452, 6451.41937225652, 6494.318096599789, 6515.869682606227, 6520.739170433129, 6607.702653380706, 6794.500832071389, 6808.38724514796, 6822.351876571718, 6826.146731658543, 6832.111825462658, 6333.96833015832, 6490.123833282447, 6547.836304092245, 6555.770548030796, 6575.63833989116, 6381.074744162163, 6429.678516074271, 6508.186476690414, 6530.640814755234, 6537.607743535403, 6325.525585581007, 6403.039544068427, 6415.166028416276, 6417.96791016042, 6419.935800642121, 6518.837029074311, 6531.706073226621, 6532.902006470002, 6552.180977377828, 6563.0811686364905, 6236.376362363727, 6340.587861788969, 6342.048692696132, 6365.872682546231, 6377.107204991212], "value": 6088.802319858091, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [766.1610536462574, 799.9000124984377, 855.3841234311295, 1253.6991122130692, 1331.5539294336397, 40124.79937600312, 40676.389854152214, 40679.186416271696, 40734.38898416526, 40769.59230407695, 32393.352132957327, 35566.93299201022, 39056.63301784438, 40449.0, 40861.38707919384, 7910.327622152109, 8077.474964127313, 8340.874886876702, 8588.699395521158, 8602.87095693565, 8520.531370774605, 8597.0, 8605.139486051406, 8608.268297194745, 8620.655173793071, 8571.100034496381, 8631.482111073356, 8636.438631488965, 8642.870356944652, 8647.610857511427, 8547.74356769298, 8603.56982150893, 8612.052674205832, 8613.181747733955, 8617.310615150836, 8362.665493380318, 8369.790755231106, 8408.0, 8431.873521897176, 8449.831003379959, 38082.61917380818, 38294.42558361627, 38308.55223029913, 38385.0, 38480.422793658116, 3586.5158203642695, 3611.0972256935765, 3629.310431018098, 3635.073056370635, 3635.94546081809, 30996.690033099607, 31059.84470077635, 31274.687253127406, 31364.37271254585, 31425.37149257002, 3082.3372974810522, 3125.484295091307, 3133.122725636825, 3145.716885480295, 3145.968540314613, 251777.0, 255543.4445655557, 286362.9999999979, 309252.0, 310088.0, 37713.43429848555, 48329.75835120802, 48371.5162848367, 48455.515444845805, 48491.0, 50123.74938125286, 50878.74560627173, 51875.48124518707, 51999.48000520022, 52045.2193217102, 14503.637409064857, 14631.780523292158, 14756.188409637572, 14774.0, 14779.113253204627, 11726.120540959462, 11824.349660768741, 11827.822582661267, 11838.0, 11853.88146118545, 14165.362558684885, 14200.573982780534, 14222.715545689029, 14264.930130240273, 14318.284085795876, 6288.339724328879, 6288.748450062038, 6334.429901308904, 6339.968300158562, 6365.777197798055, 996.153269720746, 1032.4734385463319, 1032.9018743219383, 1060.3955745225148, 1064.499685147979, 17259.482215533553, 19778.50553736168, 24374.025038998243, 24521.264362069167, 24569.262922112364, 954.3891909178132, 1121.747606788466, 1125.2179735084037, 1156.9074474042056, 1161.7618388230392, 11515.25150865191, 11761.705957351134, 12258.693532661577, 12477.00183985279, 12580.81128783069, 340180.0, 341453.0, 342942.0, 343849.0, 345035.0, 55396.0, 55563.0, 55742.0, 55974.88050239054, 56002.71998639981, 1509.8414666460083, 1530.99234503829, 1561.695469383482, 1565.7886185365057, 1569.0272031340414, 23855.880720596288, 23861.880690596437, 23935.76064239405, 24047.398815029763, 24121.879390602935, 38685.0, 38876.0, 38899.80550097232, 38911.41632875452, 38996.415053774785, 238767.0, 238914.0, 240830.0, 245318.0, 246018.76990614933, 31412.37175256528, 33964.83017584896, 35012.29975400529, 35017.64982350144, 35147.82426087853, 42495.78752106219, 43761.68714938557, 43959.0, 44003.559964401225, 44032.0, 28607.570886437137, 29779.255518612208, 30570.84714576502, 30722.385552288382, 30752.0, 25739.871300643375, 26165.607515886895, 29844.403111937205, 30669.53995690022, 30800.229994250323, 36046.819765901004, 36142.73500427473, 36159.27681446304, 36173.638263617024, 36214.0, 27987.300317492227, 29033.0, 29628.407431851672, 29932.401351972403, 30733.846330769098, 18137.637247254715, 23779.881100594386, 23873.761262387154, 24224.51550969006, 24248.272551823473], "value": 17547.92997981717, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.6897378636409, 427.0058825285914, 431.86828017454684, 442.1863770661983, 450.25033319523, 12452.377381130953, 15077.0, 21331.466713332175, 22139.335819925407, 22208.0, 20800.47998800031, 23202.535949281006, 23218.767812321872, 23296.06815727369, 23453.413664658394, 6022.698865056752, 6205.0692396140485, 6223.813285601439, 6234.968825155868, 6241.906371404432, 5328.307320048389, 6086.726097325631, 6127.509799216052, 6173.135760993465, 6208.161898143738, 5689.2319536862615, 5926.970365148169, 5983.970080149615, 6128.050152226412, 6158.384161583851, 5901.498372638331, 5904.439078287556, 5945.346011938705, 5964.254468191476, 5969.611975221596, 5285.969235998982, 5306.575473962073, 5320.547753440924, 5322.946770532284, 5323.494268044529, 27850.721492785015, 27898.860505697543, 28267.85866070677, 28271.0, 28309.57535636967, 2067.1627990663806, 2146.9355919322447, 2170.7829217078324, 2172.56548690261, 2175.9782402176093, 18191.0, 18249.0, 18495.0, 18511.629767404713, 18605.0, 2110.799474049963, 2142.646463333548, 2167.9241226556996, 2171.5873983943, 2194.5391467791783, 178498.0, 186392.0, 200680.0, 202718.0, 204673.9766301159, 10312.0, 10353.327033742782, 12608.550016748008, 12897.41961611711, 12963.611091667077, 13902.443902243796, 15853.286602102933, 16284.674306513803, 16323.673526529403, 16421.58946026359, 7123.465740069516, 7422.0, 8891.688790892287, 9014.053524379977, 9115.270778337717, 7242.23956484572, 8710.0, 8735.475871447734, 8782.253508451853, 8850.0, 7658.693652253847, 7960.40296977729, 8029.397795165386, 8071.677132914735, 8099.716509922125, 2279.259240746731, 2280.201929324723, 2286.8627882327114, 2303.5623231585946, 2454.0, 889.4485419040236, 928.8235235305406, 941.4727752458645, 965.9130678238993, 973.563993110171, 19870.70193947092, 20322.08550615226, 20381.694274585894, 20464.897675511824, 20466.590668186553, 995.7801692926107, 1017.6845177994845, 1019.6125472320471, 1033.1476531861244, 1041.1410586266209, 10129.139023183112, 10332.535035923402, 10604.416757078281, 12551.93724031374, 13700.588982330546, 550775.0, 565405.0, 567789.0, 568044.4793328021, 569367.0, 153252.0, 154602.6809597857, 155826.0, 156426.0, 156600.43399566086, 1180.7638472305573, 1216.0636310041218, 1216.0940099625836, 1228.6129869091208, 1230.5816022552197, 16811.91594042022, 17756.28974840992, 17795.46613601594, 21740.021699023266, 22054.77945220527, 59973.30120144517, 68050.0, 71814.28185718076, 73076.0, 73273.63363183363, 78564.60717696375, 87654.0, 88143.0, 88464.0, 88502.11497885197, 9920.652777152765, 10169.644062457779, 10194.031567001122, 10194.643187488402, 10204.13264872494, 10663.89336106629, 13619.523316683868, 16162.757558636395, 17632.294708211528, 17678.381256655954, 13962.511312104027, 14323.355449005028, 14458.132512049311, 14492.492762753252, 14495.275236237934, 12334.321612311409, 12598.118131731058, 12706.047046471736, 12793.168444051107, 12985.870141298845, 14240.430382784572, 14556.199409032604, 14615.269236537917, 14684.265786710408, 14722.337494812546, 9721.368111072758, 9807.852882206915, 9835.754106147117, 9861.704148875546, 9876.950615246878, 13997.020208585302, 14150.78773818414, 14157.929210353883, 14197.716045679235, 14272.429102835771], "value": 10899.635124216426, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.8693150027977, 1329.800529920512, 1383.8583168885673, 1413.2651021468844, 1422.352829462594, 11591.956723894858, 11716.355600441972, 12023.639290821278, 12044.337561434118, 12376.50493980242, 12061.035117190626, 12244.938775306133, 12331.876681233185, 12341.197822141554, 12342.13605047647, 2516.2702816183332, 2573.858437785921, 2573.8841752121107, 2574.5751950928075, 2578.729233430491, 2447.302518782147, 2508.9372765680823, 2541.0471073347494, 2545.592705167174, 2546.541622507948, 2456.115798312606, 2488.7884529814987, 2495.513374891897, 2496.8127390445698, 2504.536660717764, 2293.6352870042297, 2386.642003699442, 2431.914882979096, 2453.129139155601, 2470.110760126362, 2100.789921007902, 2440.7925326347104, 2489.166129346671, 2493.4888347888823, 2504.7370026147173, 10135.493225338703, 11124.443777811077, 11517.424128793611, 11626.0, 11636.127290453165, 6339.619622822599, 6516.022596610499, 6569.375909288658, 6588.439982601484, 6591.571547849375, 3995.720299579031, 4152.294110001307, 4217.177650358181, 4231.026863821338, 4241.172971270604, 990.0149351395336, 1158.739283661178, 1216.6046035038648, 1228.2753175626333, 1244.1788419643083, 15158.772618410732, 15589.610259743597, 16110.677786444323, 16196.75704864428, 16297.999999999882, 4831.323614693945, 5510.421405752419, 5563.554915606742, 5692.629979051431, 5728.112142617818, 9942.254330925065, 10173.54219060144, 12058.638240852788, 12205.877941220651, 12277.508899643914, 6351.841203969938, 6371.426571608577, 6436.8390790230615, 6443.0, 6449.355064493607, 5878.177055212273, 6000.909986350208, 6012.188354572077, 6022.247219097613, 6047.12316714074, 4386.8025938832825, 4426.845060422934, 4472.552744725566, 4499.122671079108, 4509.413776209138, 1260.7226410189744, 1296.7471343088007, 1311.5344052861287, 1312.6390242683337, 1326.8009798530297, 739.2939742545856, 773.3774311679152, 794.5113755040609, 799.2646764976236, 803.0604193094094, 6811.182658081062, 7079.929200707927, 7087.342246657503, 7159.498835081598, 7178.38983686382, 756.5158298688843, 906.2025417632441, 928.6285485805597, 934.5654270763991, 941.3504681769557, 3211.6948889855894, 3469.37551240774, 3960.2673505401594, 4581.496035436099, 4673.088747694167, 14526.983111182115, 14609.78085328721, 14941.477048303257, 15055.171965542, 15072.171030593423, 14606.707865842836, 14888.330025148894, 14963.775543366859, 15015.474458393905, 15063.54809355721, 8087.3125784308995, 8398.992120945404, 8458.111898250596, 8503.681929300968, 8504.149585041447, 6466.644334561644, 6842.863142737017, 6981.720731170696, 7143.321384468467, 7207.495475316678, 12380.62858114258, 12429.0, 12549.99600031977, 12761.234325940473, 12778.0, 12847.421866015864, 13079.215247085205, 13117.803232951323, 13134.671633209246, 13187.0, 8700.477971321741, 8750.168733970262, 8842.93884733836, 8873.068327825486, 8885.111488851322, 8737.519436431057, 8745.562721863755, 10760.569577217137, 10762.784744305227, 10777.838332424863, 7460.962695186707, 8818.5590720465, 8999.280057595508, 9004.549772511216, 9013.774655633662, 8536.810627580278, 8593.82812343762, 8688.695895643623, 8694.434861733967, 8764.079771624143, 8168.346532277523, 8320.459170153921, 8371.16288371158, 8382.41323107401, 8387.706430275155, 6022.337542870368, 6039.0, 6193.84515387119, 6199.938000620116, 6255.562110652393, 6110.297315808739, 6171.382861713792, 6286.402791734778, 6378.936210637834, 6393.073004414427], "value": 5310.740577595562, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.74981514418755, 391.2995737629643, 403.65487508180485, 403.7920470957457, 408.1551189038691, 6110.786122485714, 6114.296855861578, 6160.353162917896, 6262.403087212754, 6547.181602299713, 5062.873428164288, 5233.4243233244315, 5460.5085542301185, 6215.689215539228, 6346.30190679025, 4453.621442177411, 4482.910341793162, 4545.704529205591, 4643.16423043852, 4673.009274064601, 3822.57951625321, 4641.984683248999, 4670.93628543168, 4677.74272415017, 4695.201815691324, 4545.977270113644, 4594.264917613183, 4598.724076555401, 4612.331211974279, 4626.745528995921, 4608.8386906458445, 4621.2837010263465, 4629.421322334708, 4637.863723387758, 4640.5127461616385, 3999.7000224982953, 4022.1955608878047, 4028.4762980812307, 4029.0531725044593, 4030.1133750574827, 4777.95222047782, 6277.560570760049, 6277.829696003535, 6284.0, 6319.3680631936895, 4848.430331924086, 5570.86072848178, 5590.524805391506, 5596.916046259309, 5604.346717718283, 5743.827685169452, 5961.731722072517, 6211.9068213976825, 6852.862942741167, 6870.416014638762, 4567.406237189144, 4584.954150458486, 4594.379758732562, 4597.195490789099, 4610.285405762079, 6497.3827486388245, 6903.0, 7259.0, 7278.781636550912, 7296.6716497757725, 3706.1647839402876, 4350.651947844165, 4599.08018396322, 4622.399088118523, 4651.023315091073, 4731.776065249396, 4733.668643195012, 4748.572628463455, 4750.764801151726, 4763.928116173903, 5508.173773933942, 5515.889682206333, 5550.58370622205, 5559.082751346064, 5560.833175004756, 5951.315598706117, 6830.795076147723, 6840.0765896603325, 6865.176178858569, 6882.139732533434, 1605.8795590330772, 1607.4615003973565, 1609.6056466165865, 1618.7490938904489, 1621.4353149211067, 998.2762497189625, 1066.3228849680509, 1067.354250678344, 1076.8815430302661, 1081.5889961814617, 5763.971180144156, 6414.358564143548, 6537.248216455168, 6569.835754106186, 6585.1439312889015, 7102.360787529147, 7108.649356622329, 7130.180029296594, 7143.107111611049, 7196.568205907663, 5302.939412117592, 6486.643234622141, 6486.772962946275, 6488.416042556192, 6511.413972742476, 3493.615702272748, 3629.8366573504254, 3641.471986561987, 3690.3946783149418, 3731.6193008586997, 6328.0, 6605.735770569124, 6764.985252212207, 7308.159561650478, 7310.195878453366, 6679.532432729759, 6686.5653732507235, 7249.528780629243, 7302.890456643155, 7318.194998550155, 6084.817455476165, 6548.70530826114, 6697.430718388991, 7295.270472952662, 7350.558966461923, 7090.822729431599, 7102.431805455654, 7107.289271072851, 7109.893351599627, 7119.466040047121, 5783.450572195635, 6933.1333583302085, 6938.167419909643, 6979.860402792017, 7012.211885969045, 6643.867122657423, 7278.053852999076, 7324.560526368222, 7341.302576255247, 7349.669264882986, 3740.8690695825517, 4517.706349087299, 4622.976885115553, 4623.329617205419, 4651.837185698484, 6812.965935170292, 6995.370416662321, 7006.404455621329, 7132.429405647639, 7202.423806095395, 5802.854928626818, 6829.965850170717, 6872.793816185523, 6881.522651845232, 6910.274421185703, 5765.4811067004175, 5800.622959507618, 6863.588184708933, 6914.585124892523, 6962.164540255202, 5449.291592093003, 6740.8651826962205, 6746.460283177236, 6756.729730810713, 6763.357481039294, 6336.7148478319505, 6921.062102611277, 6929.930700692928, 6933.591798397259, 6938.479614029069, 5730.742116604846, 5921.864153694702, 6700.5309628327495, 6729.5625784323865, 6736.551641397123], "value": 4937.649061799304, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.7823542055095, 814.8085199978004, 818.3616778912443, 853.9573021348934, 861.7418568889422, 14582.927085364585, 15198.468053618126, 16736.748948765762, 17294.13529323535, 17396.56508587286, 15156.696866062672, 16290.185490725446, 18177.36379226724, 18282.360117395892, 18293.451196464095, 964.1997142371849, 999.7850462150636, 1008.6721815409984, 1014.3862962907446, 1018.8573599696049, 1033.633060263607, 1040.5473618975732, 1044.7231483656838, 1046.539522610053, 1048.6591857646258, 965.6330594374128, 1079.4656644960755, 1080.7622323088908, 1082.5994382078643, 1083.1876092930302, 1066.3761699405818, 1067.8932106789337, 1069.7379142110155, 1073.2755390111643, 1083.6261489786011, 775.5889378629336, 776.2354081229984, 777.3936329662872, 781.2773184804033, 787.0594639405912, 16756.581085472837, 17578.38475653359, 17756.822431775647, 17791.555211119696, 17902.552436189068, 17264.56838579033, 17509.0, 17653.38213162533, 17892.821071789247, 17918.372856949944, 14682.045667031609, 14993.100413975197, 15000.099994000395, 15051.473198438112, 15064.548063558112, 3517.050396392985, 3547.0954906498678, 3577.5349204603494, 3578.0160455874584, 3634.727395445326, 126582.0, 138397.3080134593, 142875.55062778993, 145033.0, 145801.0, 13538.45846166162, 13594.93202533981, 13679.521216757366, 13850.030497865053, 13868.653283667989, 25136.12023579166, 25360.492790144464, 25805.225843224733, 25877.741222587534, 25892.093776718102, 6705.899411508832, 6929.029935808991, 7588.392928565701, 7759.185285544936, 7957.681692732226, 3892.5718171001167, 3977.164795392942, 3982.900427489336, 3985.262726395626, 3988.0029992501873, 4227.788610569459, 4245.61789438952, 4315.287977483743, 4315.697901146889, 4330.805113769888, 930.5533343994921, 947.4978261521404, 952.1240458777941, 952.2524818017823, 962.1965658674944, 572.3703925681717, 621.4438077920236, 648.1541588227409, 670.2560158224367, 684.8118514377551, 11452.370119643332, 12181.39093045362, 12357.135000549875, 12447.626571202878, 12466.87533124657, 632.686820024091, 636.6975686548944, 639.4788247578209, 651.4234902111571, 669.3072669786748, 5173.4826517347965, 5640.407757185519, 5760.6255593386295, 5766.826995190151, 5857.970710146422, 140856.0, 147676.0, 147933.52066479198, 150685.0, 153257.0, 115748.0, 124230.13654795186, 124808.0, 125382.3730881358, 127801.3609931963, 95236.52381738185, 96019.0, 97688.51155744318, 101409.49295253328, 103113.48443257736, 9823.0, 11430.257033293143, 11837.763244734884, 12263.08026898004, 12420.565280215149, 33370.0, 37331.440028399054, 44912.8771780695, 45066.87332816706, 45527.40654077091, 62534.0, 70425.64787176032, 85098.87252818729, 85407.14592853992, 86127.0, 15108.924455377653, 16882.493525194262, 17007.639388848616, 17415.39046133379, 17759.378421755177, 14262.57212283633, 16173.67652646917, 18157.09214539294, 18986.810131899056, 19108.426747197605, 17434.041127738095, 17753.644927101126, 18144.364947226783, 18279.26882924668, 18291.54271143232, 17718.11409429549, 17841.73237401466, 17942.282308707505, 18114.456566303033, 18294.359697410528, 13483.662908426975, 15341.386344546094, 16155.515334539983, 16471.176441178133, 17987.820121798613, 11126.49930753102, 11731.88268117342, 12067.336296503425, 12287.894089531985, 12733.745325093261, 12325.260484370967, 13273.469061237442, 13395.464181432633, 13418.686084914782, 13452.596422107352], "value": 8781.383466351328, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1564.3000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 166.0, 160.0, 115.0, 112.0, 96.0, 152.0, 166.0, 111.0, 106.0], "value": 129.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 149.0, 116.0, 147.0, 111.0, 112.0, 109.0, 113.0, 156.0, 141.0], "value": 134.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 204.0, 210.0, 221.0, 229.0, 207.0, 196.0, 205.0, 195.0, 199.0], "value": 206.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 57.0, 84.0, 59.0, 63.0, 68.0, 90.0, 96.0, 49.0], "value": 70.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 84.0, 75.0, 69.0, 69.0, 87.0, 69.0, 69.0, 98.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 90.0, 89.0, 89.0, 90.0, 90.0, 94.0, 96.0, 89.0, 91.0], "value": 91.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 97.0, 96.0, 96.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 97.0, 141.0, 100.0, 103.0, 134.0, 140.0, 92.0, 94.0, 91.0], "value": 110.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 50.0, 52.0, 59.0, 75.0, 75.0, 52.0, 50.0, 51.0, 51.0], "value": 56.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 89.0, 71.0, 67.0, 64.0, 61.0, 58.0, 54.0, 56.0, 74.0], "value": 65.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 91.0, 79.0, 96.0, 119.0, 112.0, 106.0, 144.0, 91.0], "value": 101.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 138.0, 138.0, 131.0, 177.0, 160.0, 151.0, 146.0, 142.0, 153.0], "value": 147.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 191.0, 209.0, 241.0, 195.0, 185.0, 191.0, 239.0, 245.0, 230.0], "value": 211.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 69.0, 69.0, 65.0, 67.0, 65.0, 68.0, 65.0, 73.0], "value": 67.5, "unit": "score"}]}]} 12:30:58 INFO - Return code: 0 12:30:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:30:59 INFO - # TBPL SUCCESS # 12:30:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:30:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:30:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:30:59 INFO - 2017-05-31 12:30:59.046 screenresolution[2062:23909] starting screenresolution argv=screenresolution get 12:30:59 INFO - 2017-05-31 12:30:59.048 screenresolution[2062:23909] Display 0: 1600x1200x32@60 12:30:59 INFO - 2017-05-31 12:30:59.054 screenresolution[2063:23912] starting screenresolution argv=screenresolution list 12:30:59 INFO - Available Modes on Display 0 12:30:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:30:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:30:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:30:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:30:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:30:59 INFO - Intel Iris: 12:30:59 INFO - Chipset Model: Intel Iris 12:30:59 INFO - Type: GPU 12:30:59 INFO - Bus: Built-In 12:30:59 INFO - VRAM (Dynamic, Max): 1536 MB 12:30:59 INFO - Vendor: Intel (0x8086) 12:30:59 INFO - Device ID: 0x0a2e 12:30:59 INFO - Revision ID: 0x0009 12:30:59 INFO - Displays: 12:30:59 INFO - Display: 12:30:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:30:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:30:59 INFO - Display Serial Number: 1600x1200 60 12:30:59 INFO - Main Display: Yes 12:30:59 INFO - Mirror: Off 12:30:59 INFO - Online: Yes 12:30:59 INFO - Rotation: Supported 12:30:59 INFO - Return code: 0 12:30:59 INFO - Running post-action listener: _resource_record_post_action 12:30:59 INFO - [mozharness: 2017-05-31 19:30:59.293300Z] Finished run-tests step (success) 12:30:59 INFO - Running post-run listener: _resource_record_post_run 12:30:59 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 32382464; Write bytes: 570482688; Read time: 217; Write time: 1783 12:30:59 INFO - TinderboxPrint: CPU usage
53.5% 12:30:59 INFO - TinderboxPrint: I/O read bytes / time
32,382,464 / 217 12:30:59 INFO - TinderboxPrint: I/O write bytes / time
570,482,688 / 1,783 12:30:59 INFO - TinderboxPrint: CPU idle
2,339.1 (46.5%) 12:30:59 INFO - TinderboxPrint: CPU system
1,020.8 (20.3%) 12:30:59 INFO - TinderboxPrint: CPU user
1,665.8 (33.1%) 12:30:59 INFO - TinderboxPrint: Swap in / out
272,678,912 / 0 12:30:59 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 230744576; Write bytes: 228581376; Read time: 16903; Write time: 464 12:30:59 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:30:59 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 32382464; Write bytes: 321239040; Read time: 217; Write time: 1258 12:30:59 INFO - Running post-run listener: _upload_blobber_files 12:30:59 INFO - Blob upload gear active. 12:30:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:30:59 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 12:30:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:30:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:31:00 INFO - (blobuploader) - INFO - Open directory for files ... 12:31:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:31:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:31:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:01 INFO - (blobuploader) - INFO - Done attempting. 12:31:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:31:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:31:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:01 INFO - (blobuploader) - INFO - Done attempting. 12:31:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:31:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:02 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:31:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:02 INFO - (blobuploader) - INFO - Done attempting. 12:31:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:31:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:31:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:31:04 INFO - (blobuploader) - INFO - Done attempting. 12:31:04 INFO - (blobuploader) - INFO - Iteration through files over. 12:31:04 INFO - Return code: 0 12:31:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:31:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:31:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb26847b9b0be7594610d6665af36d6da0c99dcb988b7387210df2eb35ca026c873f5872a18cc2a68ec33e59ccb3bbdce2762243b50b28d64468d13ab5ed18c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0023036ec612664c073f81d8274e5e55c028db5e9149c33a38be2bffb806cec206e33cfc7c5c76516d40f8bff3f13728c2d99e43a13cc7dfcd6b3f77688a0c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d9c37a2afb5ab2719d18722824806bf54966a7d30fad5dd16a65da109fe32f3f09aa05d03c74363e2638a2040cd4c5de5cc00f9e9f90715aeeac6aafa6dff5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce8ee3a6f2f875a7fcc31c6304a3788f18de6a50db8a5490b533a367d133a1f8ad3c698cbd28f5bb72882803af500a7d1ced71dbd232c7865bbb7425d815668"} 12:31:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:31:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:31:04 INFO - Contents: 12:31:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb26847b9b0be7594610d6665af36d6da0c99dcb988b7387210df2eb35ca026c873f5872a18cc2a68ec33e59ccb3bbdce2762243b50b28d64468d13ab5ed18c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0023036ec612664c073f81d8274e5e55c028db5e9149c33a38be2bffb806cec206e33cfc7c5c76516d40f8bff3f13728c2d99e43a13cc7dfcd6b3f77688a0c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d9c37a2afb5ab2719d18722824806bf54966a7d30fad5dd16a65da109fe32f3f09aa05d03c74363e2638a2040cd4c5de5cc00f9e9f90715aeeac6aafa6dff5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce8ee3a6f2f875a7fcc31c6304a3788f18de6a50db8a5490b533a367d133a1f8ad3c698cbd28f5bb72882803af500a7d1ced71dbd232c7865bbb7425d815668"} 12:31:04 INFO - Running post-run listener: copy_logs_to_upload_dir 12:31:04 INFO - Copying logs to upload dir... 12:31:04 INFO - mkdir: /builds/slave/test/build/upload/logs 12:31:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.443599 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2017-05-31 12:31:04.809980) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 12:31:04.813456) ========= 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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb26847b9b0be7594610d6665af36d6da0c99dcb988b7387210df2eb35ca026c873f5872a18cc2a68ec33e59ccb3bbdce2762243b50b28d64468d13ab5ed18c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0023036ec612664c073f81d8274e5e55c028db5e9149c33a38be2bffb806cec206e33cfc7c5c76516d40f8bff3f13728c2d99e43a13cc7dfcd6b3f77688a0c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d9c37a2afb5ab2719d18722824806bf54966a7d30fad5dd16a65da109fe32f3f09aa05d03c74363e2638a2040cd4c5de5cc00f9e9f90715aeeac6aafa6dff5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce8ee3a6f2f875a7fcc31c6304a3788f18de6a50db8a5490b533a367d133a1f8ad3c698cbd28f5bb72882803af500a7d1ced71dbd232c7865bbb7425d815668"} build_url:https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011119 build_url: 'https://queue.taskcluster.net/v1/task/T9Vi3kDnSqiuIz9p6KxXwQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bcb26847b9b0be7594610d6665af36d6da0c99dcb988b7387210df2eb35ca026c873f5872a18cc2a68ec33e59ccb3bbdce2762243b50b28d64468d13ab5ed18c", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f0023036ec612664c073f81d8274e5e55c028db5e9149c33a38be2bffb806cec206e33cfc7c5c76516d40f8bff3f13728c2d99e43a13cc7dfcd6b3f77688a0c2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38d9c37a2afb5ab2719d18722824806bf54966a7d30fad5dd16a65da109fe32f3f09aa05d03c74363e2638a2040cd4c5de5cc00f9e9f90715aeeac6aafa6dff5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/3ce8ee3a6f2f875a7fcc31c6304a3788f18de6a50db8a5490b533a367d133a1f8ad3c698cbd28f5bb72882803af500a7d1ced71dbd232c7865bbb7425d815668"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-31 12:31:04.847016) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:31:04.847352) ========= 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.UCD0O1kXHl/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.JnfFXfRIUx/Listeners TMPDIR=/var/folders/yk/gx088sh5025_4j2qsy7fj5kw00000w/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.005125 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-31 12:31:04.895959) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 12:31:04.896294) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-31 12:31:04.896659) ========= ========= Total master_lag: 0.53 =========