builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0116 starttime: 1496168647.61 results: success (0) revision: 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.613282) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.613743) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.614120) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.639172) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.639533) ========= 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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-30 11:24:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.7M=0.001s 2017-05-30 11:24:07 (15.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127251 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.791496) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.791978) ========= 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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.030280 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:07.843936) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 11:24:07.844275) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b --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 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-30 11:24:07,939 truncating revision to first 12 chars 2017-05-30 11:24:07,939 Setting DEBUG logging. 2017-05-30 11:24:07,939 attempt 1/10 2017-05-30 11:24:07,939 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/39d5cc0fda5e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-30 11:24:09,212 unpacking tar archive at: mozilla-central-39d5cc0fda5e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.562901 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-30 11:24:09.430947) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:09.431345) ========= script_repo_revision: 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:09.431844) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:09.432134) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-30 11:24:09.451675) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 11:24:09.452085) ========= /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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:24:09 INFO - MultiFileLogger online at 20170530 11:24:09 in /builds/slave/test 11:24:09 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:24:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:24:09 INFO - {'append_to_log': False, 11:24:09 INFO - 'base_work_dir': '/builds/slave/test', 11:24:09 INFO - 'blob_upload_branch': 'Firefox', 11:24:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:24:09 INFO - 'branch': 'Firefox', 11:24:09 INFO - 'buildbot_json_path': 'buildprops.json', 11:24:09 INFO - 'config_files': ('talos/mac_config.py',), 11:24:09 INFO - 'default_actions': ('clobber', 11:24:09 INFO - 'read-buildbot-config', 11:24:09 INFO - 'download-and-extract', 11:24:09 INFO - 'populate-webroot', 11:24:09 INFO - 'create-virtualenv', 11:24:09 INFO - 'install', 11:24:09 INFO - 'setup-mitmproxy', 11:24:09 INFO - 'run-tests'), 11:24:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:24:09 INFO - 'download_minidump_stackwalk': True, 11:24:09 INFO - 'download_symbols': 'ondemand', 11:24:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:24:09 INFO - 'tooltool.py': '/tools/tooltool.py', 11:24:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:24:09 INFO - '/tools/misc-python/virtualenv.py')}, 11:24:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:24:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:24:09 INFO - 'gecko_profile': False, 11:24:09 INFO - 'gecko_profile_interval': 0, 11:24:09 INFO - 'installer_path': 'installer.exe', 11:24:09 INFO - 'log_level': 'info', 11:24:09 INFO - 'log_name': 'talos', 11:24:09 INFO - 'log_to_console': True, 11:24:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:24:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:24:09 INFO - 'opt_config_files': (), 11:24:09 INFO - 'pip_index': False, 11:24:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:24:09 INFO - 'cmd': ('bash', 11:24:09 INFO - '-c', 11:24:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:24:09 INFO - 'enabled': True, 11:24:09 INFO - 'halt_on_failure': False, 11:24:09 INFO - 'name': 'check_screen_resolution'},), 11:24:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:24:09 INFO - 'cmd': ('bash', 11:24:09 INFO - '-c', 11:24:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:24:09 INFO - 'enabled': True, 11:24:09 INFO - 'halt_on_failure': False, 11:24:09 INFO - 'name': 'check_screen_resolution'},), 11:24:09 INFO - 'run_cmd_checks_enabled': True, 11:24:09 INFO - 'suite': 'dromaeojs-e10s', 11:24:09 INFO - 'system_bits': '32', 11:24:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:24:09 INFO - 'title': 't-yosemite-r7-0116', 11:24:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:24:09 INFO - 'use_talos_json': True, 11:24:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:24:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:24:09 INFO - 'work_dir': 'build'} 11:24:09 INFO - [mozharness: 2017-05-30 18:24:09.665851Z] Running clobber step. 11:24:09 INFO - Running pre-action listener: _resource_record_pre_action 11:24:09 INFO - Running main action method: clobber 11:24:09 INFO - rmtree: /builds/slave/test/build 11:24:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:24:12 INFO - Running post-action listener: _resource_record_post_action 11:24:12 INFO - [mozharness: 2017-05-30 18:24:12.193987Z] Finished clobber step (success) 11:24:12 INFO - [mozharness: 2017-05-30 18:24:12.194132Z] Running read-buildbot-config step. 11:24:12 INFO - Running pre-action listener: _resource_record_pre_action 11:24:12 INFO - Running main action method: read_buildbot_config 11:24:12 INFO - Using buildbot properties: 11:24:12 INFO - { 11:24:12 INFO - "project": "", 11:24:12 INFO - "product": "firefox", 11:24:12 INFO - "who": "ryanvm@gmail.com", 11:24:12 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 11:24:12 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 11:24:12 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 11:24:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 11:24:12 INFO - "stage_platform": "macosx64", 11:24:12 INFO - "basedir": "/builds/slave/test", 11:24:12 INFO - "buildnumber": 170, 11:24:12 INFO - "platform": "yosemite_r7", 11:24:12 INFO - "parent_task_id": "LNOtUwL4TQibQaCarE6lGw", 11:24:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 11:24:12 INFO - "slavebuilddir": "test", 11:24:12 INFO - "taskId": "IXbVx72ATVivD1u5SNLLFA", 11:24:12 INFO - "branch": "mozilla-central", 11:24:12 INFO - "script_repo_revision": "production", 11:24:12 INFO - "revision": "39d5cc0fda5e16c49a59d29d4ca186a5534cc88b", 11:24:12 INFO - "slavename": "t-yosemite-r7-0116", 11:24:12 INFO - "repo_path": "mozilla-central" 11:24:12 INFO - } 11:24:12 INFO - Finding installer, test and symbols from parent task. 11:24:12 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IXbVx72ATVivD1u5SNLLFA'}, attempt #1 11:24:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/IXbVx72ATVivD1u5SNLLFA'}, attempt #1 11:24:13 INFO - Task dependencies: XL0SBUOiTySIe1gk2r03LA LNOtUwL4TQibQaCarE6lGw 11:24:13 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw'}, attempt #1 11:24:13 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:24:13 INFO - Set test_url: https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/test_packages.json 11:24:13 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/target.crashreporter-symbols.zip 11:24:13 INFO - Running post-action listener: _resource_record_post_action 11:24:13 INFO - [mozharness: 2017-05-30 18:24:13.957634Z] Finished read-buildbot-config step (success) 11:24:13 INFO - [mozharness: 2017-05-30 18:24:13.957792Z] Running download-and-extract step. 11:24:13 INFO - Running pre-action listener: _resource_record_pre_action 11:24:13 INFO - Running main action method: download_and_extract 11:24:13 INFO - mkdir: /builds/slave/test/build/tests 11:24:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:24:13 INFO - trying https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 11:24:13 INFO - Downloading https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:24:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 11:24:14 INFO - Downloaded 1325 bytes. 11:24:14 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 11:24:14 INFO - Using the following test package requirements: 11:24:14 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 11:24:14 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:24:14 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 11:24:14 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 11:24:14 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'jsshell-mac.zip'], 11:24:14 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 11:24:14 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 11:24:14 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 11:24:14 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 11:24:14 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 11:24:14 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 11:24:14 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 11:24:14 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:24:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:24:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:24:14 INFO - Fetch https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:24:16 INFO - Content-Length response header: 57630732 11:24:16 INFO - Bytes received: 57630732 11:24:35 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:24:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 11:24:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:24:35 INFO - Fetch https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 11:24:37 INFO - Content-Length response header: 57630732 11:24:37 INFO - Bytes received: 57630732 11:24:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 11:24:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:24:54 INFO - Fetch https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 11:24:55 INFO - Content-Length response header: 14447425 11:24:55 INFO - Bytes received: 14447425 11:24:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:24:55 INFO - trying https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:24:55 INFO - Downloading https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 11:24:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 11:24:57 INFO - Downloaded 67934277 bytes. 11:24:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:24:57 INFO - mkdir: /builds/slave/test/properties 11:24:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:24:57 INFO - Writing to file /builds/slave/test/properties/build_url 11:24:57 INFO - Contents: 11:24:57 INFO - build_url:https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 11:24:57 INFO - Running post-action listener: _resource_record_post_action 11:24:57 INFO - Running post-action listener: set_extra_try_arguments 11:24:57 INFO - [mozharness: 2017-05-30 18:24:57.688671Z] Finished download-and-extract step (success) 11:24:57 INFO - [mozharness: 2017-05-30 18:24:57.688857Z] Running populate-webroot step. 11:24:57 INFO - Running pre-action listener: _resource_record_pre_action 11:24:57 INFO - Running main action method: populate_webroot 11:24:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:24:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:24:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:24:57 INFO - '--tppagecycles', 11:24:57 INFO - '7'], 11:24:57 INFO - 'tests': ['tsvgm']}}, 11:24:57 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:24:57 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:24:57 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:24:57 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:57 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:24:57 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['damp', 'tps']}, 11:24:57 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'tests': ['damp', 'tps']}, 11:24:57 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['dromaeo_dom']}, 11:24:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:24:57 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 11:24:57 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 11:24:57 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 11:24:57 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'other-e10s': {'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['a11yr', 11:24:57 INFO - 'ts_paint', 11:24:57 INFO - 'tpaint', 11:24:57 INFO - 'sessionrestore', 11:24:57 INFO - 'sessionrestore_no_auto_restore', 11:24:57 INFO - 'tabpaint']}, 11:24:57 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 11:24:57 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 11:24:57 INFO - 'bloom_basic_ref']}, 11:24:57 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 11:24:57 INFO - 'talos_options': ['--mitmproxy', 11:24:57 INFO - 'mitmproxy-recording-1.mp', 11:24:57 INFO - '--firstNonBlankPaint'], 11:24:57 INFO - 'tests': ['Quantum_1']}, 11:24:57 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['tsvgx', 11:24:57 INFO - 'tsvgr_opacity', 11:24:57 INFO - 'tart', 11:24:57 INFO - 'tscrollx', 11:24:57 INFO - 'cart', 11:24:57 INFO - 'tsvg_static']}, 11:24:57 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:24:57 INFO - 'tsvgr_opacity', 11:24:57 INFO - 'tart', 11:24:57 INFO - 'tscrollx', 11:24:57 INFO - 'cart', 11:24:57 INFO - 'tsvg_static']}, 11:24:57 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'talos_options': ['--disable-e10s'], 11:24:57 INFO - 'tests': ['tp5o']}, 11:24:57 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 11:24:57 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'talos_options': ['--disable-e10s', 11:24:57 INFO - '--xperf_path', 11:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:57 INFO - 'tests': ['tp5n']}, 11:24:57 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 11:24:57 INFO - 'talos_options': ['--xperf_path', 11:24:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:24:57 INFO - 'tests': ['tp5n']}}, 11:24:57 INFO - 'talos.zip': {'path': '', 11:24:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:24:57 INFO - Running post-action listener: _resource_record_post_action 11:24:57 INFO - [mozharness: 2017-05-30 18:24:57.711393Z] Finished populate-webroot step (success) 11:24:57 INFO - [mozharness: 2017-05-30 18:24:57.711510Z] Running create-virtualenv step. 11:24:57 INFO - Running pre-action listener: _resource_record_pre_action 11:24:57 INFO - Running main action method: create_virtualenv 11:24:57 INFO - Creating virtualenv /builds/slave/test/build/venv 11:24:57 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:24:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:24:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:24:57 INFO - Using real prefix '/tools/python27' 11:24:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:24:58 INFO - Installing distribute.............................................................................................................................................................................................done. 11:25:01 INFO - Installing pip.................done. 11:25:01 INFO - Return code: 0 11:25:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:25:01 INFO - Reading from file tmpfile_stdout 11:25:01 INFO - Output received: 11:25:01 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:25:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 11:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 11:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:01 INFO - 'HOME': '/Users/cltbld', 11:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PAGER': '/bin/cat', 11:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:01 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - 'XPC_FLAGS': '0x0', 11:25:01 INFO - 'XPC_SERVICE_NAME': '0', 11:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:01 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:25:01 INFO - Cleaning up... 11:25:01 INFO - Return code: 0 11:25:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 11:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 11:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:01 INFO - 'HOME': '/Users/cltbld', 11:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:01 INFO - 'LOGNAME': 'cltbld', 11:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:01 INFO - 'MOZ_NO_REMOTE': '1', 11:25:01 INFO - 'NO_EM_RESTART': '1', 11:25:01 INFO - 'PAGER': '/bin/cat', 11:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:01 INFO - 'PWD': '/builds/slave/test', 11:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:01 INFO - 'SHELL': '/bin/bash', 11:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:01 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:01 INFO - 'USER': 'cltbld', 11:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:01 INFO - 'XPC_FLAGS': '0x0', 11:25:01 INFO - 'XPC_SERVICE_NAME': '0', 11:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:02 INFO - Downloading/unpacking psutil>=3.1.1 11:25:02 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:25:02 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:25:02 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:25:02 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:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:25:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:04 INFO - Installing collected packages: psutil 11:25:04 INFO - Running setup.py install for psutil 11:25:04 INFO - building 'psutil._psutil_osx' extension 11:25:04 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:25:05 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:25:05 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:25:05 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:25:05 INFO - building 'psutil._psutil_posix' extension 11:25:05 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:25:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:25:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:25:05 INFO - ^ 11:25:05 INFO - 1 warning generated. 11:25:05 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:25:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:25:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:25:05 INFO - Successfully installed psutil 11:25:05 INFO - Cleaning up... 11:25:05 INFO - Return code: 0 11:25:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:25: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:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:06 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:06 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:25:06 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:25:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:06 INFO - 'HOME': '/Users/cltbld', 11:25:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:06 INFO - 'LOGNAME': 'cltbld', 11:25:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:06 INFO - 'MOZ_NO_REMOTE': '1', 11:25:06 INFO - 'NO_EM_RESTART': '1', 11:25:06 INFO - 'PAGER': '/bin/cat', 11:25:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:06 INFO - 'PWD': '/builds/slave/test', 11:25:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:06 INFO - 'SHELL': '/bin/bash', 11:25:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:06 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:06 INFO - 'USER': 'cltbld', 11:25:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:06 INFO - 'XPC_FLAGS': '0x0', 11:25:06 INFO - 'XPC_SERVICE_NAME': '0', 11:25:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:06 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:25:06 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:25:06 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:25:06 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:25:06 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:25:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:25:07 INFO - Installing collected packages: mozsystemmonitor 11:25:07 INFO - Running setup.py install for mozsystemmonitor 11:25:07 INFO - Successfully installed mozsystemmonitor 11:25:07 INFO - Cleaning up... 11:25:08 INFO - Return code: 0 11:25:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:08 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:08 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:25:08 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:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:08 INFO - 'HOME': '/Users/cltbld', 11:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:08 INFO - 'LOGNAME': 'cltbld', 11:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:08 INFO - 'MOZ_NO_REMOTE': '1', 11:25:08 INFO - 'NO_EM_RESTART': '1', 11:25:08 INFO - 'PAGER': '/bin/cat', 11:25:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:08 INFO - 'PWD': '/builds/slave/test', 11:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:08 INFO - 'SHELL': '/bin/bash', 11:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:08 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:08 INFO - 'USER': 'cltbld', 11:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:08 INFO - 'XPC_FLAGS': '0x0', 11:25:08 INFO - 'XPC_SERVICE_NAME': '0', 11:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:08 INFO - Downloading/unpacking jsonschema==2.5.1 11:25:08 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:25:08 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:25:08 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:25:08 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:25:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:25:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:25:09 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:25:09 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:25:09 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:25:09 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:25:09 INFO - Downloading functools32-3.2.3-2.tar.gz 11:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:25:10 INFO - warning: no files found matching '*.txt' 11:25:10 INFO - no previously-included directories found matching 'build' 11:25:10 INFO - no previously-included directories found matching 'dist' 11:25:10 INFO - no previously-included directories found matching '.git*' 11:25:10 INFO - Installing collected packages: jsonschema, functools32 11:25:10 INFO - Running setup.py install for functools32 11:25:10 INFO - warning: no files found matching '*.txt' 11:25:10 INFO - no previously-included directories found matching 'build' 11:25:10 INFO - no previously-included directories found matching 'dist' 11:25:10 INFO - no previously-included directories found matching '.git*' 11:25:10 INFO - Successfully installed jsonschema functools32 11:25:10 INFO - Cleaning up... 11:25:10 INFO - Return code: 0 11:25:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:25:10 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:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:10 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:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:10 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:10 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:25:10 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:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:10 INFO - 'HOME': '/Users/cltbld', 11:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:10 INFO - 'LOGNAME': 'cltbld', 11:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:10 INFO - 'MOZ_NO_REMOTE': '1', 11:25:10 INFO - 'NO_EM_RESTART': '1', 11:25:10 INFO - 'PAGER': '/bin/cat', 11:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:10 INFO - 'PWD': '/builds/slave/test', 11:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:10 INFO - 'SHELL': '/bin/bash', 11:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:10 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:10 INFO - 'USER': 'cltbld', 11:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:10 INFO - 'XPC_FLAGS': '0x0', 11:25:10 INFO - 'XPC_SERVICE_NAME': '0', 11:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:25:10 INFO - Cleaning up... 11:25:10 INFO - Return code: 0 11:25:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:25:10 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:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:10 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:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:10 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:10 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:25:10 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:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:10 INFO - 'HOME': '/Users/cltbld', 11:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:10 INFO - 'LOGNAME': 'cltbld', 11:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:10 INFO - 'MOZ_NO_REMOTE': '1', 11:25:10 INFO - 'NO_EM_RESTART': '1', 11:25:10 INFO - 'PAGER': '/bin/cat', 11:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:10 INFO - 'PWD': '/builds/slave/test', 11:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:10 INFO - 'SHELL': '/bin/bash', 11:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:10 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:10 INFO - 'USER': 'cltbld', 11:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:10 INFO - 'XPC_FLAGS': '0x0', 11:25:10 INFO - 'XPC_SERVICE_NAME': '0', 11:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:10 INFO - Downloading/unpacking blobuploader==1.2.4 11:25:10 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:25:10 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:25:10 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:25:10 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:25:12 INFO - Downloading blobuploader-1.2.4.tar.gz 11:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:25:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:25: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:25: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:25: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:25: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:25:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:25:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:25: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:25: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:25: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:25: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:25:13 INFO - Downloading docopt-0.6.1.tar.gz 11:25:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:25:13 INFO - Installing collected packages: blobuploader, requests, docopt 11:25:13 INFO - Running setup.py install for blobuploader 11:25:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:25:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:25:13 INFO - Running setup.py install for requests 11:25:13 INFO - Running setup.py install for docopt 11:25:13 INFO - Successfully installed blobuploader requests docopt 11:25:13 INFO - Cleaning up... 11:25:14 INFO - Return code: 0 11:25:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:14 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:14 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:25:14 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:25:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:14 INFO - 'HOME': '/Users/cltbld', 11:25:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:14 INFO - 'LOGNAME': 'cltbld', 11:25:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:14 INFO - 'MOZ_NO_REMOTE': '1', 11:25:14 INFO - 'NO_EM_RESTART': '1', 11:25:14 INFO - 'PAGER': '/bin/cat', 11:25:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:14 INFO - 'PWD': '/builds/slave/test', 11:25:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:14 INFO - 'SHELL': '/bin/bash', 11:25:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:14 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:14 INFO - 'USER': 'cltbld', 11:25:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:14 INFO - 'XPC_FLAGS': '0x0', 11:25:14 INFO - 'XPC_SERVICE_NAME': '0', 11:25:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-XmXQGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-c7f8Xf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-w9k3qr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NlgQtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vNys6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ZPPQoO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:25:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:25:14 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-ccCcMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vG07dH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-aCKET0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-fR3D2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-60DAIA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-J0wkPv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-tc_2du-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-p0Z5jN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-T2mfC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-HmaHf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:25:15 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-h0377a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:25:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:25:16 INFO - Running setup.py install for manifestparser 11:25:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:25:16 INFO - Running setup.py install for mozcrash 11:25:16 INFO - Running setup.py install for mozdebug 11:25:16 INFO - Running setup.py install for mozdevice 11:25:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:25:16 INFO - Running setup.py install for mozfile 11:25:16 INFO - Running setup.py install for mozhttpd 11:25:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:25:16 INFO - Running setup.py install for mozinfo 11:25:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Running setup.py install for mozInstall 11:25:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Running setup.py install for mozleak 11:25:17 INFO - Running setup.py install for mozlog 11:25:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Running setup.py install for moznetwork 11:25:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:25:17 INFO - Running setup.py install for mozprocess 11:25:17 INFO - Running setup.py install for mozprofile 11:25:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:25:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:25:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:25:18 INFO - Running setup.py install for mozrunner 11:25:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:25:18 INFO - Running setup.py install for mozscreenshot 11:25:18 INFO - Running setup.py install for moztest 11:25:18 INFO - Running setup.py install for mozversion 11:25:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:25:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:25:18 INFO - Cleaning up... 11:25:18 INFO - Return code: 0 11:25:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25: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:25:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25: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:25:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:18 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:18 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:25:18 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:25:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:18 INFO - 'HOME': '/Users/cltbld', 11:25:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:18 INFO - 'LOGNAME': 'cltbld', 11:25:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:18 INFO - 'MOZ_NO_REMOTE': '1', 11:25:18 INFO - 'NO_EM_RESTART': '1', 11:25:18 INFO - 'PAGER': '/bin/cat', 11:25:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:18 INFO - 'PWD': '/builds/slave/test', 11:25:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:18 INFO - 'SHELL': '/bin/bash', 11:25:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:18 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:18 INFO - 'USER': 'cltbld', 11:25:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:18 INFO - 'XPC_FLAGS': '0x0', 11:25:18 INFO - 'XPC_SERVICE_NAME': '0', 11:25:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:25:18 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-QvIIsD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-aDobDI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-NCZEtZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-rftnC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Omle5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-jGFpen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-aAMbR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-W6ww65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:25:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-Rt31xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:25:19 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:25:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:25:19 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-vkamZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-abwJjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-SQ5cIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-GusDbY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-LfbZo1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-1LQQR5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-cTyZSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:25:20 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:25:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:25:20 INFO - Running setup.py (path:/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/pip-8qs3tE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:25:20 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:25:20 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:25:20 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:25:20 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:25:20 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:25:20 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:25:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:25: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:25: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:25: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:25: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:25:22 INFO - Downloading blessings-1.6.tar.gz 11:25:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:25:22 INFO - Installing collected packages: blessings 11:25:22 INFO - Running setup.py install for blessings 11:25:22 INFO - Successfully installed blessings 11:25:22 INFO - Cleaning up... 11:25:22 INFO - Return code: 0 11:25:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:22 INFO - Reading from file tmpfile_stdout 11:25:22 INFO - Current package versions: 11:25:22 INFO - blessings == 1.6 11:25:22 INFO - blobuploader == 1.2.4 11:25:22 INFO - docopt == 0.6.1 11:25:22 INFO - functools32 == 3.2.3-2 11:25:22 INFO - jsonschema == 2.5.1 11:25:22 INFO - manifestparser == 1.1 11:25:22 INFO - mozInstall == 1.12 11:25:22 INFO - mozcrash == 1.0 11:25:22 INFO - mozdebug == 0.1 11:25:22 INFO - mozdevice == 0.51 11:25:22 INFO - mozfile == 1.2 11:25:22 INFO - mozhttpd == 0.7 11:25:22 INFO - mozinfo == 0.9 11:25:22 INFO - mozleak == 0.1 11:25:22 INFO - mozlog == 3.4 11:25:22 INFO - moznetwork == 0.27 11:25:22 INFO - mozprocess == 0.25 11:25:22 INFO - mozprofile == 0.28 11:25:22 INFO - mozrunner == 6.13 11:25:22 INFO - mozscreenshot == 0.1 11:25:22 INFO - mozsystemmonitor == 0.3 11:25:22 INFO - moztest == 0.8 11:25:22 INFO - mozversion == 1.4 11:25:22 INFO - psutil == 3.1.1 11:25:22 INFO - requests == 1.2.3 11:25:22 INFO - wsgiref == 0.1.2 11:25:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:22 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:22 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:25:22 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:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:22 INFO - 'HOME': '/Users/cltbld', 11:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:22 INFO - 'LOGNAME': 'cltbld', 11:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:22 INFO - 'MOZ_NO_REMOTE': '1', 11:25:22 INFO - 'NO_EM_RESTART': '1', 11:25:22 INFO - 'PAGER': '/bin/cat', 11:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:22 INFO - 'PWD': '/builds/slave/test', 11:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:22 INFO - 'SHELL': '/bin/bash', 11:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:22 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:22 INFO - 'USER': 'cltbld', 11:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:22 INFO - 'XPC_FLAGS': '0x0', 11:25:22 INFO - 'XPC_SERVICE_NAME': '0', 11:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:23 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:25:23 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:25:23 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:25:23 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:25:23 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:25:23 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:25:23 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:25:23 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:25:23 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:25:23 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 11:25: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:25: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:25: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:25: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:25:24 INFO - Downloading marionette_driver-1.4.0.tar.gz 11:25:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 11:25:24 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:25:24 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:25:24 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:25:24 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:25:24 INFO - Installing collected packages: marionette-driver 11:25:24 INFO - Running setup.py install for marionette-driver 11:25:24 INFO - Successfully installed marionette-driver 11:25:24 INFO - Cleaning up... 11:25:24 INFO - Return code: 0 11:25:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:25: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:25:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:25: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:25:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:25:24 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 0x108825c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108829768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe13d74d80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, '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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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:25:24 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:25:24 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:25:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:24 INFO - 'HOME': '/Users/cltbld', 11:25:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:24 INFO - 'LOGNAME': 'cltbld', 11:25:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:24 INFO - 'MOZ_NO_REMOTE': '1', 11:25:24 INFO - 'NO_EM_RESTART': '1', 11:25:24 INFO - 'PAGER': '/bin/cat', 11:25:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:24 INFO - 'PWD': '/builds/slave/test', 11:25:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:24 INFO - 'SHELL': '/bin/bash', 11:25:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:24 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:24 INFO - 'USER': 'cltbld', 11:25:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:24 INFO - 'XPC_FLAGS': '0x0', 11:25:24 INFO - 'XPC_SERVICE_NAME': '0', 11:25:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:25:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:25:25 INFO - Cleaning up... 11:25:25 INFO - Return code: 0 11:25:25 INFO - Running post-action listener: _resource_record_post_action 11:25:25 INFO - Running post-action listener: _start_resource_monitoring 11:25:25 INFO - Starting resource monitoring. 11:25:25 INFO - [mozharness: 2017-05-30 18:25:25.107718Z] Finished create-virtualenv step (success) 11:25:25 INFO - [mozharness: 2017-05-30 18:25:25.108783Z] Running install step. 11:25:25 INFO - Running pre-action listener: _resource_record_pre_action 11:25:25 INFO - Running main action method: install 11:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:25:25 INFO - Reading from file tmpfile_stdout 11:25:25 INFO - Detecting whether we're running mozinstall >=1.0... 11:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:25:25 INFO - Reading from file tmpfile_stdout 11:25:25 INFO - Output received: 11:25:25 INFO - Usage: mozinstall [options] installer 11:25:25 INFO - Options: 11:25:25 INFO - -h, --help show this help message and exit 11:25:25 INFO - -d DEST, --destination=DEST 11:25:25 INFO - Directory to install application into. [default: 11:25:25 INFO - "/builds/slave/test"] 11:25:25 INFO - --app=APP Application being installed. [default: firefox] 11:25:25 INFO - mkdir: /builds/slave/test/build/application 11:25:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:25:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:25:46 INFO - Reading from file tmpfile_stdout 11:25:46 INFO - Output received: 11:25:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:25:46 INFO - Running post-action listener: _resource_record_post_action 11:25:46 INFO - [mozharness: 2017-05-30 18:25:46.836009Z] Finished install step (success) 11:25:46 INFO - [mozharness: 2017-05-30 18:25:46.836186Z] Running setup-mitmproxy step. 11:25:46 INFO - Running pre-action listener: _resource_record_pre_action 11:25:46 INFO - Running main action method: setup_mitmproxy 11:25:46 INFO - Skipping: mitmproxy is not required 11:25:46 INFO - Running post-action listener: _resource_record_post_action 11:25:46 INFO - [mozharness: 2017-05-30 18:25:46.836845Z] Finished setup-mitmproxy step (success) 11:25:46 INFO - [mozharness: 2017-05-30 18:25:46.836977Z] Running run-tests step. 11:25:46 INFO - Running pre-action listener: _resource_record_pre_action 11:25:46 INFO - Running main action method: run_tests 11:25:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:25:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:25:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:25:46 INFO - 2017-05-30 11:25:46.851 screenresolution[1627:7340] starting screenresolution argv=screenresolution get 11:25:46 INFO - 2017-05-30 11:25:46.854 screenresolution[1627:7340] Display 0: 1600x1200x32@60 11:25:46 INFO - 2017-05-30 11:25:46.859 screenresolution[1628:7342] starting screenresolution argv=screenresolution list 11:25:46 INFO - Available Modes on Display 0 11:25:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:25:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:25:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:25:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:25:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:25:47 INFO - Intel Iris: 11:25:47 INFO - Chipset Model: Intel Iris 11:25:47 INFO - Type: GPU 11:25:47 INFO - Bus: Built-In 11:25:47 INFO - VRAM (Dynamic, Max): 1536 MB 11:25:47 INFO - Vendor: Intel (0x8086) 11:25:47 INFO - Device ID: 0x0a2e 11:25:47 INFO - Revision ID: 0x0009 11:25:47 INFO - Displays: 11:25:47 INFO - Display: 11:25:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:25:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:25:47 INFO - Display Serial Number: 1600x1200 60 11:25:47 INFO - Main Display: Yes 11:25:47 INFO - Mirror: Off 11:25:47 INFO - Online: Yes 11:25:47 INFO - Rotation: Supported 11:25:47 INFO - Return code: 0 11:25:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:25:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:25:47 INFO - Python 2.7.3 11:25:47 INFO - Return code: 0 11:25:47 INFO - grabbing minidump binary from tooltool 11:25:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:25:47 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 0x1087aec60>, '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 0x108543cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10856cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:25:47 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:25:47 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:25:47 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:25:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:25:47 INFO - Return code: 0 11:25:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:25:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:25:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:25:47 INFO - ENV: RUST_BACKTRACE is now 1 11:25:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:25:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:25:47 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/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:25:47 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/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0116 --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:25:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.r7srAoN43D/Render', 11:25:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:25:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:25:47 INFO - 'HOME': '/Users/cltbld', 11:25:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:25:47 INFO - 'LOGNAME': 'cltbld', 11:25:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:25:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:25:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:25:47 INFO - 'MOZ_NO_REMOTE': '1', 11:25:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:25:47 INFO - 'NO_EM_RESTART': '1', 11:25:47 INFO - 'PAGER': '/bin/cat', 11:25:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:25:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:25:47 INFO - 'PWD': '/builds/slave/test', 11:25:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:25:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:25:47 INFO - 'RUST_BACKTRACE': '1', 11:25:47 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 11:25:47 INFO - 'SHELL': '/bin/bash', 11:25:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Ee5KYvGkxZ/Listeners', 11:25:47 INFO - 'TMPDIR': '/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/', 11:25:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:25:47 INFO - 'USER': 'cltbld', 11:25:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:25:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:25:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:25:47 INFO - 'XPC_FLAGS': '0x0', 11:25:47 INFO - 'XPC_SERVICE_NAME': '0', 11:25:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:25:47 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/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0116', '--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:25:47 INFO - mozversion application_buildid: 20170530094255 11:25:47 INFO - mozversion application_changeset: 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b 11:25:47 INFO - mozversion application_display_name: Nightly 11:25:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:25:47 INFO - mozversion application_name: Firefox 11:25:47 INFO - mozversion application_remotingname: firefox 11:25:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 11:25:47 INFO - mozversion application_vendor: Mozilla 11:25:47 INFO - mozversion application_version: 55.0a1 11:25:47 INFO - mozversion platform_buildid: 20170530094255 11:25:47 INFO - mozversion platform_changeset: 39d5cc0fda5e16c49a59d29d4ca186a5534cc88b 11:25:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 11:25:47 INFO - mozversion platform_version: 55.0a1 11:25:47 INFO - using testdate: 1496168747 11:25:47 INFO - actual date: 1496168747 11:25:47 INFO - starting webserver on 'localhost:49228' 11:25:47 INFO - SUITE-START | Running 2 tests 11:25:47 INFO - TEST-START | dromaeo_css 11:25:47 INFO - operating with platform_type : mac_ 11:25:47 INFO - Initialising browser for dromaeo_css test... 11:25:47 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpGKf6f_/profile 11:25:47 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 11:26:21 INFO - TEST-INFO | 1638: killed by SIGTERM 11:26:21 INFO - Browser initialized. 11:26:21 INFO - Running cycle 1/1 for dromaeo_css test... 11:26:21 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmpGKf6f_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:26:23 INFO - PID 1648 | 2017-05-30 11:26:23.547 plugin-container[1649:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 11:26:23 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:26:23 INFO - PID 1648 | 2017-05-30 11:26:23.549 plugin-container[1649:7921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 11:26:23 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:26:23 INFO - PID 1648 | 2017-05-30 11:26:23.549 plugin-container[1649:7921] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:26:24 INFO - PID 1648 | 2017-05-30 11:26:24.065 plugin-container[1650:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 11:26:24 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:26:24 INFO - PID 1648 | 2017-05-30 11:26:24.066 plugin-container[1650:7964] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x981f, name = 'com.apple.CFPasteboardClient' 11:26:24 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:26:24 INFO - PID 1648 | 2017-05-30 11:26:24.066 plugin-container[1650:7964] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:26:33 INFO - PID 1648 | RSS: Main: 262447104 11:26:33 INFO - PID 1648 | 11:29:36 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 11:29:37 INFO - PID 1648 | RSS: Main: 267870208 11:29:37 INFO - PID 1648 | 11:32:39 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 11:32:40 INFO - PID 1648 | RSS: Main: 280461312 11:32:40 INFO - PID 1648 | 11:35:42 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 11:35:42 INFO - PID 1648 | RSS: Main: 282402816 11:35:42 INFO - PID 1648 | 11:38:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 11:38:45 INFO - PID 1648 | RSS: Main: 286040064 11:38:45 INFO - PID 1648 | 11:41:47 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 11:41:48 INFO - PID 1648 | RSS: Main: 286572544 11:41:48 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 11:44:50 INFO - PID 1648 | RSS: Main: 286519296 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | __start_tp_report 11:44:50 INFO - PID 1648 | _x_x_mozilla_page_load 11:44:50 INFO - PID 1648 | _x_x_mozilla_page_load_details 11:44:50 INFO - PID 1648 | |i|pagename|runs| 11:44:50 INFO - PID 1648 | |0;dojo.html;3125.640551336596;3603.62161972993;3617.5116359291487;3627.056965189051;3635.8363873625685;6113.354685976882;6219.004959206528;6241.65670888101;6247.531435142366;6272.153259309992;6163.106349579309;6200.658963756991;6232.940400131977;6273.843153921142;6308.4322410983;4618.582249135632;5349.946500534984;5468.890622187554;5556.222128901957;5608.887822243552;5596.328440587135;5649.322081350223;5663.2637757091525;5684.971575142118;5711.029125048752;5469.972650136764;5501.779928802843;5534.142207957773;5568.610197286192;5643.492085712264;4496.4154659894;5561.721913904329;5607.915881261784;5629.8311050668135;5630.296212973379;5517.36550296717;5545.223668686387;5545.639533430314;5574.358948720908;5594.524465420444;6177.722002509898;6234.563580549363;6236.594621349598;6281.937180628227;6311.589746666498;6628.73485060597;6806.693698783517;6809.285025072346;6817.625030623314;6824.4199243064395;5334.1732031535175;6857.348551887562;6878.518503704742;6884.65576721162;6915.861682766316;6232.065190221503;6310.5267104967315;6319.58922670025;6329.7468101275435;6331.651759153291;6817.5568588041615;6867.2102708189195;6963.0251764752975;6991.930080699128;7025.613591252531;6448.742050317935;6521.08704781331;6577.539572229898;6626.337366263335;6651.86696266072;5747.540196784247;6749.5612785168805;6853.588784672936;6873.759418420332;6891.207511136284;6148.8770224595255;6463.450606698483;6713.832154196087;6782.355676210752;6800.455963522906;5584.46947539983;6630.668466576748;6720.529562930646;6759.019942108367;6807.353301436455;5381.515663590296;5398.352197736218;5406.053940560465;5420.322459692538;5438.15708565173;4746.121967436035;4759.714417134983;4784.712917224978;4784.7607619619575;4791.281307803857;6123.663198524035;6277.435030847246;6324.968375158095;6428.8071357859335;6459.838504037342;6920.515563910579;6922.861542769117;6922.965385173143;6931.722731090801;6942.548734332252;6404.263509696445;6424.550281480251;6445.806625801233;6481.157449531531;6492.156019717501;5819.185314056035;5893.646381217055;6010.879782404328;6032.034874420072;6073.362296958844;6978.092847929838;7011.368976792215;7038.40173585251;7042.154941407064;7064.929350706427;5776.508996735324;6827.897581536182;6879.690413931285;6886.24251332343;6901.861962760616;6797.456203503807;6891.552049116792;7000.775764326832;7073.080499535027;7092.822679433056;5240.423553409199;5798.507126894261;6734.696938637871;6749.291324410866;6761.391474767294;6884.8967265490055;6892.689828957608;6917.446604271747;6921.065656136358;6930.688119034555;6432.035194720829;6537.215534135934;6549.901751473636;6595.505337099641;6616.636085015178;5971.492423144082;6299.212598425197;6612.8346791330605;6668.466522678271;6696.732130714911;6384.138141350858;6480.513961452815;6511.544191906521;6522.804315870531;6529.216494020748;6394.200724909387;6486.2216534016225;6564.5076619252795;6642.269350371552;6675;6405;6408.839779005562;6438.452731517874;6475.384838440341;6489.123968264224 11:44:50 INFO - PID 1648 | |1;ext.html;693.4729605499821;756.2362014365492;846.1453931529155;1273.5797186928314;1315.730275293565;39515;40570;40576.99999999996;40638.39042414366;40748.981275468126;41094.17811643773;41141;41153.38269925946;41189.17621647565;41226;7950.999999999985;8098.149694282104;8377.581120943958;8582.184692454206;8598.957005214965;8483.448575842582;8602.139786021411;8610.354223433236;8623.310135189202;8626.094260102693;8542.44474109184;8587.355948303872;8649.005364383114;8660.956695216515;8676.089010653885;8576.228139467446;8614.353923455767;8627.568621568958;8629.395942284043;8648.43785153963;8256.339492840558;8285.54429506377;8350.457220280663;8353.24820766128;8382.371322150862;37779.24441511182;38029.85910422692;38032.61967380319;38091.619083809084;38223.426648600296;3522.4011917974008;3548.0774998500574;3570.393033184339;3571.6964058055096;3575.696065834381;31023.689763102306;31030.689693103006;31059.84470077658;31062;31062.223444413845;3079;3097.488914329133;3117.267442151093;3125.687431256856;3134.2164458885277;236836;239722;274910.6254468735;289497;290778;48533.75733121312;48784.75607621939;48844.267335989985;48874.75562622164;48887.755561221966;51128;51252.231216531785;51265.97468050618;54113.1883021755;54586;14800.407983680747;14949.925250373679;14955.775663365059;14962.925185374004;14971.775423368657;11918.523259069714;11954.983826374855;11981.341026243466;11991.160618756603;12001.87998120025;14285.214313212873;14301.070430422198;14325.570232892822;14342.928285358716;14447.205403702686;5288.576913846883;6243.406876346739;6284.717187726564;6291.937080629226;6305.432511073934;892.4065496444921;950.6150009246267;960.4237457525569;978.1294647763497;1006.597361055569;22167.44581385478;24759.25722228336;25071.373215669755;25162;25316.240512784647;1121.1535290855352;1125.6341688951125;1143.8798926112804;1151.1999160583314;1170.3680012792997;10441.582336706446;12741.745165096647;12794.872051279553;12934.223946563236;12977;306459;309280;309933;310517;311303;56154.87690246172;56352.87294254092;56495.71752141213;56495.71752141295;56565.15152272719;1463.2903042024823;1515.0152400939257;1516.3100789140915;1526.0462211118052;1555.4633651390252;22411.77588224162;22815.973281202052;24098.87950560306;24262.272131836075;24328.635070473603;32516.67483325231;39040;39041;39100.6089939097;39116.804415977735;248644.75677621496;271714;273670;274337;274922;34656.65343346635;35439.29121417506;35455.468167976986;35569.82215088908;35728.82135589305;45029.874253143935;45090.06914550544;45102.77448612736;45168.32247516356;45189.322160166965;28412.431751364442;29383.412331753672;30782.538261926537;30817.845910770302;30894.845525772227;22784.772152278267;30105.397892042474;30598.388032239676;30791.69208307978;31086.689133109285;35527.82236088906;36786.448203277505;37210.813945930095;37320;37413.625863741014;29246.415071698022;29471.26321841971;29726.405471890874;31019.91430299929;31213.53179702261;23080.538389231784;23691.40771480727;23770.762292376854;23966.520669586163;24153.15463958753 11:44:50 INFO - PID 1648 | |2;jquery.html;389.345898889865;406.36404027696636;421.21653724073235;443.11598361269273;455.869443779427;21782.564348713015;22579;22707.886460567715;23757.31761576954;25005;24474.510509789794;24799.50400991979;24834.379140521494;24874.75125248747;24957.625635615434;6236.688165591736;6379.649119298437;6382.20222472191;6395.968020159893;6396.840078998016;6067.817965461043;6326.65203413812;6332.810015699536;6348.26994895586;6350.904736428957;6114.877702445949;6121.143039974407;6173.382661733813;6177.62934223948;6187.28846182688;6149.692515374236;6153.323134455206;6182.041783523561;6184.721687524027;6190.442860142609;5476.014317422862;5488.780448782044;5501.5323697485765;5507.118860982224;5515.972420137874;24251.878740606186;28079.438411231662;28404.715952840415;28827.56758648622;28875.42249155026;2169.1648715244746;2264.9660255096187;2265.5695417870716;2265.6714776357335;2269.5914735347633;18696.626067478574;18985.145668444955;18989.715154272697;19007;19030.33393831223;2120.8621439606377;2160.82713382929;2190.452386903274;2191.561687662474;2222.6777117317897;164411;170479;190267.04866475717;200489.93385892865;205272;13127.540536081191;13406.93296533511;13513.459461621622;13572.524961626494;13735.656608584864;17234.913825431042;17395.130243487776;17421.65156696859;17447.04041277742;17453.7381939271;9090.318226133066;9120.726378208665;9320.114589114024;9361.62553497866;9407.4825884577;9017;9057.411268267542;9091.909080909238;9127.90872091284;9134.90865091354;6894.724211031603;6979.581225126509;8301.46040507365;8354.624041918129;8379.287760540301;2022.595480903825;2217.7116974793175;2259.864428115415;2265.875376854289;2269.251147121446;822.9466283157568;906.9727908162765;951.5955718819474;964.320154291223;979.4671339353888;19266.518337041685;21158.62468939514;22206.444838878833;22214.111435542396;22643.43391415195;956.1729104324709;963.2390411574918;972.1882228339274;986.9654562090292;990.9256805739599;9892.604295828087;11209.94395028036;11494.655160345203;11602.6519204424;11608.071354291636;439543;507565.46217268676;508140;509688;525149;150561.49438505474;152510.712339315;156751.21624392032;157175.21412392866;157988.21005894896;1013.4274135113637;1164.7554013657057;1165.2658824940404;1225.0505857960152;1233.3771445420005;23336.533269334177;23565.29304120879;23801.52396952086;24099.277021689377;24137.879310603334;70722;71305;71636.64181679058;71741.28258717345;72362.63818680873;95926.52036739772;95956;96688.51655741676;96699.033009669;97291.51354243183;9925.454100024568;10390.324628899098;10424.635137770141;10431.895681043092;10602.045815876609;18486.537836554195;18519.166637501075;18616;18710.438686839418;18749;15122.941394102307;15225.771613425586;15415.460458883885;15418.074915505105;15450;13553.322333883463;13805.033647644568;14043.297835108;14064.78902816479;14217.075890067112;15215.315310810818;15222.934394592716;15536.533903982898;15542.911996160161;15559.299831507837;9998.900010999796;10463.738406539898;10477.42374169428;10498.265121441731;10570.894291057299;12593.874061259638;14745.557633271019;15028.774568381701;15067.924660376628;15107.54677359681 11:44:50 INFO - PID 1648 | |3;mootools.html;1213.3084142039036;1363.7204373103511;1424.7791592303183;1434.2613554019688;1448.47130797259;11733.061355091582;11734.237274577168;12212.755744885097;12238.204516706419;12482.875171248284;12030;12188.451519681614;12209.51161953523;12225.816612750794;12230.205036672633;2533.607290869918;2576.278641980239;2577.9172747446023;2583.9870800646063;2585.961210581843;2123.936281911545;2158.6438237690763;2217.323716266538;2498.912538061168;2502.762237587426;2574.343542396686;2581.341757851745;2587.275562842407;2590.974090259092;2592.6777343554736;2543.834650747705;2552.5022620588993;2556.8849401776965;2557.1433569754154;2559.744025597444;2522.7729504344516;2536.4293034067373;2545.27459673992;2545.8472491650373;2547.617857321398;9811.901880981242;11756.882431175749;11998.460069296903;12001.279923204636;12004.939975300067;6493.285738568754;6740.730370785162;6745.797626071226;6746.8313292167595;6754.324567543255;4022.8189731462153;4171.228322760299;4234.703570750049;4244.236037513247;4245.9787701061605;1230.329470438613;1255.755127750089;1285.0490636928605;1285.5436320106414;1285.967850803728;16645.50063498097;16727.749083763752;16754.329826806912;16758.413455528997;16787.999999999876;4725.385699859065;5513.035218836688;5647.689377084218;5668.603197776199;5699.857503562361;9695.466749328854;11170.598321360947;12000;12007.03943684503;12008.159428840072;5493.642913210629;6390;6441.806745797634;6494.512911531559;6497.18785151856;4337.934930976038;6153.230846144232;6157.692115394212;6212.099245609361;6257.74969001244;4365.4979677337515;4422.933655995163;4451.866444006685;4471.128130014616;4487.057717879282;1175.1186610042469;1188.708766352232;1274.350081458463;1277.8977681785434;1281.5002149161835;775.8176828445311;800.2517646001007;809.4900212865857;811.1928631012181;828.7265202483167;6399.880020996307;6641.767538136142;7153.6780844862105;7217.819554511179;7256.419486441072;916.7211739623189;936.9578368973413;943.1888575824785;960.1934375124929;964.3876138652007;3635.2729454109285;3846.788426636562;3957.1294315250598;3990.20195960809;4058.492688413948;14391.712165756626;15065.246737663074;15086.471973481095;15097.698046039237;15119.395224191128;14863.851361486462;15003.024803387745;15014.324355404033;15015;15035;8236.794080148044;8825.470471771585;8838.425502342327;8857.46855188678;8870.645174193089;5898.498627616701;7129.714811407485;7140.500164988401;7366.416220512616;7374.299441553044;14044.297785110499;14196.503122390666;14206.21865797391;14220.857791421953;14288.642783930069;14094.647633809236;14291.428342866584;14407.927960360132;14463.204523750874;14489.492867749577;9162.404443711404;9471.71584852428;9480.478573678514;9542.713718588453;9634.566444510154;10904.78190436171;11018.173636977099;11020.889791102307;11031.007209351197;11037.282576632493;9153.17621414076;9243.735284855253;9307.441553506811;9318.114779095975;9501.097395747393;8965.955170224108;8986.68546600866;9074;9130.771730706785;9137.451752894847;7050.576965381888;8365.707200247962;8369.288610468178;8425.95787021061;8490;6149.96925015372;6250.4687101595055;6259.436650701458;6325.146105275729;6377.521685873486;6026.849328766816;6152.292486364126;6236.251649801871;6366.140571023038;6527.2167339919515 11:44:50 INFO - PID 1648 | |4;prototype.html;372.9123655940853;386.20056483080026;399.9920001599966;400.449382099613;411.2741012113618;5325.467453254672;6366.071607060758;6517.152770139881;6518.174911024908;6601.788400885709;4908.705477671342;5215.504527069932;6245.812625621227;6350.682465876699;6395.168628078343;4240;4244.0663054128045;4593.678442509025;4611.216093264153;4643.744594047326;4649.721016738989;4650.837220697276;4666.4400271967415;4666.486686464503;4668.2997550367545;4115.73247738896;4169.812358443862;4602.861914142581;4608.410098516007;4764.714117152982;4167.458230430025;4256.595323543239;4607.7004994675235;4609.792559334838;4611.446626404853;3662.853485860562;3934.4885164928664;3951.4863067801;3958.7030972677167;3960.7919584526708;4994.750262486861;5850.531957443394;6120.724567394478;6278.627193398704;6300.621962682254;4882.902341953177;5190.480951904817;5499.195410340715;5517.69652669103;5520.723963801794;6779.576338928887;6818.28408017156;6858.799710050771;6867.896981545281;6876.106106206211;4189.518205406387;4421.960839202785;4449.998750281193;4450.332450132506;4485.596296333346;6857.485688573377;7007.299270073003;7187.5687458752645;7249.818754531127;7320.926790732078;4309.987153019038;4497.280435130363;4516.977415112903;4532.456105267389;4540.523245059288;3999.440078389028;4296.334068219431;4582.24392975162;4591.586757191869;4606.907861842811;4865.513448655106;5459.672419654833;5474.835754927359;5652.406497317805;5829.154772557956;5146.636141422526;5643.971780141073;5943.3462319144855;6919.619420931897;6989.615571143534;1580.8893377463467;1787.7944036435977;1802.6124383257663;1806.2504060814877;1811.7644706188112;1061.2359101446946;1064.356064580933;1259.9685007874878;1262.3309645887693;1265.702559898423;5927.73325200367;6231.034189700604;6591.76928807499;6611.73553057882;6619.768308109193;5892.469677729025;6038.365971573009;7196;7210.459215558854;7222.855542889113;5227.712475813867;5302.92045619316;6221.626702397871;6536.8365790855605;6571.80284591463;3689.317476266899;3705.221903400316;3735.869244576427;3737.7383583148917;3743.438484227333;6102.481289090477;6523.151990241238;7201.675924583406;7355.499130295725;7372.447066470037;6891.310868913069;7280.271972802678;7356.926430735624;7379.926200738031;7394.667239974106;6709.932900671029;7309.122905251297;7330.941650261303;7340.596267205355;7341.375983041395;5930.406959304035;6813.625250611264;7146.714131434893;7152.320529549685;7182.4972251943955;6477.967610161919;6535.57518761279;6776.390124888783;7026.508144429841;7042.577445353295;7191.532550384209;7327.890081648886;7400.40796736249;7412.481126321321;7486.475946683898;3949.6642785363565;4225.830627029728;4334.5882141196535;4571.931421028621;4578.862634120982;5908.261467316585;6414.262359828679;7202.351788339075;7227.457940654367;7240.275972402717;6371.904421433589;6787.694553744995;6902.378785909293;6910.308969103049;7011.614361210182;6338.985762278197;6638.601683898982;6889.863172576669;6909.619970901648;6975.511714180162;5944.286685597583;6497.870042599027;6673.866522669617;6746.831329216612;6810.9318906810295;5753.539716822561;6888.104546408935;6962.268961758942;6973.965130174317;6992.615406152507;6033.366496517802;6405.231372235362;6764.2221144569;6805.434750007427;6824.692888819915 11:44:50 INFO - PID 1648 | |5;yui.html;813.9959300203498;815.5962798414786;847.2967437027266;850.0352100366086;856.8243510080434;14732.926335368307;15309;16912;17357.479275621736;17393.82606173938;17555;18041.639167216646;18248.178831952562;18341;18348.174332155053;978.2076518020391;997.4464172384323;1013.6502906497246;1014.0873214107326;1014.6803756816579;893.5576889439704;1005.8239808033602;1022.826119559673;1027.2295778166376;1027.650598796409;585.9677717725523;664.1996394344803;976.3507267666993;1040.230229630072;1047.4239168457357;1036.6527213383563;1036.72008557689;1037.870266216723;1038.9792204155913;1039.3867618105278;762.8931949527071;764.1708746010585;765.7473033898856;767.5586537740766;770.7225398856464;16917.66164676712;17014;17081.572960676083;17288.65422691539;17547.649047019117;15867.206639668086;16159.757603635955;16238.350465981346;16255.918720406322;16268.26792794327;14678.779818302733;15183.468578599695;15298.235088245543;15311.081335119818;15351.232438378036;3621.94567081494;3635.4546817977516;3653.6346365363515;3659.79871107089;3672.559292884844;109350.45324773325;141895.29052354672;144997;145569.2721536396;152549;13504.257265850276;13863.584092477242;13959.79060314096;14016.088954217943;14171.929140354232;24448.87775561111;25520.361990950376;26024.34939126499;27061.729382705922;27072;7132.964335178291;7727.49771264866;7765.223477652303;7906.9604651977525;8224.424290299621;3977.6817854571564;4071.857484988011;4076.694247931417;4080.775557344315;4095.1195492969155;4243.490781106287;4305.870823875226;4313.719608225455;4319.676024298127;4320.854973432043;930.3580529434669;939.8026414452904;940.149165005671;945.9479728614855;952.7237101240701;554.0525701051248;630.3916720364779;651.4267444648679;669.8660267946333;695.474916438086;11341.489632966372;12350.567730129582;12570.434330455151;12668.936655316664;12886.420111095023;632.2969306215872;649.1625802714445;655.4395991427357;682.1881960467127;684.2780866186122;5449.809256675997;5515.25544051556;5624.072028115315;5760.164776107441;5890.352061273256;120125.39937300257;123241;123548;126176;137075.6292437063;93635;104562;111474;114385;115570;99312;101048.49475752574;105611.4719426398;107206.92793072126;108346;10008.39949603026;11959.940200298943;12464.813027804772;12875.291858947849;13042.608721737983;43372;43544.34683479814;43947.9013024677;44038.55961440473;44992.55007449884;74522;78422;79154.6042269808;79190.6040469794;80946.19053809623;15131.167785771448;15198.544043678707;15441.382344706535;15533;15564.688706225586;16841.663166736842;18559.44321670352;19095.70851497829;19465.805341946965;19755.604887901874;17629.823701763333;18151.90924045371;18292.81707182911;18702.719459208394;18791.624167516846;15807.36770529166;17701.380451684643;18013.189406477002;18945.147468364234;19000;17649.29402823924;17885.46343609694;18551.628967420307;18983.24067037303;19003.814561325624;9808.558614862492;10407.94796026015;11236.719082023015;11887.465064071965;11964.58123965657;13943.093698909945;14089.618491561747;14172.929135354258;14207.147571145351;14219.289035548389 11:44:50 INFO - PID 1648 | __end_tp_report 11:44:50 INFO - PID 1648 | __start_cc_report 11:44:50 INFO - PID 1648 | _x_x_mozilla_cycle_collect,460 11:44:50 INFO - PID 1648 | __end_cc_report 11:44:50 INFO - PID 1648 | __startTimestamp1496169890669__endTimestamp 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | ------- Summary: start ------- 11:44:50 INFO - PID 1648 | Number of tests: 6 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6202.89 Median:6424.55 stddev:762.26 (11.9%) stddev-sans-first:725.36 11:44:50 INFO - PID 1648 | Values: 3125.6 3603.6 3617.5 3627.1 3635.8 6113.4 6219.0 6241.7 6247.5 6272.2 6163.1 6200.7 6232.9 6273.8 6308.4 4618.6 5349.9 5468.9 5556.2 5608.9 5596.3 5649.3 5663.3 5685.0 5711.0 5470.0 5501.8 5534.1 5568.6 5643.5 4496.4 5561.7 5607.9 5629.8 5630.3 5517.4 5545.2 5545.6 5574.4 5594.5 6177.7 6234.6 6236.6 6281.9 6311.6 6628.7 6806.7 6809.3 6817.6 6824.4 5334.2 6857.3 6878.5 6884.7 6915.9 6232.1 6310.5 6319.6 6329.7 6331.7 6817.6 6867.2 6963.0 6991.9 7025.6 6448.7 6521.1 6577.5 6626.3 6651.9 5747.5 6749.6 6853.6 6873.8 6891.2 6148.9 6463.5 6713.8 6782.4 6800.5 5584.5 6630.7 6720.5 6759.0 6807.4 5381.5 5398.4 5406.1 5420.3 5438.2 4746.1 4759.7 4784.7 4784.8 4791.3 6123.7 6277.4 6325.0 6428.8 6459.8 6920.5 6922.9 6923.0 6931.7 6942.5 6404.3 6424.6 6445.8 6481.2 6492.2 5819.2 5893.6 6010.9 6032.0 6073.4 6978.1 7011.4 7038.4 7042.2 7064.9 5776.5 6827.9 6879.7 6886.2 6901.9 6797.5 6891.6 7000.8 7073.1 7092.8 5240.4 5798.5 6734.7 6749.3 6761.4 6884.9 6892.7 6917.4 6921.1 6930.7 6432.0 6537.2 6549.9 6595.5 6616.6 5971.5 6299.2 6612.8 6668.5 6696.7 6384.1 6480.5 6511.5 6522.8 6529.2 6394.2 6486.2 6564.5 6642.3 6675.0 6405.0 6408.8 6438.5 6475.4 6489.1 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:43956.63 Median:19480.85 stddev:73933.04 (379.5%) stddev-sans-first:74069.08 11:44:50 INFO - PID 1648 | Values: 693.5 756.2 846.1 1273.6 1315.7 39515.0 40570.0 40577.0 40638.4 40749.0 41094.2 41141.0 41153.4 41189.2 41226.0 7951.0 8098.1 8377.6 8582.2 8599.0 8483.4 8602.1 8610.4 8623.3 8626.1 8542.4 8587.4 8649.0 8661.0 8676.1 8576.2 8614.4 8627.6 8629.4 8648.4 8256.3 8285.5 8350.5 8353.2 8382.4 37779.2 38029.9 38032.6 38091.6 38223.4 3522.4 3548.1 3570.4 3571.7 3575.7 31023.7 31030.7 31059.8 31062.0 31062.2 3079.0 3097.5 3117.3 3125.7 3134.2 236836.0 239722.0 274910.6 289497.0 290778.0 48533.8 48784.8 48844.3 48874.8 48887.8 51128.0 51252.2 51266.0 54113.2 54586.0 14800.4 14949.9 14955.8 14962.9 14971.8 11918.5 11955.0 11981.3 11991.2 12001.9 14285.2 14301.1 14325.6 14342.9 14447.2 5288.6 6243.4 6284.7 6291.9 6305.4 892.4 950.6 960.4 978.1 1006.6 22167.4 24759.3 25071.4 25162.0 25316.2 1121.2 1125.6 1143.9 1151.2 1170.4 10441.6 12741.7 12794.9 12934.2 12977.0 306459.0 309280.0 309933.0 310517.0 311303.0 56154.9 56352.9 56495.7 56495.7 56565.2 1463.3 1515.0 1516.3 1526.0 1555.5 22411.8 22816.0 24098.9 24262.3 24328.6 32516.7 39040.0 39041.0 39100.6 39116.8 248644.8 271714.0 273670.0 274337.0 274922.0 34656.7 35439.3 35455.5 35569.8 35728.8 45029.9 45090.1 45102.8 45168.3 45189.3 28412.4 29383.4 30782.5 30817.8 30894.8 22784.8 30105.4 30598.4 30791.7 31086.7 35527.8 36786.4 37210.8 37320.0 37413.6 29246.4 29471.3 29726.4 31019.9 31213.5 23080.5 23691.4 23770.8 23966.5 24153.2 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:37622.99 Median:23450.91 stddev:87986.04 (375.2%) stddev-sans-first:88188.45 11:44:50 INFO - PID 1648 | Values: 389.3 406.4 421.2 443.1 455.9 21782.6 22579.0 22707.9 23757.3 25005.0 24474.5 24799.5 24834.4 24874.8 24957.6 6236.7 6379.6 6382.2 6396.0 6396.8 6067.8 6326.7 6332.8 6348.3 6350.9 6114.9 6121.1 6173.4 6177.6 6187.3 6149.7 6153.3 6182.0 6184.7 6190.4 5476.0 5488.8 5501.5 5507.1 5516.0 24251.9 28079.4 28404.7 28827.6 28875.4 2169.2 2265.0 2265.6 2265.7 2269.6 18696.6 18985.1 18989.7 19007.0 19030.3 2120.9 2160.8 2190.5 2191.6 2222.7 164411.0 170479.0 190267.0 200489.9 205272.0 13127.5 13406.9 13513.5 13572.5 13735.7 17234.9 17395.1 17421.7 17447.0 17453.7 9090.3 9120.7 9320.1 9361.6 9407.5 9017.0 9057.4 9091.9 9127.9 9134.9 6894.7 6979.6 8301.5 8354.6 8379.3 2022.6 2217.7 2259.9 2265.9 2269.3 822.9 907.0 951.6 964.3 979.5 19266.5 21158.6 22206.4 22214.1 22643.4 956.2 963.2 972.2 987.0 990.9 9892.6 11209.9 11494.7 11602.7 11608.1 439543.0 507565.5 508140.0 509688.0 525149.0 150561.5 152510.7 156751.2 157175.2 157988.2 1013.4 1164.8 1165.3 1225.1 1233.4 23336.5 23565.3 23801.5 24099.3 24137.9 70722.0 71305.0 71636.6 71741.3 72362.6 95926.5 95956.0 96688.5 96699.0 97291.5 9925.5 10390.3 10424.6 10431.9 10602.0 18486.5 18519.2 18616.0 18710.4 18749.0 15122.9 15225.8 15415.5 15418.1 15450.0 13553.3 13805.0 14043.3 14064.8 14217.1 15215.3 15222.9 15536.5 15542.9 15559.3 9998.9 10463.7 10477.4 10498.3 10570.9 12593.9 14745.6 15028.8 15067.9 15107.5 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:7206.75 Median:3973.67 stddev:4609.62 (116.0%) stddev-sans-first:4600.55 11:44:50 INFO - PID 1648 | Values: 1213.3 1363.7 1424.8 1434.3 1448.5 11733.1 11734.2 12212.8 12238.2 12482.9 12030.0 12188.5 12209.5 12225.8 12230.2 2533.6 2576.3 2577.9 2584.0 2586.0 2123.9 2158.6 2217.3 2498.9 2502.8 2574.3 2581.3 2587.3 2591.0 2592.7 2543.8 2552.5 2556.9 2557.1 2559.7 2522.8 2536.4 2545.3 2545.8 2547.6 9811.9 11756.9 11998.5 12001.3 12004.9 6493.3 6740.7 6745.8 6746.8 6754.3 4022.8 4171.2 4234.7 4244.2 4246.0 1230.3 1255.8 1285.0 1285.5 1286.0 16645.5 16727.7 16754.3 16758.4 16788.0 4725.4 5513.0 5647.7 5668.6 5699.9 9695.5 11170.6 12000.0 12007.0 12008.2 5493.6 6390.0 6441.8 6494.5 6497.2 4337.9 6153.2 6157.7 6212.1 6257.7 4365.5 4422.9 4451.9 4471.1 4487.1 1175.1 1188.7 1274.4 1277.9 1281.5 775.8 800.3 809.5 811.2 828.7 6399.9 6641.8 7153.7 7217.8 7256.4 916.7 937.0 943.2 960.2 964.4 3635.3 3846.8 3957.1 3990.2 4058.5 14391.7 15065.2 15086.5 15097.7 15119.4 14863.9 15003.0 15014.3 15015.0 15035.0 8236.8 8825.5 8838.4 8857.5 8870.6 5898.5 7129.7 7140.5 7366.4 7374.3 14044.3 14196.5 14206.2 14220.9 14288.6 14094.6 14291.4 14407.9 14463.2 14489.5 9162.4 9471.7 9480.5 9542.7 9634.6 10904.8 11018.2 11020.9 11031.0 11037.3 9153.2 9243.7 9307.4 9318.1 9501.1 8966.0 8986.7 9074.0 9130.8 9137.5 7050.6 8365.7 8369.3 8426.0 8490.0 6150.0 6250.5 6259.4 6325.1 6377.5 6026.8 6152.3 6236.3 6366.1 6527.2 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5474.37 Median:5988.83 stddev:1745.59 (29.1%) stddev-sans-first:1707.98 11:44:50 INFO - PID 1648 | Values: 372.9 386.2 400.0 400.4 411.3 5325.5 6366.1 6517.2 6518.2 6601.8 4908.7 5215.5 6245.8 6350.7 6395.2 4240.0 4244.1 4593.7 4611.2 4643.7 4649.7 4650.8 4666.4 4666.5 4668.3 4115.7 4169.8 4602.9 4608.4 4764.7 4167.5 4256.6 4607.7 4609.8 4611.4 3662.9 3934.5 3951.5 3958.7 3960.8 4994.8 5850.5 6120.7 6278.6 6300.6 4882.9 5190.5 5499.2 5517.7 5520.7 6779.6 6818.3 6858.8 6867.9 6876.1 4189.5 4422.0 4450.0 4450.3 4485.6 6857.5 7007.3 7187.6 7249.8 7320.9 4310.0 4497.3 4517.0 4532.5 4540.5 3999.4 4296.3 4582.2 4591.6 4606.9 4865.5 5459.7 5474.8 5652.4 5829.2 5146.6 5644.0 5943.3 6919.6 6989.6 1580.9 1787.8 1802.6 1806.3 1811.8 1061.2 1064.4 1260.0 1262.3 1265.7 5927.7 6231.0 6591.8 6611.7 6619.8 5892.5 6038.4 7196.0 7210.5 7222.9 5227.7 5302.9 6221.6 6536.8 6571.8 3689.3 3705.2 3735.9 3737.7 3743.4 6102.5 6523.2 7201.7 7355.5 7372.4 6891.3 7280.3 7356.9 7379.9 7394.7 6709.9 7309.1 7330.9 7340.6 7341.4 5930.4 6813.6 7146.7 7152.3 7182.5 6478.0 6535.6 6776.4 7026.5 7042.6 7191.5 7327.9 7400.4 7412.5 7486.5 3949.7 4225.8 4334.6 4571.9 4578.9 5908.3 6414.3 7202.4 7227.5 7240.3 6371.9 6787.7 6902.4 6910.3 7011.6 6339.0 6638.6 6889.9 6909.6 6975.5 5944.3 6497.9 6673.9 6746.8 6810.9 5753.5 6888.1 6962.3 6974.0 6992.6 6033.4 6405.2 6764.2 6805.4 6824.7 11:44:50 INFO - PID 1648 | 11:44:50 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:24831.83 Median:18200.04 stddev:36823.31 (202.3%) stddev-sans-first:36882.44 11:44:50 INFO - PID 1648 | Values: 814.0 815.6 847.3 850.0 856.8 14732.9 15309.0 16912.0 17357.5 17393.8 17555.0 18041.6 18248.2 18341.0 18348.2 978.2 997.4 1013.7 1014.1 1014.7 893.6 1005.8 1022.8 1027.2 1027.7 586.0 664.2 976.4 1040.2 1047.4 1036.7 1036.7 1037.9 1039.0 1039.4 762.9 764.2 765.7 767.6 770.7 16917.7 17014.0 17081.6 17288.7 17547.6 15867.2 16159.8 16238.4 16255.9 16268.3 14678.8 15183.5 15298.2 15311.1 15351.2 3621.9 3635.5 3653.6 3659.8 3672.6 109350.5 141895.3 144997.0 145569.3 152549.0 13504.3 13863.6 13959.8 14016.1 14171.9 24448.9 25520.4 26024.3 27061.7 27072.0 7133.0 7727.5 7765.2 7907.0 8224.4 3977.7 4071.9 4076.7 4080.8 4095.1 4243.5 4305.9 4313.7 4319.7 4320.9 930.4 939.8 940.1 945.9 952.7 554.1 630.4 651.4 669.9 695.5 11341.5 12350.6 12570.4 12668.9 12886.4 632.3 649.2 655.4 682.2 684.3 5449.8 5515.3 5624.1 5760.2 5890.4 120125.4 123241.0 123548.0 126176.0 137075.6 93635.0 104562.0 111474.0 114385.0 115570.0 99312.0 101048.5 105611.5 107206.9 108346.0 10008.4 11959.9 12464.8 12875.3 13042.6 43372.0 43544.3 43947.9 44038.6 44992.6 74522.0 78422.0 79154.6 79190.6 80946.2 15131.2 15198.5 15441.4 15533.0 15564.7 16841.7 18559.4 19095.7 19465.8 19755.6 17629.8 18151.9 18292.8 18702.7 18791.6 15807.4 17701.4 18013.2 18945.1 19000.0 17649.3 17885.5 18551.6 18983.2 19003.8 9808.6 10407.9 11236.7 11887.5 11964.6 13943.1 14089.6 14172.9 14207.1 14219.3 11:44:50 INFO - PID 1648 | -------- Summary: end -------- 11:44:50 INFO - PID 1648 | 11:44:51 INFO - TEST-INFO | 1648: exit 0 11:44:51 INFO - TEST-OK | dromaeo_css | took 1143756ms 11:44:51 INFO - TEST-START | kraken 11:44:51 INFO - operating with platform_type : mac_ 11:44:51 INFO - Initialising browser for kraken test... 11:44:51 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp1odVMQ/profile 11:44:51 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 11:45:25 INFO - TEST-INFO | 1658: killed by SIGTERM 11:45:25 INFO - Browser initialized. 11:45:25 INFO - Running cycle 1/1 for kraken test... 11:45:25 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/T/tmp1odVMQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:45:27 INFO - PID 1662 | 2017-05-30 11:45:27.628 plugin-container[1663:10535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 11:45:27 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:45:27 INFO - PID 1662 | 2017-05-30 11:45:27.630 plugin-container[1663:10535] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9e0b, name = 'com.apple.CFPasteboardClient' 11:45:27 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:45:27 INFO - PID 1662 | 2017-05-30 11:45:27.630 plugin-container[1663:10535] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:45:28 INFO - PID 1662 | 2017-05-30 11:45:28.084 plugin-container[1664:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 11:45:28 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:45:28 INFO - PID 1662 | 2017-05-30 11:45:28.084 plugin-container[1664:10577] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9c0b, name = 'com.apple.CFPasteboardClient' 11:45:28 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:45:28 INFO - PID 1662 | 2017-05-30 11:45:28.084 plugin-container[1664:10577] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:45:37 INFO - PID 1662 | RSS: Main: 262299648 11:45:37 INFO - PID 1662 | 11:45:40 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 11:45:41 INFO - PID 1662 | RSS: Main: 272289792 11:45:41 INFO - PID 1662 | 11:45:43 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 11:45:44 INFO - PID 1662 | RSS: Main: 279945216 11:45:44 INFO - PID 1662 | 11:45:47 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 11:45:48 INFO - PID 1662 | RSS: Main: 281927680 11:45:48 INFO - PID 1662 | 11:45:50 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 11:45:50 INFO - PID 1662 | RSS: Main: 284123136 11:45:50 INFO - PID 1662 | 11:45:52 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:45:53 INFO - PID 1662 | RSS: Main: 284762112 11:45:53 INFO - PID 1662 | 11:45:56 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 11:45:56 INFO - PID 1662 | RSS: Main: 288989184 11:45:56 INFO - PID 1662 | 11:45:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 11:46:00 INFO - PID 1662 | RSS: Main: 292249600 11:46:00 INFO - PID 1662 | 11:46:03 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 11:46:04 INFO - PID 1662 | RSS: Main: 290856960 11:46:04 INFO - PID 1662 | 11:46:05 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:46:06 INFO - PID 1662 | RSS: Main: 290676736 11:46:06 INFO - PID 1662 | 11:46:07 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:46:08 INFO - PID 1662 | RSS: Main: 290422784 11:46:08 INFO - PID 1662 | 11:46:10 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:46:11 INFO - PID 1662 | RSS: Main: 290455552 11:46:11 INFO - PID 1662 | 11:46:14 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:46:15 INFO - PID 1662 | RSS: Main: 290066432 11:46:15 INFO - PID 1662 | 11:46:18 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:46:18 INFO - PID 1662 | RSS: Main: 290287616 11:46:18 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 11:46:20 INFO - PID 1662 | RSS: Main: 292081664 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | __start_tp_report 11:46:20 INFO - PID 1662 | _x_x_mozilla_page_load 11:46:20 INFO - PID 1662 | _x_x_mozilla_page_load_details 11:46:20 INFO - PID 1662 | |i|pagename|runs| 11:46:20 INFO - PID 1662 | |0;ai-astar;100;114;113;113;113;114;111;112;116;112 11:46:20 INFO - PID 1662 | |1;audio-beat-detection;143;114;115;146;108;114;109;114;137;113 11:46:20 INFO - PID 1662 | |2;audio-dft;200;200;201;218;236;264;257;197;199;202 11:46:20 INFO - PID 1662 | |3;audio-fft;73;47;56;89;82;82;86;50;69;47 11:46:20 INFO - PID 1662 | |4;audio-oscillator;81;67;83;69;71;70;86;68;68;93 11:46:20 INFO - PID 1662 | |5;imaging-gaussian-blur;92;91;92;91;91;92;92;97;91;90 11:46:20 INFO - PID 1662 | |6;imaging-darkroom;105;98;96;96;96;96;96;94;96;96 11:46:20 INFO - PID 1662 | |7;imaging-desaturate;90;92;93;93;95;89;91;93;116;144 11:46:20 INFO - PID 1662 | |8;json-parse-financial;49;49;51;50;51;52;51;50;53;52 11:46:20 INFO - PID 1662 | |9;json-stringify-tinderbox;56;57;56;60;64;61;57;58;57;56 11:46:20 INFO - PID 1662 | |10;stanford-crypto-aes;113;105;88;95;100;87;89;89;144;111 11:46:20 INFO - PID 1662 | |11;stanford-crypto-ccm;137;136;133;135;170;138;123;139;193;148 11:46:20 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;190;190;187;209;232;184;239;230;204;193 11:46:20 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;63;69;67;69;67;78;58;68;102;90 11:46:20 INFO - PID 1662 | __end_tp_report 11:46:20 INFO - PID 1662 | __start_cc_report 11:46:20 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1194 11:46:20 INFO - PID 1662 | __end_cc_report 11:46:20 INFO - PID 1662 | __startTimestamp1496169980982__endTimestamp 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | ------- Summary: start ------- 11:46:20 INFO - PID 1662 | Number of tests: 14 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:111.80 Median:113.00 stddev:4.37 (3.9%) stddev-sans-first:1.45 11:46:20 INFO - PID 1662 | Values: 100.0 114.0 113.0 113.0 113.0 114.0 111.0 112.0 116.0 112.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:121.30 Median:114.50 stddev:14.62 (12.8%) stddev-sans-first:13.23 11:46:20 INFO - PID 1662 | Values: 143.0 114.0 115.0 146.0 108.0 114.0 109.0 114.0 137.0 113.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:217.40 Median:210.00 stddev:25.72 (12.2%) stddev-sans-first:26.50 11:46:20 INFO - PID 1662 | Values: 200.0 200.0 201.0 218.0 236.0 264.0 257.0 197.0 199.0 202.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:68.10 Median:77.50 stddev:16.78 (21.6%) stddev-sans-first:17.70 11:46:20 INFO - PID 1662 | Values: 73.0 47.0 56.0 89.0 82.0 82.0 86.0 50.0 69.0 47.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:75.60 Median:76.00 stddev:9.31 (12.3%) stddev-sans-first:9.67 11:46:20 INFO - PID 1662 | Values: 81.0 67.0 83.0 69.0 71.0 70.0 86.0 68.0 68.0 93.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:91.90 Median:92.00 stddev:1.91 (2.1%) stddev-sans-first:2.03 11:46:20 INFO - PID 1662 | Values: 92.0 91.0 92.0 91.0 91.0 92.0 92.0 97.0 91.0 90.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:96.90 Median:96.00 stddev:3.00 (3.1%) stddev-sans-first:1.00 11:46:20 INFO - PID 1662 | Values: 105.0 98.0 96.0 96.0 96.0 96.0 96.0 94.0 96.0 96.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:92.50 stddev:17.41 (18.8%) stddev-sans-first:18.12 11:46:20 INFO - PID 1662 | Values: 90.0 92.0 93.0 93.0 95.0 89.0 91.0 93.0 116.0 144.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:50.80 Median:51.00 stddev:1.32 (2.6%) stddev-sans-first:1.22 11:46:20 INFO - PID 1662 | Values: 49.0 49.0 51.0 50.0 51.0 52.0 51.0 50.0 53.0 52.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:57.50 stddev:2.66 (4.6%) stddev-sans-first:2.70 11:46:20 INFO - PID 1662 | Values: 56.0 57.0 56.0 60.0 64.0 61.0 57.0 58.0 57.0 56.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:102.10 Median:87.50 stddev:17.60 (20.1%) stddev-sans-first:18.22 11:46:20 INFO - PID 1662 | Values: 113.0 105.0 88.0 95.0 100.0 87.0 89.0 89.0 144.0 111.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:145.20 Median:138.50 stddev:20.80 (15.0%) stddev-sans-first:21.85 11:46:20 INFO - PID 1662 | Values: 137.0 136.0 133.0 135.0 170.0 138.0 123.0 139.0 193.0 148.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:205.80 Median:206.50 stddev:20.76 (10.1%) stddev-sans-first:21.22 11:46:20 INFO - PID 1662 | Values: 190.0 190.0 187.0 209.0 232.0 184.0 239.0 230.0 204.0 193.0 11:46:20 INFO - PID 1662 | 11:46:20 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:68.50 stddev:13.37 (19.5%) stddev-sans-first:13.67 11:46:20 INFO - PID 1662 | Values: 63.0 69.0 67.0 69.0 67.0 78.0 58.0 68.0 102.0 90.0 11:46:20 INFO - PID 1662 | -------- Summary: end -------- 11:46:20 INFO - PID 1662 | 11:46:21 INFO - TEST-INFO | 1662: exit 0 11:46:21 INFO - TEST-OK | kraken | took 90522ms 11:46:21 INFO - SUITE-END | took 1234s 11:46:22 INFO - Completed test suite (00:20:34) 11:46:22 INFO - javascript benchmark 11:46:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8134.671533431601, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3125.640551336596, 3603.62161972993, 3617.5116359291487, 3627.056965189051, 3635.8363873625685, 6113.354685976882, 6219.004959206528, 6241.65670888101, 6247.531435142366, 6272.153259309992, 6163.106349579309, 6200.658963756991, 6232.940400131977, 6273.843153921142, 6308.4322410983, 4618.582249135632, 5349.946500534984, 5468.890622187554, 5556.222128901957, 5608.887822243552, 5596.328440587135, 5649.322081350223, 5663.2637757091525, 5684.971575142118, 5711.029125048752, 5469.972650136764, 5501.779928802843, 5534.142207957773, 5568.610197286192, 5643.492085712264, 4496.4154659894, 5561.721913904329, 5607.915881261784, 5629.8311050668135, 5630.296212973379, 5517.36550296717, 5545.223668686387, 5545.639533430314, 5574.358948720908, 5594.524465420444, 6177.722002509898, 6234.563580549363, 6236.594621349598, 6281.937180628227, 6311.589746666498, 6628.73485060597, 6806.693698783517, 6809.285025072346, 6817.625030623314, 6824.4199243064395, 5334.1732031535175, 6857.348551887562, 6878.518503704742, 6884.65576721162, 6915.861682766316, 6232.065190221503, 6310.5267104967315, 6319.58922670025, 6329.7468101275435, 6331.651759153291, 6817.5568588041615, 6867.2102708189195, 6963.0251764752975, 6991.930080699128, 7025.613591252531, 6448.742050317935, 6521.08704781331, 6577.539572229898, 6626.337366263335, 6651.86696266072, 5747.540196784247, 6749.5612785168805, 6853.588784672936, 6873.759418420332, 6891.207511136284, 6148.8770224595255, 6463.450606698483, 6713.832154196087, 6782.355676210752, 6800.455963522906, 5584.46947539983, 6630.668466576748, 6720.529562930646, 6759.019942108367, 6807.353301436455, 5381.515663590296, 5398.352197736218, 5406.053940560465, 5420.322459692538, 5438.15708565173, 4746.121967436035, 4759.714417134983, 4784.712917224978, 4784.7607619619575, 4791.281307803857, 6123.663198524035, 6277.435030847246, 6324.968375158095, 6428.8071357859335, 6459.838504037342, 6920.515563910579, 6922.861542769117, 6922.965385173143, 6931.722731090801, 6942.548734332252, 6404.263509696445, 6424.550281480251, 6445.806625801233, 6481.157449531531, 6492.156019717501, 5819.185314056035, 5893.646381217055, 6010.879782404328, 6032.034874420072, 6073.362296958844, 6978.092847929838, 7011.368976792215, 7038.40173585251, 7042.154941407064, 7064.929350706427, 5776.508996735324, 6827.897581536182, 6879.690413931285, 6886.24251332343, 6901.861962760616, 6797.456203503807, 6891.552049116792, 7000.775764326832, 7073.080499535027, 7092.822679433056, 5240.423553409199, 5798.507126894261, 6734.696938637871, 6749.291324410866, 6761.391474767294, 6884.8967265490055, 6892.689828957608, 6917.446604271747, 6921.065656136358, 6930.688119034555, 6432.035194720829, 6537.215534135934, 6549.901751473636, 6595.505337099641, 6616.636085015178, 5971.492423144082, 6299.212598425197, 6612.8346791330605, 6668.466522678271, 6696.732130714911, 6384.138141350858, 6480.513961452815, 6511.544191906521, 6522.804315870531, 6529.216494020748, 6394.200724909387, 6486.2216534016225, 6564.5076619252795, 6642.269350371552, 6675.0, 6405.0, 6408.839779005562, 6438.452731517874, 6475.384838440341, 6489.123968264224], "value": 6152.8240441105545, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.4729605499821, 756.2362014365492, 846.1453931529155, 1273.5797186928314, 1315.730275293565, 39515.0, 40570.0, 40576.99999999996, 40638.39042414366, 40748.981275468126, 41094.17811643773, 41141.0, 41153.38269925946, 41189.17621647565, 41226.0, 7950.999999999985, 8098.149694282104, 8377.581120943958, 8582.184692454206, 8598.957005214965, 8483.448575842582, 8602.139786021411, 8610.354223433236, 8623.310135189202, 8626.094260102693, 8542.44474109184, 8587.355948303872, 8649.005364383114, 8660.956695216515, 8676.089010653885, 8576.228139467446, 8614.353923455767, 8627.568621568958, 8629.395942284043, 8648.43785153963, 8256.339492840558, 8285.54429506377, 8350.457220280663, 8353.24820766128, 8382.371322150862, 37779.24441511182, 38029.85910422692, 38032.61967380319, 38091.619083809084, 38223.426648600296, 3522.4011917974008, 3548.0774998500574, 3570.393033184339, 3571.6964058055096, 3575.696065834381, 31023.689763102306, 31030.689693103006, 31059.84470077658, 31062.0, 31062.223444413845, 3079.0, 3097.488914329133, 3117.267442151093, 3125.687431256856, 3134.2164458885277, 236836.0, 239722.0, 274910.6254468735, 289497.0, 290778.0, 48533.75733121312, 48784.75607621939, 48844.267335989985, 48874.75562622164, 48887.755561221966, 51128.0, 51252.231216531785, 51265.97468050618, 54113.1883021755, 54586.0, 14800.407983680747, 14949.925250373679, 14955.775663365059, 14962.925185374004, 14971.775423368657, 11918.523259069714, 11954.983826374855, 11981.341026243466, 11991.160618756603, 12001.87998120025, 14285.214313212873, 14301.070430422198, 14325.570232892822, 14342.928285358716, 14447.205403702686, 5288.576913846883, 6243.406876346739, 6284.717187726564, 6291.937080629226, 6305.432511073934, 892.4065496444921, 950.6150009246267, 960.4237457525569, 978.1294647763497, 1006.597361055569, 22167.44581385478, 24759.25722228336, 25071.373215669755, 25162.0, 25316.240512784647, 1121.1535290855352, 1125.6341688951125, 1143.8798926112804, 1151.1999160583314, 1170.3680012792997, 10441.582336706446, 12741.745165096647, 12794.872051279553, 12934.223946563236, 12977.0, 306459.0, 309280.0, 309933.0, 310517.0, 311303.0, 56154.87690246172, 56352.87294254092, 56495.71752141213, 56495.71752141295, 56565.15152272719, 1463.2903042024823, 1515.0152400939257, 1516.3100789140915, 1526.0462211118052, 1555.4633651390252, 22411.77588224162, 22815.973281202052, 24098.87950560306, 24262.272131836075, 24328.635070473603, 32516.67483325231, 39040.0, 39041.0, 39100.6089939097, 39116.804415977735, 248644.75677621496, 271714.0, 273670.0, 274337.0, 274922.0, 34656.65343346635, 35439.29121417506, 35455.468167976986, 35569.82215088908, 35728.82135589305, 45029.874253143935, 45090.06914550544, 45102.77448612736, 45168.32247516356, 45189.322160166965, 28412.431751364442, 29383.412331753672, 30782.538261926537, 30817.845910770302, 30894.845525772227, 22784.772152278267, 30105.397892042474, 30598.388032239676, 30791.69208307978, 31086.689133109285, 35527.82236088906, 36786.448203277505, 37210.813945930095, 37320.0, 37413.625863741014, 29246.415071698022, 29471.26321841971, 29726.405471890874, 31019.91430299929, 31213.53179702261, 23080.538389231784, 23691.40771480727, 23770.762292376854, 23966.520669586163, 24153.15463958753], "value": 17656.13638888186, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.345898889865, 406.36404027696636, 421.21653724073235, 443.11598361269273, 455.869443779427, 21782.564348713015, 22579.0, 22707.886460567715, 23757.31761576954, 25005.0, 24474.510509789794, 24799.50400991979, 24834.379140521494, 24874.75125248747, 24957.625635615434, 6236.688165591736, 6379.649119298437, 6382.20222472191, 6395.968020159893, 6396.840078998016, 6067.817965461043, 6326.65203413812, 6332.810015699536, 6348.26994895586, 6350.904736428957, 6114.877702445949, 6121.143039974407, 6173.382661733813, 6177.62934223948, 6187.28846182688, 6149.692515374236, 6153.323134455206, 6182.041783523561, 6184.721687524027, 6190.442860142609, 5476.014317422862, 5488.780448782044, 5501.5323697485765, 5507.118860982224, 5515.972420137874, 24251.878740606186, 28079.438411231662, 28404.715952840415, 28827.56758648622, 28875.42249155026, 2169.1648715244746, 2264.9660255096187, 2265.5695417870716, 2265.6714776357335, 2269.5914735347633, 18696.626067478574, 18985.145668444955, 18989.715154272697, 19007.0, 19030.33393831223, 2120.8621439606377, 2160.82713382929, 2190.452386903274, 2191.561687662474, 2222.6777117317897, 164411.0, 170479.0, 190267.04866475717, 200489.93385892865, 205272.0, 13127.540536081191, 13406.93296533511, 13513.459461621622, 13572.524961626494, 13735.656608584864, 17234.913825431042, 17395.130243487776, 17421.65156696859, 17447.04041277742, 17453.7381939271, 9090.318226133066, 9120.726378208665, 9320.114589114024, 9361.62553497866, 9407.4825884577, 9017.0, 9057.411268267542, 9091.909080909238, 9127.90872091284, 9134.90865091354, 6894.724211031603, 6979.581225126509, 8301.46040507365, 8354.624041918129, 8379.287760540301, 2022.595480903825, 2217.7116974793175, 2259.864428115415, 2265.875376854289, 2269.251147121446, 822.9466283157568, 906.9727908162765, 951.5955718819474, 964.320154291223, 979.4671339353888, 19266.518337041685, 21158.62468939514, 22206.444838878833, 22214.111435542396, 22643.43391415195, 956.1729104324709, 963.2390411574918, 972.1882228339274, 986.9654562090292, 990.9256805739599, 9892.604295828087, 11209.94395028036, 11494.655160345203, 11602.6519204424, 11608.071354291636, 439543.0, 507565.46217268676, 508140.0, 509688.0, 525149.0, 150561.49438505474, 152510.712339315, 156751.21624392032, 157175.21412392866, 157988.21005894896, 1013.4274135113637, 1164.7554013657057, 1165.2658824940404, 1225.0505857960152, 1233.3771445420005, 23336.533269334177, 23565.29304120879, 23801.52396952086, 24099.277021689377, 24137.879310603334, 70722.0, 71305.0, 71636.64181679058, 71741.28258717345, 72362.63818680873, 95926.52036739772, 95956.0, 96688.51655741676, 96699.033009669, 97291.51354243183, 9925.454100024568, 10390.324628899098, 10424.635137770141, 10431.895681043092, 10602.045815876609, 18486.537836554195, 18519.166637501075, 18616.0, 18710.438686839418, 18749.0, 15122.941394102307, 15225.771613425586, 15415.460458883885, 15418.074915505105, 15450.0, 13553.322333883463, 13805.033647644568, 14043.297835108, 14064.78902816479, 14217.075890067112, 15215.315310810818, 15222.934394592716, 15536.533903982898, 15542.911996160161, 15559.299831507837, 9998.900010999796, 10463.738406539898, 10477.42374169428, 10498.265121441731, 10570.894291057299, 12593.874061259638, 14745.557633271019, 15028.774568381701, 15067.924660376628, 15107.54677359681], "value": 11366.283423971327, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1213.3084142039036, 1363.7204373103511, 1424.7791592303183, 1434.2613554019688, 1448.47130797259, 11733.061355091582, 11734.237274577168, 12212.755744885097, 12238.204516706419, 12482.875171248284, 12030.0, 12188.451519681614, 12209.51161953523, 12225.816612750794, 12230.205036672633, 2533.607290869918, 2576.278641980239, 2577.9172747446023, 2583.9870800646063, 2585.961210581843, 2123.936281911545, 2158.6438237690763, 2217.323716266538, 2498.912538061168, 2502.762237587426, 2574.343542396686, 2581.341757851745, 2587.275562842407, 2590.974090259092, 2592.6777343554736, 2543.834650747705, 2552.5022620588993, 2556.8849401776965, 2557.1433569754154, 2559.744025597444, 2522.7729504344516, 2536.4293034067373, 2545.27459673992, 2545.8472491650373, 2547.617857321398, 9811.901880981242, 11756.882431175749, 11998.460069296903, 12001.279923204636, 12004.939975300067, 6493.285738568754, 6740.730370785162, 6745.797626071226, 6746.8313292167595, 6754.324567543255, 4022.8189731462153, 4171.228322760299, 4234.703570750049, 4244.236037513247, 4245.9787701061605, 1230.329470438613, 1255.755127750089, 1285.0490636928605, 1285.5436320106414, 1285.967850803728, 16645.50063498097, 16727.749083763752, 16754.329826806912, 16758.413455528997, 16787.999999999876, 4725.385699859065, 5513.035218836688, 5647.689377084218, 5668.603197776199, 5699.857503562361, 9695.466749328854, 11170.598321360947, 12000.0, 12007.03943684503, 12008.159428840072, 5493.642913210629, 6390.0, 6441.806745797634, 6494.512911531559, 6497.18785151856, 4337.934930976038, 6153.230846144232, 6157.692115394212, 6212.099245609361, 6257.74969001244, 4365.4979677337515, 4422.933655995163, 4451.866444006685, 4471.128130014616, 4487.057717879282, 1175.1186610042469, 1188.708766352232, 1274.350081458463, 1277.8977681785434, 1281.5002149161835, 775.8176828445311, 800.2517646001007, 809.4900212865857, 811.1928631012181, 828.7265202483167, 6399.880020996307, 6641.767538136142, 7153.6780844862105, 7217.819554511179, 7256.419486441072, 916.7211739623189, 936.9578368973413, 943.1888575824785, 960.1934375124929, 964.3876138652007, 3635.2729454109285, 3846.788426636562, 3957.1294315250598, 3990.20195960809, 4058.492688413948, 14391.712165756626, 15065.246737663074, 15086.471973481095, 15097.698046039237, 15119.395224191128, 14863.851361486462, 15003.024803387745, 15014.324355404033, 15015.0, 15035.0, 8236.794080148044, 8825.470471771585, 8838.425502342327, 8857.46855188678, 8870.645174193089, 5898.498627616701, 7129.714811407485, 7140.500164988401, 7366.416220512616, 7374.299441553044, 14044.297785110499, 14196.503122390666, 14206.21865797391, 14220.857791421953, 14288.642783930069, 14094.647633809236, 14291.428342866584, 14407.927960360132, 14463.204523750874, 14489.492867749577, 9162.404443711404, 9471.71584852428, 9480.478573678514, 9542.713718588453, 9634.566444510154, 10904.78190436171, 11018.173636977099, 11020.889791102307, 11031.007209351197, 11037.282576632493, 9153.17621414076, 9243.735284855253, 9307.441553506811, 9318.114779095975, 9501.097395747393, 8965.955170224108, 8986.68546600866, 9074.0, 9130.771730706785, 9137.451752894847, 7050.576965381888, 8365.707200247962, 8369.288610468178, 8425.95787021061, 8490.0, 6149.96925015372, 6250.4687101595055, 6259.436650701458, 6325.146105275729, 6377.521685873486, 6026.849328766816, 6152.292486364126, 6236.251649801871, 6366.140571023038, 6527.2167339919515], "value": 5425.456285029901, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.9123655940853, 386.20056483080026, 399.9920001599966, 400.449382099613, 411.2741012113618, 5325.467453254672, 6366.071607060758, 6517.152770139881, 6518.174911024908, 6601.788400885709, 4908.705477671342, 5215.504527069932, 6245.812625621227, 6350.682465876699, 6395.168628078343, 4240.0, 4244.0663054128045, 4593.678442509025, 4611.216093264153, 4643.744594047326, 4649.721016738989, 4650.837220697276, 4666.4400271967415, 4666.486686464503, 4668.2997550367545, 4115.73247738896, 4169.812358443862, 4602.861914142581, 4608.410098516007, 4764.714117152982, 4167.458230430025, 4256.595323543239, 4607.7004994675235, 4609.792559334838, 4611.446626404853, 3662.853485860562, 3934.4885164928664, 3951.4863067801, 3958.7030972677167, 3960.7919584526708, 4994.750262486861, 5850.531957443394, 6120.724567394478, 6278.627193398704, 6300.621962682254, 4882.902341953177, 5190.480951904817, 5499.195410340715, 5517.69652669103, 5520.723963801794, 6779.576338928887, 6818.28408017156, 6858.799710050771, 6867.896981545281, 6876.106106206211, 4189.518205406387, 4421.960839202785, 4449.998750281193, 4450.332450132506, 4485.596296333346, 6857.485688573377, 7007.299270073003, 7187.5687458752645, 7249.818754531127, 7320.926790732078, 4309.987153019038, 4497.280435130363, 4516.977415112903, 4532.456105267389, 4540.523245059288, 3999.440078389028, 4296.334068219431, 4582.24392975162, 4591.586757191869, 4606.907861842811, 4865.513448655106, 5459.672419654833, 5474.835754927359, 5652.406497317805, 5829.154772557956, 5146.636141422526, 5643.971780141073, 5943.3462319144855, 6919.619420931897, 6989.615571143534, 1580.8893377463467, 1787.7944036435977, 1802.6124383257663, 1806.2504060814877, 1811.7644706188112, 1061.2359101446946, 1064.356064580933, 1259.9685007874878, 1262.3309645887693, 1265.702559898423, 5927.73325200367, 6231.034189700604, 6591.76928807499, 6611.73553057882, 6619.768308109193, 5892.469677729025, 6038.365971573009, 7196.0, 7210.459215558854, 7222.855542889113, 5227.712475813867, 5302.92045619316, 6221.626702397871, 6536.8365790855605, 6571.80284591463, 3689.317476266899, 3705.221903400316, 3735.869244576427, 3737.7383583148917, 3743.438484227333, 6102.481289090477, 6523.151990241238, 7201.675924583406, 7355.499130295725, 7372.447066470037, 6891.310868913069, 7280.271972802678, 7356.926430735624, 7379.926200738031, 7394.667239974106, 6709.932900671029, 7309.122905251297, 7330.941650261303, 7340.596267205355, 7341.375983041395, 5930.406959304035, 6813.625250611264, 7146.714131434893, 7152.320529549685, 7182.4972251943955, 6477.967610161919, 6535.57518761279, 6776.390124888783, 7026.508144429841, 7042.577445353295, 7191.532550384209, 7327.890081648886, 7400.40796736249, 7412.481126321321, 7486.475946683898, 3949.6642785363565, 4225.830627029728, 4334.5882141196535, 4571.931421028621, 4578.862634120982, 5908.261467316585, 6414.262359828679, 7202.351788339075, 7227.457940654367, 7240.275972402717, 6371.904421433589, 6787.694553744995, 6902.378785909293, 6910.308969103049, 7011.614361210182, 6338.985762278197, 6638.601683898982, 6889.863172576669, 6909.619970901648, 6975.511714180162, 5944.286685597583, 6497.870042599027, 6673.866522669617, 6746.831329216612, 6810.9318906810295, 5753.539716822561, 6888.104546408935, 6962.268961758942, 6973.965130174317, 6992.615406152507, 6033.366496517802, 6405.231372235362, 6764.2221144569, 6805.434750007427, 6824.692888819915], "value": 4946.044300982224, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [813.9959300203498, 815.5962798414786, 847.2967437027266, 850.0352100366086, 856.8243510080434, 14732.926335368307, 15309.0, 16912.0, 17357.479275621736, 17393.82606173938, 17555.0, 18041.639167216646, 18248.178831952562, 18341.0, 18348.174332155053, 978.2076518020391, 997.4464172384323, 1013.6502906497246, 1014.0873214107326, 1014.6803756816579, 893.5576889439704, 1005.8239808033602, 1022.826119559673, 1027.2295778166376, 1027.650598796409, 585.9677717725523, 664.1996394344803, 976.3507267666993, 1040.230229630072, 1047.4239168457357, 1036.6527213383563, 1036.72008557689, 1037.870266216723, 1038.9792204155913, 1039.3867618105278, 762.8931949527071, 764.1708746010585, 765.7473033898856, 767.5586537740766, 770.7225398856464, 16917.66164676712, 17014.0, 17081.572960676083, 17288.65422691539, 17547.649047019117, 15867.206639668086, 16159.757603635955, 16238.350465981346, 16255.918720406322, 16268.26792794327, 14678.779818302733, 15183.468578599695, 15298.235088245543, 15311.081335119818, 15351.232438378036, 3621.94567081494, 3635.4546817977516, 3653.6346365363515, 3659.79871107089, 3672.559292884844, 109350.45324773325, 141895.29052354672, 144997.0, 145569.2721536396, 152549.0, 13504.257265850276, 13863.584092477242, 13959.79060314096, 14016.088954217943, 14171.929140354232, 24448.87775561111, 25520.361990950376, 26024.34939126499, 27061.729382705922, 27072.0, 7132.964335178291, 7727.49771264866, 7765.223477652303, 7906.9604651977525, 8224.424290299621, 3977.6817854571564, 4071.857484988011, 4076.694247931417, 4080.775557344315, 4095.1195492969155, 4243.490781106287, 4305.870823875226, 4313.719608225455, 4319.676024298127, 4320.854973432043, 930.3580529434669, 939.8026414452904, 940.149165005671, 945.9479728614855, 952.7237101240701, 554.0525701051248, 630.3916720364779, 651.4267444648679, 669.8660267946333, 695.474916438086, 11341.489632966372, 12350.567730129582, 12570.434330455151, 12668.936655316664, 12886.420111095023, 632.2969306215872, 649.1625802714445, 655.4395991427357, 682.1881960467127, 684.2780866186122, 5449.809256675997, 5515.25544051556, 5624.072028115315, 5760.164776107441, 5890.352061273256, 120125.39937300257, 123241.0, 123548.0, 126176.0, 137075.6292437063, 93635.0, 104562.0, 111474.0, 114385.0, 115570.0, 99312.0, 101048.49475752574, 105611.4719426398, 107206.92793072126, 108346.0, 10008.39949603026, 11959.940200298943, 12464.813027804772, 12875.291858947849, 13042.608721737983, 43372.0, 43544.34683479814, 43947.9013024677, 44038.55961440473, 44992.55007449884, 74522.0, 78422.0, 79154.6042269808, 79190.6040469794, 80946.19053809623, 15131.167785771448, 15198.544043678707, 15441.382344706535, 15533.0, 15564.688706225586, 16841.663166736842, 18559.44321670352, 19095.70851497829, 19465.805341946965, 19755.604887901874, 17629.823701763333, 18151.90924045371, 18292.81707182911, 18702.719459208394, 18791.624167516846, 15807.36770529166, 17701.380451684643, 18013.189406477002, 18945.147468364234, 19000.0, 17649.29402823924, 17885.46343609694, 18551.628967420307, 18983.24067037303, 19003.814561325624, 9808.558614862492, 10407.94796026015, 11236.719082023015, 11887.465064071965, 11964.58123965657, 13943.093698909945, 14089.618491561747, 14172.929135354258, 14207.147571145351, 14219.289035548389], "value": 8744.331865309314, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 114.0, 113.0, 113.0, 113.0, 114.0, 111.0, 112.0, 116.0, 112.0], "value": 111.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 114.0, 115.0, 146.0, 108.0, 114.0, 109.0, 114.0, 137.0, 113.0], "value": 121.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 200.0, 201.0, 218.0, 236.0, 264.0, 257.0, 197.0, 199.0, 202.0], "value": 217.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 47.0, 56.0, 89.0, 82.0, 82.0, 86.0, 50.0, 69.0, 47.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 67.0, 83.0, 69.0, 71.0, 70.0, 86.0, 68.0, 68.0, 93.0], "value": 75.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 92.0, 91.0, 91.0, 92.0, 92.0, 97.0, 91.0, 90.0], "value": 91.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 98.0, 96.0, 96.0, 96.0, 96.0, 96.0, 94.0, 96.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 93.0, 93.0, 95.0, 89.0, 91.0, 93.0, 116.0, 144.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 49.0, 51.0, 50.0, 51.0, 52.0, 51.0, 50.0, 53.0, 52.0], "value": 50.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 60.0, 64.0, 61.0, 57.0, 58.0, 57.0, 56.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 88.0, 95.0, 100.0, 87.0, 89.0, 89.0, 144.0, 111.0], "value": 102.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 136.0, 133.0, 135.0, 170.0, 138.0, 123.0, 139.0, 193.0, 148.0], "value": 145.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 190.0, 187.0, 209.0, 232.0, 184.0, 239.0, 230.0, 204.0, 193.0], "value": 205.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 69.0, 67.0, 69.0, 67.0, 78.0, 58.0, 68.0, 102.0, 90.0], "value": 73.1, "unit": "score"}]}]} 11:46:22 INFO - Return code: 0 11:46:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:46:22 INFO - # TBPL SUCCESS # 11:46:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:46:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:46:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:46:22 INFO - 2017-05-30 11:46:22.293 screenresolution[1670:10956] starting screenresolution argv=screenresolution get 11:46:22 INFO - 2017-05-30 11:46:22.295 screenresolution[1670:10956] Display 0: 1600x1200x32@60 11:46:22 INFO - 2017-05-30 11:46:22.301 screenresolution[1671:10959] starting screenresolution argv=screenresolution list 11:46:22 INFO - Available Modes on Display 0 11:46:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:46:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:46:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:46:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:46:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:46:22 INFO - Intel Iris: 11:46:22 INFO - Chipset Model: Intel Iris 11:46:22 INFO - Type: GPU 11:46:22 INFO - Bus: Built-In 11:46:22 INFO - VRAM (Dynamic, Max): 1536 MB 11:46:22 INFO - Vendor: Intel (0x8086) 11:46:22 INFO - Device ID: 0x0a2e 11:46:22 INFO - Revision ID: 0x0009 11:46:22 INFO - Displays: 11:46:22 INFO - Display: 11:46:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:46:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:46:22 INFO - Display Serial Number: 1600x1200 60 11:46:22 INFO - Main Display: Yes 11:46:22 INFO - Mirror: Off 11:46:22 INFO - Online: Yes 11:46:22 INFO - Rotation: Supported 11:46:22 INFO - Return code: 0 11:46:22 INFO - Running post-action listener: _resource_record_post_action 11:46:22 INFO - [mozharness: 2017-05-30 18:46:22.536214Z] Finished run-tests step (success) 11:46:22 INFO - Running post-run listener: _resource_record_post_run 11:46:22 INFO - Total resource usage - Wall time: 1257s; CPU: 53.0%; Read bytes: 46771200; Write bytes: 570581504; Read time: 488; Write time: 1855 11:46:22 INFO - TinderboxPrint: CPU usage
53.4% 11:46:22 INFO - TinderboxPrint: I/O read bytes / time
46,771,200 / 488 11:46:22 INFO - TinderboxPrint: I/O write bytes / time
570,581,504 / 1,855 11:46:22 INFO - TinderboxPrint: CPU idle
2,345.1 (46.6%) 11:46:22 INFO - TinderboxPrint: CPU system
1,020.6 (20.3%) 11:46:22 INFO - TinderboxPrint: CPU user
1,664.0 (33.1%) 11:46:22 INFO - TinderboxPrint: Swap in / out
287,551,488 / 0 11:46:22 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 231041536; Write bytes: 229860864; Read time: 16718; Write time: 483 11:46:22 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 11:46:22 INFO - run-tests - Wall time: 1236s; CPU: 53.0%; Read bytes: 45747200; Write bytes: 319552512; Read time: 472; Write time: 1318 11:46:23 INFO - Running post-run listener: _upload_blobber_files 11:46:23 INFO - Blob upload gear active. 11:46:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:46:23 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 11:46:23 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'] 11:46:23 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 11:46:23 INFO - (blobuploader) - INFO - Open directory for files ... 11:46:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:46:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:46:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:24 INFO - (blobuploader) - INFO - Done attempting. 11:46:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:46:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:46:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:25 INFO - (blobuploader) - INFO - Done attempting. 11:46:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 11:46:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:26 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 11:46:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:26 INFO - (blobuploader) - INFO - Done attempting. 11:46:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:46:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:46:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:46:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:46:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:46:28 INFO - (blobuploader) - INFO - Done attempting. 11:46:28 INFO - (blobuploader) - INFO - Iteration through files over. 11:46:28 INFO - Return code: 0 11:46:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:46:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:46:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a0c334e75ef5274cc0253890b52c67b9a59ea5b5696a4e5aa61e63a98279f8418a96679a17a8011aedae4071efff18a8415333e1c5e57624c298c3733142e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55d751c02c7f0917ddef790b171e7474b1fbacbbe0754f47ee9514270161017a2067dbc1693655bf972039c5eeb8cee97334c4e59f3874650126dc1ef1cfb42f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d86ecedd1be3e4d2ca2bc2b900a8d8a3b318cdeb6052028d286353037a1452a1bc4e064545f785c65aa1cf6e84f6ef30de7f7ab62de40aa0fde9c21d8551013", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ea6e86b574977c57a880c0d414c0a6f20a8a263929b88708258c9e5fbf9a6be7e1c9ad566d1b270fb0e348217a31e553b9a955e33be8e14358a80be6630bfa3"} 11:46:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:46:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:46:28 INFO - Contents: 11:46:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a0c334e75ef5274cc0253890b52c67b9a59ea5b5696a4e5aa61e63a98279f8418a96679a17a8011aedae4071efff18a8415333e1c5e57624c298c3733142e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55d751c02c7f0917ddef790b171e7474b1fbacbbe0754f47ee9514270161017a2067dbc1693655bf972039c5eeb8cee97334c4e59f3874650126dc1ef1cfb42f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d86ecedd1be3e4d2ca2bc2b900a8d8a3b318cdeb6052028d286353037a1452a1bc4e064545f785c65aa1cf6e84f6ef30de7f7ab62de40aa0fde9c21d8551013", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ea6e86b574977c57a880c0d414c0a6f20a8a263929b88708258c9e5fbf9a6be7e1c9ad566d1b270fb0e348217a31e553b9a955e33be8e14358a80be6630bfa3"} 11:46:28 INFO - Running post-run listener: copy_logs_to_upload_dir 11:46:28 INFO - Copying logs to upload dir... 11:46:28 INFO - mkdir: /builds/slave/test/build/upload/logs 11:46:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1339.450456 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 19 secs) (at 2017-05-30 11:46:28.942300) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:46:28.946320) ========= 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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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/46a0c334e75ef5274cc0253890b52c67b9a59ea5b5696a4e5aa61e63a98279f8418a96679a17a8011aedae4071efff18a8415333e1c5e57624c298c3733142e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55d751c02c7f0917ddef790b171e7474b1fbacbbe0754f47ee9514270161017a2067dbc1693655bf972039c5eeb8cee97334c4e59f3874650126dc1ef1cfb42f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d86ecedd1be3e4d2ca2bc2b900a8d8a3b318cdeb6052028d286353037a1452a1bc4e064545f785c65aa1cf6e84f6ef30de7f7ab62de40aa0fde9c21d8551013", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ea6e86b574977c57a880c0d414c0a6f20a8a263929b88708258c9e5fbf9a6be7e1c9ad566d1b270fb0e348217a31e553b9a955e33be8e14358a80be6630bfa3"} build_url:https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011070 build_url: 'https://queue.taskcluster.net/v1/task/LNOtUwL4TQibQaCarE6lGw/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/46a0c334e75ef5274cc0253890b52c67b9a59ea5b5696a4e5aa61e63a98279f8418a96679a17a8011aedae4071efff18a8415333e1c5e57624c298c3733142e6", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/55d751c02c7f0917ddef790b171e7474b1fbacbbe0754f47ee9514270161017a2067dbc1693655bf972039c5eeb8cee97334c4e59f3874650126dc1ef1cfb42f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4d86ecedd1be3e4d2ca2bc2b900a8d8a3b318cdeb6052028d286353037a1452a1bc4e064545f785c65aa1cf6e84f6ef30de7f7ab62de40aa0fde9c21d8551013", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8ea6e86b574977c57a880c0d414c0a6f20a8a263929b88708258c9e5fbf9a6be7e1c9ad566d1b270fb0e348217a31e553b9a955e33be8e14358a80be6630bfa3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-30 11:46:28.983851) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:46:28.984613) ========= 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.r7srAoN43D/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.Ee5KYvGkxZ/Listeners TMPDIR=/var/folders/_v/qcc1dbqn143dkpf_m98bdkkr00000w/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.004783 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-30 11:46:29.033907) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:46:29.034254) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-30 11:46:29.034601) ========= ========= Total master_lag: 0.18 =========