builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0387 starttime: 1497473559.37 results: success (0) revision: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:39.373416) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:39.374024) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:39.374305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:39.421574) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:39.421924) ========= 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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-06-14 13:52:39-- 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% 17.7M=0.001s 2017-06-14 13:52:40 (17.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.580445 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:40.027170) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:40.027467) ========= 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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.026311 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:40.076997) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-14 13:52:40.077313) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9b5584b94beb70d24a194ffad513ea30f1ab9dfd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 9b5584b94beb70d24a194ffad513ea30f1ab9dfd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-06-14 13:52:40,176 truncating revision to first 12 chars 2017-06-14 13:52:40,176 Setting DEBUG logging. 2017-06-14 13:52:40,176 attempt 1/10 2017-06-14 13:52:40,176 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/9b5584b94beb?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-14 13:52:40,896 unpacking tar archive at: mozilla-beta-9b5584b94beb/testing/mozharness/ program finished with exit code 0 elapsedTime=1.014687 ========= master_lag: 1.35 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-06-14 13:52:42.443114) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:42.443409) ========= script_repo_revision: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:42.443750) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:42.444007) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-14 13:52:42.521632) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-14 13:52:42.521917) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:52:42 INFO - MultiFileLogger online at 20170614 13:52:42 in /builds/slave/test 13:52:42 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 13:52:42 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:52:42 INFO - {'append_to_log': False, 13:52:42 INFO - 'base_work_dir': '/builds/slave/test', 13:52:42 INFO - 'blob_upload_branch': 'Mozilla-Beta', 13:52:42 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:52:42 INFO - 'branch': 'Mozilla-Beta', 13:52:42 INFO - 'buildbot_json_path': 'buildprops.json', 13:52:42 INFO - 'config_files': ('talos/mac_config.py',), 13:52:42 INFO - 'default_actions': ('clobber', 13:52:42 INFO - 'read-buildbot-config', 13:52:42 INFO - 'download-and-extract', 13:52:42 INFO - 'populate-webroot', 13:52:42 INFO - 'create-virtualenv', 13:52:42 INFO - 'install', 13:52:42 INFO - 'setup-mitmproxy', 13:52:42 INFO - 'run-tests'), 13:52:42 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:52:42 INFO - 'download_minidump_stackwalk': True, 13:52:42 INFO - 'download_symbols': 'ondemand', 13:52:42 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:52:42 INFO - 'tooltool.py': '/tools/tooltool.py', 13:52:42 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:52:42 INFO - '/tools/misc-python/virtualenv.py')}, 13:52:42 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:52:42 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:52:42 INFO - 'gecko_profile': False, 13:52:42 INFO - 'gecko_profile_interval': 0, 13:52:42 INFO - 'installer_path': 'installer.exe', 13:52:42 INFO - 'log_level': 'info', 13:52:42 INFO - 'log_name': 'talos', 13:52:42 INFO - 'log_to_console': True, 13:52:42 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:52:42 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:52:42 INFO - 'opt_config_files': (), 13:52:42 INFO - 'pip_index': False, 13:52:42 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:52:42 INFO - 'cmd': ('bash', 13:52:42 INFO - '-c', 13:52:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:52:42 INFO - 'enabled': True, 13:52:42 INFO - 'halt_on_failure': False, 13:52:42 INFO - 'name': 'check_screen_resolution'},), 13:52:42 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:52:42 INFO - 'cmd': ('bash', 13:52:42 INFO - '-c', 13:52:42 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:52:42 INFO - 'enabled': True, 13:52:42 INFO - 'halt_on_failure': False, 13:52:42 INFO - 'name': 'check_screen_resolution'},), 13:52:42 INFO - 'run_cmd_checks_enabled': True, 13:52:42 INFO - 'suite': 'dromaeojs-e10s', 13:52:42 INFO - 'system_bits': '32', 13:52:42 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:52:42 INFO - 'title': 't-yosemite-r7-0387', 13:52:42 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:52:42 INFO - 'use_talos_json': True, 13:52:42 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:52:42 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:52:42 INFO - 'work_dir': 'build'} 13:52:42 INFO - [mozharness: 2017-06-14 20:52:42.746232Z] Running clobber step. 13:52:42 INFO - Running pre-action listener: _resource_record_pre_action 13:52:42 INFO - Running main action method: clobber 13:52:42 INFO - rmtree: /builds/slave/test/build 13:52:42 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:52:45 INFO - Running post-action listener: _resource_record_post_action 13:52:45 INFO - [mozharness: 2017-06-14 20:52:45.165739Z] Finished clobber step (success) 13:52:45 INFO - [mozharness: 2017-06-14 20:52:45.165933Z] Running read-buildbot-config step. 13:52:45 INFO - Running pre-action listener: _resource_record_pre_action 13:52:45 INFO - Running main action method: read_buildbot_config 13:52:45 INFO - Using buildbot properties: 13:52:45 INFO - { 13:52:45 INFO - "project": "", 13:52:45 INFO - "product": "firefox", 13:52:45 INFO - "who": "ryanvm@gmail.com", 13:52:45 INFO - "installer_path": "public/build/firefox-55.0.en-US.mac.dmg", 13:52:45 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 13:52:45 INFO - "repository": "https://hg.mozilla.org/releases/mozilla-beta/", 13:52:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 13:52:45 INFO - "stage_platform": "macosx64", 13:52:45 INFO - "basedir": "/builds/slave/test", 13:52:45 INFO - "buildnumber": 1, 13:52:45 INFO - "platform": "yosemite_r7", 13:52:45 INFO - "parent_task_id": "TynwykiOS6KoVq8GJjuOkw", 13:52:45 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 13:52:45 INFO - "slavebuilddir": "test", 13:52:45 INFO - "taskId": "JWr4g3rzQj6B5mn40O81bQ", 13:52:45 INFO - "branch": "mozilla-beta", 13:52:45 INFO - "script_repo_revision": "production", 13:52:45 INFO - "revision": "9b5584b94beb70d24a194ffad513ea30f1ab9dfd", 13:52:45 INFO - "slavename": "t-yosemite-r7-0387", 13:52:45 INFO - "repo_path": "releases/mozilla-beta" 13:52:45 INFO - } 13:52:45 INFO - Finding installer, test and symbols from parent task. 13:52:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JWr4g3rzQj6B5mn40O81bQ'}, attempt #1 13:52:45 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JWr4g3rzQj6B5mn40O81bQ'}, attempt #1 13:52:46 INFO - Task dependencies: HV9WUVQ5RfKXYF-PDdpHGg TynwykiOS6KoVq8GJjuOkw 13:52:46 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw'}, attempt #1 13:52:46 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:52:46 INFO - Set test_url: https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/test_packages.json 13:52:46 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/target.crashreporter-symbols.zip 13:52:46 INFO - Running post-action listener: _resource_record_post_action 13:52:46 INFO - [mozharness: 2017-06-14 20:52:46.566838Z] Finished read-buildbot-config step (success) 13:52:46 INFO - [mozharness: 2017-06-14 20:52:46.566948Z] Running download-and-extract step. 13:52:46 INFO - Running pre-action listener: _resource_record_pre_action 13:52:46 INFO - Running main action method: download_and_extract 13:52:46 INFO - mkdir: /builds/slave/test/build/tests 13:52:46 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')]} 13:52:46 INFO - trying https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json 13:52:46 INFO - Downloading https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:52:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json'}, attempt #1 13:52:47 INFO - Downloaded 1290 bytes. 13:52:47 INFO - Reading from file /builds/slave/test/build/firefox-55.0.en-US.mac.test_packages.json 13:52:47 INFO - Using the following test package requirements: 13:52:47 INFO - {u'awsy': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.awsy.tests.zip'], 13:52:47 INFO - u'common': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:52:47 INFO - u'cppunittest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.cppunittest.tests.zip'], 13:52:47 INFO - u'gtest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.gtest.tests.zip'], 13:52:47 INFO - u'jittest': [u'firefox-55.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 13:52:47 INFO - u'mochitest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.mochitest.tests.zip'], 13:52:47 INFO - u'mozbase': [u'firefox-55.0.en-US.mac.common.tests.zip'], 13:52:47 INFO - u'reftest': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.reftest.tests.zip'], 13:52:47 INFO - u'talos': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.talos.tests.zip'], 13:52:47 INFO - u'web-platform': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.web-platform.tests.tar.gz'], 13:52:47 INFO - u'xpcshell': [u'firefox-55.0.en-US.mac.common.tests.zip', 13:52:47 INFO - u'firefox-55.0.en-US.mac.xpcshell.tests.zip']} 13:52:47 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip'] for test suite category: common 13:52:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:52:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:52:47 INFO - Fetch https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:52:49 INFO - Content-Length response header: 58981264 13:52:49 INFO - Bytes received: 58981264 13:53:07 INFO - Downloading packages: [u'firefox-55.0.en-US.mac.common.tests.zip', u'firefox-55.0.en-US.mac.talos.tests.zip'] for test suite category: talos 13:53:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip 13:53:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip'}, attempt #1 13:53:07 INFO - Fetch https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.common.tests.zip into memory 13:53:09 INFO - Content-Length response header: 58981264 13:53:09 INFO - Bytes received: 58981264 13:53:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip 13:53:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip'}, attempt #1 13:53:26 INFO - Fetch https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.talos.tests.zip into memory 13:53:28 INFO - Content-Length response header: 14450018 13:53:28 INFO - Bytes received: 14450018 13:53:28 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')]} 13:53:28 INFO - trying https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:53:28 INFO - Downloading https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0.en-US.mac.dmg 13:53:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg'}, attempt #1 13:53:30 INFO - Downloaded 51078830 bytes. 13:53:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:53:30 INFO - mkdir: /builds/slave/test/properties 13:53:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:53:30 INFO - Writing to file /builds/slave/test/properties/build_url 13:53:30 INFO - Contents: 13:53:30 INFO - build_url:https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg 13:53:30 INFO - Running post-action listener: _resource_record_post_action 13:53:30 INFO - Running post-action listener: set_extra_try_arguments 13:53:30 INFO - [mozharness: 2017-06-14 20:53:30.011933Z] Finished download-and-extract step (success) 13:53:30 INFO - [mozharness: 2017-06-14 20:53:30.012049Z] Running populate-webroot step. 13:53:30 INFO - Running pre-action listener: _resource_record_pre_action 13:53:30 INFO - Running main action method: populate_webroot 13:53:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:53:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:53:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:53:30 INFO - '--tppagecycles', 13:53:30 INFO - '7'], 13:53:30 INFO - 'tests': ['tsvgm']}}, 13:53:30 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:53:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:53:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:53:30 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:53:30 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:53:30 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['damp', 'tps']}, 13:53:30 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'tests': ['damp', 'tps']}, 13:53:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['dromaeo_dom']}, 13:53:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:53:30 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 13:53:30 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 13:53:30 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 13:53:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'other-e10s': {'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['a11yr', 13:53:30 INFO - 'ts_paint', 13:53:30 INFO - 'tpaint', 13:53:30 INFO - 'sessionrestore', 13:53:30 INFO - 'sessionrestore_no_auto_restore', 13:53:30 INFO - 'tabpaint']}, 13:53:30 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 13:53:30 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 13:53:30 INFO - 'bloom_basic_ref']}, 13:53:30 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set-win10.zip', 13:53:30 INFO - 'talos_options': ['--mitmproxy', 13:53:30 INFO - 'mitmproxy-recording-google.mp mitmproxy-recording-youtube.mp mitmproxy-recording-amazon.mp mitmproxy-recording-facebook.mp', 13:53:30 INFO - '--firstNonBlankPaint'], 13:53:30 INFO - 'tests': ['quantum_pageload_google', 13:53:30 INFO - 'quantum_pageload_youtube', 13:53:30 INFO - 'quantum_pageload_amazon', 13:53:30 INFO - 'quantum_pageload_facebook']}, 13:53:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['tsvgx', 13:53:30 INFO - 'tsvgr_opacity', 13:53:30 INFO - 'tart', 13:53:30 INFO - 'tscrollx', 13:53:30 INFO - 'cart', 13:53:30 INFO - 'tsvg_static']}, 13:53:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:53:30 INFO - 'tsvgr_opacity', 13:53:30 INFO - 'tart', 13:53:30 INFO - 'tscrollx', 13:53:30 INFO - 'cart', 13:53:30 INFO - 'tsvg_static']}, 13:53:30 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'talos_options': ['--disable-e10s'], 13:53:30 INFO - 'tests': ['tp5o']}, 13:53:30 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:53:30 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'talos_options': ['--disable-e10s', 13:53:30 INFO - '--xperf_path', 13:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:53:30 INFO - 'tests': ['tp5n']}, 13:53:30 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:53:30 INFO - 'talos_options': ['--xperf_path', 13:53:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:53:30 INFO - 'tests': ['tp5n']}}, 13:53:30 INFO - 'talos.zip': {'path': '', 13:53:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:53:30 INFO - Running post-action listener: _resource_record_post_action 13:53:30 INFO - [mozharness: 2017-06-14 20:53:30.034097Z] Finished populate-webroot step (success) 13:53:30 INFO - [mozharness: 2017-06-14 20:53:30.034244Z] Running create-virtualenv step. 13:53:30 INFO - Running pre-action listener: _resource_record_pre_action 13:53:30 INFO - Running main action method: create_virtualenv 13:53:30 INFO - Creating virtualenv /builds/slave/test/build/venv 13:53:30 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 13:53:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:53:30 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 13:53:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:53:30 INFO - Using real prefix '/tools/python27' 13:53:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:53:30 INFO - Installing distribute.............................................................................................................................................................................................done. 13:53:33 INFO - Installing pip.................done. 13:53:33 INFO - Return code: 0 13:53:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:53:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:53:34 INFO - Reading from file tmpfile_stdout 13:53:34 INFO - Output received: 13:53:34 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) 13:53:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:53:34 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')]} 13:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:34 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')]} 13:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:34 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:34 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 13:53:34 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 13:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:34 INFO - 'HOME': '/Users/cltbld', 13:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:34 INFO - 'LOGNAME': 'cltbld', 13:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:34 INFO - 'MOZ_NO_REMOTE': '1', 13:53:34 INFO - 'NO_EM_RESTART': '1', 13:53:34 INFO - 'PAGER': '/bin/cat', 13:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:34 INFO - 'PWD': '/builds/slave/test', 13:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:34 INFO - 'SHELL': '/bin/bash', 13:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:34 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:34 INFO - 'USER': 'cltbld', 13:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:34 INFO - 'XPC_FLAGS': '0x0', 13:53:34 INFO - 'XPC_SERVICE_NAME': '0', 13:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:34 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 13:53:34 INFO - Cleaning up... 13:53:34 INFO - Return code: 0 13:53:34 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:53:34 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')]} 13:53:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:34 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')]} 13:53:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:34 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:34 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 13:53:34 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 13:53:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:34 INFO - 'HOME': '/Users/cltbld', 13:53:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:34 INFO - 'LOGNAME': 'cltbld', 13:53:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:34 INFO - 'MOZ_NO_REMOTE': '1', 13:53:34 INFO - 'NO_EM_RESTART': '1', 13:53:34 INFO - 'PAGER': '/bin/cat', 13:53:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:34 INFO - 'PWD': '/builds/slave/test', 13:53:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:34 INFO - 'SHELL': '/bin/bash', 13:53:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:34 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:34 INFO - 'USER': 'cltbld', 13:53:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:34 INFO - 'XPC_FLAGS': '0x0', 13:53:34 INFO - 'XPC_SERVICE_NAME': '0', 13:53:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:34 INFO - Downloading/unpacking psutil>=3.1.1 13:53:34 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 13:53:34 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 13:53:34 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 13:53:34 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 13:53:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:53:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:53:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:53:36 INFO - Installing collected packages: psutil 13:53:36 INFO - Running setup.py install for psutil 13:53:37 INFO - building 'psutil._psutil_osx' extension 13:53:37 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 13:53:37 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 13:53:37 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 13:53:37 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 13:53:37 INFO - building 'psutil._psutil_posix' extension 13:53:37 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 13:53:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:53:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:53:37 INFO - ^ 13:53:37 INFO - 1 warning generated. 13:53:37 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 13:53:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:53:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:53:38 INFO - Successfully installed psutil 13:53:38 INFO - Cleaning up... 13:53:38 INFO - Return code: 0 13:53:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:38 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:38 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 13:53:38 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 13:53:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:38 INFO - 'HOME': '/Users/cltbld', 13:53:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:38 INFO - 'LOGNAME': 'cltbld', 13:53:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:38 INFO - 'MOZ_NO_REMOTE': '1', 13:53:38 INFO - 'NO_EM_RESTART': '1', 13:53:38 INFO - 'PAGER': '/bin/cat', 13:53:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:38 INFO - 'PWD': '/builds/slave/test', 13:53:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:38 INFO - 'SHELL': '/bin/bash', 13:53:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:38 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:38 INFO - 'USER': 'cltbld', 13:53:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:38 INFO - 'XPC_FLAGS': '0x0', 13:53:38 INFO - 'XPC_SERVICE_NAME': '0', 13:53:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:53:38 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 13:53:38 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 13:53:38 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 13:53:38 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 13:53:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:53:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:53:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:53:39 INFO - Installing collected packages: mozsystemmonitor 13:53:39 INFO - Running setup.py install for mozsystemmonitor 13:53:39 INFO - Successfully installed mozsystemmonitor 13:53:39 INFO - Cleaning up... 13:53:39 INFO - Return code: 0 13:53:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:39 INFO - 'HOME': '/Users/cltbld', 13:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:39 INFO - 'LOGNAME': 'cltbld', 13:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:39 INFO - 'MOZ_NO_REMOTE': '1', 13:53:39 INFO - 'NO_EM_RESTART': '1', 13:53:39 INFO - 'PAGER': '/bin/cat', 13:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:39 INFO - 'PWD': '/builds/slave/test', 13:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:39 INFO - 'SHELL': '/bin/bash', 13:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:39 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:39 INFO - 'USER': 'cltbld', 13:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:39 INFO - 'XPC_FLAGS': '0x0', 13:53:39 INFO - 'XPC_SERVICE_NAME': '0', 13:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:40 INFO - Downloading/unpacking jsonschema==2.5.1 13:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:53:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:53:41 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 13:53:41 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 13:53:41 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 13:53:41 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 13:53:41 INFO - Downloading functools32-3.2.3-2.tar.gz 13:53:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:53:41 INFO - warning: no files found matching '*.txt' 13:53:41 INFO - no previously-included directories found matching 'build' 13:53:41 INFO - no previously-included directories found matching 'dist' 13:53:41 INFO - no previously-included directories found matching '.git*' 13:53:41 INFO - Installing collected packages: jsonschema, functools32 13:53:42 INFO - Running setup.py install for functools32 13:53:42 INFO - warning: no files found matching '*.txt' 13:53:42 INFO - no previously-included directories found matching 'build' 13:53:42 INFO - no previously-included directories found matching 'dist' 13:53:42 INFO - no previously-included directories found matching '.git*' 13:53:42 INFO - Successfully installed jsonschema functools32 13:53:42 INFO - Cleaning up... 13:53:42 INFO - Return code: 0 13:53:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:42 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:42 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 13:53:42 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 13:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:42 INFO - 'HOME': '/Users/cltbld', 13:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:42 INFO - 'LOGNAME': 'cltbld', 13:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:42 INFO - 'MOZ_NO_REMOTE': '1', 13:53:42 INFO - 'NO_EM_RESTART': '1', 13:53:42 INFO - 'PAGER': '/bin/cat', 13:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:42 INFO - 'PWD': '/builds/slave/test', 13:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:42 INFO - 'SHELL': '/bin/bash', 13:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:42 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:42 INFO - 'USER': 'cltbld', 13:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:42 INFO - 'XPC_FLAGS': '0x0', 13:53:42 INFO - 'XPC_SERVICE_NAME': '0', 13:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:53:42 INFO - Cleaning up... 13:53:42 INFO - Return code: 0 13:53:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:42 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:42 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 13:53:42 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 13:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:42 INFO - 'HOME': '/Users/cltbld', 13:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:42 INFO - 'LOGNAME': 'cltbld', 13:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:42 INFO - 'MOZ_NO_REMOTE': '1', 13:53:42 INFO - 'NO_EM_RESTART': '1', 13:53:42 INFO - 'PAGER': '/bin/cat', 13:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:42 INFO - 'PWD': '/builds/slave/test', 13:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:42 INFO - 'SHELL': '/bin/bash', 13:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:42 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:42 INFO - 'USER': 'cltbld', 13:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:42 INFO - 'XPC_FLAGS': '0x0', 13:53:42 INFO - 'XPC_SERVICE_NAME': '0', 13:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:42 INFO - Downloading/unpacking blobuploader==1.2.4 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:44 INFO - Downloading blobuploader-1.2.4.tar.gz 13:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:53:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:53:44 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 13:53:44 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 13:53:44 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 13:53:44 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 13:53:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:53:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:53:44 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 13:53:44 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 13:53:44 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 13:53:44 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 13:53:45 INFO - Downloading docopt-0.6.1.tar.gz 13:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:53:45 INFO - Installing collected packages: blobuploader, requests, docopt 13:53:45 INFO - Running setup.py install for blobuploader 13:53:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:53:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:53:45 INFO - Running setup.py install for requests 13:53:45 INFO - Running setup.py install for docopt 13:53:45 INFO - Successfully installed blobuploader requests docopt 13:53:45 INFO - Cleaning up... 13:53:46 INFO - Return code: 0 13:53:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53:46 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')]} 13:53:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:46 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')]} 13:53:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:46 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:46 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 13:53:46 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 13:53:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:46 INFO - 'HOME': '/Users/cltbld', 13:53:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:46 INFO - 'LOGNAME': 'cltbld', 13:53:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:46 INFO - 'MOZ_NO_REMOTE': '1', 13:53:46 INFO - 'NO_EM_RESTART': '1', 13:53:46 INFO - 'PAGER': '/bin/cat', 13:53:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:46 INFO - 'PWD': '/builds/slave/test', 13:53:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:46 INFO - 'SHELL': '/bin/bash', 13:53:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:46 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:46 INFO - 'USER': 'cltbld', 13:53:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:46 INFO - 'XPC_FLAGS': '0x0', 13:53:46 INFO - 'XPC_SERVICE_NAME': '0', 13:53:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-4PU1kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ZmxQDi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-r8zrTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-H16QSM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-JfV1Lr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fbiHfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-WwjuyN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:53:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:53:46 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-3lYVdE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-lX0DtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-5Y69VO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-e6lQRo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-z5l4wH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-_flDFG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-3ALsjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Na8NfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Sdu3lF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:53:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:53:47 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Fs1MRQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:53:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:53:48 INFO - Running setup.py install for manifestparser 13:53:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:53:48 INFO - Running setup.py install for mozcrash 13:53:48 INFO - Running setup.py install for mozdebug 13:53:48 INFO - Running setup.py install for mozdevice 13:53:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:53:48 INFO - Running setup.py install for mozfile 13:53:48 INFO - Running setup.py install for mozhttpd 13:53:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:53:48 INFO - Running setup.py install for mozinfo 13:53:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Running setup.py install for mozInstall 13:53:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Running setup.py install for mozleak 13:53:49 INFO - Running setup.py install for mozlog 13:53:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Running setup.py install for moznetwork 13:53:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Running setup.py install for mozprocess 13:53:49 INFO - Running setup.py install for mozprofile 13:53:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:53:49 INFO - Running setup.py install for mozrunner 13:53:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:53:50 INFO - Running setup.py install for mozscreenshot 13:53:50 INFO - Running setup.py install for moztest 13:53:50 INFO - Running setup.py install for mozversion 13:53:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:53:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:53:50 INFO - Cleaning up... 13:53:50 INFO - Return code: 0 13:53:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53:50 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')]} 13:53:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:50 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')]} 13:53:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:50 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:50 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 13:53:50 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 13:53:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:50 INFO - 'HOME': '/Users/cltbld', 13:53:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:50 INFO - 'LOGNAME': 'cltbld', 13:53:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:50 INFO - 'MOZ_NO_REMOTE': '1', 13:53:50 INFO - 'NO_EM_RESTART': '1', 13:53:50 INFO - 'PAGER': '/bin/cat', 13:53:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:50 INFO - 'PWD': '/builds/slave/test', 13:53:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:50 INFO - 'SHELL': '/bin/bash', 13:53:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:50 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:50 INFO - 'USER': 'cltbld', 13:53:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:50 INFO - 'XPC_FLAGS': '0x0', 13:53:50 INFO - 'XPC_SERVICE_NAME': '0', 13:53:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:53:50 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-rRBwt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:53:50 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)) 13:53:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:53:50 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-tQDwq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-_e7Uzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-AoJ3gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-9mVrQK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-m1iFSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-2hmtZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.10 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Y39OHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:53:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.13 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fcGT5T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ZHX1fn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:53:51 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)) 13:53:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:53:51 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vkWKJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-tl4DKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-K0m530-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-s2f5zw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fzPDNT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-tspZ9m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:53:52 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)) 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:53:52 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-YvCna4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:53:52 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)) 13:53:52 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)) 13:53:52 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)) 13:53:52 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)) 13:53:52 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)) 13:53:52 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)) 13:53:52 INFO - Requirement already satisfied (use --upgrade to upgrade): requests in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozInstall==1.13->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 13:53:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:53:52 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 13:53:52 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 13:53:52 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 13:53:52 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 13:53:54 INFO - Downloading blessings-1.6.tar.gz 13:53:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:53:54 INFO - Installing collected packages: blessings 13:53:54 INFO - Running setup.py install for blessings 13:53:54 INFO - Successfully installed blessings 13:53:54 INFO - Cleaning up... 13:53:54 INFO - Return code: 0 13:53:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:53:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:53:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:53:54 INFO - Reading from file tmpfile_stdout 13:53:54 INFO - Current package versions: 13:53:54 INFO - blessings == 1.6 13:53:54 INFO - blobuploader == 1.2.4 13:53:54 INFO - docopt == 0.6.1 13:53:54 INFO - functools32 == 3.2.3-2 13:53:54 INFO - jsonschema == 2.5.1 13:53:54 INFO - manifestparser == 1.1 13:53:54 INFO - mozInstall == 1.13 13:53:54 INFO - mozcrash == 1.0 13:53:54 INFO - mozdebug == 0.1 13:53:54 INFO - mozdevice == 0.51 13:53:54 INFO - mozfile == 1.2 13:53:54 INFO - mozhttpd == 0.7 13:53:54 INFO - mozinfo == 0.10 13:53:54 INFO - mozleak == 0.1 13:53:54 INFO - mozlog == 3.4 13:53:54 INFO - moznetwork == 0.27 13:53:54 INFO - mozprocess == 0.25 13:53:54 INFO - mozprofile == 0.28 13:53:54 INFO - mozrunner == 6.13 13:53:54 INFO - mozscreenshot == 0.1 13:53:54 INFO - mozsystemmonitor == 0.3 13:53:54 INFO - moztest == 0.8 13:53:54 INFO - mozversion == 1.4 13:53:54 INFO - psutil == 3.1.1 13:53:54 INFO - requests == 1.2.3 13:53:54 INFO - wsgiref == 0.1.2 13:53:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53:54 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')]} 13:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:54 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')]} 13:53:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:54 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:54 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 13:53:54 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 13:53:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:54 INFO - 'HOME': '/Users/cltbld', 13:53:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:54 INFO - 'LOGNAME': 'cltbld', 13:53:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:54 INFO - 'MOZ_NO_REMOTE': '1', 13:53:54 INFO - 'NO_EM_RESTART': '1', 13:53:54 INFO - 'PAGER': '/bin/cat', 13:53:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:54 INFO - 'PWD': '/builds/slave/test', 13:53:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:54 INFO - 'SHELL': '/bin/bash', 13:53:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:54 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:54 INFO - 'USER': 'cltbld', 13:53:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:54 INFO - 'XPC_FLAGS': '0x0', 13:53:54 INFO - 'XPC_SERVICE_NAME': '0', 13:53:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 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)) 13:53:54 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 13:53:54 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 13:53:54 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 13:53:54 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 13:53:54 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 13:53:56 INFO - Downloading marionette_driver-1.4.0.tar.gz 13:53:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 13:53:56 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)) 13:53:56 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)) 13:53:56 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)) 13:53:56 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)) 13:53:56 INFO - Installing collected packages: marionette-driver 13:53:56 INFO - Running setup.py install for marionette-driver 13:53:56 INFO - Successfully installed marionette-driver 13:53:56 INFO - Cleaning up... 13:53:56 INFO - Return code: 0 13:53:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:53:56 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')]} 13:53:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:56 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')]} 13:53:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:56 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 0x1042f6c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1042fa768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fceb0cd5c60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, '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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 13:53:56 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 13:53:56 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 13:53:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:53:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:56 INFO - 'HOME': '/Users/cltbld', 13:53:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:56 INFO - 'LOGNAME': 'cltbld', 13:53:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:56 INFO - 'MOZ_NO_REMOTE': '1', 13:53:56 INFO - 'NO_EM_RESTART': '1', 13:53:56 INFO - 'PAGER': '/bin/cat', 13:53:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:56 INFO - 'PWD': '/builds/slave/test', 13:53:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:56 INFO - 'SHELL': '/bin/bash', 13:53:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:53:56 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:53:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:56 INFO - 'USER': 'cltbld', 13:53:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:56 INFO - 'XPC_FLAGS': '0x0', 13:53:56 INFO - 'XPC_SERVICE_NAME': '0', 13:53:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:53:56 INFO - Cleaning up... 13:53:56 INFO - Return code: 0 13:53:56 INFO - Running post-action listener: _resource_record_post_action 13:53:56 INFO - Running post-action listener: _start_resource_monitoring 13:53:56 INFO - Starting resource monitoring. 13:53:56 INFO - [mozharness: 2017-06-14 20:53:56.921550Z] Finished create-virtualenv step (success) 13:53:56 INFO - [mozharness: 2017-06-14 20:53:56.922548Z] Running install step. 13:53:56 INFO - Running pre-action listener: _resource_record_pre_action 13:53:56 INFO - Running main action method: install 13:53:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:53:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:53:57 INFO - Reading from file tmpfile_stdout 13:53:57 INFO - Detecting whether we're running mozinstall >=1.0... 13:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:53:57 INFO - Reading from file tmpfile_stdout 13:53:57 INFO - Output received: 13:53:57 INFO - Usage: mozinstall [options] installer 13:53:57 INFO - Options: 13:53:57 INFO - -h, --help show this help message and exit 13:53:57 INFO - -d DEST, --destination=DEST 13:53:57 INFO - Directory to install application into. [default: 13:53:57 INFO - "/builds/slave/test"] 13:53:57 INFO - --app=APP Application being installed. [default: firefox] 13:53:57 INFO - mkdir: /builds/slave/test/build/application 13:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0.en-US.mac.dmg --destination /builds/slave/test/build/application 13:54:12 INFO - Reading from file tmpfile_stdout 13:54:12 INFO - Output received: 13:54:12 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 13:54:12 INFO - Running post-action listener: _resource_record_post_action 13:54:12 INFO - [mozharness: 2017-06-14 20:54:12.886945Z] Finished install step (success) 13:54:12 INFO - [mozharness: 2017-06-14 20:54:12.887063Z] Running setup-mitmproxy step. 13:54:12 INFO - Running pre-action listener: _resource_record_pre_action 13:54:12 INFO - Running main action method: setup_mitmproxy 13:54:12 INFO - Skipping: mitmproxy is not required 13:54:12 INFO - Running post-action listener: _resource_record_post_action 13:54:12 INFO - [mozharness: 2017-06-14 20:54:12.887746Z] Finished setup-mitmproxy step (success) 13:54:12 INFO - [mozharness: 2017-06-14 20:54:12.887853Z] Running run-tests step. 13:54:12 INFO - Running pre-action listener: _resource_record_pre_action 13:54:12 INFO - Running main action method: run_tests 13:54:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:54:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:54:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:54:12 INFO - 2017-06-14 13:54:12.902 screenresolution[1630:7832] starting screenresolution argv=screenresolution get 13:54:12 INFO - 2017-06-14 13:54:12.905 screenresolution[1630:7832] Display 0: 1600x1200x32@60 13:54:12 INFO - 2017-06-14 13:54:12.910 screenresolution[1631:7834] starting screenresolution argv=screenresolution list 13:54:12 INFO - Available Modes on Display 0 13:54:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:54:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:54:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:54:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:54:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:54:13 INFO - Intel Iris: 13:54:13 INFO - Chipset Model: Intel Iris 13:54:13 INFO - Type: GPU 13:54:13 INFO - Bus: Built-In 13:54:13 INFO - VRAM (Dynamic, Max): 1536 MB 13:54:13 INFO - Vendor: Intel (0x8086) 13:54:13 INFO - Device ID: 0x0a2e 13:54:13 INFO - Revision ID: 0x0009 13:54:13 INFO - Displays: 13:54:13 INFO - Display: 13:54:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:54:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:54:13 INFO - Display Serial Number: 1600x1200 60 13:54:13 INFO - Main Display: Yes 13:54:13 INFO - Mirror: Off 13:54:13 INFO - Online: Yes 13:54:13 INFO - Rotation: Supported 13:54:13 INFO - Return code: 0 13:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:54:13 INFO - Python 2.7.3 13:54:13 INFO - Return code: 0 13:54:13 INFO - grabbing minidump binary from tooltool 13:54: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')]} 13:54:13 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 0x10404cd30>, '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 0x104016cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10403dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:54:13 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 13:54:13 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 13:54:13 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 13:54:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:54:13 INFO - Return code: 0 13:54:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:54:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:54:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:54:13 INFO - ENV: RUST_BACKTRACE is now 1 13:54:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:54:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:54:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 13:54:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0387 --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 13:54:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.86a8Mdxe6Y/Render', 13:54:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:13 INFO - 'HOME': '/Users/cltbld', 13:54:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:13 INFO - 'LOGNAME': 'cltbld', 13:54:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:54:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:13 INFO - 'MOZ_NO_REMOTE': '1', 13:54:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:54:13 INFO - 'NO_EM_RESTART': '1', 13:54:13 INFO - 'PAGER': '/bin/cat', 13:54:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:13 INFO - 'PWD': '/builds/slave/test', 13:54:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:54:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:13 INFO - 'RUST_BACKTRACE': '1', 13:54:13 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 13:54:13 INFO - 'SHELL': '/bin/bash', 13:54:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Xgk4vWAQFW/Listeners', 13:54:13 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 13:54:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:13 INFO - 'USER': 'cltbld', 13:54:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:13 INFO - 'XPC_FLAGS': '0x0', 13:54:13 INFO - 'XPC_SERVICE_NAME': '0', 13:54:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:13 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 13:54:13 INFO - mozversion application_buildid: 20170614122816 13:54:13 INFO - mozversion application_changeset: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd 13:54:13 INFO - mozversion application_display_name: Firefox 13:54:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:54:13 INFO - mozversion application_name: Firefox 13:54:13 INFO - mozversion application_remotingname: firefox 13:54:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 13:54:13 INFO - mozversion application_vendor: Mozilla 13:54:13 INFO - mozversion application_version: 55.0 13:54:13 INFO - mozversion platform_buildid: 20170614122816 13:54:13 INFO - mozversion platform_changeset: 9b5584b94beb70d24a194ffad513ea30f1ab9dfd 13:54:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 13:54:13 INFO - mozversion platform_version: 55.0 13:54:13 INFO - using testdate: 1497473653 13:54:13 INFO - actual date: 1497473653 13:54:13 INFO - starting webserver on 'localhost:49226' 13:54:13 INFO - SUITE-START | Running 2 tests 13:54:13 INFO - TEST-START | dromaeo_css 13:54:13 INFO - operating with platform_type : mac_ 13:54:13 INFO - Initialising browser for dromaeo_css test... 13:54:13 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp6nxHk4/profile 13:54:13 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 13:54:46 INFO - TEST-INFO | 1641: killed by SIGTERM 13:54:46 INFO - Browser initialized. 13:54:46 INFO - Running cycle 1/1 for dromaeo_css test... 13:54:46 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp6nxHk4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:54:48 INFO - PID 1649 | 2017-06-14 13:54:48.450 plugin-container[1650:8386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x933b, name = 'com.apple.tsm.portname' 13:54:48 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:48 INFO - PID 1649 | 2017-06-14 13:54:48.450 plugin-container[1650:8386] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x960b, name = 'com.apple.CFPasteboardClient' 13:54:48 INFO - PID 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:48 INFO - PID 1649 | 2017-06-14 13:54:48.450 plugin-container[1650:8386] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:54:58 INFO - PID 1649 | RSS: Main: 184971264 13:54:58 INFO - PID 1649 | 13:58:01 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49226/tests/dromaeo/cssquery-ext.html) 13:58:02 INFO - PID 1649 | RSS: Main: 210214912 13:58:02 INFO - PID 1649 | 14:01:04 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-ext.html (next: http://localhost:49226/tests/dromaeo/cssquery-jquery.html) 14:01:05 INFO - PID 1649 | RSS: Main: 217251840 14:01:05 INFO - PID 1649 | 14:04:06 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49226/tests/dromaeo/cssquery-mootools.html) 14:04:07 INFO - PID 1649 | RSS: Main: 219033600 14:04:07 INFO - PID 1649 | 14:07:09 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49226/tests/dromaeo/cssquery-prototype.html) 14:07:09 INFO - PID 1649 | RSS: Main: 222666752 14:07:09 INFO - PID 1649 | 14:10:12 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49226/tests/dromaeo/cssquery-yui.html) 14:10:13 INFO - PID 1649 | RSS: Main: 223289344 14:10:13 INFO - PID 1649 | 14:13:14 INFO - PID 1649 | Cycle 1(1): loaded http://localhost:49226/tests/dromaeo/cssquery-yui.html (next: http://localhost:49226/tests/dromaeo/cssquery-dojo.html) 14:13:15 INFO - PID 1649 | RSS: Main: 221507584 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | __start_tp_report 14:13:15 INFO - PID 1649 | _x_x_mozilla_page_load 14:13:15 INFO - PID 1649 | _x_x_mozilla_page_load_details 14:13:15 INFO - PID 1649 | |i|pagename|runs| 14:13:15 INFO - PID 1649 | |0;dojo.html;3379.526866238727;3639.0356555512813;3806.619338066194;3837.05992031952;3886.455896174536;5040.294358789768;5312.335958005249;6409.551331406803;6450.5807122537;6534.967325163368;6492.513061520393;6515.120458738081;6541.934580654192;6553.934460655392;6625.337466253372;5850.151727999438;5852.707364631773;5856.707164641751;6025.638461692312;6030.09548567714;5659.292588426447;5926.288845338566;5932.851678708023;5949.107633854913;5977.970110149465;4650.372199753024;5685.1756495308155;5720.914186287209;5746.597738158331;5761.30864296285;5830.154627578977;5868.09044598088;5905.852353691149;5913.201717768089;5930.080837470199;5740.339860915965;5797.971010144965;5803.854903627401;5816.8836623267725;5819.359870414251;5463.836084917419;6562.44219241365;6573.342665733437;6591.242007169189;6617.933820661828;6811.557248778813;6969.546979446371;7036.366726994595;7068.151821781367;7144.714211431537;5987.550933679948;7140.321669441447;7213.819654508627;7249.746258880967;7264.4914855960105;6644.634545100018;6685.431738302299;6730.360615741497;6730.831729206711;6743.797686069426;7139.5716257023705;7419.777406677808;7451.180370159278;7455.329020388082;7470.738524151738;6795;6829.658517074126;6846.349596788297;6958.686859091353;7043.964780176067;7147.142342918779;7176.497645164788;7229.2409297024105;7231.493795434374;7239.022731931227;7123.287671232835;7123.82190445243;7123.964380178169;7134.036905017756;7135.5361901476235;7080.079589653312;7116.573005619679;7136.143662760398;7139.750108746272;7146.606936618432;4840.903181936342;5484.122540393518;5598.356189038313;5693.68684722336;5719.714014299351;4514.232580461329;4848.272759086165;4952.108620448282;4952.257161425743;4982.701037937735;6474.838129046717;6567.605943643397;6578.243501997236;6591.6374599397495;6663.7672030674485;7229.313215244543;7248.275172482709;7252.963735181396;7259.564426134343;7261.164966028974;6763.932360676428;6771.255161932184;6781;6781.6609169541325;6800.387965083166;5189.974050129801;5626.874625075001;6097.542684298695;6252.124702541648;6300.338464461165;7115.67979440916;7422.517536360119;7439.739609113655;7450.18048014718;7484.663190156347;7000.299970003162;7015.508914376149;7030.789076327718;7032.859342813217;7034.894476582753;5819.79630712923;6982.406495447944;7449.143348514774;7501.699932002659;7509.361704254981;7125.3230943060325;7128.8217794553475;7137.464690148364;7151.499395042297;7161.713531458891;7198.388137008204;7265.745698900513;7267.273272672691;7282.0533330668795;7299.671514781741;6750.35871592198;6814.488913331421;6814.93185068143;6907.516473846783;6982.441404687511;6938.826529336807;7000.7549735759;7017.578945263301;7034.859302814018;7068.257832927674;5322.813701520428;6681.198256209286;6847.144106986627;6874.0032695257905;6894.827629309308;6298.401651843067;6817.5909445433435;6997.020417141503;7021.5787052777;7032.437405007485;6535.836604084936;6673.9666301668185;6676.298988606321;6677.76627818024;6905.896411553931 14:13:15 INFO - PID 1649 | |1;ext.html;748.2779118150985;834.9833003339934;887.9822403551927;901.3014913442081;1270.2061211742662;36248.63751362485;37164.628353716456;37197;37207.81396093022;37224.88325350237;37669.62330376695;37884.43173352402;37916.24167516648;37937.24125517488;37998;7893.842123157548;8014.078380986174;8024.839503209932;8033;8034.116247212802;7170.856582868366;7901.565413902233;8276.172382761735;8467.915320846805;8556.144385561425;8528.57357132144;8557.957210213972;8621.913780862174;8628.050914399442;8629.352798540134;7182.6408679566075;8576.185262400062;8578.227959483645;8578.270846978014;8603.182697643713;8242.876356854653;8302.875456868152;8359.665613375457;8365.916340836635;8366.37252206087;35653.46519802205;35948.82025589881;35995;36020.639793602255;36040.45939310912;3666.7616604920595;3688.2807852468777;3707.1844194277214;3711.962880371189;3715.442683597482;30128.397432051235;30224.94212702545;30229.848850755825;30273.545896811567;30301.93943211999;3191.186247506871;3227.2254658881934;3252.398306313316;3260.3805276997296;3277.246233366315;249351.50648493646;252195.21707174406;254564.63588410438;323623;324586.7541324604;45584.77207614007;46160.5383946163;46162.538374615826;46180.5381946183;46527.06945861132;48188.518114818406;48637.75681121572;48732.512674873506;48765.75617121892;48777.02445951061;15684.058956462648;15789.131597762003;15799;15817.762733559006;15821.417858214316;12864.678383040311;13008.284544349963;13008.609741707764;13025.609231723063;13041;13459.3943272551;13507.662308442368;13553.728925421436;13560.93219533896;13602.727945441036;5961.791337303173;6013.789517366871;6047.697615119227;6051.304100028553;6064.969675151684;981.6417007792104;982.9655962041294;987.9802403952025;992.1170158558888;1035.8808736995343;20417.24617524357;21550.78449215488;21773.782262177174;22885.542289154124;22951.196708115134;1086.9076128529164;1091.7052395853234;1115.4590023838432;1140.236041851961;1150.5685367987005;10303.639372621923;15912.602184945468;16101.355945762038;16133.757993630104;16144.75782863258;306351.9364806368;311271;311410;358850.4114958817;360790;54417.45582544203;54681.179782302504;54695.72652136714;54847;55120.724396378566;1533.4479587348546;1545;1549.9070055796687;1550.9767353489706;1555.7165338595703;23134.76865231326;26847.791849366437;27837.582436263066;29994.55008174923;30045;37092.629073708915;38135.85592432231;38293;38347.61652383552;38419.03952401212;314533;314652;314714;315234;316328;30033.69966300309;30858.845705771328;32848.68605255763;34643.82678086694;34807.12982175466;39417.40873886951;39743;39752;39998.40002399908;41726.95682607959;28331.575026375034;28656.71343286624;29038.419231614826;29875.70124298816;30676.386472269984;23795;28743.993960211294;29076.7092329074;29351.679174436773;30381.392372152874;29153.56269655999;36273.63726362808;36275.7303494387;36311.91064268076;36314;24273;28029.57955630708;30156;30357.848210758806;31662.89179878693;22261;23394.883025584764;24212.63681044821;24599.50800984006;24927.875360623806 14:13:15 INFO - PID 1649 | |2;jquery.html;422.61753113432354;511.60095125801877;526.5445389737876;535.871390866192;556.0075265650814;23965;24163.27510174693;24494.510109797815;24797.62803557946;27296.45407091857;26176;26520.469590608176;26704.866475667644;27041.729582704167;27131;6808.761693340734;6964.059851920002;6975.128108986377;6981.441484681213;6984.615846128461;3896.2207558488276;5956.344802071769;6963.512554121214;6967.825804354881;7051.576905385693;6939.410150137244;6984.860302793941;6990.475714321421;7005.649717514104;7009.719611215545;6955.617441040741;6958;6992.6853291602;6992.790216293494;6994.265602111781;6332.905006424908;6352.237731472253;6361.968190159019;6376.936230637727;6381;32135;32170;33149.3370132596;33287.16782080443;33407;2371.3004663624274;2412.915547955813;2416.5408572371193;2421.2736179146364;2422.7456117107804;16523.66952660952;20456.181752729874;20658.690119648218;20695.379138625864;20705.68941465879;2282.9429264268524;2320.9883950580306;2362.669226308301;2364.3261670424017;2372.1934542255717;172480.13759931247;180771;188891;189509;190090;13097.017723670762;14202.087291113841;15789.605259868595;15893.74178293447;16004.599885002735;19035.238590456225;21730.56538869214;21739.782602173775;21781.564368712854;21814.781852181273;10831.458427078773;10867.83698244527;10868.891311086945;10901.400422976654;10920.50857711405;9980.45107519079;10002.499875006366;10031.29780915329;10059.69820905374;10095.697129086138;8748.65005399777;9228.446293222429;9766.218702503782;9770.706878793648;9794.167495762798;2465.890349342803;2715.6150363314455;2758.462099890502;2770.515159847019;2899.7245261700527;957.707899090784;966.2634088489881;991.9355241909253;1012.9285610925527;1017.7353887989176;21953;22061.00725467326;22182.667259991114;22229.999650015463;22975.195868144536;1007.4710776842217;1019.5004447820663;1038.1850247555783;1053.2416660004785;1064.3135177810268;11118.444077796239;11193.440327983732;11367.715807104889;11416.657500275005;11736.530538778525;511049;530818;531931.0633714302;536112;541376;138331;147907;155337;157840.21079894528;159275;1186.988130118705;1221.7312191317897;1231.4704676988981;1233.5189276182116;1234.5247079874134;19028.429147125607;19414.320498782476;19798.911059891845;20086.899565502077;20258.898705506377;65744.34255657574;66000.75311651414;67182;68627.65686171537;68760.3123968754;85312.72030919418;85327.5733621328;88436;88740.66888996541;89712;11699.181057325906;12023.338716370683;12500.937495312466;12599.87400125987;12688.302143381834;18361.08194590292;19270.421887343404;19513.609727805648;19696.507587310432;19780;15128.924355378593;15892.920535397248;16020.439284625449;16031;16062.19689015568;13717.657058573217;13965.3715582797;14128.081674691111;14210.928945355206;14697.92651036738;16005.51983440452;16213.837861621232;16232.756508652143;16475.34098636041;16486.83513164853;12379.876201237872;12380.752384952431;12412;12513.687157821127;12548.933340666146;15376.92361534682;15426.382944682087;15496.225188740744;15500.069995800288;15507.457238996582 14:13:15 INFO - PID 1649 | |3;mootools.html;1525.7787620794984;1567.5140706381017;1588.698147352003;1595.4176725495197;1600.255881015328;14206.289685515721;14782.186979716138;14853.480128195515;14894.851051489482;14895.18076505793;13857.93071034646;14651.340689668963;14659.413623455075;14668.679818816305;14723.779143312859;2526.5325914705745;2949.115265420376;2996.2060054085687;3000.489916714153;3000.6249218847643;2911.38860839223;2959.978807311474;2964.570137330086;2966.8516574171204;2969.5397213431843;2804.621376114229;2917.081119447378;2964.64424269088;2966.2732630505557;2967.035713393145;2864.4843928092937;2940.9411811763753;2970.019893435161;2978.4638765022287;2979.8063125896747;2918.3594409623142;2928.063019833654;2937.2656835791054;2941.3234955960247;2960.422717570075;14060.296985150702;14357;14393.92803035978;14396.78404823918;14402.567922962327;8011.0386753589355;8163.591820408956;8307.792305192359;8312.33501319893;8312.376571757142;4490.910181796346;4926.679765815211;5164.251183578398;5186.299849520305;5247.291615631879;1458.5478501664518;1489.2777003153462;1493.9850601494063;1517.1731406383547;1521.9771703424458;19354.806451935583;20368.083436245255;21553.030113644923;21748.803815790125;21751;6895.586264824026;6897.793066208022;7009.404200642901;7020.192677842114;7086.893696594555;12277.877221227853;14657.973941824182;14870.851291486946;14884.776728349083;14887.255637218095;7824.804379890435;7964.2433968772875;7982.840343193104;7988.720394786154;7996.760097197093;7144.9285507145305;7383.778486645409;7385.519941203805;7450.6647200875;7484.251574842581;5280.551153151949;5482.80810171642;5497.505224529811;5553.750081246273;5624.887502249932;1621.4324986254953;1626.3738460692718;1634.4933070748107;1634.6567220883749;1646.1111000060073;835.6824406725527;856.8500512410428;870.7648934787572;875.1598465473216;898.0615257056392;8261.793455163668;8301.958490207511;8707.825843483095;8719.215270625675;9123.589438475285;987.53585814667;1020.2756043209256;1048.3762161513887;1051.4795176387738;1075.3924032921375;4456.309272062836;4790.137775200427;4958.578520825698;5025.597952163818;5140.640155189176;18741.90629046846;19247.615047699248;19270.61458770817;19329.613407731766;19443.125059372363;18064;18920.52698682544;19048.42854714361;19049.809501904805;19240.615187696167;10090.142337901361;10154.289199755945;10187.694369168938;10273.794524109477;10274.921133281048;7436.628168591657;7703.614819258903;7952.204779522001;8228.136045715113;8333.374996875138;17411.912940435217;17993.640127197123;18074.186661599997;18101;18293.54266143357;17050.403235886686;18180.81819181791;18321.175547100145;18513.35203267879;18604.720929186344;11322.433878306216;11513.366764828015;11554.480048397674;11559.479823408135;11585.536578536765;12712.427940742873;12824.038197135065;12824.743505129658;12898.355082245662;12982.740345192855;10245.385276883411;10446.895531044593;10482.580696772044;10817.405042722727;10906.563737450413;9265.490398028172;9486.905130948602;10473;10604.575816967235;10855.837162442413;9354.111359420844;9625.03749625032;9652.27607929422;9711.9028809711;9805.313628045968;7002.929970700432;7074.434045276468;7240.565566066053;7364.864189385233;7421.740239091606;7200.963995179991;7313.610414021319;7413.962930185315;7511.737089201852;7533.7363192288 14:13:15 INFO - PID 1649 | |4;prototype.html;412.7853516171591;426.300866578811;448.3991451455054;476.9880752981176;489.42492571228803;6590;6627.436667883229;6668.299828518003;6672.599644021362;6704.3295670432935;5021.648484606079;5923.348431672513;6267.122602835607;6491.221053473578;6491.22105347359;4057.6753859691303;4495.34817451471;4912.508749125077;4950.925736113961;4973;4428.933565996513;4610.732048686621;4942.283368911506;4943.901121977558;4974.781178611227;4295.50601680806;4655.7439340836245;4753.500882407349;4902.65681402302;4947.356843610343;4909.558139767402;4927.236278376858;4947.1589829728855;4947.282644016615;4969.751512424365;4342;4342.283523218697;4343.478782546114;4354.716943398669;4371.519132895379;5640.971795141039;6208.441240288351;6391.45672617828;6404.231492220917;6408.967955160194;5157.613179011589;5418.83743487692;5858.707064646751;5883.323417806964;5887;5389.892202155974;6775.457963362919;7093.900445430966;7102.822429439254;7124.821879453003;4147.6474499667565;4971.403431588196;4977.875553111165;4979.676321039157;5025.623078269108;7515.02304700391;7627.275408836152;7636.312731854105;7655.693772249103;7664.965229693976;5075.2133419319325;5086.669366491166;5105.234214867725;5107.387113546324;5119.360079990001;5212.661177023481;5221.791128354899;5229.744861233276;5231.581473482112;5232.162853943427;5143.456962911112;5680.289963754531;5860.501857342174;5894.145348924382;5916.467517923407;7140.5358651687475;7165.92834071663;7180.533265337841;7183.964080179566;7207.9639601801655;1347.5014244729514;1398.0772690024535;1477.7217706683641;1636.7135751243484;1769.4691592522192;1132.1395739238237;1171.1743221029153;1183.2782002978176;1202.194529665109;1210.6549633354452;6354;6605.70274337656;6612.768553100618;6735.764248251287;6764.255931847493;6658.801415745115;7055.400290975222;7369.8157546061775;7424.814379640444;7452.441066919894;6104.992676208376;6139.355367686418;6179.474744646755;6675.699593518303;6705.664716764142;3339.298747263102;3808.409696497047;3838.1172330364175;3856.7878766668105;3867.9226415471535;5680.11958146488;5759.395263497272;7436.9256307436235;7624.656890439944;7625.937379783086;6006.879862402728;6887.139107611549;6895.586264824126;7260.927390726025;7482.653122438013;6644.800655980328;6816.182058153054;7074.504784665024;7098.338123203798;7617.324188678482;7323;7342.889856652261;7346;7380.704771809067;7399.260073992557;7198.228681957588;7202.919562065732;7210.315020073084;7215.618845780985;7247.818804529929;7120.148761322197;7268.89096663561;7565.243475652391;7623.313901748869;7630.923690763021;5072.797088116434;5073.44192138857;5079.87300317495;5106.642535022661;5114.667546609458;7356.926430735624;7386.2613738625705;7394.741184058747;7408.777736667908;7410.814729631802;7101.8579628406105;7102.289771022856;7136.4290856730295;7147.141743146924;7205.279472052753;6426.003969384753;6940.687669054803;7120.00639820851;7145.535540189871;7148.142222933281;6195.256569211723;6812.761553345609;6869.690863911036;6899.413549848319;6959.965200173967;5931.762729490944;6518.902216466662;7053.894191587027;7114.857702846018;7130.429565634633;5890.4698577128265;6544.738210471528;6909.447244220551;6910.8617827644175;6945.895811562729 14:13:15 INFO - PID 1649 | |5;yui.html;826.6652005937594;865.5888452984833;885.1679421343932;885.5218182181626;912.1835956818647;17719.025453600047;18226.270949162034;18334.174962126704;18361.449156525294;18410.35563755269;16831.91584042078;18941.242350305973;19393.903030484827;19596.804031959677;19778.40664780055;1042.890496497868;1049.4333060147512;1052.9947350263237;1065.5418170186817;1078.14826287233;1088.9945550272237;1089.2538611051455;1090.1006669497697;1091.1870656361023;1093.2565855218447;1119.8992090711833;1141.8230174322994;1145.816669332907;1149.2070471374725;1151.5163631274843;1110.9166812489016;1116.9609063682733;1125.3979121170216;1144.2848219862585;1145.6563031090682;809.7408829174666;810.4934415990007;814.6212011414661;834.390894646905;838.0320729557371;15179.241037948168;18599.44201673952;19110.904445477823;19123.330683426015;19147.52131196717;15704.685906281811;15980.920095399564;17898.731519027224;18774.718379224323;19098.33155839553;15110.39558417673;15441.845581544265;15472.30374633133;15651.843481565153;15673.764893526606;3468.618451970281;3779.7337891806005;3805.676517496016;3816.446615240802;3819.4079917612535;98451;136765.3161734195;143222.28388858092;167791;169880;12122.515099395925;12381.380930953417;12675.366231688378;14882.479113230986;14891;27348.863255683595;27371.726282737316;27447.313817154733;27741.306467338076;27762.750676219617;8239.670413183405;8428.662853485914;8451.45065570736;8494.277986371104;8581.95709021451;3770.434434834797;4194.643455306272;4241.533431322552;4256.659467242614;4262.190183865056;4938.407391113089;4938.506149385104;5000.64995450315;5024.874378140503;5037.546620804145;923.6028507741736;931.5947562810064;1075.9193060520493;1085.8262677971486;1088.2708585247904;667.396006614015;676.3980057748607;686.8557602903346;687.3264201082867;710.7867639708066;11904.107191960638;11952.820707689392;12214.816777748341;12560.560380386825;12577.685557861127;657.1457105762489;664.9468042556681;677.8136012596575;694.8818700820873;703.3177817517001;5514.751836167382;5824.359320474744;5858.765649373977;5865.824025279248;6034.818955431344;136491.31754341166;138243;138613;138951;142060.28969855292;114806;117179.41410292895;117311.41344293224;121374;122887;98297.50851245841;102589.48705256575;104135;105059.42410863843;109027.45486272359;9050.140236677506;11539.826902596637;11601.361925093863;11885.108616853944;11911.583094591648;46432.30351544662;47457.28814067861;48651;49491.50508495013;49697.503024969286;59542.70228648829;59700.40299596948;62393.37606623876;62852;63402;15588.376464941275;15702.136382499073;15948.920255398649;15986.680266394374;16020.27908744086;17016.914915425343;17040.31838726437;19478.90260548688;19494.902525487283;20499.59000820005;18897;18970.81029189746;19130.330438434587;19465.61068778588;19594.90202548978;18937;19364.225430982602;19379.51551211231;19496.512587185432;19590.510237244183;17010.149492525077;17088.145592720066;17421.738673920154;17662.381816636356;18801.717974230673;11810.114241431755;11817.704557386134;12404.25574465535;12609.558665446666;12732.23606583608;13919.582412527641;14860.331285091981;14898.776518352017;14982.550523484313;15042 14:13:15 INFO - PID 1649 | __end_tp_report 14:13:15 INFO - PID 1649 | __start_cc_report 14:13:15 INFO - PID 1649 | _x_x_mozilla_cycle_collect,396 14:13:15 INFO - PID 1649 | __end_cc_report 14:13:15 INFO - PID 1649 | __startTimestamp1497474795175__endTimestamp 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | ------- Summary: start ------- 14:13:15 INFO - PID 1649 | Number of tests: 6 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#0] dojo.html Cycles:165 Average:6502.87 Median:6781.00 stddev:845.68 (12.5%) stddev-sans-first:812.00 14:13:15 INFO - PID 1649 | Values: 3379.5 3639.0 3806.6 3837.1 3886.5 5040.3 5312.3 6409.6 6450.6 6535.0 6492.5 6515.1 6541.9 6553.9 6625.3 5850.2 5852.7 5856.7 6025.6 6030.1 5659.3 5926.3 5932.9 5949.1 5978.0 4650.4 5685.2 5720.9 5746.6 5761.3 5830.2 5868.1 5905.9 5913.2 5930.1 5740.3 5798.0 5803.9 5816.9 5819.4 5463.8 6562.4 6573.3 6591.2 6617.9 6811.6 6969.5 7036.4 7068.2 7144.7 5987.6 7140.3 7213.8 7249.7 7264.5 6644.6 6685.4 6730.4 6730.8 6743.8 7139.6 7419.8 7451.2 7455.3 7470.7 6795.0 6829.7 6846.3 6958.7 7044.0 7147.1 7176.5 7229.2 7231.5 7239.0 7123.3 7123.8 7124.0 7134.0 7135.5 7080.1 7116.6 7136.1 7139.8 7146.6 4840.9 5484.1 5598.4 5693.7 5719.7 4514.2 4848.3 4952.1 4952.3 4982.7 6474.8 6567.6 6578.2 6591.6 6663.8 7229.3 7248.3 7253.0 7259.6 7261.2 6763.9 6771.3 6781.0 6781.7 6800.4 5190.0 5626.9 6097.5 6252.1 6300.3 7115.7 7422.5 7439.7 7450.2 7484.7 7000.3 7015.5 7030.8 7032.9 7034.9 5819.8 6982.4 7449.1 7501.7 7509.4 7125.3 7128.8 7137.5 7151.5 7161.7 7198.4 7265.7 7267.3 7282.1 7299.7 6750.4 6814.5 6814.9 6907.5 6982.4 6938.8 7000.8 7017.6 7034.9 7068.3 5322.8 6681.2 6847.1 6874.0 6894.8 6298.4 6817.6 6997.0 7021.6 7032.4 6535.8 6674.0 6676.3 6677.8 6905.9 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#1] ext.html Cycles:180 Average:45496.13 Median:35230.30 stddev:81475.05 (231.3%) stddev-sans-first:81634.34 14:13:15 INFO - PID 1649 | Values: 748.3 835.0 888.0 901.3 1270.2 36248.6 37164.6 37197.0 37207.8 37224.9 37669.6 37884.4 37916.2 37937.2 37998.0 7893.8 8014.1 8024.8 8033.0 8034.1 7170.9 7901.6 8276.2 8467.9 8556.1 8528.6 8558.0 8621.9 8628.1 8629.4 7182.6 8576.2 8578.2 8578.3 8603.2 8242.9 8302.9 8359.7 8365.9 8366.4 35653.5 35948.8 35995.0 36020.6 36040.5 3666.8 3688.3 3707.2 3712.0 3715.4 30128.4 30224.9 30229.8 30273.5 30301.9 3191.2 3227.2 3252.4 3260.4 3277.2 249351.5 252195.2 254564.6 323623.0 324586.8 45584.8 46160.5 46162.5 46180.5 46527.1 48188.5 48637.8 48732.5 48765.8 48777.0 15684.1 15789.1 15799.0 15817.8 15821.4 12864.7 13008.3 13008.6 13025.6 13041.0 13459.4 13507.7 13553.7 13560.9 13602.7 5961.8 6013.8 6047.7 6051.3 6065.0 981.6 983.0 988.0 992.1 1035.9 20417.2 21550.8 21773.8 22885.5 22951.2 1086.9 1091.7 1115.5 1140.2 1150.6 10303.6 15912.6 16101.4 16133.8 16144.8 306351.9 311271.0 311410.0 358850.4 360790.0 54417.5 54681.2 54695.7 54847.0 55120.7 1533.4 1545.0 1549.9 1551.0 1555.7 23134.8 26847.8 27837.6 29994.6 30045.0 37092.6 38135.9 38293.0 38347.6 38419.0 314533.0 314652.0 314714.0 315234.0 316328.0 30033.7 30858.8 32848.7 34643.8 34807.1 39417.4 39743.0 39752.0 39998.4 41727.0 28331.6 28656.7 29038.4 29875.7 30676.4 23795.0 28744.0 29076.7 29351.7 30381.4 29153.6 36273.6 36275.7 36311.9 36314.0 24273.0 28029.6 30156.0 30357.8 31662.9 22261.0 23394.9 24212.6 24599.5 24927.9 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#2] jquery.html Cycles:180 Average:38772.73 Median:21883.89 stddev:92022.05 (420.5%) stddev-sans-first:92235.15 14:13:15 INFO - PID 1649 | Values: 422.6 511.6 526.5 535.9 556.0 23965.0 24163.3 24494.5 24797.6 27296.5 26176.0 26520.5 26704.9 27041.7 27131.0 6808.8 6964.1 6975.1 6981.4 6984.6 3896.2 5956.3 6963.5 6967.8 7051.6 6939.4 6984.9 6990.5 7005.6 7009.7 6955.6 6958.0 6992.7 6992.8 6994.3 6332.9 6352.2 6362.0 6376.9 6381.0 32135.0 32170.0 33149.3 33287.2 33407.0 2371.3 2412.9 2416.5 2421.3 2422.7 16523.7 20456.2 20658.7 20695.4 20705.7 2282.9 2321.0 2362.7 2364.3 2372.2 172480.1 180771.0 188891.0 189509.0 190090.0 13097.0 14202.1 15789.6 15893.7 16004.6 19035.2 21730.6 21739.8 21781.6 21814.8 10831.5 10867.8 10868.9 10901.4 10920.5 9980.5 10002.5 10031.3 10059.7 10095.7 8748.7 9228.4 9766.2 9770.7 9794.2 2465.9 2715.6 2758.5 2770.5 2899.7 957.7 966.3 991.9 1012.9 1017.7 21953.0 22061.0 22182.7 22230.0 22975.2 1007.5 1019.5 1038.2 1053.2 1064.3 11118.4 11193.4 11367.7 11416.7 11736.5 511049.0 530818.0 531931.1 536112.0 541376.0 138331.0 147907.0 155337.0 157840.2 159275.0 1187.0 1221.7 1231.5 1233.5 1234.5 19028.4 19414.3 19798.9 20086.9 20258.9 65744.3 66000.8 67182.0 68627.7 68760.3 85312.7 85327.6 88436.0 88740.7 89712.0 11699.2 12023.3 12500.9 12599.9 12688.3 18361.1 19270.4 19513.6 19696.5 19780.0 15128.9 15892.9 16020.4 16031.0 16062.2 13717.7 13965.4 14128.1 14210.9 14697.9 16005.5 16213.8 16232.8 16475.3 16486.8 12379.9 12380.8 12412.0 12513.7 12548.9 15376.9 15426.4 15496.2 15500.1 15507.5 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#3] mootools.html Cycles:180 Average:8784.29 Median:2914.23 stddev:5782.75 (198.4%) stddev-sans-first:5773.25 14:13:15 INFO - PID 1649 | Values: 1525.8 1567.5 1588.7 1595.4 1600.3 14206.3 14782.2 14853.5 14894.9 14895.2 13857.9 14651.3 14659.4 14668.7 14723.8 2526.5 2949.1 2996.2 3000.5 3000.6 2911.4 2960.0 2964.6 2966.9 2969.5 2804.6 2917.1 2964.6 2966.3 2967.0 2864.5 2940.9 2970.0 2978.5 2979.8 2918.4 2928.1 2937.3 2941.3 2960.4 14060.3 14357.0 14393.9 14396.8 14402.6 8011.0 8163.6 8307.8 8312.3 8312.4 4490.9 4926.7 5164.3 5186.3 5247.3 1458.5 1489.3 1494.0 1517.2 1522.0 19354.8 20368.1 21553.0 21748.8 21751.0 6895.6 6897.8 7009.4 7020.2 7086.9 12277.9 14658.0 14870.9 14884.8 14887.3 7824.8 7964.2 7982.8 7988.7 7996.8 7144.9 7383.8 7385.5 7450.7 7484.3 5280.6 5482.8 5497.5 5553.8 5624.9 1621.4 1626.4 1634.5 1634.7 1646.1 835.7 856.9 870.8 875.2 898.1 8261.8 8302.0 8707.8 8719.2 9123.6 987.5 1020.3 1048.4 1051.5 1075.4 4456.3 4790.1 4958.6 5025.6 5140.6 18741.9 19247.6 19270.6 19329.6 19443.1 18064.0 18920.5 19048.4 19049.8 19240.6 10090.1 10154.3 10187.7 10273.8 10274.9 7436.6 7703.6 7952.2 8228.1 8333.4 17411.9 17993.6 18074.2 18101.0 18293.5 17050.4 18180.8 18321.2 18513.4 18604.7 11322.4 11513.4 11554.5 11559.5 11585.5 12712.4 12824.0 12824.7 12898.4 12982.7 10245.4 10446.9 10482.6 10817.4 10906.6 9265.5 9486.9 10473.0 10604.6 10855.8 9354.1 9625.0 9652.3 9711.9 9805.3 7002.9 7074.4 7240.6 7364.9 7421.7 7201.0 7313.6 7414.0 7511.7 7533.7 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#4] prototype.html Cycles:180 Average:5747.70 Median:6372.73 stddev:1779.73 (27.9%) stddev-sans-first:1739.09 14:13:15 INFO - PID 1649 | Values: 412.8 426.3 448.4 477.0 489.4 6590.0 6627.4 6668.3 6672.6 6704.3 5021.6 5923.3 6267.1 6491.2 6491.2 4057.7 4495.3 4912.5 4950.9 4973.0 4428.9 4610.7 4942.3 4943.9 4974.8 4295.5 4655.7 4753.5 4902.7 4947.4 4909.6 4927.2 4947.2 4947.3 4969.8 4342.0 4342.3 4343.5 4354.7 4371.5 5641.0 6208.4 6391.5 6404.2 6409.0 5157.6 5418.8 5858.7 5883.3 5887.0 5389.9 6775.5 7093.9 7102.8 7124.8 4147.6 4971.4 4977.9 4979.7 5025.6 7515.0 7627.3 7636.3 7655.7 7665.0 5075.2 5086.7 5105.2 5107.4 5119.4 5212.7 5221.8 5229.7 5231.6 5232.2 5143.5 5680.3 5860.5 5894.1 5916.5 7140.5 7165.9 7180.5 7184.0 7208.0 1347.5 1398.1 1477.7 1636.7 1769.5 1132.1 1171.2 1183.3 1202.2 1210.7 6354.0 6605.7 6612.8 6735.8 6764.3 6658.8 7055.4 7369.8 7424.8 7452.4 6105.0 6139.4 6179.5 6675.7 6705.7 3339.3 3808.4 3838.1 3856.8 3867.9 5680.1 5759.4 7436.9 7624.7 7625.9 6006.9 6887.1 6895.6 7260.9 7482.7 6644.8 6816.2 7074.5 7098.3 7617.3 7323.0 7342.9 7346.0 7380.7 7399.3 7198.2 7202.9 7210.3 7215.6 7247.8 7120.1 7268.9 7565.2 7623.3 7630.9 5072.8 5073.4 5079.9 5106.6 5114.7 7356.9 7386.3 7394.7 7408.8 7410.8 7101.9 7102.3 7136.4 7147.1 7205.3 6426.0 6940.7 7120.0 7145.5 7148.1 6195.3 6812.8 6869.7 6899.4 6960.0 5931.8 6518.9 7053.9 7114.9 7130.4 5890.5 6544.7 6909.4 6910.9 6945.9 14:13:15 INFO - PID 1649 | 14:13:15 INFO - PID 1649 | [#5] yui.html Cycles:180 Average:25572.01 Median:18504.90 stddev:38454.91 (207.8%) stddev-sans-first:38517.90 14:13:15 INFO - PID 1649 | Values: 826.7 865.6 885.2 885.5 912.2 17719.0 18226.3 18334.2 18361.4 18410.4 16831.9 18941.2 19393.9 19596.8 19778.4 1042.9 1049.4 1053.0 1065.5 1078.1 1089.0 1089.3 1090.1 1091.2 1093.3 1119.9 1141.8 1145.8 1149.2 1151.5 1110.9 1117.0 1125.4 1144.3 1145.7 809.7 810.5 814.6 834.4 838.0 15179.2 18599.4 19110.9 19123.3 19147.5 15704.7 15980.9 17898.7 18774.7 19098.3 15110.4 15441.8 15472.3 15651.8 15673.8 3468.6 3779.7 3805.7 3816.4 3819.4 98451.0 136765.3 143222.3 167791.0 169880.0 12122.5 12381.4 12675.4 14882.5 14891.0 27348.9 27371.7 27447.3 27741.3 27762.8 8239.7 8428.7 8451.5 8494.3 8582.0 3770.4 4194.6 4241.5 4256.7 4262.2 4938.4 4938.5 5000.6 5024.9 5037.5 923.6 931.6 1075.9 1085.8 1088.3 667.4 676.4 686.9 687.3 710.8 11904.1 11952.8 12214.8 12560.6 12577.7 657.1 664.9 677.8 694.9 703.3 5514.8 5824.4 5858.8 5865.8 6034.8 136491.3 138243.0 138613.0 138951.0 142060.3 114806.0 117179.4 117311.4 121374.0 122887.0 98297.5 102589.5 104135.0 105059.4 109027.5 9050.1 11539.8 11601.4 11885.1 11911.6 46432.3 47457.3 48651.0 49491.5 49697.5 59542.7 59700.4 62393.4 62852.0 63402.0 15588.4 15702.1 15948.9 15986.7 16020.3 17016.9 17040.3 19478.9 19494.9 20499.6 18897.0 18970.8 19130.3 19465.6 19594.9 18937.0 19364.2 19379.5 19496.5 19590.5 17010.1 17088.1 17421.7 17662.4 18801.7 11810.1 11817.7 12404.3 12609.6 12732.2 13919.6 14860.3 14898.8 14982.6 15042.0 14:13:15 INFO - PID 1649 | -------- Summary: end -------- 14:13:15 INFO - PID 1649 | 14:13:15 INFO - TEST-INFO | 1649: exit 0 14:13:15 INFO - TEST-OK | dromaeo_css | took 1142176ms 14:13:15 INFO - TEST-START | kraken 14:13:15 INFO - operating with platform_type : mac_ 14:13:15 INFO - Initialising browser for kraken test... 14:13:15 INFO - Application command: /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp7kvmkM/profile 14:13:15 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox http://localhost:49226/getInfo.html -foreground) 14:13:48 INFO - TEST-INFO | 1657: killed by SIGTERM 14:13:48 INFO - Browser initialized. 14:13:48 INFO - Running cycle 1/1 for kraken test... 14:13:48 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp7kvmkM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:50 INFO - PID 1660 | 2017-06-14 14:13:50.212 plugin-container[1661:10768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x954f, name = 'com.apple.tsm.portname' 14:13:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:13:50 INFO - PID 1660 | 2017-06-14 14:13:50.212 plugin-container[1661:10768] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9a0b, name = 'com.apple.CFPasteboardClient' 14:13:50 INFO - PID 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:13:50 INFO - PID 1660 | 2017-06-14 14:13:50.212 plugin-container[1661:10768] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:14:00 INFO - PID 1660 | RSS: Main: 185012224 14:14:00 INFO - PID 1660 | 14:14:02 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection) 14:14:03 INFO - PID 1660 | RSS: Main: 199323648 14:14:03 INFO - PID 1660 | 14:14:06 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-dft) 14:14:06 INFO - PID 1660 | RSS: Main: 208748544 14:14:06 INFO - PID 1660 | 14:14:10 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-fft) 14:14:10 INFO - PID 1660 | RSS: Main: 212750336 14:14:10 INFO - PID 1660 | 14:14:12 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator) 14:14:13 INFO - PID 1660 | RSS: Main: 213442560 14:14:13 INFO - PID 1660 | 14:14:15 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:14:16 INFO - PID 1660 | RSS: Main: 214749184 14:14:16 INFO - PID 1660 | 14:14:18 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom) 14:14:19 INFO - PID 1660 | RSS: Main: 220008448 14:14:19 INFO - PID 1660 | 14:14:22 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate) 14:14:23 INFO - PID 1660 | RSS: Main: 220073984 14:14:23 INFO - PID 1660 | 14:14:26 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial) 14:14:26 INFO - PID 1660 | RSS: Main: 220217344 14:14:26 INFO - PID 1660 | 14:14:28 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:14:28 INFO - PID 1660 | RSS: Main: 219541504 14:14:28 INFO - PID 1660 | 14:14:30 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:14:31 INFO - PID 1660 | RSS: Main: 219586560 14:14:31 INFO - PID 1660 | 14:14:33 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:14:33 INFO - PID 1660 | RSS: Main: 219930624 14:14:33 INFO - PID 1660 | 14:14:36 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:14:37 INFO - PID 1660 | RSS: Main: 219762688 14:14:37 INFO - PID 1660 | 14:14:40 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:14:40 INFO - PID 1660 | RSS: Main: 220049408 14:14:40 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | Cycle 1(1): loaded http://localhost:49226/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49226/tests/kraken/driver.html?testName=ai-astar) 14:14:42 INFO - PID 1660 | RSS: Main: 221544448 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | __start_tp_report 14:14:42 INFO - PID 1660 | _x_x_mozilla_page_load 14:14:42 INFO - PID 1660 | _x_x_mozilla_page_load_details 14:14:42 INFO - PID 1660 | |i|pagename|runs| 14:14:42 INFO - PID 1660 | |0;ai-astar;102;115;106;122;117;115;116;102;116;116 14:14:42 INFO - PID 1660 | |1;audio-beat-detection;143;110;144;150;108;128;128;94;159;92 14:14:42 INFO - PID 1660 | |2;audio-dft;195;206;195;198;196;195;193;230;281;247 14:14:42 INFO - PID 1660 | |3;audio-fft;63;79;73;146;57;57;56;62;118;55 14:14:42 INFO - PID 1660 | |4;audio-oscillator;69;74;116;72;82;97;112;70;73;103 14:14:42 INFO - PID 1660 | |5;imaging-gaussian-blur;89;89;90;90;91;96;90;89;90;89 14:14:42 INFO - PID 1660 | |6;imaging-darkroom;98;97;103;155;103;144;153;97;98;97 14:14:42 INFO - PID 1660 | |7;imaging-desaturate;91;92;92;92;93;92;92;92;91;88 14:14:42 INFO - PID 1660 | |8;json-parse-financial;51;70;61;50;50;49;52;52;52;49 14:14:42 INFO - PID 1660 | |9;json-stringify-tinderbox;52;55;54;62;57;62;55;53;55;57 14:14:42 INFO - PID 1660 | |10;stanford-crypto-aes;81;78;80;81;81;84;73;99;70;69 14:14:42 INFO - PID 1660 | |11;stanford-crypto-ccm;156;131;157;143;133;153;156;145;136;144 14:14:42 INFO - PID 1660 | |12;stanford-crypto-pbkdf2;185;188;185;184;186;185;188;183;189;188 14:14:42 INFO - PID 1660 | |13;stanford-crypto-sha256-iterative;67;66;66;71;90;92;94;75;65;66 14:14:42 INFO - PID 1660 | __end_tp_report 14:14:42 INFO - PID 1660 | __start_cc_report 14:14:42 INFO - PID 1660 | _x_x_mozilla_cycle_collect,981 14:14:42 INFO - PID 1660 | __end_cc_report 14:14:42 INFO - PID 1660 | __startTimestamp1497474882946__endTimestamp 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | ------- Summary: start ------- 14:14:42 INFO - PID 1660 | Number of tests: 14 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#0] ai-astar Cycles:10 Average:112.70 Median:116.00 stddev:6.85 (5.9%) stddev-sans-first:6.07 14:14:42 INFO - PID 1660 | Values: 102.0 115.0 106.0 122.0 117.0 115.0 116.0 102.0 116.0 116.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#1] audio-beat-detection Cycles:10 Average:125.60 Median:147.00 stddev:23.67 (16.1%) stddev-sans-first:24.26 14:14:42 INFO - PID 1660 | Values: 143.0 110.0 144.0 150.0 108.0 128.0 128.0 94.0 159.0 92.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#2] audio-dft Cycles:10 Average:213.60 Median:202.00 stddev:29.81 (14.8%) stddev-sans-first:30.85 14:14:42 INFO - PID 1660 | Values: 195.0 206.0 195.0 198.0 196.0 195.0 193.0 230.0 281.0 247.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#3] audio-fft Cycles:10 Average:76.60 Median:59.50 stddev:30.92 (52.0%) stddev-sans-first:32.41 14:14:42 INFO - PID 1660 | Values: 63.0 79.0 73.0 146.0 57.0 57.0 56.0 62.0 118.0 55.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#4] audio-oscillator Cycles:10 Average:86.80 Median:72.50 stddev:18.41 (25.4%) stddev-sans-first:18.36 14:14:42 INFO - PID 1660 | Values: 69.0 74.0 116.0 72.0 82.0 97.0 112.0 70.0 73.0 103.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:90.30 Median:90.00 stddev:2.11 (2.3%) stddev-sans-first:2.19 14:14:42 INFO - PID 1660 | Values: 89.0 89.0 90.0 90.0 91.0 96.0 90.0 89.0 90.0 89.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#6] imaging-darkroom Cycles:10 Average:114.50 Median:97.00 stddev:25.21 (26.0%) stddev-sans-first:26.02 14:14:42 INFO - PID 1660 | Values: 98.0 97.0 103.0 155.0 103.0 144.0 153.0 97.0 98.0 97.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:92.00 stddev:1.35 (1.5%) stddev-sans-first:1.42 14:14:42 INFO - PID 1660 | Values: 91.0 92.0 92.0 92.0 93.0 92.0 92.0 92.0 91.0 88.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:52.00 stddev:6.72 (12.9%) stddev-sans-first:7.06 14:14:42 INFO - PID 1660 | Values: 51.0 70.0 61.0 50.0 50.0 49.0 52.0 52.0 52.0 49.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:56.20 Median:56.00 stddev:3.43 (6.1%) stddev-sans-first:3.28 14:14:42 INFO - PID 1660 | Values: 52.0 55.0 54.0 62.0 57.0 62.0 55.0 53.0 55.0 57.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:81.00 stddev:8.51 (10.5%) stddev-sans-first:9.02 14:14:42 INFO - PID 1660 | Values: 81.0 78.0 80.0 81.0 81.0 84.0 73.0 99.0 70.0 69.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:149.00 stddev:9.86 (6.6%) stddev-sans-first:9.68 14:14:42 INFO - PID 1660 | Values: 156.0 131.0 157.0 143.0 133.0 153.0 156.0 145.0 136.0 144.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:187.00 stddev:2.02 (1.1%) stddev-sans-first:2.11 14:14:42 INFO - PID 1660 | Values: 185.0 188.0 185.0 184.0 186.0 185.0 188.0 183.0 189.0 188.0 14:14:42 INFO - PID 1660 | 14:14:42 INFO - PID 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.20 Median:73.00 stddev:12.01 (16.4%) stddev-sans-first:12.36 14:14:42 INFO - PID 1660 | Values: 67.0 66.0 66.0 71.0 90.0 92.0 94.0 75.0 65.0 66.0 14:14:42 INFO - PID 1660 | -------- Summary: end -------- 14:14:42 INFO - PID 1660 | 14:14:43 INFO - TEST-INFO | 1660: exit 0 14:14:43 INFO - TEST-OK | kraken | took 88109ms 14:14:43 INFO - SUITE-END | took 1230s 14:14:44 INFO - Completed test suite (00:20:30) 14:14:44 INFO - javascript benchmark 14:14:44 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8683.882336101824, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3379.526866238727, 3639.0356555512813, 3806.619338066194, 3837.05992031952, 3886.455896174536, 5040.294358789768, 5312.335958005249, 6409.551331406803, 6450.5807122537, 6534.967325163368, 6492.513061520393, 6515.120458738081, 6541.934580654192, 6553.934460655392, 6625.337466253372, 5850.151727999438, 5852.707364631773, 5856.707164641751, 6025.638461692312, 6030.09548567714, 5659.292588426447, 5926.288845338566, 5932.851678708023, 5949.107633854913, 5977.970110149465, 4650.372199753024, 5685.1756495308155, 5720.914186287209, 5746.597738158331, 5761.30864296285, 5830.154627578977, 5868.09044598088, 5905.852353691149, 5913.201717768089, 5930.080837470199, 5740.339860915965, 5797.971010144965, 5803.854903627401, 5816.8836623267725, 5819.359870414251, 5463.836084917419, 6562.44219241365, 6573.342665733437, 6591.242007169189, 6617.933820661828, 6811.557248778813, 6969.546979446371, 7036.366726994595, 7068.151821781367, 7144.714211431537, 5987.550933679948, 7140.321669441447, 7213.819654508627, 7249.746258880967, 7264.4914855960105, 6644.634545100018, 6685.431738302299, 6730.360615741497, 6730.831729206711, 6743.797686069426, 7139.5716257023705, 7419.777406677808, 7451.180370159278, 7455.329020388082, 7470.738524151738, 6795.0, 6829.658517074126, 6846.349596788297, 6958.686859091353, 7043.964780176067, 7147.142342918779, 7176.497645164788, 7229.2409297024105, 7231.493795434374, 7239.022731931227, 7123.287671232835, 7123.82190445243, 7123.964380178169, 7134.036905017756, 7135.5361901476235, 7080.079589653312, 7116.573005619679, 7136.143662760398, 7139.750108746272, 7146.606936618432, 4840.903181936342, 5484.122540393518, 5598.356189038313, 5693.68684722336, 5719.714014299351, 4514.232580461329, 4848.272759086165, 4952.108620448282, 4952.257161425743, 4982.701037937735, 6474.838129046717, 6567.605943643397, 6578.243501997236, 6591.6374599397495, 6663.7672030674485, 7229.313215244543, 7248.275172482709, 7252.963735181396, 7259.564426134343, 7261.164966028974, 6763.932360676428, 6771.255161932184, 6781.0, 6781.6609169541325, 6800.387965083166, 5189.974050129801, 5626.874625075001, 6097.542684298695, 6252.124702541648, 6300.338464461165, 7115.67979440916, 7422.517536360119, 7439.739609113655, 7450.18048014718, 7484.663190156347, 7000.299970003162, 7015.508914376149, 7030.789076327718, 7032.859342813217, 7034.894476582753, 5819.79630712923, 6982.406495447944, 7449.143348514774, 7501.699932002659, 7509.361704254981, 7125.3230943060325, 7128.8217794553475, 7137.464690148364, 7151.499395042297, 7161.713531458891, 7198.388137008204, 7265.745698900513, 7267.273272672691, 7282.0533330668795, 7299.671514781741, 6750.35871592198, 6814.488913331421, 6814.93185068143, 6907.516473846783, 6982.441404687511, 6938.826529336807, 7000.7549735759, 7017.578945263301, 7034.859302814018, 7068.257832927674, 5322.813701520428, 6681.198256209286, 6847.144106986627, 6874.0032695257905, 6894.827629309308, 6298.401651843067, 6817.5909445433435, 6997.020417141503, 7021.5787052777, 7032.437405007485, 6535.836604084936, 6673.9666301668185, 6676.298988606321, 6677.76627818024, 6905.896411553931], "value": 6444.858402457977, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [748.2779118150985, 834.9833003339934, 887.9822403551927, 901.3014913442081, 1270.2061211742662, 36248.63751362485, 37164.628353716456, 37197.0, 37207.81396093022, 37224.88325350237, 37669.62330376695, 37884.43173352402, 37916.24167516648, 37937.24125517488, 37998.0, 7893.842123157548, 8014.078380986174, 8024.839503209932, 8033.0, 8034.116247212802, 7170.856582868366, 7901.565413902233, 8276.172382761735, 8467.915320846805, 8556.144385561425, 8528.57357132144, 8557.957210213972, 8621.913780862174, 8628.050914399442, 8629.352798540134, 7182.6408679566075, 8576.185262400062, 8578.227959483645, 8578.270846978014, 8603.182697643713, 8242.876356854653, 8302.875456868152, 8359.665613375457, 8365.916340836635, 8366.37252206087, 35653.46519802205, 35948.82025589881, 35995.0, 36020.639793602255, 36040.45939310912, 3666.7616604920595, 3688.2807852468777, 3707.1844194277214, 3711.962880371189, 3715.442683597482, 30128.397432051235, 30224.94212702545, 30229.848850755825, 30273.545896811567, 30301.93943211999, 3191.186247506871, 3227.2254658881934, 3252.398306313316, 3260.3805276997296, 3277.246233366315, 249351.50648493646, 252195.21707174406, 254564.63588410438, 323623.0, 324586.7541324604, 45584.77207614007, 46160.5383946163, 46162.538374615826, 46180.5381946183, 46527.06945861132, 48188.518114818406, 48637.75681121572, 48732.512674873506, 48765.75617121892, 48777.02445951061, 15684.058956462648, 15789.131597762003, 15799.0, 15817.762733559006, 15821.417858214316, 12864.678383040311, 13008.284544349963, 13008.609741707764, 13025.609231723063, 13041.0, 13459.3943272551, 13507.662308442368, 13553.728925421436, 13560.93219533896, 13602.727945441036, 5961.791337303173, 6013.789517366871, 6047.697615119227, 6051.304100028553, 6064.969675151684, 981.6417007792104, 982.9655962041294, 987.9802403952025, 992.1170158558888, 1035.8808736995343, 20417.24617524357, 21550.78449215488, 21773.782262177174, 22885.542289154124, 22951.196708115134, 1086.9076128529164, 1091.7052395853234, 1115.4590023838432, 1140.236041851961, 1150.5685367987005, 10303.639372621923, 15912.602184945468, 16101.355945762038, 16133.757993630104, 16144.75782863258, 306351.9364806368, 311271.0, 311410.0, 358850.4114958817, 360790.0, 54417.45582544203, 54681.179782302504, 54695.72652136714, 54847.0, 55120.724396378566, 1533.4479587348546, 1545.0, 1549.9070055796687, 1550.9767353489706, 1555.7165338595703, 23134.76865231326, 26847.791849366437, 27837.582436263066, 29994.55008174923, 30045.0, 37092.629073708915, 38135.85592432231, 38293.0, 38347.61652383552, 38419.03952401212, 314533.0, 314652.0, 314714.0, 315234.0, 316328.0, 30033.69966300309, 30858.845705771328, 32848.68605255763, 34643.82678086694, 34807.12982175466, 39417.40873886951, 39743.0, 39752.0, 39998.40002399908, 41726.95682607959, 28331.575026375034, 28656.71343286624, 29038.419231614826, 29875.70124298816, 30676.386472269984, 23795.0, 28743.993960211294, 29076.7092329074, 29351.679174436773, 30381.392372152874, 29153.56269655999, 36273.63726362808, 36275.7303494387, 36311.91064268076, 36314.0, 24273.0, 28029.57955630708, 30156.0, 30357.848210758806, 31662.89179878693, 22261.0, 23394.883025584764, 24212.63681044821, 24599.50800984006, 24927.875360623806], "value": 17535.173923063958, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.61753113432354, 511.60095125801877, 526.5445389737876, 535.871390866192, 556.0075265650814, 23965.0, 24163.27510174693, 24494.510109797815, 24797.62803557946, 27296.45407091857, 26176.0, 26520.469590608176, 26704.866475667644, 27041.729582704167, 27131.0, 6808.761693340734, 6964.059851920002, 6975.128108986377, 6981.441484681213, 6984.615846128461, 3896.2207558488276, 5956.344802071769, 6963.512554121214, 6967.825804354881, 7051.576905385693, 6939.410150137244, 6984.860302793941, 6990.475714321421, 7005.649717514104, 7009.719611215545, 6955.617441040741, 6958.0, 6992.6853291602, 6992.790216293494, 6994.265602111781, 6332.905006424908, 6352.237731472253, 6361.968190159019, 6376.936230637727, 6381.0, 32135.0, 32170.0, 33149.3370132596, 33287.16782080443, 33407.0, 2371.3004663624274, 2412.915547955813, 2416.5408572371193, 2421.2736179146364, 2422.7456117107804, 16523.66952660952, 20456.181752729874, 20658.690119648218, 20695.379138625864, 20705.68941465879, 2282.9429264268524, 2320.9883950580306, 2362.669226308301, 2364.3261670424017, 2372.1934542255717, 172480.13759931247, 180771.0, 188891.0, 189509.0, 190090.0, 13097.017723670762, 14202.087291113841, 15789.605259868595, 15893.74178293447, 16004.599885002735, 19035.238590456225, 21730.56538869214, 21739.782602173775, 21781.564368712854, 21814.781852181273, 10831.458427078773, 10867.83698244527, 10868.891311086945, 10901.400422976654, 10920.50857711405, 9980.45107519079, 10002.499875006366, 10031.29780915329, 10059.69820905374, 10095.697129086138, 8748.65005399777, 9228.446293222429, 9766.218702503782, 9770.706878793648, 9794.167495762798, 2465.890349342803, 2715.6150363314455, 2758.462099890502, 2770.515159847019, 2899.7245261700527, 957.707899090784, 966.2634088489881, 991.9355241909253, 1012.9285610925527, 1017.7353887989176, 21953.0, 22061.00725467326, 22182.667259991114, 22229.999650015463, 22975.195868144536, 1007.4710776842217, 1019.5004447820663, 1038.1850247555783, 1053.2416660004785, 1064.3135177810268, 11118.444077796239, 11193.440327983732, 11367.715807104889, 11416.657500275005, 11736.530538778525, 511049.0, 530818.0, 531931.0633714302, 536112.0, 541376.0, 138331.0, 147907.0, 155337.0, 157840.21079894528, 159275.0, 1186.988130118705, 1221.7312191317897, 1231.4704676988981, 1233.5189276182116, 1234.5247079874134, 19028.429147125607, 19414.320498782476, 19798.911059891845, 20086.899565502077, 20258.898705506377, 65744.34255657574, 66000.75311651414, 67182.0, 68627.65686171537, 68760.3123968754, 85312.72030919418, 85327.5733621328, 88436.0, 88740.66888996541, 89712.0, 11699.181057325906, 12023.338716370683, 12500.937495312466, 12599.87400125987, 12688.302143381834, 18361.08194590292, 19270.421887343404, 19513.609727805648, 19696.507587310432, 19780.0, 15128.924355378593, 15892.920535397248, 16020.439284625449, 16031.0, 16062.19689015568, 13717.657058573217, 13965.3715582797, 14128.081674691111, 14210.928945355206, 14697.92651036738, 16005.51983440452, 16213.837861621232, 16232.756508652143, 16475.34098636041, 16486.83513164853, 12379.876201237872, 12380.752384952431, 12412.0, 12513.687157821127, 12548.933340666146, 15376.92361534682, 15426.382944682087, 15496.225188740744, 15500.069995800288, 15507.457238996582], "value": 12218.299491743082, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.7787620794984, 1567.5140706381017, 1588.698147352003, 1595.4176725495197, 1600.255881015328, 14206.289685515721, 14782.186979716138, 14853.480128195515, 14894.851051489482, 14895.18076505793, 13857.93071034646, 14651.340689668963, 14659.413623455075, 14668.679818816305, 14723.779143312859, 2526.5325914705745, 2949.115265420376, 2996.2060054085687, 3000.489916714153, 3000.6249218847643, 2911.38860839223, 2959.978807311474, 2964.570137330086, 2966.8516574171204, 2969.5397213431843, 2804.621376114229, 2917.081119447378, 2964.64424269088, 2966.2732630505557, 2967.035713393145, 2864.4843928092937, 2940.9411811763753, 2970.019893435161, 2978.4638765022287, 2979.8063125896747, 2918.3594409623142, 2928.063019833654, 2937.2656835791054, 2941.3234955960247, 2960.422717570075, 14060.296985150702, 14357.0, 14393.92803035978, 14396.78404823918, 14402.567922962327, 8011.0386753589355, 8163.591820408956, 8307.792305192359, 8312.33501319893, 8312.376571757142, 4490.910181796346, 4926.679765815211, 5164.251183578398, 5186.299849520305, 5247.291615631879, 1458.5478501664518, 1489.2777003153462, 1493.9850601494063, 1517.1731406383547, 1521.9771703424458, 19354.806451935583, 20368.083436245255, 21553.030113644923, 21748.803815790125, 21751.0, 6895.586264824026, 6897.793066208022, 7009.404200642901, 7020.192677842114, 7086.893696594555, 12277.877221227853, 14657.973941824182, 14870.851291486946, 14884.776728349083, 14887.255637218095, 7824.804379890435, 7964.2433968772875, 7982.840343193104, 7988.720394786154, 7996.760097197093, 7144.9285507145305, 7383.778486645409, 7385.519941203805, 7450.6647200875, 7484.251574842581, 5280.551153151949, 5482.80810171642, 5497.505224529811, 5553.750081246273, 5624.887502249932, 1621.4324986254953, 1626.3738460692718, 1634.4933070748107, 1634.6567220883749, 1646.1111000060073, 835.6824406725527, 856.8500512410428, 870.7648934787572, 875.1598465473216, 898.0615257056392, 8261.793455163668, 8301.958490207511, 8707.825843483095, 8719.215270625675, 9123.589438475285, 987.53585814667, 1020.2756043209256, 1048.3762161513887, 1051.4795176387738, 1075.3924032921375, 4456.309272062836, 4790.137775200427, 4958.578520825698, 5025.597952163818, 5140.640155189176, 18741.90629046846, 19247.615047699248, 19270.61458770817, 19329.613407731766, 19443.125059372363, 18064.0, 18920.52698682544, 19048.42854714361, 19049.809501904805, 19240.615187696167, 10090.142337901361, 10154.289199755945, 10187.694369168938, 10273.794524109477, 10274.921133281048, 7436.628168591657, 7703.614819258903, 7952.204779522001, 8228.136045715113, 8333.374996875138, 17411.912940435217, 17993.640127197123, 18074.186661599997, 18101.0, 18293.54266143357, 17050.403235886686, 18180.81819181791, 18321.175547100145, 18513.35203267879, 18604.720929186344, 11322.433878306216, 11513.366764828015, 11554.480048397674, 11559.479823408135, 11585.536578536765, 12712.427940742873, 12824.038197135065, 12824.743505129658, 12898.355082245662, 12982.740345192855, 10245.385276883411, 10446.895531044593, 10482.580696772044, 10817.405042722727, 10906.563737450413, 9265.490398028172, 9486.905130948602, 10473.0, 10604.575816967235, 10855.837162442413, 9354.111359420844, 9625.03749625032, 9652.27607929422, 9711.9028809711, 9805.313628045968, 7002.929970700432, 7074.434045276468, 7240.565566066053, 7364.864189385233, 7421.740239091606, 7200.963995179991, 7313.610414021319, 7413.962930185315, 7511.737089201852, 7533.7363192288], "value": 6522.534612632722, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [412.7853516171591, 426.300866578811, 448.3991451455054, 476.9880752981176, 489.42492571228803, 6590.0, 6627.436667883229, 6668.299828518003, 6672.599644021362, 6704.3295670432935, 5021.648484606079, 5923.348431672513, 6267.122602835607, 6491.221053473578, 6491.22105347359, 4057.6753859691303, 4495.34817451471, 4912.508749125077, 4950.925736113961, 4973.0, 4428.933565996513, 4610.732048686621, 4942.283368911506, 4943.901121977558, 4974.781178611227, 4295.50601680806, 4655.7439340836245, 4753.500882407349, 4902.65681402302, 4947.356843610343, 4909.558139767402, 4927.236278376858, 4947.1589829728855, 4947.282644016615, 4969.751512424365, 4342.0, 4342.283523218697, 4343.478782546114, 4354.716943398669, 4371.519132895379, 5640.971795141039, 6208.441240288351, 6391.45672617828, 6404.231492220917, 6408.967955160194, 5157.613179011589, 5418.83743487692, 5858.707064646751, 5883.323417806964, 5887.0, 5389.892202155974, 6775.457963362919, 7093.900445430966, 7102.822429439254, 7124.821879453003, 4147.6474499667565, 4971.403431588196, 4977.875553111165, 4979.676321039157, 5025.623078269108, 7515.02304700391, 7627.275408836152, 7636.312731854105, 7655.693772249103, 7664.965229693976, 5075.2133419319325, 5086.669366491166, 5105.234214867725, 5107.387113546324, 5119.360079990001, 5212.661177023481, 5221.791128354899, 5229.744861233276, 5231.581473482112, 5232.162853943427, 5143.456962911112, 5680.289963754531, 5860.501857342174, 5894.145348924382, 5916.467517923407, 7140.5358651687475, 7165.92834071663, 7180.533265337841, 7183.964080179566, 7207.9639601801655, 1347.5014244729514, 1398.0772690024535, 1477.7217706683641, 1636.7135751243484, 1769.4691592522192, 1132.1395739238237, 1171.1743221029153, 1183.2782002978176, 1202.194529665109, 1210.6549633354452, 6354.0, 6605.70274337656, 6612.768553100618, 6735.764248251287, 6764.255931847493, 6658.801415745115, 7055.400290975222, 7369.8157546061775, 7424.814379640444, 7452.441066919894, 6104.992676208376, 6139.355367686418, 6179.474744646755, 6675.699593518303, 6705.664716764142, 3339.298747263102, 3808.409696497047, 3838.1172330364175, 3856.7878766668105, 3867.9226415471535, 5680.11958146488, 5759.395263497272, 7436.9256307436235, 7624.656890439944, 7625.937379783086, 6006.879862402728, 6887.139107611549, 6895.586264824126, 7260.927390726025, 7482.653122438013, 6644.800655980328, 6816.182058153054, 7074.504784665024, 7098.338123203798, 7617.324188678482, 7323.0, 7342.889856652261, 7346.0, 7380.704771809067, 7399.260073992557, 7198.228681957588, 7202.919562065732, 7210.315020073084, 7215.618845780985, 7247.818804529929, 7120.148761322197, 7268.89096663561, 7565.243475652391, 7623.313901748869, 7630.923690763021, 5072.797088116434, 5073.44192138857, 5079.87300317495, 5106.642535022661, 5114.667546609458, 7356.926430735624, 7386.2613738625705, 7394.741184058747, 7408.777736667908, 7410.814729631802, 7101.8579628406105, 7102.289771022856, 7136.4290856730295, 7147.141743146924, 7205.279472052753, 6426.003969384753, 6940.687669054803, 7120.00639820851, 7145.535540189871, 7148.142222933281, 6195.256569211723, 6812.761553345609, 6869.690863911036, 6899.413549848319, 6959.965200173967, 5931.762729490944, 6518.902216466662, 7053.894191587027, 7114.857702846018, 7130.429565634633, 5890.4698577128265, 6544.738210471528, 6909.447244220551, 6910.8617827644175, 6945.895811562729], "value": 5196.707402211327, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [826.6652005937594, 865.5888452984833, 885.1679421343932, 885.5218182181626, 912.1835956818647, 17719.025453600047, 18226.270949162034, 18334.174962126704, 18361.449156525294, 18410.35563755269, 16831.91584042078, 18941.242350305973, 19393.903030484827, 19596.804031959677, 19778.40664780055, 1042.890496497868, 1049.4333060147512, 1052.9947350263237, 1065.5418170186817, 1078.14826287233, 1088.9945550272237, 1089.2538611051455, 1090.1006669497697, 1091.1870656361023, 1093.2565855218447, 1119.8992090711833, 1141.8230174322994, 1145.816669332907, 1149.2070471374725, 1151.5163631274843, 1110.9166812489016, 1116.9609063682733, 1125.3979121170216, 1144.2848219862585, 1145.6563031090682, 809.7408829174666, 810.4934415990007, 814.6212011414661, 834.390894646905, 838.0320729557371, 15179.241037948168, 18599.44201673952, 19110.904445477823, 19123.330683426015, 19147.52131196717, 15704.685906281811, 15980.920095399564, 17898.731519027224, 18774.718379224323, 19098.33155839553, 15110.39558417673, 15441.845581544265, 15472.30374633133, 15651.843481565153, 15673.764893526606, 3468.618451970281, 3779.7337891806005, 3805.676517496016, 3816.446615240802, 3819.4079917612535, 98451.0, 136765.3161734195, 143222.28388858092, 167791.0, 169880.0, 12122.515099395925, 12381.380930953417, 12675.366231688378, 14882.479113230986, 14891.0, 27348.863255683595, 27371.726282737316, 27447.313817154733, 27741.306467338076, 27762.750676219617, 8239.670413183405, 8428.662853485914, 8451.45065570736, 8494.277986371104, 8581.95709021451, 3770.434434834797, 4194.643455306272, 4241.533431322552, 4256.659467242614, 4262.190183865056, 4938.407391113089, 4938.506149385104, 5000.64995450315, 5024.874378140503, 5037.546620804145, 923.6028507741736, 931.5947562810064, 1075.9193060520493, 1085.8262677971486, 1088.2708585247904, 667.396006614015, 676.3980057748607, 686.8557602903346, 687.3264201082867, 710.7867639708066, 11904.107191960638, 11952.820707689392, 12214.816777748341, 12560.560380386825, 12577.685557861127, 657.1457105762489, 664.9468042556681, 677.8136012596575, 694.8818700820873, 703.3177817517001, 5514.751836167382, 5824.359320474744, 5858.765649373977, 5865.824025279248, 6034.818955431344, 136491.31754341166, 138243.0, 138613.0, 138951.0, 142060.28969855292, 114806.0, 117179.41410292895, 117311.41344293224, 121374.0, 122887.0, 98297.50851245841, 102589.48705256575, 104135.0, 105059.42410863843, 109027.45486272359, 9050.140236677506, 11539.826902596637, 11601.361925093863, 11885.108616853944, 11911.583094591648, 46432.30351544662, 47457.28814067861, 48651.0, 49491.50508495013, 49697.503024969286, 59542.70228648829, 59700.40299596948, 62393.37606623876, 62852.0, 63402.0, 15588.376464941275, 15702.136382499073, 15948.920255398649, 15986.680266394374, 16020.27908744086, 17016.914915425343, 17040.31838726437, 19478.90260548688, 19494.902525487283, 20499.59000820005, 18897.0, 18970.81029189746, 19130.330438434587, 19465.61068778588, 19594.90202548978, 18937.0, 19364.225430982602, 19379.51551211231, 19496.512587185432, 19590.510237244183, 17010.149492525077, 17088.145592720066, 17421.738673920154, 17662.381816636356, 18801.717974230673, 11810.114241431755, 11817.704557386134, 12404.25574465535, 12609.558665446666, 12732.23606583608, 13919.582412527641, 14860.331285091981, 14898.776518352017, 14982.550523484313, 15042.0], "value": 9161.77284901238, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1507.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 115.0, 106.0, 122.0, 117.0, 115.0, 116.0, 102.0, 116.0, 116.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 110.0, 144.0, 150.0, 108.0, 128.0, 128.0, 94.0, 159.0, 92.0], "value": 125.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 206.0, 195.0, 198.0, 196.0, 195.0, 193.0, 230.0, 281.0, 247.0], "value": 213.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 79.0, 73.0, 146.0, 57.0, 57.0, 56.0, 62.0, 118.0, 55.0], "value": 76.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 74.0, 116.0, 72.0, 82.0, 97.0, 112.0, 70.0, 73.0, 103.0], "value": 86.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 89.0, 90.0, 90.0, 91.0, 96.0, 90.0, 89.0, 90.0, 89.0], "value": 90.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 97.0, 103.0, 155.0, 103.0, 144.0, 153.0, 97.0, 98.0, 97.0], "value": 114.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 92.0, 92.0, 93.0, 92.0, 92.0, 92.0, 91.0, 88.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 70.0, 61.0, 50.0, 50.0, 49.0, 52.0, 52.0, 52.0, 49.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 54.0, 62.0, 57.0, 62.0, 55.0, 53.0, 55.0, 57.0], "value": 56.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 78.0, 80.0, 81.0, 81.0, 84.0, 73.0, 99.0, 70.0, 69.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 131.0, 157.0, 143.0, 133.0, 153.0, 156.0, 145.0, 136.0, 144.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 188.0, 185.0, 184.0, 186.0, 185.0, 188.0, 183.0, 189.0, 188.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 66.0, 71.0, 90.0, 92.0, 94.0, 75.0, 65.0, 66.0], "value": 75.2, "unit": "score"}]}]} 14:14:44 INFO - Return code: 0 14:14:44 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:14:44 INFO - # TBPL SUCCESS # 14:14:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:14:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:14:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:14:44 INFO - 2017-06-14 14:14:44.330 screenresolution[1667:11162] starting screenresolution argv=screenresolution get 14:14:44 INFO - 2017-06-14 14:14:44.332 screenresolution[1667:11162] Display 0: 1600x1200x32@60 14:14:44 INFO - 2017-06-14 14:14:44.338 screenresolution[1668:11165] starting screenresolution argv=screenresolution list 14:14:44 INFO - Available Modes on Display 0 14:14:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:14:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:14:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:14:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:14:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:14:44 INFO - Intel Iris: 14:14:44 INFO - Chipset Model: Intel Iris 14:14:44 INFO - Type: GPU 14:14:44 INFO - Bus: Built-In 14:14:44 INFO - VRAM (Dynamic, Max): 1536 MB 14:14:44 INFO - Vendor: Intel (0x8086) 14:14:44 INFO - Device ID: 0x0a2e 14:14:44 INFO - Revision ID: 0x0009 14:14:44 INFO - Displays: 14:14:44 INFO - Display: 14:14:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:14:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:14:44 INFO - Display Serial Number: 1600x1200 60 14:14:44 INFO - Main Display: Yes 14:14:44 INFO - Mirror: Off 14:14:44 INFO - Online: Yes 14:14:44 INFO - Rotation: Supported 14:14:44 INFO - Return code: 0 14:14:44 INFO - Running post-action listener: _resource_record_post_action 14:14:44 INFO - [mozharness: 2017-06-14 21:14:44.573909Z] Finished run-tests step (success) 14:14:44 INFO - Running post-run listener: _resource_record_post_run 14:14:44 INFO - Total resource usage - Wall time: 1247s; CPU: 53.0%; Read bytes: 35262976; Write bytes: 473193984; Read time: 218; Write time: 1603 14:14:44 INFO - TinderboxPrint: CPU usage
53.0% 14:14:44 INFO - TinderboxPrint: I/O read bytes / time
35,262,976 / 218 14:14:44 INFO - TinderboxPrint: I/O write bytes / time
473,193,984 / 1,603 14:14:44 INFO - TinderboxPrint: CPU idle
2,345.2 (47.0%) 14:14:44 INFO - TinderboxPrint: CPU system
1,010.8 (20.3%) 14:14:44 INFO - TinderboxPrint: CPU user
1,633.6 (32.7%) 14:14:44 INFO - TinderboxPrint: Swap in / out
185,094,144 / 0 14:14:44 INFO - install - Wall time: 16s; CPU: 54.0%; Read bytes: 131678720; Write bytes: 126510592; Read time: 10552; Write time: 276 14:14:44 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 14:14:44 INFO - run-tests - Wall time: 1232s; CPU: 53.0%; Read bytes: 34292224; Write bytes: 317327360; Read time: 205; Write time: 1252 14:14:45 INFO - Running post-run listener: _upload_blobber_files 14:14:45 INFO - Blob upload gear active. 14:14:45 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:14:45 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:14:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:14:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:14:45 INFO - (blobuploader) - INFO - Open directory for files ... 14:14:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:14:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:14:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:46 INFO - (blobuploader) - INFO - Done attempting. 14:14:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:14:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:14:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:47 INFO - (blobuploader) - INFO - Done attempting. 14:14:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:14:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:48 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:14:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:48 INFO - (blobuploader) - INFO - Done attempting. 14:14:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:14:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:14:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:50 INFO - (blobuploader) - INFO - Done attempting. 14:14:50 INFO - (blobuploader) - INFO - Iteration through files over. 14:14:50 INFO - Return code: 0 14:14:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:14:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:14:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/387f2cb22dfab07ee2f190b48b065862bb6924e5ae1fb71cbcce969d9674d13e751a8a93bc14e23a8f103d0d511e8279c7654827ed57d774ef1839ddc7496375", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ed115ca5bf9c4af989f3386cfec283d30faa934f2ed8ba9c672858f7b7d634e299d4acb27a6611a1e5d8ab72ed1d2f8e8112fda8d159b4f3cbf59733179d3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13c5c1b83d3488650132ae69f160f96d10331124a615849e9fb412bbf22506f9344519824a9d3257cc6f4c0a7856e998411409c0479cc2e9cdf863e6fff978dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8af097f40f6887fa5dd4feb2ceedb2dd2b6d34424becb213c72bd1483c562de263a5e97be7d6ea63d6025662b4be38a0d9d9a0d5b929ca05e0f7b7d681f42a40"} 14:14:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:14:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:14:50 INFO - Contents: 14:14:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/387f2cb22dfab07ee2f190b48b065862bb6924e5ae1fb71cbcce969d9674d13e751a8a93bc14e23a8f103d0d511e8279c7654827ed57d774ef1839ddc7496375", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ed115ca5bf9c4af989f3386cfec283d30faa934f2ed8ba9c672858f7b7d634e299d4acb27a6611a1e5d8ab72ed1d2f8e8112fda8d159b4f3cbf59733179d3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13c5c1b83d3488650132ae69f160f96d10331124a615849e9fb412bbf22506f9344519824a9d3257cc6f4c0a7856e998411409c0479cc2e9cdf863e6fff978dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8af097f40f6887fa5dd4feb2ceedb2dd2b6d34424becb213c72bd1483c562de263a5e97be7d6ea63d6025662b4be38a0d9d9a0d5b929ca05e0f7b7d681f42a40"} 14:14:50 INFO - Running post-run listener: copy_logs_to_upload_dir 14:14:50 INFO - Copying logs to upload dir... 14:14:50 INFO - mkdir: /builds/slave/test/build/upload/logs 14:14:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1327.970187 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 8 secs) (at 2017-06-14 14:14:50.551603) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 14:14:50.555012) ========= 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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/387f2cb22dfab07ee2f190b48b065862bb6924e5ae1fb71cbcce969d9674d13e751a8a93bc14e23a8f103d0d511e8279c7654827ed57d774ef1839ddc7496375", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ed115ca5bf9c4af989f3386cfec283d30faa934f2ed8ba9c672858f7b7d634e299d4acb27a6611a1e5d8ab72ed1d2f8e8112fda8d159b4f3cbf59733179d3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13c5c1b83d3488650132ae69f160f96d10331124a615849e9fb412bbf22506f9344519824a9d3257cc6f4c0a7856e998411409c0479cc2e9cdf863e6fff978dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8af097f40f6887fa5dd4feb2ceedb2dd2b6d34424becb213c72bd1483c562de263a5e97be7d6ea63d6025662b4be38a0d9d9a0d5b929ca05e0f7b7d681f42a40"} build_url:https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011018 build_url: 'https://queue.taskcluster.net/v1/task/TynwykiOS6KoVq8GJjuOkw/artifacts/public/build/firefox-55.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/387f2cb22dfab07ee2f190b48b065862bb6924e5ae1fb71cbcce969d9674d13e751a8a93bc14e23a8f103d0d511e8279c7654827ed57d774ef1839ddc7496375", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4ed115ca5bf9c4af989f3386cfec283d30faa934f2ed8ba9c672858f7b7d634e299d4acb27a6611a1e5d8ab72ed1d2f8e8112fda8d159b4f3cbf59733179d3a2", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/13c5c1b83d3488650132ae69f160f96d10331124a615849e9fb412bbf22506f9344519824a9d3257cc6f4c0a7856e998411409c0479cc2e9cdf863e6fff978dd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8af097f40f6887fa5dd4feb2ceedb2dd2b6d34424becb213c72bd1483c562de263a5e97be7d6ea63d6025662b4be38a0d9d9a0d5b929ca05e0f7b7d681f42a40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-14 14:14:50.590822) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 14:14:50.591406) ========= 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.86a8Mdxe6Y/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.Xgk4vWAQFW/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005281 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-14 14:14:50.643784) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 14:14:50.644341) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-14 14:14:50.644835) ========= ========= Total master_lag: 1.53 =========