builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0028 starttime: 1497552978.05 results: success (0) revision: ac2d0008d149be9bd183dd1fb3a127997bf3f14e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.050517) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.051603) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.052314) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.078912) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.079640) ========= 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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-15 11:56:18-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-06-15 11:56:18 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125463 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.229890) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.230266) ========= 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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.039602 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:18.291378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 11:56:18.291806) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ac2d0008d149be9bd183dd1fb3a127997bf3f14e --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 ac2d0008d149be9bd183dd1fb3a127997bf3f14e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-15 11:56:18,385 truncating revision to first 12 chars 2017-06-15 11:56:18,385 Setting DEBUG logging. 2017-06-15 11:56:18,385 attempt 1/10 2017-06-15 11:56:18,385 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ac2d0008d149?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-15 11:56:20,057 unpacking tar archive at: mozilla-central-ac2d0008d149/testing/mozharness/ program finished with exit code 0 elapsedTime=1.953988 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-15 11:56:20.267478) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:20.267861) ========= script_repo_revision: ac2d0008d149be9bd183dd1fb3a127997bf3f14e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:20.268324) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:20.268644) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-15 11:56:20.286765) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-15 11:56:20.287296) ========= /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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:56:20 INFO - MultiFileLogger online at 20170615 11:56:20 in /builds/slave/test 11:56:20 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 11:56:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:56:20 INFO - {'append_to_log': False, 11:56:20 INFO - 'base_work_dir': '/builds/slave/test', 11:56:20 INFO - 'blob_upload_branch': 'Firefox', 11:56:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:56:20 INFO - 'branch': 'Firefox', 11:56:20 INFO - 'buildbot_json_path': 'buildprops.json', 11:56:20 INFO - 'config_files': ('talos/mac_config.py',), 11:56:20 INFO - 'default_actions': ('clobber', 11:56:20 INFO - 'read-buildbot-config', 11:56:20 INFO - 'download-and-extract', 11:56:20 INFO - 'populate-webroot', 11:56:20 INFO - 'create-virtualenv', 11:56:20 INFO - 'install', 11:56:20 INFO - 'setup-mitmproxy', 11:56:20 INFO - 'run-tests'), 11:56:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:56:20 INFO - 'download_minidump_stackwalk': True, 11:56:20 INFO - 'download_symbols': 'ondemand', 11:56:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:56:20 INFO - 'tooltool.py': '/tools/tooltool.py', 11:56:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:56:20 INFO - '/tools/misc-python/virtualenv.py')}, 11:56:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:56:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:56:20 INFO - 'gecko_profile': False, 11:56:20 INFO - 'gecko_profile_interval': 0, 11:56:20 INFO - 'installer_path': 'installer.exe', 11:56:20 INFO - 'log_level': 'info', 11:56:20 INFO - 'log_name': 'talos', 11:56:20 INFO - 'log_to_console': True, 11:56:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:56:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:56:20 INFO - 'opt_config_files': (), 11:56:20 INFO - 'pip_index': False, 11:56:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:20 INFO - 'cmd': ('bash', 11:56:20 INFO - '-c', 11:56:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:20 INFO - 'enabled': True, 11:56:20 INFO - 'halt_on_failure': False, 11:56:20 INFO - 'name': 'check_screen_resolution'},), 11:56:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:56:20 INFO - 'cmd': ('bash', 11:56:20 INFO - '-c', 11:56:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:56:20 INFO - 'enabled': True, 11:56:20 INFO - 'halt_on_failure': False, 11:56:20 INFO - 'name': 'check_screen_resolution'},), 11:56:20 INFO - 'run_cmd_checks_enabled': True, 11:56:20 INFO - 'suite': 'dromaeojs-e10s', 11:56:20 INFO - 'system_bits': '32', 11:56:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:56:20 INFO - 'title': 't-yosemite-r7-0028', 11:56:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:56:20 INFO - 'use_talos_json': True, 11:56:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:56:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:56:20 INFO - 'work_dir': 'build'} 11:56:20 INFO - [mozharness: 2017-06-15 18:56:20.499935Z] Running clobber step. 11:56:20 INFO - Running pre-action listener: _resource_record_pre_action 11:56:20 INFO - Running main action method: clobber 11:56:20 INFO - rmtree: /builds/slave/test/build 11:56:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:56:22 INFO - Running post-action listener: _resource_record_post_action 11:56:22 INFO - [mozharness: 2017-06-15 18:56:22.975816Z] Finished clobber step (success) 11:56:22 INFO - [mozharness: 2017-06-15 18:56:22.975946Z] Running read-buildbot-config step. 11:56:22 INFO - Running pre-action listener: _resource_record_pre_action 11:56:22 INFO - Running main action method: read_buildbot_config 11:56:22 INFO - Using buildbot properties: 11:56:22 INFO - { 11:56:22 INFO - "project": "", 11:56:22 INFO - "product": "firefox", 11:56:22 INFO - "who": "ffxbld@noreply.mozilla.org", 11:56:22 INFO - "installer_path": "public/build/firefox-56.0a1.en-US.mac.dmg", 11:56:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:56:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:56:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:56:22 INFO - "stage_platform": "macosx64", 11:56:22 INFO - "basedir": "/builds/slave/test", 11:56:22 INFO - "buildnumber": 91, 11:56:22 INFO - "platform": "yosemite_r7", 11:56:22 INFO - "parent_task_id": "Do2Rzs7hQKavgDHc6_BeAQ", 11:56:22 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 11:56:22 INFO - "slavebuilddir": "test", 11:56:22 INFO - "taskId": "d435Rz_lQxe5hH-CTyutsg", 11:56:22 INFO - "branch": "mozilla-central", 11:56:22 INFO - "script_repo_revision": "production", 11:56:22 INFO - "revision": "ac2d0008d149be9bd183dd1fb3a127997bf3f14e", 11:56:22 INFO - "slavename": "t-yosemite-r7-0028", 11:56:22 INFO - "repo_path": "mozilla-central" 11:56:22 INFO - } 11:56:22 INFO - Finding installer, test and symbols from parent task. 11:56:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d435Rz_lQxe5hH-CTyutsg'}, attempt #1 11:56:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d435Rz_lQxe5hH-CTyutsg'}, attempt #1 11:56:23 INFO - Task dependencies: HIFBUlS5QVa6u57SPWqJcw Do2Rzs7hQKavgDHc6_BeAQ 11:56:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ'}, attempt #1 11:56:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 11:56:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/test_packages.json 11:56:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/target.crashreporter-symbols.zip 11:56:24 INFO - Running post-action listener: _resource_record_post_action 11:56:24 INFO - [mozharness: 2017-06-15 18:56:24.370563Z] Finished read-buildbot-config step (success) 11:56:24 INFO - [mozharness: 2017-06-15 18:56:24.370687Z] Running download-and-extract step. 11:56:24 INFO - Running pre-action listener: _resource_record_pre_action 11:56:24 INFO - Running main action method: download_and_extract 11:56:24 INFO - mkdir: /builds/slave/test/build/tests 11:56: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')]} 11:56:24 INFO - trying https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json 11:56:24 INFO - Downloading https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 11:56:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json'}, attempt #1 11:56:24 INFO - Downloaded 1328 bytes. 11:56:24 INFO - Reading from file /builds/slave/test/build/firefox-56.0a1.en-US.mac.test_packages.json 11:56:24 INFO - Using the following test package requirements: 11:56:24 INFO - {u'awsy': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.awsy.tests.zip'], 11:56:24 INFO - u'common': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 11:56:24 INFO - u'cppunittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.cppunittest.tests.zip'], 11:56:24 INFO - u'gtest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.gtest.tests.zip'], 11:56:24 INFO - u'jittest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'jsshell-mac.zip'], 11:56:24 INFO - u'mochitest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.mochitest.tests.zip'], 11:56:24 INFO - u'mozbase': [u'firefox-56.0a1.en-US.mac.common.tests.zip'], 11:56:24 INFO - u'reftest': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.reftest.tests.zip'], 11:56:24 INFO - u'talos': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.talos.tests.zip'], 11:56:24 INFO - u'web-platform': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.web-platform.tests.tar.gz'], 11:56:24 INFO - u'xpcshell': [u'firefox-56.0a1.en-US.mac.common.tests.zip', 11:56:24 INFO - u'firefox-56.0a1.en-US.mac.xpcshell.tests.zip']} 11:56:24 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:56:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 11:56:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:56:24 INFO - Fetch https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 11:56:26 INFO - Content-Length response header: 59590001 11:56:26 INFO - Bytes received: 59590001 11:56:45 INFO - Downloading packages: [u'firefox-56.0a1.en-US.mac.common.tests.zip', u'firefox-56.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:56:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip 11:56:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:56:45 INFO - Fetch https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.common.tests.zip into memory 11:56:47 INFO - Content-Length response header: 59590001 11:56:47 INFO - Bytes received: 59590001 11:57:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip 11:57:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:57:04 INFO - Fetch https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.talos.tests.zip into memory 11:57:05 INFO - Content-Length response header: 14454477 11:57:05 INFO - Bytes received: 14454477 11:57:05 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')]} 11:57:05 INFO - trying https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 11:57:05 INFO - Downloading https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg 11:57:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg'}, attempt #1 11:57:07 INFO - Downloaded 69588182 bytes. 11:57:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 11:57:07 INFO - mkdir: /builds/slave/test/properties 11:57:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:57:07 INFO - Writing to file /builds/slave/test/properties/build_url 11:57:07 INFO - Contents: 11:57:07 INFO - build_url:https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg 11:57:07 INFO - Running post-action listener: _resource_record_post_action 11:57:07 INFO - Running post-action listener: set_extra_try_arguments 11:57:07 INFO - [mozharness: 2017-06-15 18:57:07.772452Z] Finished download-and-extract step (success) 11:57:07 INFO - [mozharness: 2017-06-15 18:57:07.772578Z] Running populate-webroot step. 11:57:07 INFO - Running pre-action listener: _resource_record_pre_action 11:57:07 INFO - Running main action method: populate_webroot 11:57:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:57:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:57:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:57:07 INFO - '--tppagecycles', 11:57:07 INFO - '7'], 11:57:07 INFO - 'tests': ['tsvgm']}}, 11:57:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:57:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:57:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:57:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:57:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['damp', 'tps']}, 11:57:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'tests': ['damp', 'tps']}, 11:57:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['dromaeo_dom']}, 11:57:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:57:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:57:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:57:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:57:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['a11yr', 11:57:07 INFO - 'ts_paint', 11:57:07 INFO - 'tpaint', 11:57:07 INFO - 'sessionrestore', 11:57:07 INFO - 'sessionrestore_no_auto_restore', 11:57:07 INFO - 'tabpaint']}, 11:57:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:57:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:57:07 INFO - 'bloom_basic_ref']}, 11:57:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 11:57:07 INFO - 'talos_options': ['--mitmproxy', 11:57:07 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 11:57:07 INFO - '--firstNonBlankPaint'], 11:57:07 INFO - 'tests': ['quantum_pageload_google', 11:57:07 INFO - 'quantum_pageload_youtube', 11:57:07 INFO - 'quantum_pageload_amazon', 11:57:07 INFO - 'quantum_pageload_facebook']}, 11:57:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tsvgx', 11:57:07 INFO - 'tsvgr_opacity', 11:57:07 INFO - 'tart', 11:57:07 INFO - 'tscrollx', 11:57:07 INFO - 'cart', 11:57:07 INFO - 'tsvg_static']}, 11:57:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:57:07 INFO - 'tsvgr_opacity', 11:57:07 INFO - 'tart', 11:57:07 INFO - 'tscrollx', 11:57:07 INFO - 'cart', 11:57:07 INFO - 'tsvg_static']}, 11:57:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s'], 11:57:07 INFO - 'tests': ['tp5o']}, 11:57:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:57:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--disable-e10s', 11:57:07 INFO - '--xperf_path', 11:57:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:07 INFO - 'tests': ['tp5n']}, 11:57:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:57:07 INFO - 'talos_options': ['--xperf_path', 11:57:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:57:07 INFO - 'tests': ['tp5n']}}, 11:57:07 INFO - 'talos.zip': {'path': '', 11:57:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:57:07 INFO - Running post-action listener: _resource_record_post_action 11:57:07 INFO - [mozharness: 2017-06-15 18:57:07.795574Z] Finished populate-webroot step (success) 11:57:07 INFO - [mozharness: 2017-06-15 18:57:07.795691Z] Running create-virtualenv step. 11:57:07 INFO - Running pre-action listener: _resource_record_pre_action 11:57:07 INFO - Running main action method: create_virtualenv 11:57:07 INFO - Creating virtualenv /builds/slave/test/build/venv 11:57:07 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 11:57:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:57:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 11:57:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:57:07 INFO - Using real prefix '/tools/python27' 11:57:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:57:08 INFO - Installing distribute.............................................................................................................................................................................................done. 11:57:11 INFO - Installing pip.................done. 11:57:11 INFO - Return code: 0 11:57:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:57:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:57:11 INFO - Reading from file tmpfile_stdout 11:57:11 INFO - Output received: 11:57:11 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) 11:57:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:57:11 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')]} 11:57:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:11 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')]} 11:57:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:11 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:11 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 11:57:11 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 11:57:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:11 INFO - 'HOME': '/Users/cltbld', 11:57:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:11 INFO - 'LOGNAME': 'cltbld', 11:57:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:11 INFO - 'MOZ_NO_REMOTE': '1', 11:57:11 INFO - 'NO_EM_RESTART': '1', 11:57:11 INFO - 'PAGER': '/bin/cat', 11:57:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:11 INFO - 'PWD': '/builds/slave/test', 11:57:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:11 INFO - 'SHELL': '/bin/bash', 11:57:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:11 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:11 INFO - 'USER': 'cltbld', 11:57:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:11 INFO - 'XPC_FLAGS': '0x0', 11:57:11 INFO - 'XPC_SERVICE_NAME': '0', 11:57:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:12 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 11:57:12 INFO - Cleaning up... 11:57:12 INFO - Return code: 0 11:57:12 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:57:12 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')]} 11:57:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:12 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')]} 11:57:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:12 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:12 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 11:57:12 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 11:57:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:12 INFO - 'HOME': '/Users/cltbld', 11:57:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:12 INFO - 'LOGNAME': 'cltbld', 11:57:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:12 INFO - 'MOZ_NO_REMOTE': '1', 11:57:12 INFO - 'NO_EM_RESTART': '1', 11:57:12 INFO - 'PAGER': '/bin/cat', 11:57:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:12 INFO - 'PWD': '/builds/slave/test', 11:57:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:12 INFO - 'SHELL': '/bin/bash', 11:57:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:12 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:12 INFO - 'USER': 'cltbld', 11:57:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:12 INFO - 'XPC_FLAGS': '0x0', 11:57:12 INFO - 'XPC_SERVICE_NAME': '0', 11:57:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:12 INFO - Downloading/unpacking psutil>=3.1.1 11:57:12 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 11:57:12 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 11:57:12 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 11:57:12 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 11:57:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:57:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:14 INFO - Installing collected packages: psutil 11:57:14 INFO - Running setup.py install for psutil 11:57:14 INFO - building 'psutil._psutil_osx' extension 11:57:14 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 11:57:14 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 11:57:14 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 11:57:15 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 11:57:15 INFO - building 'psutil._psutil_posix' extension 11:57:15 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 11:57:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:57:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:57:15 INFO - ^ 11:57:15 INFO - 1 warning generated. 11:57:15 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 11:57:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:57:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:57:15 INFO - Successfully installed psutil 11:57:15 INFO - Cleaning up... 11:57:15 INFO - Return code: 0 11:57:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:57:15 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')]} 11:57:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:15 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')]} 11:57:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:16 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:16 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 11:57:16 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 11:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:16 INFO - 'HOME': '/Users/cltbld', 11:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:16 INFO - 'LOGNAME': 'cltbld', 11:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:16 INFO - 'MOZ_NO_REMOTE': '1', 11:57:16 INFO - 'NO_EM_RESTART': '1', 11:57:16 INFO - 'PAGER': '/bin/cat', 11:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:16 INFO - 'PWD': '/builds/slave/test', 11:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:16 INFO - 'SHELL': '/bin/bash', 11:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:16 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:16 INFO - 'USER': 'cltbld', 11:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:16 INFO - 'XPC_FLAGS': '0x0', 11:57:16 INFO - 'XPC_SERVICE_NAME': '0', 11:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:57:16 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 11:57:16 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 11:57:16 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 11:57:16 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 11:57:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:57:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:57:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:57:18 INFO - Installing collected packages: mozsystemmonitor 11:57:18 INFO - Running setup.py install for mozsystemmonitor 11:57:18 INFO - Successfully installed mozsystemmonitor 11:57:18 INFO - Cleaning up... 11:57:18 INFO - Return code: 0 11:57:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:57:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:57:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:18 INFO - 'HOME': '/Users/cltbld', 11:57:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:18 INFO - 'LOGNAME': 'cltbld', 11:57:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:18 INFO - 'MOZ_NO_REMOTE': '1', 11:57:18 INFO - 'NO_EM_RESTART': '1', 11:57:18 INFO - 'PAGER': '/bin/cat', 11:57:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:18 INFO - 'PWD': '/builds/slave/test', 11:57:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:18 INFO - 'SHELL': '/bin/bash', 11:57:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:18 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:18 INFO - 'USER': 'cltbld', 11:57:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:18 INFO - 'XPC_FLAGS': '0x0', 11:57:18 INFO - 'XPC_SERVICE_NAME': '0', 11:57:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:18 INFO - Downloading/unpacking jsonschema==2.5.1 11:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:57:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:57:20 INFO - warning: no files found matching '*.txt' 11:57:20 INFO - no previously-included directories found matching 'build' 11:57:20 INFO - no previously-included directories found matching 'dist' 11:57:20 INFO - no previously-included directories found matching '.git*' 11:57:20 INFO - Installing collected packages: jsonschema, functools32 11:57:20 INFO - Running setup.py install for functools32 11:57:20 INFO - warning: no files found matching '*.txt' 11:57:20 INFO - no previously-included directories found matching 'build' 11:57:20 INFO - no previously-included directories found matching 'dist' 11:57:20 INFO - no previously-included directories found matching '.git*' 11:57:20 INFO - Successfully installed jsonschema functools32 11:57:20 INFO - Cleaning up... 11:57:20 INFO - Return code: 0 11:57:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:57:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:57:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:20 INFO - 'HOME': '/Users/cltbld', 11:57:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:20 INFO - 'LOGNAME': 'cltbld', 11:57:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:20 INFO - 'MOZ_NO_REMOTE': '1', 11:57:20 INFO - 'NO_EM_RESTART': '1', 11:57:20 INFO - 'PAGER': '/bin/cat', 11:57:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:20 INFO - 'PWD': '/builds/slave/test', 11:57:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:20 INFO - 'SHELL': '/bin/bash', 11:57:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:20 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:20 INFO - 'USER': 'cltbld', 11:57:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:20 INFO - 'XPC_FLAGS': '0x0', 11:57:20 INFO - 'XPC_SERVICE_NAME': '0', 11:57:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:57:20 INFO - Cleaning up... 11:57:21 INFO - Return code: 0 11:57:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:57:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:57:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:21 INFO - 'HOME': '/Users/cltbld', 11:57:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:21 INFO - 'LOGNAME': 'cltbld', 11:57:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:21 INFO - 'MOZ_NO_REMOTE': '1', 11:57:21 INFO - 'NO_EM_RESTART': '1', 11:57:21 INFO - 'PAGER': '/bin/cat', 11:57:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:21 INFO - 'PWD': '/builds/slave/test', 11:57:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:21 INFO - 'SHELL': '/bin/bash', 11:57:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:21 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:21 INFO - 'USER': 'cltbld', 11:57:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:21 INFO - 'XPC_FLAGS': '0x0', 11:57:21 INFO - 'XPC_SERVICE_NAME': '0', 11:57:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:22 INFO - Downloading blobuploader-1.2.4.tar.gz 11:57:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:57:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:57:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:23 INFO - Downloading docopt-0.6.1.tar.gz 11:57:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:57:23 INFO - Installing collected packages: blobuploader, requests, docopt 11:57:23 INFO - Running setup.py install for blobuploader 11:57:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:57:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:57:23 INFO - Running setup.py install for requests 11:57:24 INFO - Running setup.py install for docopt 11:57:24 INFO - Successfully installed blobuploader requests docopt 11:57:24 INFO - Cleaning up... 11:57:24 INFO - Return code: 0 11:57:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57: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')]} 11:57:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57: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')]} 11:57:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:24 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:24 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 11:57:24 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 11:57:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:24 INFO - 'HOME': '/Users/cltbld', 11:57:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:24 INFO - 'LOGNAME': 'cltbld', 11:57:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:24 INFO - 'MOZ_NO_REMOTE': '1', 11:57:24 INFO - 'NO_EM_RESTART': '1', 11:57:24 INFO - 'PAGER': '/bin/cat', 11:57:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:24 INFO - 'PWD': '/builds/slave/test', 11:57:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:24 INFO - 'SHELL': '/bin/bash', 11:57:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:24 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:24 INFO - 'USER': 'cltbld', 11:57:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:24 INFO - 'XPC_FLAGS': '0x0', 11:57:24 INFO - 'XPC_SERVICE_NAME': '0', 11:57:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:24 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-QTv9jf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:24 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-yhkNIa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:24 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ITWNsh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-md7QPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-SdeNmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-kVDUqe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-mOf9ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-rFDzYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GKS3cU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Cms_yJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-DsMypK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:25 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-GDmf_e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:26 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-hosl8m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:26 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-PCHWVU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:26 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-HyDEpL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:26 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-pcfbLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:26 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-qu66q8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:57:26 INFO - Running setup.py install for manifestparser 11:57:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:57:26 INFO - Running setup.py install for mozcrash 11:57:26 INFO - Running setup.py install for mozdebug 11:57:26 INFO - Running setup.py install for mozdevice 11:57:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozfile 11:57:27 INFO - Running setup.py install for mozhttpd 11:57:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozinfo 11:57:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozInstall 11:57:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for mozleak 11:57:27 INFO - Running setup.py install for mozlog 11:57:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:57:27 INFO - Running setup.py install for moznetwork 11:57:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:57:28 INFO - Running setup.py install for mozprocess 11:57:28 INFO - Running setup.py install for mozprofile 11:57:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:57:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:57:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:57:28 INFO - Running setup.py install for mozrunner 11:57:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:57:28 INFO - Running setup.py install for mozscreenshot 11:57:28 INFO - Running setup.py install for moztest 11:57:28 INFO - Running setup.py install for mozversion 11:57:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:57:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:57:29 INFO - Cleaning up... 11:57:29 INFO - Return code: 0 11:57:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:29 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:29 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 11:57:29 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 11:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:29 INFO - 'HOME': '/Users/cltbld', 11:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:29 INFO - 'LOGNAME': 'cltbld', 11:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:29 INFO - 'MOZ_NO_REMOTE': '1', 11:57:29 INFO - 'NO_EM_RESTART': '1', 11:57:29 INFO - 'PAGER': '/bin/cat', 11:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:29 INFO - 'PWD': '/builds/slave/test', 11:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:29 INFO - 'SHELL': '/bin/bash', 11:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:29 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:29 INFO - 'USER': 'cltbld', 11:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:29 INFO - 'XPC_FLAGS': '0x0', 11:57:29 INFO - 'XPC_SERVICE_NAME': '0', 11:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-sAsJaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-zUIQM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-2gaP68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-JLNx4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-F9tCMu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-WBFgng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:57:29 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)) 11:57:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:57:29 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-0RrH2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-8lpKQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:57:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-9G1JUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-QUjktC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-SKdYIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-ufbHZb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-JwRU73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-Qu3YJn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-8iIXiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:57:30 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)) 11:57:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:57:30 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-QpRVOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:57:31 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)) 11:57:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:57:31 INFO - Running setup.py (path:/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/pip-_ZOm6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 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)) 11:57:31 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:57:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:32 INFO - Downloading blessings-1.6.tar.gz 11:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:57:32 INFO - Installing collected packages: blessings 11:57:32 INFO - Running setup.py install for blessings 11:57:32 INFO - Successfully installed blessings 11:57:32 INFO - Cleaning up... 11:57:32 INFO - Return code: 0 11:57:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:57:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:33 INFO - Reading from file tmpfile_stdout 11:57:33 INFO - Current package versions: 11:57:33 INFO - blessings == 1.6 11:57:33 INFO - blobuploader == 1.2.4 11:57:33 INFO - docopt == 0.6.1 11:57:33 INFO - functools32 == 3.2.3-2 11:57:33 INFO - jsonschema == 2.5.1 11:57:33 INFO - manifestparser == 1.1 11:57:33 INFO - mozInstall == 1.13 11:57:33 INFO - mozcrash == 1.0 11:57:33 INFO - mozdebug == 0.1 11:57:33 INFO - mozdevice == 0.51 11:57:33 INFO - mozfile == 1.2 11:57:33 INFO - mozhttpd == 0.7 11:57:33 INFO - mozinfo == 0.10 11:57:33 INFO - mozleak == 0.1 11:57:33 INFO - mozlog == 3.4 11:57:33 INFO - moznetwork == 0.27 11:57:33 INFO - mozprocess == 0.25 11:57:33 INFO - mozprofile == 0.28 11:57:33 INFO - mozrunner == 6.13 11:57:33 INFO - mozscreenshot == 0.1 11:57:33 INFO - mozsystemmonitor == 0.3 11:57:33 INFO - moztest == 0.8 11:57:33 INFO - mozversion == 1.4 11:57:33 INFO - psutil == 3.1.1 11:57:33 INFO - requests == 1.2.3 11:57:33 INFO - wsgiref == 0.1.2 11:57:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:33 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:33 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 11:57:33 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 11:57:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:33 INFO - 'HOME': '/Users/cltbld', 11:57:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:33 INFO - 'LOGNAME': 'cltbld', 11:57:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:33 INFO - 'MOZ_NO_REMOTE': '1', 11:57:33 INFO - 'NO_EM_RESTART': '1', 11:57:33 INFO - 'PAGER': '/bin/cat', 11:57:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:33 INFO - 'PWD': '/builds/slave/test', 11:57:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:33 INFO - 'SHELL': '/bin/bash', 11:57:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:33 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:33 INFO - 'USER': 'cltbld', 11:57:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:33 INFO - 'XPC_FLAGS': '0x0', 11:57:33 INFO - 'XPC_SERVICE_NAME': '0', 11:57:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 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)) 11:57:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:57:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:57:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:57:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:57:34 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)) 11:57:34 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)) 11:57:34 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)) 11:57:34 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)) 11:57:34 INFO - Installing collected packages: marionette-driver 11:57:34 INFO - Running setup.py install for marionette-driver 11:57:35 INFO - Successfully installed marionette-driver 11:57:35 INFO - Cleaning up... 11:57:35 INFO - Return code: 0 11:57:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:57:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:57:35 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 0x100db3c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100db7768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6b95a2610>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, '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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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 11:57:35 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 11:57:35 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 11:57:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:35 INFO - 'HOME': '/Users/cltbld', 11:57:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:35 INFO - 'LOGNAME': 'cltbld', 11:57:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:35 INFO - 'MOZ_NO_REMOTE': '1', 11:57:35 INFO - 'NO_EM_RESTART': '1', 11:57:35 INFO - 'PAGER': '/bin/cat', 11:57:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:35 INFO - 'PWD': '/builds/slave/test', 11:57:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:35 INFO - 'SHELL': '/bin/bash', 11:57:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:35 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:35 INFO - 'USER': 'cltbld', 11:57:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:35 INFO - 'XPC_FLAGS': '0x0', 11:57:35 INFO - 'XPC_SERVICE_NAME': '0', 11:57:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:57:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:57:35 INFO - Cleaning up... 11:57:35 INFO - Return code: 0 11:57:35 INFO - Running post-action listener: _resource_record_post_action 11:57:35 INFO - Running post-action listener: _start_resource_monitoring 11:57:35 INFO - Starting resource monitoring. 11:57:35 INFO - [mozharness: 2017-06-15 18:57:35.492886Z] Finished create-virtualenv step (success) 11:57:35 INFO - [mozharness: 2017-06-15 18:57:35.493924Z] Running install step. 11:57:35 INFO - Running pre-action listener: _resource_record_pre_action 11:57:35 INFO - Running main action method: install 11:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:57:35 INFO - Reading from file tmpfile_stdout 11:57:35 INFO - Detecting whether we're running mozinstall >=1.0... 11:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:57:35 INFO - Reading from file tmpfile_stdout 11:57:35 INFO - Output received: 11:57:35 INFO - Usage: mozinstall [options] installer 11:57:35 INFO - Options: 11:57:35 INFO - -h, --help show this help message and exit 11:57:35 INFO - -d DEST, --destination=DEST 11:57:35 INFO - Directory to install application into. [default: 11:57:35 INFO - "/builds/slave/test"] 11:57:35 INFO - --app=APP Application being installed. [default: firefox] 11:57:35 INFO - mkdir: /builds/slave/test/build/application 11:57:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:57:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-56.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:57:57 INFO - Reading from file tmpfile_stdout 11:57:57 INFO - Output received: 11:57:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:57:57 INFO - Running post-action listener: _resource_record_post_action 11:57:57 INFO - [mozharness: 2017-06-15 18:57:57.970039Z] Finished install step (success) 11:57:57 INFO - [mozharness: 2017-06-15 18:57:57.970175Z] Running setup-mitmproxy step. 11:57:57 INFO - Running pre-action listener: _resource_record_pre_action 11:57:57 INFO - Running main action method: setup_mitmproxy 11:57:57 INFO - Skipping: mitmproxy is not required 11:57:57 INFO - Running post-action listener: _resource_record_post_action 11:57:57 INFO - [mozharness: 2017-06-15 18:57:57.970930Z] Finished setup-mitmproxy step (success) 11:57:57 INFO - [mozharness: 2017-06-15 18:57:57.971064Z] Running run-tests step. 11:57:57 INFO - Running pre-action listener: _resource_record_pre_action 11:57:57 INFO - Running main action method: run_tests 11:57:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:57:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:57:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:57:57 INFO - 2017-06-15 11:57:57.985 screenresolution[1621:7368] starting screenresolution argv=screenresolution get 11:57:57 INFO - 2017-06-15 11:57:57.988 screenresolution[1621:7368] Display 0: 1600x1200x32@60 11:57:57 INFO - 2017-06-15 11:57:57.994 screenresolution[1622:7371] starting screenresolution argv=screenresolution list 11:57:57 INFO - Available Modes on Display 0 11:57:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:57:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:57:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:57:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:57:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:57:58 INFO - Intel Iris: 11:57:58 INFO - Chipset Model: Intel Iris 11:57:58 INFO - Type: GPU 11:57:58 INFO - Bus: Built-In 11:57:58 INFO - VRAM (Dynamic, Max): 1536 MB 11:57:58 INFO - Vendor: Intel (0x8086) 11:57:58 INFO - Device ID: 0x0a2e 11:57:58 INFO - Revision ID: 0x0009 11:57:58 INFO - Displays: 11:57:58 INFO - Display: 11:57:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:57:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:57:58 INFO - Display Serial Number: 1600x1200 60 11:57:58 INFO - Main Display: Yes 11:57:58 INFO - Mirror: Off 11:57:58 INFO - Online: Yes 11:57:58 INFO - Rotation: Supported 11:57:58 INFO - Return code: 0 11:57:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:57:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:57:58 INFO - Python 2.7.3 11:57:58 INFO - Return code: 0 11:57:58 INFO - grabbing minidump binary from tooltool 11:57:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:57:58 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b09d30>, '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 0x100ad3cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100afabe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:57:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:57:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:57:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:57:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:57:58 INFO - Return code: 0 11:57:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:57:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:57:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:57:58 INFO - ENV: RUST_BACKTRACE is now 1 11:57:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:57:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:57:58 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/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 11:57:58 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/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0028 --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 11:57:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YdOF2eNLyh/Render', 11:57:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:57:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:57:58 INFO - 'HOME': '/Users/cltbld', 11:57:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:57:58 INFO - 'LOGNAME': 'cltbld', 11:57:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:57:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:57:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:57:58 INFO - 'MOZ_NO_REMOTE': '1', 11:57:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:57:58 INFO - 'NO_EM_RESTART': '1', 11:57:58 INFO - 'PAGER': '/bin/cat', 11:57:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:57:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:57:58 INFO - 'PWD': '/builds/slave/test', 11:57:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:57:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:57:58 INFO - 'RUST_BACKTRACE': '1', 11:57:58 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:57:58 INFO - 'SHELL': '/bin/bash', 11:57:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.S6T0dYy8Gi/Listeners', 11:57:58 INFO - 'TMPDIR': '/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/', 11:57:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:57:58 INFO - 'USER': 'cltbld', 11:57:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:57:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:57:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:57:58 INFO - 'XPC_FLAGS': '0x0', 11:57:58 INFO - 'XPC_SERVICE_NAME': '0', 11:57:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:57:58 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/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0028', '--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 11:57:58 INFO - mozversion application_buildid: 20170615084212 11:57:58 INFO - mozversion application_changeset: ac2d0008d149be9bd183dd1fb3a127997bf3f14e 11:57:58 INFO - mozversion application_display_name: Nightly 11:57:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:57:58 INFO - mozversion application_name: Firefox 11:57:58 INFO - mozversion application_remotingname: firefox 11:57:58 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:57:58 INFO - mozversion application_vendor: Mozilla 11:57:58 INFO - mozversion application_version: 56.0a1 11:57:58 INFO - mozversion platform_buildid: 20170615084212 11:57:58 INFO - mozversion platform_changeset: ac2d0008d149be9bd183dd1fb3a127997bf3f14e 11:57:58 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:57:58 INFO - mozversion platform_version: 56.0a1 11:57:58 INFO - using testdate: 1497553078 11:57:58 INFO - actual date: 1497553078 11:57:58 INFO - starting webserver on 'localhost:49222' 11:57:58 INFO - SUITE-START | Running 2 tests 11:57:58 INFO - TEST-START | dromaeo_css 11:57:58 INFO - operating with platform_type : mac_ 11:57:58 INFO - Initialising browser for dromaeo_css test... 11:57:58 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpRcd218/profile 11:57:58 INFO - TEST-INFO | started process 1632 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 11:58:32 INFO - TEST-INFO | 1632: killed by SIGTERM 11:58:32 INFO - Browser initialized. 11:58:32 INFO - Running cycle 1/1 for dromaeo_css test... 11:58:32 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmpRcd218/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.224 plugin-container[1640:7871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:58:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.225 plugin-container[1640:7871] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c1f, name = 'com.apple.CFPasteboardClient' 11:58:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.225 plugin-container[1640:7871] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.987 plugin-container[1641:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x953f, name = 'com.apple.tsm.portname' 11:58:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.987 plugin-container[1641:7916] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9d0b, name = 'com.apple.CFPasteboardClient' 11:58:34 INFO - PID 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:58:34 INFO - PID 1639 | 2017-06-15 11:58:34.987 plugin-container[1641:7916] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:58:44 INFO - PID 1639 | RSS: Main: 254349312 11:58:44 INFO - PID 1639 | 12:01:48 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49222/tests/dromaeo/cssquery-ext.html) 12:01:50 INFO - PID 1639 | RSS: Main: 267952128 12:01:50 INFO - PID 1639 | 12:04:52 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-ext.html (next: http://localhost:49222/tests/dromaeo/cssquery-jquery.html) 12:04:53 INFO - PID 1639 | RSS: Main: 275312640 12:04:53 INFO - PID 1639 | 12:07:54 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49222/tests/dromaeo/cssquery-mootools.html) 12:07:55 INFO - PID 1639 | RSS: Main: 280289280 12:07:55 INFO - PID 1639 | 12:10:57 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49222/tests/dromaeo/cssquery-prototype.html) 12:10:57 INFO - PID 1639 | RSS: Main: 280772608 12:10:57 INFO - PID 1639 | 12:14:00 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49222/tests/dromaeo/cssquery-yui.html) 12:14:01 INFO - PID 1639 | RSS: Main: 284303360 12:14:01 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | Cycle 1(1): loaded http://localhost:49222/tests/dromaeo/cssquery-yui.html (next: http://localhost:49222/tests/dromaeo/cssquery-dojo.html) 12:17:03 INFO - PID 1639 | RSS: Main: 281284608 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | __start_tp_report 12:17:03 INFO - PID 1639 | _x_x_mozilla_page_load 12:17:03 INFO - PID 1639 | _x_x_mozilla_page_load_details 12:17:03 INFO - PID 1639 | |i|pagename|runs| 12:17:03 INFO - PID 1639 | |0;dojo.html;3508.5789705235343;3576.141725985763;3601.9819900900493;3619.8009109498985;3622.00394891405;6134.2025536680285;6165.383461653838;6187.814365569035;6236.656983865897;6245.344238854917;6296.464800491952;6319.936800631992;6322.494200463963;6339.841503962403;6340.334264902188;5503.504684578397;5535.944640553583;5554.333479982408;5640.5487560995025;5689.032864413039;5519.69641669708;5602.719864006804;5672.5178359839465;5678.9493943620355;5689.459501347365;4380.474343078819;5436.864078398032;5472.2338872557875;5535.58483113768;5590.133529302964;5459.590530710213;5518.9238098570795;5521.089020311644;5539.944600554023;5562;5442.891142177174;5500.7524661390335;5502.889942201134;5540.889182216333;5562.7774889004395;6163.414475624853;6270.937290627081;6280.717367718464;6294.402031806971;6302.369763023707;6612.07430959666;6796.422304104157;6811.761588344434;6830.180378354579;6848.212455567574;6777.762778302785;6801.047853300542;6827.829304267383;6889.0355350251;6895.069165662621;5234.900670859162;6260.311365749764;6347.777827776098;6348.841278968017;6412.5190610704385;7056.976738372907;7059.976303436076;7077.504574679723;7079;7088.574685518885;6465.418112369909;6539.182602174728;6556;6581.440577550962;6582.045603387482;6793.660316984131;6899.413549848319;6904.378605925491;6927.618980956096;6934.133233345832;5819.214406055213;6368.140301059298;6814.863702725917;6816.182058153054;6850.623215723183;5059.519345662156;5666.838298148869;6768.187817461936;6831.9658401707675;6845.623490708059;4786.659735274129;5047.974760126176;5361.169018802092;5422.322209723785;5485.341758988947;3828.349180639298;4078.7552746835286;4775.95224047762;4845.055214233261;4899.926501102486;6193.969030154821;6332.810015699536;6357.809265722036;6364.299927008025;6369.235691716931;6934.202566704894;6960.860782784417;6966.825829354205;6982.2319544850025;7054.082969214072;5331.893362132813;6218.067289906459;6329.461995730415;6351.301356850743;6351.809445716636;5731.541476681856;5926.7925622603;5930.644161350333;5954.07711804671;5981.461668449773;6003.63978161303;6979.546329488567;7020.894686579808;7034.733747925423;7054.6472676367;6899.48253880971;6936.098307220231;6939.375456208965;6951.478639101986;6954.547954382949;7061.1173603299585;7063.540869843444;7064.081669382947;7069.469789765891;7076.504644674823;5950.285965684146;6527.596566738174;6813.523053386215;6813.557118787263;6816.863662726817;6722.663866806738;6762.256151823394;6785.660716964231;6792.490563207681;6797.6941037654415;6626.138601981712;6641.169853768279;6709.530332876652;6710.563813352116;6729.865402692017;6633;6681.632510211791;6692.5315227935525;6695;6719.126513553207;6351.174347334817;6432.581882177644;6467.288598254094;6508.9349106508325;6584.440322572635;6561.311062338385;6561.934380656132;6567.211934567882;6569.770058047945;6608.966955165193;6388.137601423934;6513.576617519846;6514.902276465951;6529.118568993125;6542.182227221598 12:17:03 INFO - PID 1639 | |1;ext.html;725.7858931615173;731.9788894492184;799.2846402469789;799.3964556759646;834.449263486099;37806.810965945166;38763;38897.66614003155;38899.61100388995;38906.41640375393;30017;38031.809840950824;38360.42459363112;38388.42417363735;38392.42411363825;6099.359567245442;7365.521241119337;7482.738104166356;7484.663190156456;7495.400367970549;7302.306280903305;7320.707171713125;7340.045794046793;7379.040724705781;7395.445341599401;7457.328840404362;7640.274173953466;8147.918520814805;8383.035950865667;8397.454165479256;7646.738288182443;7786.454948153631;8378.078411374743;8418.621162047722;8451.281641060486;8151.959240203761;8212.301954333889;8222.13667564909;8222.588870556448;8266;36411.08972275688;36529.086772830626;36592.26815463676;36593;36609.08477288089;3116.2520994961274;3577.928441431183;3601.7478776485655;3602.873899413508;3613.1870329175727;29788;30393.696063039308;30461.93383231576;30558.930437434585;30579.541306880416;3049.237690577356;3057.434374640676;3108.3783243351195;3121.82829944353;3130.827804470753;251747;257986.71006644846;302460;306943.4652826744;307950;38890.416643750366;47425.28862067072;48069;48074.759626201645;48196;50387;50719.239211411856;50733.23900141501;50740.73148171325;50825.712842922156;14500.637484062983;14514.637134071732;14631.487897923522;14643;15059.397624094914;9778.826540815147;11903.047756179485;12025.939870300768;12092.455839487244;12137.271763694207;13495.25776082325;13575.117617354841;13585.184888906697;13600.775930166332;13683.794743078863;5856.297244330623;5916.2013128228;5928.555358348141;5947.851303717442;5965.492933100735;1017.516679577195;1020.8928062553473;1024.5850430575629;1080.0765345629532;1085.4898197846999;22477.77522224757;23272.4181895454;31103.68896311008;31122.377552449278;31913.883014094394;964.4165280005636;1072.2708558180357;1079.4426790515765;1098.2531878322657;1133.1897693149438;10323.380597164194;12001.099917506222;12711.364431778558;16047.438339658056;16564.887777567506;319311.80688193283;319717;321824;323732;324833;46486.76756616263;56890.862182756944;56993.71503142458;57026.42973570211;57635.71182144062;1483.577180503573;1522.7487464568221;1527.7784721215362;1530;1534.8388419216044;28516.857415712788;29764;30491.085267442013;30556.541651875683;30625.387492250476;39339.409908851965;39481.2103757929;39586.604133958295;39590.01024974399;39761.40357894692;295569;296349;296435;296849.51575241983;296944;28723.28191795222;28905.855470722512;31506.527402089447;34081.829590851885;34167.65832341645;32864.835675821465;40426.59573404228;41120.38319425151;41399.37900931428;41948.580514194466;28797.856010719814;28953;29042.983495577555;30381.936632217767;30947.381052379278;29662;30862.69137308598;31093;31868.521972169972;31962.68037319597;35485.82257088698;37155.8142209298;37645.05887352838;38014;38209.426858596584;28879.711202888542;30193;30581.54127688131;31669.524957125202;31756.682433175374;20190.495237619176;20834.374968750963;21797.019134138685;22487.76317302564;23142 12:17:03 INFO - PID 1639 | |2;jquery.html;487.9168246492787;488.32610996824377;495.7496464285538;501.41585053412786;524.0854708533609;21092;22221.888905554737;22713.54572908541;23028.65457018144;23029.654555181685;25009.624855627135;25183.874080629572;25288.494230115433;25337.61993570098;25343.74656253437;6453.161089058428;6634.402903738663;6635.900461493082;6639.3692599203;6644.700988455531;5766.904288185252;6361.68191590421;6523.249826269992;6531.281559028503;6565.573237739532;5589.357223919261;5740.7703691852275;5870.383609720981;5895.380984996578;6281.120643109968;6252.6561039142825;6297.149884765544;6298.496120310364;6311.2426508819235;6317.905231421532;5726.914096288559;5732.541396688255;5749.1663708762;5753.510951569122;5757.884842303172;26822.731772682415;29357.26606834825;30365.392692146033;30460.08619741411;30901.38197236065;2087.0295312679523;2210.5247371815135;2251.4371407148215;2263.19656521934;2300.1719381022817;19489;19878.006099694958;19983.200671973103;19985.800141998683;20030.89830059346;2159.1039718516954;2162.9783702162936;2199.890005499735;2201.8348623853117;2233.910643574255;167383;174663;183362;184249;197464.0380394281;12173.939130304292;14348.928255358656;14965.92517037408;15159.603714922769;15203.771943420857;19958.10188541519;20163.899180504297;20214.191432342835;20234.291799786937;20272.695909561367;9786.41281523111;9832.655857044969;9978.800423991479;10047.748806279902;10116.988301169971;7685.847122931605;7961.880571791427;8853.690120845868;9260.259179265642;9260.536973151315;7457.552546847207;7481.5511069334925;9263.90736092644;9304.627814887464;9316.767080822898;2602.7787638050977;2611.8955241790495;2612.712601613821;2623.698274698396;2764.944701106007;808.789714674189;917.7934964632905;928.7863791328033;979.6522234606755;979.6962941488162;19470.415887523395;20609.690854637192;22359.66460503094;26035.218943431726;28867.566986495218;1010.3887148275335;1021.8416145497459;1039.95840166394;1040.8542804007445;1042.3433237060603;10160.33957792756;11135.33188008722;11217.439128043565;11411.600593979338;11609.3614851184;597579;599012;608886;614043;616176.9191154016;144937.27531362275;158167;159179.20410397873;160091;161606;862.2283056664376;907.4963395315531;909.3043821476575;911.6216770040492;1154.2439701995038;22640.66039009449;24655.87672061628;24775.389599675964;25031.624525632495;25105.372365690273;70601.29398706152;72520;73488.26511734814;74187;74543.88184177133;94158.58762118711;94799;96385;98442;99525.50237248768;11596.768064638829;11748.471318790846;11764.470598823245;11829.171957963204;11871.109666774853;18065.187066581773;18202.635947281244;18473.104627274053;18772.87362758184;18776.24895004239;12487.562935297221;14214.573562793134;15202.923985380445;15403.61490962735;15405;11313.264637798517;13062.934685326512;13166;13186.208827470382;13413.66465838362;15471.458498952483;15571.287158412792;15579.532614021597;15604.985675931028;15693.450729224422;9436.858447123435;9549.331546791658;11487.597934072268;11516.481758320728;11579.710507237387;13768.105073170213;14664.120152790867;14898.404063837324;14930.402783888523;14951.476698315506 12:17:03 INFO - PID 1639 | |3;mootools.html;1372.9931350343247;1411.9717605647888;1437.539987204095;1439.7768345906381;1452.2883786944396;13219.008574356925;13677.589672309821;13758.931205343984;13779.793303100461;13795.793063104062;12994.805077923838;13250.271235082068;13409.262490563015;13419.19484830911;13429.395677194525;2711.7423844734717;2754.7796176305947;2756.682981457128;2756.807023508355;2767.5433553463654;2264.162259963816;2689.1394753678833;2712.32191952012;2727.4954133485367;2729.6314997475383;2177.8148857347146;2327.767223277676;2355.9646605300936;2359.445530300378;2741.451709658076;2268.8638681679154;2679.5846643770246;2709.945801083977;2727.7681397081174;2730.085421383839;2700.540908045637;2705.2154875086203;2711.227300219448;2713.3623598454346;2716.606092116632;11521.827172592419;13342.86657133426;13344;13439.395227214798;13440.529581464602;7158.427325813923;7469.1410487794055;7489;7535.660895259672;7548.849023019508;4290;4566.18303186271;4709.552592503706;4793.740256625493;4863.902721945541;1396.553103007038;1402.8947828912872;1404.8384435789912;1428.300132934865;1431.813864197658;13538.59384218475;19942.900285498625;20106.99465026743;20114.994250287426;20147.39557813253;6396.488280937514;6456.354364563506;6486.318936511692;6601.458438501211;6609.3060228675295;14185.007049506436;14201.00592958483;14205;14215.147091174562;14263.07290026145;7236.9276307237305;7299.744508942162;7384.335409813142;7390.778276651709;7398.075240594926;5894.263217097863;6319.178506794087;6335.113084168128;6692.99605059245;6728.495362847806;4901.632377571696;5057.974710126499;5060.519250671254;5087.211482220262;5154.149565321755;1355.6610847288177;1405.2130806748253;1480.370842391979;1485.3538710660896;1494.671172342083;756.7540549321493;800.5756948817136;835.4360806455618;841.1042240014365;846.327169899931;6777.152855893013;6963.1296087989;7025.192102908231;7082.893756593655;7244.058272424551;946.7254496196164;962.9422234665942;998.201438848918;998.4009594243541;1017.9745506362252;3490.528778614905;3912.726109172331;4221.493420789464;4267.338562522814;4304.5910638489295;17914.193861276275;18104.185311661007;18272.451826445227;18467.630647386977;18483.630327393377;18232.36186733458;18432;18466.891986480856;18483.260669573334;18529;7412;8182.590870456573;9362.95318523403;9515.57179926905;9545.379550329206;6679.83300417474;7200.459965502503;7957.641906114123;8162.265396114378;8484.872726908978;13196.736065278448;15426.46007389736;16196.595085122966;16348.67302653964;16576.751348729536;15524.84475155234;16022.439214627431;16763.32946682119;16826.411075612294;16899.831001689825;10762.139028877828;10996.12031037531;10998.12015038779;11015.504302306255;11073.944630276797;12152.331621760888;12485.500579976699;12542.1847579907;12619.432125554556;12675.492980280686;9844.261680373857;9911.405315681082;10057.446840423554;10062.044105809937;10285.640002599874;9816.80366392654;9850.507474626384;10145;10199.949000255248;10259.897401025893;9262.675806346746;9300.069993000645;9411.200047995722;9413.435193888388;9466.242700584075;6120.816959313997;6274.933261345491;6692.765753198614;7166.390856777232;7236.38490728294;6869.896951545631;7065.6113913735235;7256.818579535343;7298.671559779928;7335.77992660221 12:17:03 INFO - PID 1639 | |4;prototype.html;391.7571105914333;429.9398084268201;458.8183188936607;462.7408651155353;478.3207844860296;5204.16733322668;6402.423781859632;6418.71115799789;6448.258450278214;6469.288378278391;6007;6202.596831205969;6208.844778880531;6282.277538083122;6418.743250269984;4048.2915489789316;4335.0679603885155;4590.908181836361;4906.337644417994;4948.628852836043;4878.170710979124;4918.2376731606655;4919.13915064864;4930.679505832128;4933.580645645125;4399.494058183318;4559.544045595447;4718.4809670936165;4719.221328480797;4728.27417992432;4234.280172370704;4438.668399480143;4726.621870250372;4741.715497070152;4751.85744427666;4075.49056367954;4129.958700412987;4147.398627199039;4152.626263636287;4160.875173744793;4940.90118197634;6182.536309776785;6203.2866220384785;6205.441510264098;6298.118263443122;5604.3835178130375;5642.492175704166;5644.57665675076;5650.208970744099;5672.432756724335;6309.148264984215;6734.831629209309;6837.521373503857;6857.485688573377;6881;3657.3965295726175;4732.763361831895;4748.264019077049;4757;4766.474728087013;7124.073870396815;7187.245339239357;7196.352328290479;7201.3518783309755;7255.9637201814185;4245.511766146933;4569.9314510282375;4815.518448155157;4815.711057336501;4818.98801251741;4317.517427479212;4903.803847846118;4910.361652985089;4927.384076990376;4929;5308.336457942757;5592.888142237132;5607.887842243133;5625.831225063254;5635.830925072254;5333.6266461348105;6670.199576050905;6802.523823332319;6809.523333366716;6814.931850681529;1386.3969173409598;1577.5503981365255;1665.641886994302;1674.4306935641696;1680.2186983052695;1023.9657945474975;1060.1148041385375;1070.4380200394855;1102.2174256277972;1131.3268605179906;5295.470452954751;5565.638233514808;5670.149477578314;6074.878502429927;6404.583702059351;6429.54993150475;6652.800415987432;7168.749093781693;7187.310981919245;7283.344498995116;5349.625526213128;5623.353314368818;5679.403662615366;6167.259928808482;6252.406021427957;3065.3409516953516;3445.707114895212;3611.5846677632408;3649.3431182386894;3682.723795715332;6720;7001.194862590867;7102.573845569283;7192.4246060315045;7197.280271972761;7090.680919358641;7243.414509817569;7247.9637601811655;7268.418526517866;7273.309035641606;6273.843153921097;7186.281371862981;7230.74692385764;7234.63826808668;7257.52826066304;5772;6057.545684073624;6592.538917068578;6933.92524158736;6982.895256571257;6878.896816547655;6896.517243793087;6906.482013848881;6917.858553338844;6925.203601585882;6346.587471814318;7166.928330716834;7170.605616691132;7224.385927196037;7227.349538541556;4105.8973525662095;4205.969537463341;4797.9040419192115;4799.02460474508;4803.855884323476;7020.326724030428;7031.7890463286185;7098.3256590623805;7121.643917804193;7185.676644550903;5213.713245771367;5626.690532020778;5919.378465261086;5971.701414929149;6402.943514320085;4876.463589005278;5580.77676892936;5768.798092066758;6177.598456100339;6839.179298484214;6639.834004149935;6681.129283800544;6699.564528305645;6704.229013663296;6721.831954200989;5840.707964601668;5923.378045305181;6723.798286051427;6724.831879203059;6745.392914637706;5463.562914966873;6297.307296197506;6414.390632889868;6583.045458408408;6624.503162262946 12:17:03 INFO - PID 1639 | |5;yui.html;822.2887202569779;828.5567221536478;839.4249938791925;857.2713193785283;863.1929146248257;16900.492985210432;16924.4076457324;16984.660306793856;17111.57221069474;17154.656906861856;12799.360031998385;13254.271015094166;13321.333933303344;15816.52550423489;16199.91900040501;971.6842026341432;972.0522490571707;986.9555869985832;988.5106872098321;996.6163027234494;1015.664830605902;1015.8476228565701;1017.7150397888603;1018.5162048027165;1019.4291196929706;1045.4929359260752;1048.491481631408;1048.96328628498;1054.5465449856558;1055.145332280855;951.524237881063;970.3207754571757;1044.984325235122;1051.8422236664485;1052.878918924322;775.1976704111217;778.8831675248701;778.9610519474004;779.2830595851773;779.3180966654178;17629.7355539667;17701.645967080585;17771.555711107198;17837.108144592847;17844.732329014947;17073.14634268294;17950.743447958517;18023;18080.4575862723;18310.992895390747;14510.201938893357;14943.327550260155;15086.245687715571;15117.168555729431;15144.697106057818;3551.875684351061;3560.768550044265;3584.13980644646;3585.8027808470524;3592.8562857485667;150051;158050.20974895052;159297;169335;170027;13196.736065278641;13607.863921360859;13669.179849209078;13709.177449352872;13880;22956;23152.884235578713;23426.7657323428;23470.295891123293;23531.0587576495;7766.766996990099;7813.257740514642;7856.842863142705;7858;7867;3849.3071247175776;3893.5133108361456;3917.0794863207125;3925.2345792570177;3936.173403585222;4617.792199351037;4625.907481850344;4655.906881862344;4699.083678682692;4714.622830173578;795.6220795122387;896.6895984006601;947.4031360243141;955;957.5690939077443;627.2692313454774;631.255118959632;632.2792017100509;636.1062706896818;640.9070684750685;11453.54185832574;11642.24325418845;11655.59205427806;11958.58144964922;12585.119041667178;618.8824123416626;631.1637080867869;634.514596333805;650.9218893732688;651.771879842061;4551.362809206714;4645.001324715135;5109.6678715883345;5668.603197776116;5806.593538452352;95118;101135.98864011413;135427.32286338703;137561.31219343838;138226;113101;117645.4117729423;119095;121597;124409;103781;104137.47931260447;104269.95730042603;105499.94500055055;106509;9136.908630913606;10083.495825208856;11265.26775759573;11280.435978201222;11920.642380728592;45280.09439811119;46194.722910425764;49256;50381;50713.74643126761;83527;83670.58164709137;85510;86765.83085422663;89555.55222223848;16918.830811692216;17516.649667006845;17907.10464476797;17997.460076197734;18039.729404058686;18519.259229630665;18846.623067538298;18943.242270309034;19038.809611904257;19376.903115484332;15642.843571564139;16517.426642788112;18008.279668813102;18153;18455.354062607745;16701.91649041747;17855.821441785934;18000.729989050436;18064.819351806313;18455.446336609388;16682.582935426224;17246.82753172452;17272;17483.82516174822;17595.384161554284;11756.294622322688;12479.376031198586;13013.349332533524;13025.804612931002;13138.868611314147;11643.952044316053;12572.501023250345;12738.617841464771;13523.391447385087;14414.639634009234 12:17:03 INFO - PID 1639 | __end_tp_report 12:17:03 INFO - PID 1639 | __start_cc_report 12:17:03 INFO - PID 1639 | _x_x_mozilla_cycle_collect,464 12:17:03 INFO - PID 1639 | __end_cc_report 12:17:03 INFO - PID 1639 | __startTimestamp1497554223306__endTimestamp 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | ------- Summary: start ------- 12:17:03 INFO - PID 1639 | Number of tests: 6 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#0] dojo.html Cycles:165 Average:6213.03 Median:6412.52 stddev:799.27 (12.5%) stddev-sans-first:773.05 12:17:03 INFO - PID 1639 | Values: 3508.6 3576.1 3602.0 3619.8 3622.0 6134.2 6165.4 6187.8 6236.7 6245.3 6296.5 6319.9 6322.5 6339.8 6340.3 5503.5 5535.9 5554.3 5640.5 5689.0 5519.7 5602.7 5672.5 5678.9 5689.5 4380.5 5436.9 5472.2 5535.6 5590.1 5459.6 5518.9 5521.1 5539.9 5562.0 5442.9 5500.8 5502.9 5540.9 5562.8 6163.4 6270.9 6280.7 6294.4 6302.4 6612.1 6796.4 6811.8 6830.2 6848.2 6777.8 6801.0 6827.8 6889.0 6895.1 5234.9 6260.3 6347.8 6348.8 6412.5 7057.0 7060.0 7077.5 7079.0 7088.6 6465.4 6539.2 6556.0 6581.4 6582.0 6793.7 6899.4 6904.4 6927.6 6934.1 5819.2 6368.1 6814.9 6816.2 6850.6 5059.5 5666.8 6768.2 6832.0 6845.6 4786.7 5048.0 5361.2 5422.3 5485.3 3828.3 4078.8 4776.0 4845.1 4899.9 6194.0 6332.8 6357.8 6364.3 6369.2 6934.2 6960.9 6966.8 6982.2 7054.1 5331.9 6218.1 6329.5 6351.3 6351.8 5731.5 5926.8 5930.6 5954.1 5981.5 6003.6 6979.5 7020.9 7034.7 7054.6 6899.5 6936.1 6939.4 6951.5 6954.5 7061.1 7063.5 7064.1 7069.5 7076.5 5950.3 6527.6 6813.5 6813.6 6816.9 6722.7 6762.3 6785.7 6792.5 6797.7 6626.1 6641.2 6709.5 6710.6 6729.9 6633.0 6681.6 6692.5 6695.0 6719.1 6351.2 6432.6 6467.3 6508.9 6584.4 6561.3 6561.9 6567.2 6569.8 6609.0 6388.1 6513.6 6514.9 6529.1 6542.2 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#1] ext.html Cycles:180 Average:45161.51 Median:322778.00 stddev:79204.57 (24.5%) stddev-sans-first:79356.49 12:17:03 INFO - PID 1639 | Values: 725.8 732.0 799.3 799.4 834.4 37806.8 38763.0 38897.7 38899.6 38906.4 30017.0 38031.8 38360.4 38388.4 38392.4 6099.4 7365.5 7482.7 7484.7 7495.4 7302.3 7320.7 7340.0 7379.0 7395.4 7457.3 7640.3 8147.9 8383.0 8397.5 7646.7 7786.5 8378.1 8418.6 8451.3 8152.0 8212.3 8222.1 8222.6 8266.0 36411.1 36529.1 36592.3 36593.0 36609.1 3116.3 3577.9 3601.7 3602.9 3613.2 29788.0 30393.7 30461.9 30558.9 30579.5 3049.2 3057.4 3108.4 3121.8 3130.8 251747.0 257986.7 302460.0 306943.5 307950.0 38890.4 47425.3 48069.0 48074.8 48196.0 50387.0 50719.2 50733.2 50740.7 50825.7 14500.6 14514.6 14631.5 14643.0 15059.4 9778.8 11903.0 12025.9 12092.5 12137.3 13495.3 13575.1 13585.2 13600.8 13683.8 5856.3 5916.2 5928.6 5947.9 5965.5 1017.5 1020.9 1024.6 1080.1 1085.5 22477.8 23272.4 31103.7 31122.4 31913.9 964.4 1072.3 1079.4 1098.3 1133.2 10323.4 12001.1 12711.4 16047.4 16564.9 319311.8 319717.0 321824.0 323732.0 324833.0 46486.8 56890.9 56993.7 57026.4 57635.7 1483.6 1522.7 1527.8 1530.0 1534.8 28516.9 29764.0 30491.1 30556.5 30625.4 39339.4 39481.2 39586.6 39590.0 39761.4 295569.0 296349.0 296435.0 296849.5 296944.0 28723.3 28905.9 31506.5 34081.8 34167.7 32864.8 40426.6 41120.4 41399.4 41948.6 28797.9 28953.0 29043.0 30381.9 30947.4 29662.0 30862.7 31093.0 31868.5 31962.7 35485.8 37155.8 37645.1 38014.0 38209.4 28879.7 30193.0 30581.5 31669.5 31756.7 20190.5 20834.4 21797.0 22487.8 23142.0 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#2] jquery.html Cycles:180 Average:40909.17 Median:24715.63 stddev:103965.47 (420.6%) stddev-sans-first:104212.83 12:17:03 INFO - PID 1639 | Values: 487.9 488.3 495.7 501.4 524.1 21092.0 22221.9 22713.5 23028.7 23029.7 25009.6 25183.9 25288.5 25337.6 25343.7 6453.2 6634.4 6635.9 6639.4 6644.7 5766.9 6361.7 6523.2 6531.3 6565.6 5589.4 5740.8 5870.4 5895.4 6281.1 6252.7 6297.1 6298.5 6311.2 6317.9 5726.9 5732.5 5749.2 5753.5 5757.9 26822.7 29357.3 30365.4 30460.1 30901.4 2087.0 2210.5 2251.4 2263.2 2300.2 19489.0 19878.0 19983.2 19985.8 20030.9 2159.1 2163.0 2199.9 2201.8 2233.9 167383.0 174663.0 183362.0 184249.0 197464.0 12173.9 14348.9 14965.9 15159.6 15203.8 19958.1 20163.9 20214.2 20234.3 20272.7 9786.4 9832.7 9978.8 10047.7 10117.0 7685.8 7961.9 8853.7 9260.3 9260.5 7457.6 7481.6 9263.9 9304.6 9316.8 2602.8 2611.9 2612.7 2623.7 2764.9 808.8 917.8 928.8 979.7 979.7 19470.4 20609.7 22359.7 26035.2 28867.6 1010.4 1021.8 1040.0 1040.9 1042.3 10160.3 11135.3 11217.4 11411.6 11609.4 597579.0 599012.0 608886.0 614043.0 616176.9 144937.3 158167.0 159179.2 160091.0 161606.0 862.2 907.5 909.3 911.6 1154.2 22640.7 24655.9 24775.4 25031.6 25105.4 70601.3 72520.0 73488.3 74187.0 74543.9 94158.6 94799.0 96385.0 98442.0 99525.5 11596.8 11748.5 11764.5 11829.2 11871.1 18065.2 18202.6 18473.1 18772.9 18776.2 12487.6 14214.6 15202.9 15403.6 15405.0 11313.3 13062.9 13166.0 13186.2 13413.7 15471.5 15571.3 15579.5 15605.0 15693.5 9436.9 9549.3 11487.6 11516.5 11579.7 13768.1 14664.1 14898.4 14930.4 14951.5 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#3] mootools.html Cycles:180 Average:8124.69 Median:2712.84 stddev:5420.12 (199.8%) stddev-sans-first:5411.58 12:17:03 INFO - PID 1639 | Values: 1373.0 1412.0 1437.5 1439.8 1452.3 13219.0 13677.6 13758.9 13779.8 13795.8 12994.8 13250.3 13409.3 13419.2 13429.4 2711.7 2754.8 2756.7 2756.8 2767.5 2264.2 2689.1 2712.3 2727.5 2729.6 2177.8 2327.8 2356.0 2359.4 2741.5 2268.9 2679.6 2709.9 2727.8 2730.1 2700.5 2705.2 2711.2 2713.4 2716.6 11521.8 13342.9 13344.0 13439.4 13440.5 7158.4 7469.1 7489.0 7535.7 7548.8 4290.0 4566.2 4709.6 4793.7 4863.9 1396.6 1402.9 1404.8 1428.3 1431.8 13538.6 19942.9 20107.0 20115.0 20147.4 6396.5 6456.4 6486.3 6601.5 6609.3 14185.0 14201.0 14205.0 14215.1 14263.1 7236.9 7299.7 7384.3 7390.8 7398.1 5894.3 6319.2 6335.1 6693.0 6728.5 4901.6 5058.0 5060.5 5087.2 5154.1 1355.7 1405.2 1480.4 1485.4 1494.7 756.8 800.6 835.4 841.1 846.3 6777.2 6963.1 7025.2 7082.9 7244.1 946.7 962.9 998.2 998.4 1018.0 3490.5 3912.7 4221.5 4267.3 4304.6 17914.2 18104.2 18272.5 18467.6 18483.6 18232.4 18432.0 18466.9 18483.3 18529.0 7412.0 8182.6 9363.0 9515.6 9545.4 6679.8 7200.5 7957.6 8162.3 8484.9 13196.7 15426.5 16196.6 16348.7 16576.8 15524.8 16022.4 16763.3 16826.4 16899.8 10762.1 10996.1 10998.1 11015.5 11073.9 12152.3 12485.5 12542.2 12619.4 12675.5 9844.3 9911.4 10057.4 10062.0 10285.6 9816.8 9850.5 10145.0 10199.9 10259.9 9262.7 9300.1 9411.2 9413.4 9466.2 6120.8 6274.9 6692.8 7166.4 7236.4 6869.9 7065.6 7256.8 7298.7 7335.8 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#4] prototype.html Cycles:180 Average:5453.39 Median:5806.35 stddev:1695.15 (29.2%) stddev-sans-first:1656.79 12:17:03 INFO - PID 1639 | Values: 391.8 429.9 458.8 462.7 478.3 5204.2 6402.4 6418.7 6448.3 6469.3 6007.0 6202.6 6208.8 6282.3 6418.7 4048.3 4335.1 4590.9 4906.3 4948.6 4878.2 4918.2 4919.1 4930.7 4933.6 4399.5 4559.5 4718.5 4719.2 4728.3 4234.3 4438.7 4726.6 4741.7 4751.9 4075.5 4130.0 4147.4 4152.6 4160.9 4940.9 6182.5 6203.3 6205.4 6298.1 5604.4 5642.5 5644.6 5650.2 5672.4 6309.1 6734.8 6837.5 6857.5 6881.0 3657.4 4732.8 4748.3 4757.0 4766.5 7124.1 7187.2 7196.4 7201.4 7256.0 4245.5 4569.9 4815.5 4815.7 4819.0 4317.5 4903.8 4910.4 4927.4 4929.0 5308.3 5592.9 5607.9 5625.8 5635.8 5333.6 6670.2 6802.5 6809.5 6814.9 1386.4 1577.6 1665.6 1674.4 1680.2 1024.0 1060.1 1070.4 1102.2 1131.3 5295.5 5565.6 5670.1 6074.9 6404.6 6429.5 6652.8 7168.7 7187.3 7283.3 5349.6 5623.4 5679.4 6167.3 6252.4 3065.3 3445.7 3611.6 3649.3 3682.7 6720.0 7001.2 7102.6 7192.4 7197.3 7090.7 7243.4 7248.0 7268.4 7273.3 6273.8 7186.3 7230.7 7234.6 7257.5 5772.0 6057.5 6592.5 6933.9 6982.9 6878.9 6896.5 6906.5 6917.9 6925.2 6346.6 7166.9 7170.6 7224.4 7227.3 4105.9 4206.0 4797.9 4799.0 4803.9 7020.3 7031.8 7098.3 7121.6 7185.7 5213.7 5626.7 5919.4 5971.7 6402.9 4876.5 5580.8 5768.8 6177.6 6839.2 6639.8 6681.1 6699.6 6704.2 6721.8 5840.7 5923.4 6723.8 6724.8 6745.4 5463.6 6297.3 6414.4 6583.0 6624.5 12:17:03 INFO - PID 1639 | 12:17:03 INFO - PID 1639 | [#5] yui.html Cycles:180 Average:25837.44 Median:17999.10 stddev:39731.26 (220.7%) stddev-sans-first:39798.32 12:17:03 INFO - PID 1639 | Values: 822.3 828.6 839.4 857.3 863.2 16900.5 16924.4 16984.7 17111.6 17154.7 12799.4 13254.3 13321.3 15816.5 16199.9 971.7 972.1 987.0 988.5 996.6 1015.7 1015.8 1017.7 1018.5 1019.4 1045.5 1048.5 1049.0 1054.5 1055.1 951.5 970.3 1045.0 1051.8 1052.9 775.2 778.9 779.0 779.3 779.3 17629.7 17701.6 17771.6 17837.1 17844.7 17073.1 17950.7 18023.0 18080.5 18311.0 14510.2 14943.3 15086.2 15117.2 15144.7 3551.9 3560.8 3584.1 3585.8 3592.9 150051.0 158050.2 159297.0 169335.0 170027.0 13196.7 13607.9 13669.2 13709.2 13880.0 22956.0 23152.9 23426.8 23470.3 23531.1 7766.8 7813.3 7856.8 7858.0 7867.0 3849.3 3893.5 3917.1 3925.2 3936.2 4617.8 4625.9 4655.9 4699.1 4714.6 795.6 896.7 947.4 955.0 957.6 627.3 631.3 632.3 636.1 640.9 11453.5 11642.2 11655.6 11958.6 12585.1 618.9 631.2 634.5 650.9 651.8 4551.4 4645.0 5109.7 5668.6 5806.6 95118.0 101136.0 135427.3 137561.3 138226.0 113101.0 117645.4 119095.0 121597.0 124409.0 103781.0 104137.5 104270.0 105499.9 106509.0 9136.9 10083.5 11265.3 11280.4 11920.6 45280.1 46194.7 49256.0 50381.0 50713.7 83527.0 83670.6 85510.0 86765.8 89555.6 16918.8 17516.6 17907.1 17997.5 18039.7 18519.3 18846.6 18943.2 19038.8 19376.9 15642.8 16517.4 18008.3 18153.0 18455.4 16701.9 17855.8 18000.7 18064.8 18455.4 16682.6 17246.8 17272.0 17483.8 17595.4 11756.3 12479.4 13013.3 13025.8 13138.9 11644.0 12572.5 12738.6 13523.4 14414.6 12:17:03 INFO - PID 1639 | -------- Summary: end -------- 12:17:03 INFO - PID 1639 | 12:17:03 INFO - TEST-INFO | 1639: exit 0 12:17:03 INFO - TEST-OK | dromaeo_css | took 1145299ms 12:17:03 INFO - TEST-START | kraken 12:17:03 INFO - operating with platform_type : mac_ 12:17:03 INFO - Initialising browser for kraken test... 12:17:03 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp1Yo2zu/profile 12:17:03 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49222/getInfo.html -foreground) 12:17:37 INFO - TEST-INFO | 1652: killed by SIGTERM 12:17:37 INFO - Browser initialized. 12:17:37 INFO - Running cycle 1/1 for kraken test... 12:17:37 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/T/tmp1Yo2zu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:39 INFO - PID 1656 | 2017-06-15 12:17:39.443 plugin-container[1657:10693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9737, name = 'com.apple.tsm.portname' 12:17:39 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:39 INFO - PID 1656 | 2017-06-15 12:17:39.444 plugin-container[1657:10693] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e17, name = 'com.apple.CFPasteboardClient' 12:17:39 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:39 INFO - PID 1656 | 2017-06-15 12:17:39.444 plugin-container[1657:10693] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:40 INFO - PID 1656 | 2017-06-15 12:17:40.223 plugin-container[1658:10740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 12:17:40 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:40 INFO - PID 1656 | 2017-06-15 12:17:40.223 plugin-container[1658:10740] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x480f, name = 'com.apple.CFPasteboardClient' 12:17:40 INFO - PID 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:17:40 INFO - PID 1656 | 2017-06-15 12:17:40.223 plugin-container[1658:10740] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:17:49 INFO - PID 1656 | RSS: Main: 254218240 12:17:49 INFO - PID 1656 | 12:17:52 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection) 12:17:53 INFO - PID 1656 | RSS: Main: 266268672 12:17:53 INFO - PID 1656 | 12:17:56 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-dft) 12:17:56 INFO - PID 1656 | RSS: Main: 278863872 12:17:56 INFO - PID 1656 | 12:18:00 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-fft) 12:18:00 INFO - PID 1656 | RSS: Main: 281665536 12:18:00 INFO - PID 1656 | 12:18:02 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator) 12:18:03 INFO - PID 1656 | RSS: Main: 282521600 12:18:03 INFO - PID 1656 | 12:18:05 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:18:05 INFO - PID 1656 | RSS: Main: 282857472 12:18:05 INFO - PID 1656 | 12:18:08 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom) 12:18:09 INFO - PID 1656 | RSS: Main: 286953472 12:18:09 INFO - PID 1656 | 12:18:12 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate) 12:18:12 INFO - PID 1656 | RSS: Main: 288432128 12:18:12 INFO - PID 1656 | 12:18:15 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial) 12:18:16 INFO - PID 1656 | RSS: Main: 288546816 12:18:16 INFO - PID 1656 | 12:18:18 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:18:18 INFO - PID 1656 | RSS: Main: 288260096 12:18:18 INFO - PID 1656 | 12:18:20 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:18:21 INFO - PID 1656 | RSS: Main: 288292864 12:18:21 INFO - PID 1656 | 12:18:23 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:18:23 INFO - PID 1656 | RSS: Main: 288477184 12:18:23 INFO - PID 1656 | 12:18:26 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:18:27 INFO - PID 1656 | RSS: Main: 288927744 12:18:27 INFO - PID 1656 | 12:18:30 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:18:31 INFO - PID 1656 | RSS: Main: 289116160 12:18:31 INFO - PID 1656 | 12:18:32 INFO - PID 1656 | Cycle 1(1): loaded http://localhost:49222/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49222/tests/kraken/driver.html?testName=ai-astar) 12:18:33 INFO - PID 1656 | RSS: Main: 290852864 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | __start_tp_report 12:18:33 INFO - PID 1656 | _x_x_mozilla_page_load 12:18:33 INFO - PID 1656 | _x_x_mozilla_page_load_details 12:18:33 INFO - PID 1656 | |i|pagename|runs| 12:18:33 INFO - PID 1656 | |0;ai-astar;108;113;117;153;170;96;166;135;154;181 12:18:33 INFO - PID 1656 | |1;audio-beat-detection;134;113;128;141;118;114;127;185;175;111 12:18:33 INFO - PID 1656 | |2;audio-dft;207;203;213;230;228;206;200;202;197;239 12:18:33 INFO - PID 1656 | |3;audio-fft;72;79;72;126;62;58;62;62;107;57 12:18:33 INFO - PID 1656 | |4;audio-oscillator;66;88;150;103;103;84;109;72;73;109 12:18:33 INFO - PID 1656 | |5;imaging-gaussian-blur;126;91;90;89;91;89;97;91;91;89 12:18:33 INFO - PID 1656 | |6;imaging-darkroom;97;96;97;96;98;97;97;97;97;96 12:18:33 INFO - PID 1656 | |7;imaging-desaturate;99;96;91;118;93;123;77;136;140;117 12:18:33 INFO - PID 1656 | |8;json-parse-financial;53;49;52;54;54;52;51;53;54;53 12:18:33 INFO - PID 1656 | |9;json-stringify-tinderbox;56;56;61;60;62;81;82;82;85;56 12:18:33 INFO - PID 1656 | |10;stanford-crypto-aes;81;82;81;80;78;80;82;81;80;84 12:18:33 INFO - PID 1656 | |11;stanford-crypto-ccm;145;136;136;134;136;137;139;139;120;158 12:18:33 INFO - PID 1656 | |12;stanford-crypto-pbkdf2;224;171;227;225;181;177;181;176;177;176 12:18:33 INFO - PID 1656 | |13;stanford-crypto-sha256-iterative;65;67;67;63;74;94;62;63;88;90 12:18:33 INFO - PID 1656 | __end_tp_report 12:18:33 INFO - PID 1656 | __start_cc_report 12:18:33 INFO - PID 1656 | _x_x_mozilla_cycle_collect,1160 12:18:33 INFO - PID 1656 | __end_cc_report 12:18:33 INFO - PID 1656 | __startTimestamp1497554313191__endTimestamp 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | ------- Summary: start ------- 12:18:33 INFO - PID 1656 | Number of tests: 14 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#0] ai-astar Cycles:10 Average:139.30 Median:160.00 stddev:29.55 (18.5%) stddev-sans-first:29.09 12:18:33 INFO - PID 1656 | Values: 108.0 113.0 117.0 153.0 170.0 96.0 166.0 135.0 154.0 181.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:131.00 stddev:25.90 (19.8%) stddev-sans-first:27.47 12:18:33 INFO - PID 1656 | Values: 134.0 113.0 128.0 141.0 118.0 114.0 127.0 185.0 175.0 111.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#2] audio-dft Cycles:10 Average:212.50 Median:210.00 stddev:14.60 (7.0%) stddev-sans-first:15.35 12:18:33 INFO - PID 1656 | Values: 207.0 203.0 213.0 230.0 228.0 206.0 200.0 202.0 197.0 239.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#3] audio-fft Cycles:10 Average:75.70 Median:62.00 stddev:23.03 (37.1%) stddev-sans-first:24.39 12:18:33 INFO - PID 1656 | Values: 72.0 79.0 72.0 126.0 62.0 58.0 62.0 62.0 107.0 57.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#4] audio-oscillator Cycles:10 Average:95.70 Median:69.00 stddev:24.86 (36.0%) stddev-sans-first:23.94 12:18:33 INFO - PID 1656 | Values: 66.0 88.0 150.0 103.0 103.0 84.0 109.0 72.0 73.0 109.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:91.00 stddev:11.35 (12.5%) stddev-sans-first:2.47 12:18:33 INFO - PID 1656 | Values: 126.0 91.0 90.0 89.0 91.0 89.0 97.0 91.0 91.0 89.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#6] imaging-darkroom Cycles:10 Average:96.80 Median:97.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 12:18:33 INFO - PID 1656 | Values: 97.0 96.0 97.0 96.0 98.0 97.0 97.0 97.0 97.0 96.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#7] imaging-desaturate Cycles:10 Average:109.00 Median:84.00 stddev:20.83 (24.8%) stddev-sans-first:21.77 12:18:33 INFO - PID 1656 | Values: 99.0 96.0 91.0 118.0 93.0 123.0 77.0 136.0 140.0 117.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:53.00 stddev:1.58 (3.0%) stddev-sans-first:1.67 12:18:33 INFO - PID 1656 | Values: 53.0 49.0 52.0 54.0 54.0 52.0 51.0 53.0 54.0 53.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#9] json-stringify-tinderbox Cycles:10 Average:68.10 Median:71.50 stddev:12.61 (17.6%) stddev-sans-first:12.59 12:18:33 INFO - PID 1656 | Values: 56.0 56.0 61.0 60.0 62.0 81.0 82.0 82.0 85.0 56.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#10] stanford-crypto-aes Cycles:10 Average:80.90 Median:81.00 stddev:1.60 (2.0%) stddev-sans-first:1.69 12:18:33 INFO - PID 1656 | Values: 81.0 82.0 81.0 80.0 78.0 80.0 82.0 81.0 80.0 84.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:138.00 stddev:9.45 (6.8%) stddev-sans-first:9.68 12:18:33 INFO - PID 1656 | Values: 145.0 136.0 136.0 134.0 136.0 137.0 139.0 139.0 120.0 158.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.50 Median:181.00 stddev:23.52 (13.0%) stddev-sans-first:21.81 12:18:33 INFO - PID 1656 | Values: 224.0 171.0 227.0 225.0 181.0 177.0 181.0 176.0 177.0 176.0 12:18:33 INFO - PID 1656 | 12:18:33 INFO - PID 1656 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:70.50 stddev:12.53 (17.8%) stddev-sans-first:12.92 12:18:33 INFO - PID 1656 | Values: 65.0 67.0 67.0 63.0 74.0 94.0 62.0 63.0 88.0 90.0 12:18:33 INFO - PID 1656 | -------- Summary: end -------- 12:18:33 INFO - PID 1656 | 12:18:34 INFO - TEST-INFO | 1656: exit 0 12:18:34 INFO - TEST-OK | kraken | took 90144ms 12:18:34 INFO - SUITE-END | took 1235s 12:18:34 INFO - Completed test suite (00:20:35) 12:18:34 INFO - javascript benchmark 12:18:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8289.881974037067, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3508.5789705235343, 3576.141725985763, 3601.9819900900493, 3619.8009109498985, 3622.00394891405, 6134.2025536680285, 6165.383461653838, 6187.814365569035, 6236.656983865897, 6245.344238854917, 6296.464800491952, 6319.936800631992, 6322.494200463963, 6339.841503962403, 6340.334264902188, 5503.504684578397, 5535.944640553583, 5554.333479982408, 5640.5487560995025, 5689.032864413039, 5519.69641669708, 5602.719864006804, 5672.5178359839465, 5678.9493943620355, 5689.459501347365, 4380.474343078819, 5436.864078398032, 5472.2338872557875, 5535.58483113768, 5590.133529302964, 5459.590530710213, 5518.9238098570795, 5521.089020311644, 5539.944600554023, 5562.0, 5442.891142177174, 5500.7524661390335, 5502.889942201134, 5540.889182216333, 5562.7774889004395, 6163.414475624853, 6270.937290627081, 6280.717367718464, 6294.402031806971, 6302.369763023707, 6612.07430959666, 6796.422304104157, 6811.761588344434, 6830.180378354579, 6848.212455567574, 6777.762778302785, 6801.047853300542, 6827.829304267383, 6889.0355350251, 6895.069165662621, 5234.900670859162, 6260.311365749764, 6347.777827776098, 6348.841278968017, 6412.5190610704385, 7056.976738372907, 7059.976303436076, 7077.504574679723, 7079.0, 7088.574685518885, 6465.418112369909, 6539.182602174728, 6556.0, 6581.440577550962, 6582.045603387482, 6793.660316984131, 6899.413549848319, 6904.378605925491, 6927.618980956096, 6934.133233345832, 5819.214406055213, 6368.140301059298, 6814.863702725917, 6816.182058153054, 6850.623215723183, 5059.519345662156, 5666.838298148869, 6768.187817461936, 6831.9658401707675, 6845.623490708059, 4786.659735274129, 5047.974760126176, 5361.169018802092, 5422.322209723785, 5485.341758988947, 3828.349180639298, 4078.7552746835286, 4775.95224047762, 4845.055214233261, 4899.926501102486, 6193.969030154821, 6332.810015699536, 6357.809265722036, 6364.299927008025, 6369.235691716931, 6934.202566704894, 6960.860782784417, 6966.825829354205, 6982.2319544850025, 7054.082969214072, 5331.893362132813, 6218.067289906459, 6329.461995730415, 6351.301356850743, 6351.809445716636, 5731.541476681856, 5926.7925622603, 5930.644161350333, 5954.07711804671, 5981.461668449773, 6003.63978161303, 6979.546329488567, 7020.894686579808, 7034.733747925423, 7054.6472676367, 6899.48253880971, 6936.098307220231, 6939.375456208965, 6951.478639101986, 6954.547954382949, 7061.1173603299585, 7063.540869843444, 7064.081669382947, 7069.469789765891, 7076.504644674823, 5950.285965684146, 6527.596566738174, 6813.523053386215, 6813.557118787263, 6816.863662726817, 6722.663866806738, 6762.256151823394, 6785.660716964231, 6792.490563207681, 6797.6941037654415, 6626.138601981712, 6641.169853768279, 6709.530332876652, 6710.563813352116, 6729.865402692017, 6633.0, 6681.632510211791, 6692.5315227935525, 6695.0, 6719.126513553207, 6351.174347334817, 6432.581882177644, 6467.288598254094, 6508.9349106508325, 6584.440322572635, 6561.311062338385, 6561.934380656132, 6567.211934567882, 6569.770058047945, 6608.966955165193, 6388.137601423934, 6513.576617519846, 6514.902276465951, 6529.118568993125, 6542.182227221598], "value": 6158.011470381405, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [725.7858931615173, 731.9788894492184, 799.2846402469789, 799.3964556759646, 834.449263486099, 37806.810965945166, 38763.0, 38897.66614003155, 38899.61100388995, 38906.41640375393, 30017.0, 38031.809840950824, 38360.42459363112, 38388.42417363735, 38392.42411363825, 6099.359567245442, 7365.521241119337, 7482.738104166356, 7484.663190156456, 7495.400367970549, 7302.306280903305, 7320.707171713125, 7340.045794046793, 7379.040724705781, 7395.445341599401, 7457.328840404362, 7640.274173953466, 8147.918520814805, 8383.035950865667, 8397.454165479256, 7646.738288182443, 7786.454948153631, 8378.078411374743, 8418.621162047722, 8451.281641060486, 8151.959240203761, 8212.301954333889, 8222.13667564909, 8222.588870556448, 8266.0, 36411.08972275688, 36529.086772830626, 36592.26815463676, 36593.0, 36609.08477288089, 3116.2520994961274, 3577.928441431183, 3601.7478776485655, 3602.873899413508, 3613.1870329175727, 29788.0, 30393.696063039308, 30461.93383231576, 30558.930437434585, 30579.541306880416, 3049.237690577356, 3057.434374640676, 3108.3783243351195, 3121.82829944353, 3130.827804470753, 251747.0, 257986.71006644846, 302460.0, 306943.4652826744, 307950.0, 38890.416643750366, 47425.28862067072, 48069.0, 48074.759626201645, 48196.0, 50387.0, 50719.239211411856, 50733.23900141501, 50740.73148171325, 50825.712842922156, 14500.637484062983, 14514.637134071732, 14631.487897923522, 14643.0, 15059.397624094914, 9778.826540815147, 11903.047756179485, 12025.939870300768, 12092.455839487244, 12137.271763694207, 13495.25776082325, 13575.117617354841, 13585.184888906697, 13600.775930166332, 13683.794743078863, 5856.297244330623, 5916.2013128228, 5928.555358348141, 5947.851303717442, 5965.492933100735, 1017.516679577195, 1020.8928062553473, 1024.5850430575629, 1080.0765345629532, 1085.4898197846999, 22477.77522224757, 23272.4181895454, 31103.68896311008, 31122.377552449278, 31913.883014094394, 964.4165280005636, 1072.2708558180357, 1079.4426790515765, 1098.2531878322657, 1133.1897693149438, 10323.380597164194, 12001.099917506222, 12711.364431778558, 16047.438339658056, 16564.887777567506, 319311.80688193283, 319717.0, 321824.0, 323732.0, 324833.0, 46486.76756616263, 56890.862182756944, 56993.71503142458, 57026.42973570211, 57635.71182144062, 1483.577180503573, 1522.7487464568221, 1527.7784721215362, 1530.0, 1534.8388419216044, 28516.857415712788, 29764.0, 30491.085267442013, 30556.541651875683, 30625.387492250476, 39339.409908851965, 39481.2103757929, 39586.604133958295, 39590.01024974399, 39761.40357894692, 295569.0, 296349.0, 296435.0, 296849.51575241983, 296944.0, 28723.28191795222, 28905.855470722512, 31506.527402089447, 34081.829590851885, 34167.65832341645, 32864.835675821465, 40426.59573404228, 41120.38319425151, 41399.37900931428, 41948.580514194466, 28797.856010719814, 28953.0, 29042.983495577555, 30381.936632217767, 30947.381052379278, 29662.0, 30862.69137308598, 31093.0, 31868.521972169972, 31962.68037319597, 35485.82257088698, 37155.8142209298, 37645.05887352838, 38014.0, 38209.426858596584, 28879.711202888542, 30193.0, 30581.54127688131, 31669.524957125202, 31756.682433175374, 20190.495237619176, 20834.374968750963, 21797.019134138685, 22487.76317302564, 23142.0], "value": 17324.016724408244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [487.9168246492787, 488.32610996824377, 495.7496464285538, 501.41585053412786, 524.0854708533609, 21092.0, 22221.888905554737, 22713.54572908541, 23028.65457018144, 23029.654555181685, 25009.624855627135, 25183.874080629572, 25288.494230115433, 25337.61993570098, 25343.74656253437, 6453.161089058428, 6634.402903738663, 6635.900461493082, 6639.3692599203, 6644.700988455531, 5766.904288185252, 6361.68191590421, 6523.249826269992, 6531.281559028503, 6565.573237739532, 5589.357223919261, 5740.7703691852275, 5870.383609720981, 5895.380984996578, 6281.120643109968, 6252.6561039142825, 6297.149884765544, 6298.496120310364, 6311.2426508819235, 6317.905231421532, 5726.914096288559, 5732.541396688255, 5749.1663708762, 5753.510951569122, 5757.884842303172, 26822.731772682415, 29357.26606834825, 30365.392692146033, 30460.08619741411, 30901.38197236065, 2087.0295312679523, 2210.5247371815135, 2251.4371407148215, 2263.19656521934, 2300.1719381022817, 19489.0, 19878.006099694958, 19983.200671973103, 19985.800141998683, 20030.89830059346, 2159.1039718516954, 2162.9783702162936, 2199.890005499735, 2201.8348623853117, 2233.910643574255, 167383.0, 174663.0, 183362.0, 184249.0, 197464.0380394281, 12173.939130304292, 14348.928255358656, 14965.92517037408, 15159.603714922769, 15203.771943420857, 19958.10188541519, 20163.899180504297, 20214.191432342835, 20234.291799786937, 20272.695909561367, 9786.41281523111, 9832.655857044969, 9978.800423991479, 10047.748806279902, 10116.988301169971, 7685.847122931605, 7961.880571791427, 8853.690120845868, 9260.259179265642, 9260.536973151315, 7457.552546847207, 7481.5511069334925, 9263.90736092644, 9304.627814887464, 9316.767080822898, 2602.7787638050977, 2611.8955241790495, 2612.712601613821, 2623.698274698396, 2764.944701106007, 808.789714674189, 917.7934964632905, 928.7863791328033, 979.6522234606755, 979.6962941488162, 19470.415887523395, 20609.690854637192, 22359.66460503094, 26035.218943431726, 28867.566986495218, 1010.3887148275335, 1021.8416145497459, 1039.95840166394, 1040.8542804007445, 1042.3433237060603, 10160.33957792756, 11135.33188008722, 11217.439128043565, 11411.600593979338, 11609.3614851184, 597579.0, 599012.0, 608886.0, 614043.0, 616176.9191154016, 144937.27531362275, 158167.0, 159179.20410397873, 160091.0, 161606.0, 862.2283056664376, 907.4963395315531, 909.3043821476575, 911.6216770040492, 1154.2439701995038, 22640.66039009449, 24655.87672061628, 24775.389599675964, 25031.624525632495, 25105.372365690273, 70601.29398706152, 72520.0, 73488.26511734814, 74187.0, 74543.88184177133, 94158.58762118711, 94799.0, 96385.0, 98442.0, 99525.50237248768, 11596.768064638829, 11748.471318790846, 11764.470598823245, 11829.171957963204, 11871.109666774853, 18065.187066581773, 18202.635947281244, 18473.104627274053, 18772.87362758184, 18776.24895004239, 12487.562935297221, 14214.573562793134, 15202.923985380445, 15403.61490962735, 15405.0, 11313.264637798517, 13062.934685326512, 13166.0, 13186.208827470382, 13413.66465838362, 15471.458498952483, 15571.287158412792, 15579.532614021597, 15604.985675931028, 15693.450729224422, 9436.858447123435, 9549.331546791658, 11487.597934072268, 11516.481758320728, 11579.710507237387, 13768.105073170213, 14664.120152790867, 14898.404063837324, 14930.402783888523, 14951.476698315506], "value": 11697.711711322352, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.9931350343247, 1411.9717605647888, 1437.539987204095, 1439.7768345906381, 1452.2883786944396, 13219.008574356925, 13677.589672309821, 13758.931205343984, 13779.793303100461, 13795.793063104062, 12994.805077923838, 13250.271235082068, 13409.262490563015, 13419.19484830911, 13429.395677194525, 2711.7423844734717, 2754.7796176305947, 2756.682981457128, 2756.807023508355, 2767.5433553463654, 2264.162259963816, 2689.1394753678833, 2712.32191952012, 2727.4954133485367, 2729.6314997475383, 2177.8148857347146, 2327.767223277676, 2355.9646605300936, 2359.445530300378, 2741.451709658076, 2268.8638681679154, 2679.5846643770246, 2709.945801083977, 2727.7681397081174, 2730.085421383839, 2700.540908045637, 2705.2154875086203, 2711.227300219448, 2713.3623598454346, 2716.606092116632, 11521.827172592419, 13342.86657133426, 13344.0, 13439.395227214798, 13440.529581464602, 7158.427325813923, 7469.1410487794055, 7489.0, 7535.660895259672, 7548.849023019508, 4290.0, 4566.18303186271, 4709.552592503706, 4793.740256625493, 4863.902721945541, 1396.553103007038, 1402.8947828912872, 1404.8384435789912, 1428.300132934865, 1431.813864197658, 13538.59384218475, 19942.900285498625, 20106.99465026743, 20114.994250287426, 20147.39557813253, 6396.488280937514, 6456.354364563506, 6486.318936511692, 6601.458438501211, 6609.3060228675295, 14185.007049506436, 14201.00592958483, 14205.0, 14215.147091174562, 14263.07290026145, 7236.9276307237305, 7299.744508942162, 7384.335409813142, 7390.778276651709, 7398.075240594926, 5894.263217097863, 6319.178506794087, 6335.113084168128, 6692.99605059245, 6728.495362847806, 4901.632377571696, 5057.974710126499, 5060.519250671254, 5087.211482220262, 5154.149565321755, 1355.6610847288177, 1405.2130806748253, 1480.370842391979, 1485.3538710660896, 1494.671172342083, 756.7540549321493, 800.5756948817136, 835.4360806455618, 841.1042240014365, 846.327169899931, 6777.152855893013, 6963.1296087989, 7025.192102908231, 7082.893756593655, 7244.058272424551, 946.7254496196164, 962.9422234665942, 998.201438848918, 998.4009594243541, 1017.9745506362252, 3490.528778614905, 3912.726109172331, 4221.493420789464, 4267.338562522814, 4304.5910638489295, 17914.193861276275, 18104.185311661007, 18272.451826445227, 18467.630647386977, 18483.630327393377, 18232.36186733458, 18432.0, 18466.891986480856, 18483.260669573334, 18529.0, 7412.0, 8182.590870456573, 9362.95318523403, 9515.57179926905, 9545.379550329206, 6679.83300417474, 7200.459965502503, 7957.641906114123, 8162.265396114378, 8484.872726908978, 13196.736065278448, 15426.46007389736, 16196.595085122966, 16348.67302653964, 16576.751348729536, 15524.84475155234, 16022.439214627431, 16763.32946682119, 16826.411075612294, 16899.831001689825, 10762.139028877828, 10996.12031037531, 10998.12015038779, 11015.504302306255, 11073.944630276797, 12152.331621760888, 12485.500579976699, 12542.1847579907, 12619.432125554556, 12675.492980280686, 9844.261680373857, 9911.405315681082, 10057.446840423554, 10062.044105809937, 10285.640002599874, 9816.80366392654, 9850.507474626384, 10145.0, 10199.949000255248, 10259.897401025893, 9262.675806346746, 9300.069993000645, 9411.200047995722, 9413.435193888388, 9466.242700584075, 6120.816959313997, 6274.933261345491, 6692.765753198614, 7166.390856777232, 7236.38490728294, 6869.896951545631, 7065.6113913735235, 7256.818579535343, 7298.671559779928, 7335.77992660221], "value": 5993.198536244382, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.7571105914333, 429.9398084268201, 458.8183188936607, 462.7408651155353, 478.3207844860296, 5204.16733322668, 6402.423781859632, 6418.71115799789, 6448.258450278214, 6469.288378278391, 6007.0, 6202.596831205969, 6208.844778880531, 6282.277538083122, 6418.743250269984, 4048.2915489789316, 4335.0679603885155, 4590.908181836361, 4906.337644417994, 4948.628852836043, 4878.170710979124, 4918.2376731606655, 4919.13915064864, 4930.679505832128, 4933.580645645125, 4399.494058183318, 4559.544045595447, 4718.4809670936165, 4719.221328480797, 4728.27417992432, 4234.280172370704, 4438.668399480143, 4726.621870250372, 4741.715497070152, 4751.85744427666, 4075.49056367954, 4129.958700412987, 4147.398627199039, 4152.626263636287, 4160.875173744793, 4940.90118197634, 6182.536309776785, 6203.2866220384785, 6205.441510264098, 6298.118263443122, 5604.3835178130375, 5642.492175704166, 5644.57665675076, 5650.208970744099, 5672.432756724335, 6309.148264984215, 6734.831629209309, 6837.521373503857, 6857.485688573377, 6881.0, 3657.3965295726175, 4732.763361831895, 4748.264019077049, 4757.0, 4766.474728087013, 7124.073870396815, 7187.245339239357, 7196.352328290479, 7201.3518783309755, 7255.9637201814185, 4245.511766146933, 4569.9314510282375, 4815.518448155157, 4815.711057336501, 4818.98801251741, 4317.517427479212, 4903.803847846118, 4910.361652985089, 4927.384076990376, 4929.0, 5308.336457942757, 5592.888142237132, 5607.887842243133, 5625.831225063254, 5635.830925072254, 5333.6266461348105, 6670.199576050905, 6802.523823332319, 6809.523333366716, 6814.931850681529, 1386.3969173409598, 1577.5503981365255, 1665.641886994302, 1674.4306935641696, 1680.2186983052695, 1023.9657945474975, 1060.1148041385375, 1070.4380200394855, 1102.2174256277972, 1131.3268605179906, 5295.470452954751, 5565.638233514808, 5670.149477578314, 6074.878502429927, 6404.583702059351, 6429.54993150475, 6652.800415987432, 7168.749093781693, 7187.310981919245, 7283.344498995116, 5349.625526213128, 5623.353314368818, 5679.403662615366, 6167.259928808482, 6252.406021427957, 3065.3409516953516, 3445.707114895212, 3611.5846677632408, 3649.3431182386894, 3682.723795715332, 6720.0, 7001.194862590867, 7102.573845569283, 7192.4246060315045, 7197.280271972761, 7090.680919358641, 7243.414509817569, 7247.9637601811655, 7268.418526517866, 7273.309035641606, 6273.843153921097, 7186.281371862981, 7230.74692385764, 7234.63826808668, 7257.52826066304, 5772.0, 6057.545684073624, 6592.538917068578, 6933.92524158736, 6982.895256571257, 6878.896816547655, 6896.517243793087, 6906.482013848881, 6917.858553338844, 6925.203601585882, 6346.587471814318, 7166.928330716834, 7170.605616691132, 7224.385927196037, 7227.349538541556, 4105.8973525662095, 4205.969537463341, 4797.9040419192115, 4799.02460474508, 4803.855884323476, 7020.326724030428, 7031.7890463286185, 7098.3256590623805, 7121.643917804193, 7185.676644550903, 5213.713245771367, 5626.690532020778, 5919.378465261086, 5971.701414929149, 6402.943514320085, 4876.463589005278, 5580.77676892936, 5768.798092066758, 6177.598456100339, 6839.179298484214, 6639.834004149935, 6681.129283800544, 6699.564528305645, 6704.229013663296, 6721.831954200989, 5840.707964601668, 5923.378045305181, 6723.798286051427, 6724.831879203059, 6745.392914637706, 5463.562914966873, 6297.307296197506, 6414.390632889868, 6583.045458408408, 6624.503162262946], "value": 4938.4671935669, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [822.2887202569779, 828.5567221536478, 839.4249938791925, 857.2713193785283, 863.1929146248257, 16900.492985210432, 16924.4076457324, 16984.660306793856, 17111.57221069474, 17154.656906861856, 12799.360031998385, 13254.271015094166, 13321.333933303344, 15816.52550423489, 16199.91900040501, 971.6842026341432, 972.0522490571707, 986.9555869985832, 988.5106872098321, 996.6163027234494, 1015.664830605902, 1015.8476228565701, 1017.7150397888603, 1018.5162048027165, 1019.4291196929706, 1045.4929359260752, 1048.491481631408, 1048.96328628498, 1054.5465449856558, 1055.145332280855, 951.524237881063, 970.3207754571757, 1044.984325235122, 1051.8422236664485, 1052.878918924322, 775.1976704111217, 778.8831675248701, 778.9610519474004, 779.2830595851773, 779.3180966654178, 17629.7355539667, 17701.645967080585, 17771.555711107198, 17837.108144592847, 17844.732329014947, 17073.14634268294, 17950.743447958517, 18023.0, 18080.4575862723, 18310.992895390747, 14510.201938893357, 14943.327550260155, 15086.245687715571, 15117.168555729431, 15144.697106057818, 3551.875684351061, 3560.768550044265, 3584.13980644646, 3585.8027808470524, 3592.8562857485667, 150051.0, 158050.20974895052, 159297.0, 169335.0, 170027.0, 13196.736065278641, 13607.863921360859, 13669.179849209078, 13709.177449352872, 13880.0, 22956.0, 23152.884235578713, 23426.7657323428, 23470.295891123293, 23531.0587576495, 7766.766996990099, 7813.257740514642, 7856.842863142705, 7858.0, 7867.0, 3849.3071247175776, 3893.5133108361456, 3917.0794863207125, 3925.2345792570177, 3936.173403585222, 4617.792199351037, 4625.907481850344, 4655.906881862344, 4699.083678682692, 4714.622830173578, 795.6220795122387, 896.6895984006601, 947.4031360243141, 955.0, 957.5690939077443, 627.2692313454774, 631.255118959632, 632.2792017100509, 636.1062706896818, 640.9070684750685, 11453.54185832574, 11642.24325418845, 11655.59205427806, 11958.58144964922, 12585.119041667178, 618.8824123416626, 631.1637080867869, 634.514596333805, 650.9218893732688, 651.771879842061, 4551.362809206714, 4645.001324715135, 5109.6678715883345, 5668.603197776116, 5806.593538452352, 95118.0, 101135.98864011413, 135427.32286338703, 137561.31219343838, 138226.0, 113101.0, 117645.4117729423, 119095.0, 121597.0, 124409.0, 103781.0, 104137.47931260447, 104269.95730042603, 105499.94500055055, 106509.0, 9136.908630913606, 10083.495825208856, 11265.26775759573, 11280.435978201222, 11920.642380728592, 45280.09439811119, 46194.722910425764, 49256.0, 50381.0, 50713.74643126761, 83527.0, 83670.58164709137, 85510.0, 86765.83085422663, 89555.55222223848, 16918.830811692216, 17516.649667006845, 17907.10464476797, 17997.460076197734, 18039.729404058686, 18519.259229630665, 18846.623067538298, 18943.242270309034, 19038.809611904257, 19376.903115484332, 15642.843571564139, 16517.426642788112, 18008.279668813102, 18153.0, 18455.354062607745, 16701.91649041747, 17855.821441785934, 18000.729989050436, 18064.819351806313, 18455.446336609388, 16682.582935426224, 17246.82753172452, 17272.0, 17483.82516174822, 17595.384161554284, 11756.294622322688, 12479.376031198586, 13013.349332533524, 13025.804612931002, 13138.868611314147, 11643.952044316053, 12572.501023250345, 12738.617841464771, 13523.391447385087, 14414.639634009234], "value": 8786.603506030604, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1562.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 113.0, 117.0, 153.0, 170.0, 96.0, 166.0, 135.0, 154.0, 181.0], "value": 139.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 113.0, 128.0, 141.0, 118.0, 114.0, 127.0, 185.0, 175.0, 111.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [207.0, 203.0, 213.0, 230.0, 228.0, 206.0, 200.0, 202.0, 197.0, 239.0], "value": 212.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 72.0, 126.0, 62.0, 58.0, 62.0, 62.0, 107.0, 57.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 88.0, 150.0, 103.0, 103.0, 84.0, 109.0, 72.0, 73.0, 109.0], "value": 95.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 91.0, 90.0, 89.0, 91.0, 89.0, 97.0, 91.0, 91.0, 89.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 97.0, 96.0, 98.0, 97.0, 97.0, 97.0, 97.0, 96.0], "value": 96.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 96.0, 91.0, 118.0, 93.0, 123.0, 77.0, 136.0, 140.0, 117.0], "value": 109.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 49.0, 52.0, 54.0, 54.0, 52.0, 51.0, 53.0, 54.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 61.0, 60.0, 62.0, 81.0, 82.0, 82.0, 85.0, 56.0], "value": 68.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 81.0, 80.0, 78.0, 80.0, 82.0, 81.0, 80.0, 84.0], "value": 80.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 136.0, 136.0, 134.0, 136.0, 137.0, 139.0, 139.0, 120.0, 158.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 171.0, 227.0, 225.0, 181.0, 177.0, 181.0, 176.0, 177.0, 176.0], "value": 191.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 67.0, 63.0, 74.0, 94.0, 62.0, 63.0, 88.0, 90.0], "value": 73.3, "unit": "score"}]}]} 12:18:34 INFO - Return code: 0 12:18:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:18:34 INFO - # TBPL SUCCESS # 12:18:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:34 INFO - 2017-06-15 12:18:34.583 screenresolution[1664:11125] starting screenresolution argv=screenresolution get 12:18:34 INFO - 2017-06-15 12:18:34.585 screenresolution[1664:11125] Display 0: 1600x1200x32@60 12:18:34 INFO - 2017-06-15 12:18:34.591 screenresolution[1665:11128] starting screenresolution argv=screenresolution list 12:18:34 INFO - Available Modes on Display 0 12:18:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:34 INFO - Intel Iris: 12:18:34 INFO - Chipset Model: Intel Iris 12:18:34 INFO - Type: GPU 12:18:34 INFO - Bus: Built-In 12:18:34 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:34 INFO - Vendor: Intel (0x8086) 12:18:34 INFO - Device ID: 0x0a2e 12:18:34 INFO - Revision ID: 0x0009 12:18:34 INFO - Displays: 12:18:34 INFO - Display: 12:18:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:34 INFO - Display Serial Number: 1600x1200 60 12:18:34 INFO - Main Display: Yes 12:18:34 INFO - Mirror: Off 12:18:34 INFO - Online: Yes 12:18:34 INFO - Rotation: Supported 12:18:34 INFO - Return code: 0 12:18:34 INFO - Running post-action listener: _resource_record_post_action 12:18:34 INFO - [mozharness: 2017-06-15 19:18:34.832108Z] Finished run-tests step (success) 12:18:34 INFO - Running post-run listener: _resource_record_post_run 12:18:34 INFO - Total resource usage - Wall time: 1258s; CPU: 53.0%; Read bytes: 49030656; Write bytes: 576821248; Read time: 551; Write time: 1811 12:18:34 INFO - TinderboxPrint: CPU usage
53.4% 12:18:34 INFO - TinderboxPrint: I/O read bytes / time
49,030,656 / 551 12:18:34 INFO - TinderboxPrint: I/O write bytes / time
576,821,248 / 1,811 12:18:34 INFO - TinderboxPrint: CPU idle
2,346.8 (46.6%) 12:18:34 INFO - TinderboxPrint: CPU system
1,022.8 (20.3%) 12:18:34 INFO - TinderboxPrint: CPU user
1,664.2 (33.1%) 12:18:34 INFO - TinderboxPrint: Swap in / out
295,256,064 / 0 12:18:34 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 244806144; Write bytes: 240228352; Read time: 17905; Write time: 502 12:18:34 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 12:18:34 INFO - run-tests - Wall time: 1237s; CPU: 53.0%; Read bytes: 46933504; Write bytes: 318300160; Read time: 523; Write time: 1264 12:18:35 INFO - Running post-run listener: _upload_blobber_files 12:18:35 INFO - Blob upload gear active. 12:18:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:18:35 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:18:35 INFO - (blobuploader) - INFO - Open directory for files ... 12:18:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 12:18:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 12:18:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:36 INFO - (blobuploader) - INFO - Done attempting. 12:18:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 12:18:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 12:18:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:37 INFO - (blobuploader) - INFO - Done attempting. 12:18:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 12:18:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:39 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 12:18:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:39 INFO - (blobuploader) - INFO - Done attempting. 12:18:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:18:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:18:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:18:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:18:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:18:41 INFO - (blobuploader) - INFO - Done attempting. 12:18:41 INFO - (blobuploader) - INFO - Iteration through files over. 12:18:41 INFO - Return code: 0 12:18:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:18:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:18:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b15606f6e494ab452bfa9843115844a76e3beeee402e66a19e2aaca8ebf770cffde33ae205be344fc30d90f16d327f3c18449b42c17138adc02a0875e772e996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b04ac464edfcddd5f1b75646ede9077a181824d035f7463efc222479d3f8d1970204c6ec854c6ca70943bd341119c9b4b600eb2d62be80838f26a6bcc60108ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4aa8f3f33e92ad5e38acd98c53e04bfb5daa0fbe431eea65b9ab2338fb36287a438412c6754f197ff28f72e582598bcdaba9e46e75be2ec976b6b1f654c709bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1fbdea8b951d571b53cbc140e20ea5305d0a2fd9e22f44310b70ea7a578a7dddefd9fae9a86779734f85d8534f60a99449927a456780fb8167d0d0c032def44"} 12:18:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:18:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:18:41 INFO - Contents: 12:18:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b15606f6e494ab452bfa9843115844a76e3beeee402e66a19e2aaca8ebf770cffde33ae205be344fc30d90f16d327f3c18449b42c17138adc02a0875e772e996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b04ac464edfcddd5f1b75646ede9077a181824d035f7463efc222479d3f8d1970204c6ec854c6ca70943bd341119c9b4b600eb2d62be80838f26a6bcc60108ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4aa8f3f33e92ad5e38acd98c53e04bfb5daa0fbe431eea65b9ab2338fb36287a438412c6754f197ff28f72e582598bcdaba9e46e75be2ec976b6b1f654c709bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1fbdea8b951d571b53cbc140e20ea5305d0a2fd9e22f44310b70ea7a578a7dddefd9fae9a86779734f85d8534f60a99449927a456780fb8167d0d0c032def44"} 12:18:41 INFO - Running post-run listener: copy_logs_to_upload_dir 12:18:41 INFO - Copying logs to upload dir... 12:18:41 INFO - mkdir: /builds/slave/test/build/upload/logs 12:18:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1341.111259 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 21 secs) (at 2017-06-15 12:18:41.441435) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 12:18:41.444566) ========= 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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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/b15606f6e494ab452bfa9843115844a76e3beeee402e66a19e2aaca8ebf770cffde33ae205be344fc30d90f16d327f3c18449b42c17138adc02a0875e772e996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b04ac464edfcddd5f1b75646ede9077a181824d035f7463efc222479d3f8d1970204c6ec854c6ca70943bd341119c9b4b600eb2d62be80838f26a6bcc60108ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4aa8f3f33e92ad5e38acd98c53e04bfb5daa0fbe431eea65b9ab2338fb36287a438412c6754f197ff28f72e582598bcdaba9e46e75be2ec976b6b1f654c709bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1fbdea8b951d571b53cbc140e20ea5305d0a2fd9e22f44310b70ea7a578a7dddefd9fae9a86779734f85d8534f60a99449927a456780fb8167d0d0c032def44"} build_url:https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010794 build_url: 'https://queue.taskcluster.net/v1/task/Do2Rzs7hQKavgDHc6_BeAQ/artifacts/public/build/firefox-56.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b15606f6e494ab452bfa9843115844a76e3beeee402e66a19e2aaca8ebf770cffde33ae205be344fc30d90f16d327f3c18449b42c17138adc02a0875e772e996", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b04ac464edfcddd5f1b75646ede9077a181824d035f7463efc222479d3f8d1970204c6ec854c6ca70943bd341119c9b4b600eb2d62be80838f26a6bcc60108ec", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4aa8f3f33e92ad5e38acd98c53e04bfb5daa0fbe431eea65b9ab2338fb36287a438412c6754f197ff28f72e582598bcdaba9e46e75be2ec976b6b1f654c709bb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f1fbdea8b951d571b53cbc140e20ea5305d0a2fd9e22f44310b70ea7a578a7dddefd9fae9a86779734f85d8534f60a99449927a456780fb8167d0d0c032def44"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-15 12:18:41.477429) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:18:41.477797) ========= 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.YdOF2eNLyh/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.S6T0dYy8Gi/Listeners TMPDIR=/var/folders/z0/m8w9z33x49b2v28r7k58cfh800000w/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.005047 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-15 12:18:41.527317) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 12:18:41.527670) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-15 12:18:41.527973) ========= ========= Total master_lag: 0.18 =========