builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0201 starttime: 1496354898.38 results: success (0) revision: 15e32469eb048957223bac458140776a30251c6b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.381424) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.381861) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.382160) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.407953) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.408355) ========= 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.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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-01 15:08:18-- 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% 18.4M=0.001s 2017-06-01 15:08:18 (18.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.553714) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.554073) ========= 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.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.039634 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:18.615168) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 15:08:18.615526) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 15e32469eb048957223bac458140776a30251c6b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 15e32469eb048957223bac458140776a30251c6b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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-01 15:08:18,714 truncating revision to first 12 chars 2017-06-01 15:08:18,714 Setting DEBUG logging. 2017-06-01 15:08:18,714 attempt 1/10 2017-06-01 15:08:18,714 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/15e32469eb04?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-06-01 15:08:19,481 unpacking tar archive at: mozilla-central-15e32469eb04/testing/mozharness/ program finished with exit code 0 elapsedTime=1.104039 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-06-01 15:08:19.742145) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:19.742468) ========= script_repo_revision: 15e32469eb048957223bac458140776a30251c6b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:19.742933) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:19.743216) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-06-01 15:08:19.762450) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 15:08:19.763044) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:08:19 INFO - MultiFileLogger online at 20170601 15:08:19 in /builds/slave/test 15:08:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 15:08:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:08:19 INFO - {'append_to_log': False, 15:08:19 INFO - 'base_work_dir': '/builds/slave/test', 15:08:19 INFO - 'blob_upload_branch': 'Firefox', 15:08:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:08:19 INFO - 'branch': 'Firefox', 15:08:19 INFO - 'buildbot_json_path': 'buildprops.json', 15:08:19 INFO - 'config_files': ('talos/mac_config.py',), 15:08:19 INFO - 'default_actions': ('clobber', 15:08:19 INFO - 'read-buildbot-config', 15:08:19 INFO - 'download-and-extract', 15:08:19 INFO - 'populate-webroot', 15:08:19 INFO - 'create-virtualenv', 15:08:19 INFO - 'install', 15:08:19 INFO - 'setup-mitmproxy', 15:08:19 INFO - 'run-tests'), 15:08:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:08:19 INFO - 'download_minidump_stackwalk': True, 15:08:19 INFO - 'download_symbols': 'ondemand', 15:08:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:08:19 INFO - 'tooltool.py': '/tools/tooltool.py', 15:08:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:08:19 INFO - '/tools/misc-python/virtualenv.py')}, 15:08:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:08:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:08:19 INFO - 'gecko_profile': False, 15:08:19 INFO - 'gecko_profile_interval': 0, 15:08:19 INFO - 'installer_path': 'installer.exe', 15:08:19 INFO - 'log_level': 'info', 15:08:19 INFO - 'log_name': 'talos', 15:08:19 INFO - 'log_to_console': True, 15:08:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:08:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:08:19 INFO - 'opt_config_files': (), 15:08:19 INFO - 'pip_index': False, 15:08:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:19 INFO - 'cmd': ('bash', 15:08:19 INFO - '-c', 15:08:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:19 INFO - 'enabled': True, 15:08:19 INFO - 'halt_on_failure': False, 15:08:19 INFO - 'name': 'check_screen_resolution'},), 15:08:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:08:19 INFO - 'cmd': ('bash', 15:08:19 INFO - '-c', 15:08:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:08:19 INFO - 'enabled': True, 15:08:19 INFO - 'halt_on_failure': False, 15:08:19 INFO - 'name': 'check_screen_resolution'},), 15:08:19 INFO - 'run_cmd_checks_enabled': True, 15:08:19 INFO - 'suite': 'dromaeojs-e10s', 15:08:19 INFO - 'system_bits': '32', 15:08:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:08:19 INFO - 'title': 't-yosemite-r7-0201', 15:08:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:08:19 INFO - 'use_talos_json': True, 15:08:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:08:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:08:19 INFO - 'work_dir': 'build'} 15:08:19 INFO - [mozharness: 2017-06-01 22:08:19.972367Z] Running clobber step. 15:08:19 INFO - Running pre-action listener: _resource_record_pre_action 15:08:19 INFO - Running main action method: clobber 15:08:19 INFO - rmtree: /builds/slave/test/build 15:08:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:08:22 INFO - Running post-action listener: _resource_record_post_action 15:08:22 INFO - [mozharness: 2017-06-01 22:08:22.411667Z] Finished clobber step (success) 15:08:22 INFO - [mozharness: 2017-06-01 22:08:22.411792Z] Running read-buildbot-config step. 15:08:22 INFO - Running pre-action listener: _resource_record_pre_action 15:08:22 INFO - Running main action method: read_buildbot_config 15:08:22 INFO - Using buildbot properties: 15:08:22 INFO - { 15:08:22 INFO - "project": "", 15:08:22 INFO - "product": "firefox", 15:08:22 INFO - "who": "ryanvm@gmail.com", 15:08:22 INFO - "installer_path": "public/build/firefox-55.0a1.en-US.mac.dmg", 15:08:22 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:08:22 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 15:08:22 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:08:22 INFO - "stage_platform": "macosx64", 15:08:22 INFO - "basedir": "/builds/slave/test", 15:08:22 INFO - "buildnumber": 72, 15:08:22 INFO - "platform": "yosemite_r7", 15:08:22 INFO - "parent_task_id": "JRolW9joQC619GsvPdReow", 15:08:22 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:08:22 INFO - "slavebuilddir": "test", 15:08:22 INFO - "taskId": "U7lu4QQ8SyqIsApwjwinGg", 15:08:22 INFO - "branch": "mozilla-central", 15:08:22 INFO - "script_repo_revision": "production", 15:08:22 INFO - "revision": "15e32469eb048957223bac458140776a30251c6b", 15:08:22 INFO - "slavename": "t-yosemite-r7-0201", 15:08:22 INFO - "repo_path": "mozilla-central" 15:08:22 INFO - } 15:08:22 INFO - Finding installer, test and symbols from parent task. 15:08:22 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U7lu4QQ8SyqIsApwjwinGg'}, attempt #1 15:08:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U7lu4QQ8SyqIsApwjwinGg'}, attempt #1 15:08:23 INFO - Task dependencies: eEW189k7S5uD1-8ihuoswA JRolW9joQC619GsvPdReow 15:08:23 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow'}, attempt #1 15:08:24 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:08:24 INFO - Set test_url: https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/test_packages.json 15:08:24 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/target.crashreporter-symbols.zip 15:08:24 INFO - Running post-action listener: _resource_record_post_action 15:08:24 INFO - [mozharness: 2017-06-01 22:08:24.167635Z] Finished read-buildbot-config step (success) 15:08:24 INFO - [mozharness: 2017-06-01 22:08:24.167736Z] Running download-and-extract step. 15:08:24 INFO - Running pre-action listener: _resource_record_pre_action 15:08:24 INFO - Running main action method: download_and_extract 15:08:24 INFO - mkdir: /builds/slave/test/build/tests 15:08:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:08:24 INFO - trying https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 15:08:24 INFO - Downloading https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:08:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 15:08:25 INFO - Downloaded 1325 bytes. 15:08:25 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 15:08:25 INFO - Using the following test package requirements: 15:08:25 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 15:08:25 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:08:25 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 15:08:25 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 15:08:25 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'jsshell-mac.zip'], 15:08:25 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 15:08:25 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 15:08:25 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 15:08:25 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 15:08:25 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 15:08:25 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 15:08:25 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 15:08:25 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:08:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:08:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:08:25 INFO - Fetch https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:08:26 INFO - Content-Length response header: 57630416 15:08:26 INFO - Bytes received: 57630416 15:08:44 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:08:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 15:08:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:08:44 INFO - Fetch https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 15:08:47 INFO - Content-Length response header: 57630416 15:08:47 INFO - Bytes received: 57630416 15:09:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 15:09:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:09:04 INFO - Fetch https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 15:09:04 INFO - Content-Length response header: 14447425 15:09:04 INFO - Bytes received: 14447425 15:09:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:05 INFO - trying https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:09:05 INFO - Downloading https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 15:09:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 15:09:07 INFO - Downloaded 67921308 bytes. 15:09:07 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:09:07 INFO - mkdir: /builds/slave/test/properties 15:09:07 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:09:07 INFO - Writing to file /builds/slave/test/properties/build_url 15:09:07 INFO - Contents: 15:09:07 INFO - build_url:https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 15:09:07 INFO - Running post-action listener: _resource_record_post_action 15:09:07 INFO - Running post-action listener: set_extra_try_arguments 15:09:07 INFO - [mozharness: 2017-06-01 22:09:07.117221Z] Finished download-and-extract step (success) 15:09:07 INFO - [mozharness: 2017-06-01 22:09:07.117376Z] Running populate-webroot step. 15:09:07 INFO - Running pre-action listener: _resource_record_pre_action 15:09:07 INFO - Running main action method: populate_webroot 15:09:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:09:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:09:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:09:07 INFO - '--tppagecycles', 15:09:07 INFO - '7'], 15:09:07 INFO - 'tests': ['tsvgm']}}, 15:09:07 INFO - 'suites': {'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:09:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:09:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:09:07 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:07 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:09:07 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['damp', 'tps']}, 15:09:07 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'tests': ['damp', 'tps']}, 15:09:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['dromaeo_dom']}, 15:09:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:09:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 15:09:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 15:09:07 INFO - 'g5-e10s': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'tests': ['ts_paint_webext', 'tp5o_webext']}, 15:09:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'other-e10s': {'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['a11yr', 15:09:07 INFO - 'ts_paint', 15:09:07 INFO - 'tpaint', 15:09:07 INFO - 'sessionrestore', 15:09:07 INFO - 'sessionrestore_no_auto_restore', 15:09:07 INFO - 'tabpaint']}, 15:09:07 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 15:09:07 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 15:09:07 INFO - 'bloom_basic_ref']}, 15:09:07 INFO - 'quantum-pageload-e10s': {'mitmproxy_recording_set': 'mitmproxy-recording-set.zip', 15:09:07 INFO - 'talos_options': ['--mitmproxy', 15:09:07 INFO - 'mitmproxy-recording-1.mp', 15:09:07 INFO - '--firstNonBlankPaint'], 15:09:07 INFO - 'tests': ['Quantum_1']}, 15:09:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['tsvgx', 15:09:07 INFO - 'tsvgr_opacity', 15:09:07 INFO - 'tart', 15:09:07 INFO - 'tscrollx', 15:09:07 INFO - 'cart', 15:09:07 INFO - 'tsvg_static']}, 15:09:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:09:07 INFO - 'tsvgr_opacity', 15:09:07 INFO - 'tart', 15:09:07 INFO - 'tscrollx', 15:09:07 INFO - 'cart', 15:09:07 INFO - 'tsvg_static']}, 15:09:07 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'talos_options': ['--disable-e10s'], 15:09:07 INFO - 'tests': ['tp5o']}, 15:09:07 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:09:07 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'talos_options': ['--disable-e10s', 15:09:07 INFO - '--xperf_path', 15:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:07 INFO - 'tests': ['tp5n']}, 15:09:07 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:09:07 INFO - 'talos_options': ['--xperf_path', 15:09:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:09:07 INFO - 'tests': ['tp5n']}}, 15:09:07 INFO - 'talos.zip': {'path': '', 15:09:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:09:07 INFO - Running post-action listener: _resource_record_post_action 15:09:07 INFO - [mozharness: 2017-06-01 22:09:07.139336Z] Finished populate-webroot step (success) 15:09:07 INFO - [mozharness: 2017-06-01 22:09:07.139475Z] Running create-virtualenv step. 15:09:07 INFO - Running pre-action listener: _resource_record_pre_action 15:09:07 INFO - Running main action method: create_virtualenv 15:09:07 INFO - Creating virtualenv /builds/slave/test/build/venv 15:09:07 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:09:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:09:07 INFO - Using partial env: {'VIRTUALENV_NO_DOWNLOAD': '1'} 15:09:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:09:07 INFO - Using real prefix '/tools/python27' 15:09:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:09:08 INFO - Installing distribute.............................................................................................................................................................................................done. 15:09:10 INFO - Installing pip.................done. 15:09:10 INFO - Return code: 0 15:09:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:09:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:09:11 INFO - Reading from file tmpfile_stdout 15:09:11 INFO - Output received: 15:09:11 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:09:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:11 INFO - 'HOME': '/Users/cltbld', 15:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:11 INFO - 'LOGNAME': 'cltbld', 15:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:11 INFO - 'MOZ_NO_REMOTE': '1', 15:09:11 INFO - 'NO_EM_RESTART': '1', 15:09:11 INFO - 'PAGER': '/bin/cat', 15:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:11 INFO - 'PWD': '/builds/slave/test', 15:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:11 INFO - 'SHELL': '/bin/bash', 15:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:11 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:11 INFO - 'USER': 'cltbld', 15:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:11 INFO - 'XPC_FLAGS': '0x0', 15:09:11 INFO - 'XPC_SERVICE_NAME': '0', 15:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:11 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 15:09:11 INFO - Cleaning up... 15:09:11 INFO - Return code: 0 15:09:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:09:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:09:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:11 INFO - 'HOME': '/Users/cltbld', 15:09:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:11 INFO - 'LOGNAME': 'cltbld', 15:09:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:11 INFO - 'MOZ_NO_REMOTE': '1', 15:09:11 INFO - 'NO_EM_RESTART': '1', 15:09:11 INFO - 'PAGER': '/bin/cat', 15:09:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:11 INFO - 'PWD': '/builds/slave/test', 15:09:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:11 INFO - 'SHELL': '/bin/bash', 15:09:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:11 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:11 INFO - 'USER': 'cltbld', 15:09:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:11 INFO - 'XPC_FLAGS': '0x0', 15:09:11 INFO - 'XPC_SERVICE_NAME': '0', 15:09:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:11 INFO - Downloading/unpacking psutil>=3.1.1 15:09:11 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 15:09:11 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 15:09:11 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 15:09:11 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 15:09:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:09:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:13 INFO - Installing collected packages: psutil 15:09:13 INFO - Running setup.py install for psutil 15:09:13 INFO - building 'psutil._psutil_osx' extension 15:09:13 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 15:09:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:09:14 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:09:14 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 15:09:14 INFO - building 'psutil._psutil_posix' extension 15:09:14 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 15:09:14 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:09:14 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:09:14 INFO - ^ 15:09:14 INFO - 1 warning generated. 15:09:14 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 15:09:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:09:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:09:14 INFO - Successfully installed psutil 15:09:14 INFO - Cleaning up... 15:09:14 INFO - Return code: 0 15:09:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:09:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:15 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 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:15 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 15:09:15 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 15:09:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:15 INFO - 'HOME': '/Users/cltbld', 15:09:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:15 INFO - 'LOGNAME': 'cltbld', 15:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:15 INFO - 'MOZ_NO_REMOTE': '1', 15:09:15 INFO - 'NO_EM_RESTART': '1', 15:09:15 INFO - 'PAGER': '/bin/cat', 15:09:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:15 INFO - 'PWD': '/builds/slave/test', 15:09:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:15 INFO - 'SHELL': '/bin/bash', 15:09:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:15 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:15 INFO - 'USER': 'cltbld', 15:09:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:15 INFO - 'XPC_FLAGS': '0x0', 15:09:15 INFO - 'XPC_SERVICE_NAME': '0', 15:09:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:16 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:17 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:09:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:09:18 INFO - Installing collected packages: mozsystemmonitor 15:09:18 INFO - Running setup.py install for mozsystemmonitor 15:09:18 INFO - Successfully installed mozsystemmonitor 15:09:18 INFO - Cleaning up... 15:09:18 INFO - Return code: 0 15:09:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:09:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:09:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:18 INFO - 'HOME': '/Users/cltbld', 15:09:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:18 INFO - 'LOGNAME': 'cltbld', 15:09:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:18 INFO - 'MOZ_NO_REMOTE': '1', 15:09:18 INFO - 'NO_EM_RESTART': '1', 15:09:18 INFO - 'PAGER': '/bin/cat', 15:09:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:18 INFO - 'PWD': '/builds/slave/test', 15:09:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:18 INFO - 'SHELL': '/bin/bash', 15:09:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:18 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:18 INFO - 'USER': 'cltbld', 15:09:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:18 INFO - 'XPC_FLAGS': '0x0', 15:09:18 INFO - 'XPC_SERVICE_NAME': '0', 15:09:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:18 INFO - Downloading/unpacking jsonschema==2.5.1 15:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:09:20 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:20 INFO - Downloading functools32-3.2.3-2.tar.gz 15:09:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:09:20 INFO - warning: no files found matching '*.txt' 15:09:20 INFO - no previously-included directories found matching 'build' 15:09:20 INFO - no previously-included directories found matching 'dist' 15:09:20 INFO - no previously-included directories found matching '.git*' 15:09:20 INFO - Installing collected packages: jsonschema, functools32 15:09:20 INFO - Running setup.py install for functools32 15:09:20 INFO - warning: no files found matching '*.txt' 15:09:20 INFO - no previously-included directories found matching 'build' 15:09:20 INFO - no previously-included directories found matching 'dist' 15:09:20 INFO - no previously-included directories found matching '.git*' 15:09:20 INFO - Successfully installed jsonschema functools32 15:09:20 INFO - Cleaning up... 15:09:20 INFO - Return code: 0 15:09:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:20 INFO - 'HOME': '/Users/cltbld', 15:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:20 INFO - 'LOGNAME': 'cltbld', 15:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:20 INFO - 'MOZ_NO_REMOTE': '1', 15:09:20 INFO - 'NO_EM_RESTART': '1', 15:09:20 INFO - 'PAGER': '/bin/cat', 15:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:20 INFO - 'PWD': '/builds/slave/test', 15:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:20 INFO - 'SHELL': '/bin/bash', 15:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:20 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:20 INFO - 'USER': 'cltbld', 15:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:20 INFO - 'XPC_FLAGS': '0x0', 15:09:20 INFO - 'XPC_SERVICE_NAME': '0', 15:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:20 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:09:20 INFO - Cleaning up... 15:09:20 INFO - Return code: 0 15:09:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:09:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:09:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:20 INFO - 'HOME': '/Users/cltbld', 15:09:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:20 INFO - 'LOGNAME': 'cltbld', 15:09:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:20 INFO - 'MOZ_NO_REMOTE': '1', 15:09:20 INFO - 'NO_EM_RESTART': '1', 15:09:20 INFO - 'PAGER': '/bin/cat', 15:09:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:20 INFO - 'PWD': '/builds/slave/test', 15:09:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:20 INFO - 'SHELL': '/bin/bash', 15:09:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:20 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:20 INFO - 'USER': 'cltbld', 15:09:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:20 INFO - 'XPC_FLAGS': '0x0', 15:09:20 INFO - 'XPC_SERVICE_NAME': '0', 15:09:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:21 INFO - Downloading/unpacking blobuploader==1.2.4 15:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:22 INFO - Downloading blobuploader-1.2.4.tar.gz 15:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:09:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:09:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:23 INFO - Downloading docopt-0.6.1.tar.gz 15:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:09:23 INFO - Installing collected packages: blobuploader, requests, docopt 15:09:23 INFO - Running setup.py install for blobuploader 15:09:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:09:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:09:23 INFO - Running setup.py install for requests 15:09:24 INFO - Running setup.py install for docopt 15:09:24 INFO - Successfully installed blobuploader requests docopt 15:09:24 INFO - Cleaning up... 15:09:24 INFO - Return code: 0 15:09:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:09:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:09:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:24 INFO - 'HOME': '/Users/cltbld', 15:09:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:24 INFO - 'LOGNAME': 'cltbld', 15:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:24 INFO - 'MOZ_NO_REMOTE': '1', 15:09:24 INFO - 'NO_EM_RESTART': '1', 15:09:24 INFO - 'PAGER': '/bin/cat', 15:09:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:24 INFO - 'PWD': '/builds/slave/test', 15:09:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:24 INFO - 'SHELL': '/bin/bash', 15:09:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:24 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:24 INFO - 'USER': 'cltbld', 15:09:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:24 INFO - 'XPC_FLAGS': '0x0', 15:09:24 INFO - 'XPC_SERVICE_NAME': '0', 15:09:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-KPPYvU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-6FOD2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Pt_4iG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-yuFO5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:24 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-L0G_Sn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-BJZAoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-uXLekA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-E8GI1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-D2VZEm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2AwxK_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ZemQoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-jdKb4n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-PfDGTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-LmTFWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:25 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-K55MgD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:26 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-j23D2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:26 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Tte0fD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:09:26 INFO - Running setup.py install for manifestparser 15:09:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Running setup.py install for mozcrash 15:09:26 INFO - Running setup.py install for mozdebug 15:09:26 INFO - Running setup.py install for mozdevice 15:09:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:09:26 INFO - Running setup.py install for mozfile 15:09:27 INFO - Running setup.py install for mozhttpd 15:09:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for mozinfo 15:09:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for mozInstall 15:09:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for mozleak 15:09:27 INFO - Running setup.py install for mozlog 15:09:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for moznetwork 15:09:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:09:27 INFO - Running setup.py install for mozprocess 15:09:28 INFO - Running setup.py install for mozprofile 15:09:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozrunner 15:09:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Running setup.py install for mozscreenshot 15:09:28 INFO - Running setup.py install for moztest 15:09:28 INFO - Running setup.py install for mozversion 15:09:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:09:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:09:28 INFO - Cleaning up... 15:09:28 INFO - Return code: 0 15:09:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09: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')]} 15:09:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:28 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 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:28 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 15:09:28 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 15:09:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:28 INFO - 'HOME': '/Users/cltbld', 15:09:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:28 INFO - 'LOGNAME': 'cltbld', 15:09:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:28 INFO - 'MOZ_NO_REMOTE': '1', 15:09:28 INFO - 'NO_EM_RESTART': '1', 15:09:28 INFO - 'PAGER': '/bin/cat', 15:09:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:28 INFO - 'PWD': '/builds/slave/test', 15:09:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:28 INFO - 'SHELL': '/bin/bash', 15:09:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:28 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:28 INFO - 'USER': 'cltbld', 15:09:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:28 INFO - 'XPC_FLAGS': '0x0', 15:09:28 INFO - 'XPC_SERVICE_NAME': '0', 15:09:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-gMN1jS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2kE2pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-2ZpmRK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-6dOuj8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.51 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-EuA3On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-ZQDQ5l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-Ck2sBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-mvtTrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:09:29 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-wj1Nja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-1MS4IN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-pIoum4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-hKNP5R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-_3N5oR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-bJLeiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-xiM4DT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:09:30 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-M1dt04-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:09:30 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)) 15:09:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:09:30 INFO - Running setup.py (path:/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/pip-NeMIRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:09:30 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)) 15:09:30 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)) 15:09:30 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)) 15:09:30 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)) 15:09:30 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)) 15:09:30 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)) 15:09:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:32 INFO - Downloading blessings-1.6.tar.gz 15:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:09:32 INFO - Installing collected packages: blessings 15:09:32 INFO - Running setup.py install for blessings 15:09:32 INFO - Successfully installed blessings 15:09:32 INFO - Cleaning up... 15:09:32 INFO - Return code: 0 15:09:32 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:09:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:32 INFO - Reading from file tmpfile_stdout 15:09:32 INFO - Current package versions: 15:09:32 INFO - blessings == 1.6 15:09:32 INFO - blobuploader == 1.2.4 15:09:32 INFO - docopt == 0.6.1 15:09:32 INFO - functools32 == 3.2.3-2 15:09:32 INFO - jsonschema == 2.5.1 15:09:32 INFO - manifestparser == 1.1 15:09:32 INFO - mozInstall == 1.12 15:09:32 INFO - mozcrash == 1.0 15:09:32 INFO - mozdebug == 0.1 15:09:32 INFO - mozdevice == 0.51 15:09:32 INFO - mozfile == 1.2 15:09:32 INFO - mozhttpd == 0.7 15:09:32 INFO - mozinfo == 0.9 15:09:32 INFO - mozleak == 0.1 15:09:32 INFO - mozlog == 3.4 15:09:32 INFO - moznetwork == 0.27 15:09:32 INFO - mozprocess == 0.25 15:09:32 INFO - mozprofile == 0.28 15:09:32 INFO - mozrunner == 6.13 15:09:32 INFO - mozscreenshot == 0.1 15:09:32 INFO - mozsystemmonitor == 0.3 15:09:32 INFO - moztest == 0.8 15:09:32 INFO - mozversion == 1.4 15:09:32 INFO - psutil == 3.1.1 15:09:32 INFO - requests == 1.2.3 15:09:32 INFO - wsgiref == 0.1.2 15:09:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09:32 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 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09:32 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 15:09:32 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 15:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:32 INFO - 'HOME': '/Users/cltbld', 15:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:32 INFO - 'LOGNAME': 'cltbld', 15:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:32 INFO - 'MOZ_NO_REMOTE': '1', 15:09:32 INFO - 'NO_EM_RESTART': '1', 15:09:32 INFO - 'PAGER': '/bin/cat', 15:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:32 INFO - 'PWD': '/builds/slave/test', 15:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:32 INFO - 'SHELL': '/bin/bash', 15:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:32 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:32 INFO - 'USER': 'cltbld', 15:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:32 INFO - 'XPC_FLAGS': '0x0', 15:09:32 INFO - 'XPC_SERVICE_NAME': '0', 15:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:09:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:09:33 INFO - Downloading/unpacking marionette-driver>=1.4.0 (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:09:34 INFO - Downloading marionette_driver-1.4.0.tar.gz 15:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/marionette-driver/setup.py) egg_info for package marionette-driver 15:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner>=6.9 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:09:34 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice>=0.37 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozrunner>=6.9->marionette-driver>=1.4.0->-r /builds/slave/test/build/tests/talos/requirements.txt (line 10)) 15:09:34 INFO - Installing collected packages: marionette-driver 15:09:34 INFO - Running setup.py install for marionette-driver 15:09:34 INFO - Successfully installed marionette-driver 15:09:34 INFO - Cleaning up... 15:09:34 INFO - Return code: 0 15:09:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:09: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')]} 15:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:09: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')]} 15:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:09: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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104a44c10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a48768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbd6ad7dec0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, '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.CgMwagR147/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.jkPAjrZr0k/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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 15:09: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', 'jsonschema'] in /builds/slave/test/build 15:09: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 jsonschema 15:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:34 INFO - 'HOME': '/Users/cltbld', 15:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:34 INFO - 'LOGNAME': 'cltbld', 15:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:34 INFO - 'MOZ_NO_REMOTE': '1', 15:09:34 INFO - 'NO_EM_RESTART': '1', 15:09:34 INFO - 'PAGER': '/bin/cat', 15:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:34 INFO - 'PWD': '/builds/slave/test', 15:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:34 INFO - 'SHELL': '/bin/bash', 15:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:34 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:34 INFO - 'USER': 'cltbld', 15:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:34 INFO - 'XPC_FLAGS': '0x0', 15:09:34 INFO - 'XPC_SERVICE_NAME': '0', 15:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:09:35 INFO - Cleaning up... 15:09:35 INFO - Return code: 0 15:09:35 INFO - Running post-action listener: _resource_record_post_action 15:09:35 INFO - Running post-action listener: _start_resource_monitoring 15:09:35 INFO - Starting resource monitoring. 15:09:35 INFO - [mozharness: 2017-06-01 22:09:35.234140Z] Finished create-virtualenv step (success) 15:09:35 INFO - [mozharness: 2017-06-01 22:09:35.235110Z] Running install step. 15:09:35 INFO - Running pre-action listener: _resource_record_pre_action 15:09:35 INFO - Running main action method: install 15:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:09:35 INFO - Reading from file tmpfile_stdout 15:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 15:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:09:35 INFO - Reading from file tmpfile_stdout 15:09:35 INFO - Output received: 15:09:35 INFO - Usage: mozinstall [options] installer 15:09:35 INFO - Options: 15:09:35 INFO - -h, --help show this help message and exit 15:09:35 INFO - -d DEST, --destination=DEST 15:09:35 INFO - Directory to install application into. [default: 15:09:35 INFO - "/builds/slave/test"] 15:09:35 INFO - --app=APP Application being installed. [default: firefox] 15:09:35 INFO - mkdir: /builds/slave/test/build/application 15:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:09:57 INFO - Reading from file tmpfile_stdout 15:09:57 INFO - Output received: 15:09:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:09:57 INFO - Running post-action listener: _resource_record_post_action 15:09:57 INFO - [mozharness: 2017-06-01 22:09:57.102654Z] Finished install step (success) 15:09:57 INFO - [mozharness: 2017-06-01 22:09:57.102777Z] Running setup-mitmproxy step. 15:09:57 INFO - Running pre-action listener: _resource_record_pre_action 15:09:57 INFO - Running main action method: setup_mitmproxy 15:09:57 INFO - Skipping: mitmproxy is not required 15:09:57 INFO - Running post-action listener: _resource_record_post_action 15:09:57 INFO - [mozharness: 2017-06-01 22:09:57.103462Z] Finished setup-mitmproxy step (success) 15:09:57 INFO - [mozharness: 2017-06-01 22:09:57.103579Z] Running run-tests step. 15:09:57 INFO - Running pre-action listener: _resource_record_pre_action 15:09:57 INFO - Running main action method: run_tests 15:09:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:09:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:09:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:09:57 INFO - 2017-06-01 15:09:57.118 screenresolution[1627:7379] starting screenresolution argv=screenresolution get 15:09:57 INFO - 2017-06-01 15:09:57.120 screenresolution[1627:7379] Display 0: 1600x1200x32@60 15:09:57 INFO - 2017-06-01 15:09:57.126 screenresolution[1628:7381] starting screenresolution argv=screenresolution list 15:09:57 INFO - Available Modes on Display 0 15:09:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:09:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:09:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:09:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:09:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:09:57 INFO - Intel Iris: 15:09:57 INFO - Chipset Model: Intel Iris 15:09:57 INFO - Type: GPU 15:09:57 INFO - Bus: Built-In 15:09:57 INFO - VRAM (Dynamic, Max): 1536 MB 15:09:57 INFO - Vendor: Intel (0x8086) 15:09:57 INFO - Device ID: 0x0a2e 15:09:57 INFO - Revision ID: 0x0009 15:09:57 INFO - Displays: 15:09:57 INFO - Display: 15:09:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:09:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:09:57 INFO - Display Serial Number: 1600x1200 60 15:09:57 INFO - Main Display: Yes 15:09:57 INFO - Mirror: Off 15:09:57 INFO - Online: Yes 15:09:57 INFO - Rotation: Supported 15:09:57 INFO - Return code: 0 15:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:09:57 INFO - Python 2.7.3 15:09:57 INFO - Return code: 0 15:09:57 INFO - grabbing minidump binary from tooltool 15:09:57 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')]} 15:09:57 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 0x1049cdc60>, '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 0x104762cd8>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10478bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:09:57 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 15:09:57 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 15:09:57 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 15:09:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:09:57 INFO - Return code: 0 15:09:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:09:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:09:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:09:57 INFO - ENV: RUST_BACKTRACE is now 1 15:09:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:09:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 15:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/target.crashreporter-symbols.zip --title t-yosemite-r7-0201 --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 15:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CgMwagR147/Render', 15:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:09:57 INFO - 'HOME': '/Users/cltbld', 15:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:09:57 INFO - 'LOGNAME': 'cltbld', 15:09:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:09:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:09:57 INFO - 'MOZ_NO_REMOTE': '1', 15:09:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:09:57 INFO - 'NO_EM_RESTART': '1', 15:09:57 INFO - 'PAGER': '/bin/cat', 15:09:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:09:57 INFO - 'PWD': '/builds/slave/test', 15:09:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:09:57 INFO - 'RUST_BACKTRACE': '1', 15:09:57 INFO - 'SCRIPTSPATH': '/builds/slave/test/scripts', 15:09:57 INFO - 'SHELL': '/bin/bash', 15:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jkPAjrZr0k/Listeners', 15:09:57 INFO - 'TMPDIR': '/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/', 15:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:09:57 INFO - 'USER': 'cltbld', 15:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:09:57 INFO - 'XPC_FLAGS': '0x0', 15:09:57 INFO - 'XPC_SERVICE_NAME': '0', 15:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:09:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0201', '--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 15:09:57 INFO - mozversion application_buildid: 20170601134446 15:09:57 INFO - mozversion application_changeset: 15e32469eb048957223bac458140776a30251c6b 15:09:57 INFO - mozversion application_display_name: Nightly 15:09:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:09:57 INFO - mozversion application_name: Firefox 15:09:57 INFO - mozversion application_remotingname: firefox 15:09:57 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:09:57 INFO - mozversion application_vendor: Mozilla 15:09:57 INFO - mozversion application_version: 55.0a1 15:09:57 INFO - mozversion platform_buildid: 20170601134446 15:09:57 INFO - mozversion platform_changeset: 15e32469eb048957223bac458140776a30251c6b 15:09:57 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:09:57 INFO - mozversion platform_version: 55.0a1 15:09:57 INFO - using testdate: 1496354997 15:09:57 INFO - actual date: 1496354997 15:09:57 INFO - starting webserver on 'localhost:49228' 15:09:57 INFO - SUITE-START | Running 2 tests 15:09:57 INFO - TEST-START | dromaeo_css 15:09:57 INFO - operating with platform_type : mac_ 15:09:57 INFO - Initialising browser for dromaeo_css test... 15:09:57 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmputg6CN/profile 15:09:57 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 15:10:31 INFO - TEST-INFO | 1638: killed by SIGTERM 15:10:31 INFO - Browser initialized. 15:10:31 INFO - Running cycle 1/1 for dromaeo_css test... 15:10:31 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmputg6CN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:10:33 INFO - PID 1648 | 2017-06-01 15:10:33.846 plugin-container[1649:7970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x983f, name = 'com.apple.tsm.portname' 15:10:33 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:10:33 INFO - PID 1648 | 2017-06-01 15:10:33.850 plugin-container[1649:7970] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.CFPasteboardClient' 15:10:33 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:10:33 INFO - PID 1648 | 2017-06-01 15:10:33.851 plugin-container[1649:7970] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:10:34 INFO - PID 1648 | 2017-06-01 15:10:34.338 plugin-container[1650:8017] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x963f, name = 'com.apple.tsm.portname' 15:10:34 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:10:34 INFO - PID 1648 | 2017-06-01 15:10:34.339 plugin-container[1650:8017] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9b0b, name = 'com.apple.CFPasteboardClient' 15:10:34 INFO - PID 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:10:34 INFO - PID 1648 | 2017-06-01 15:10:34.339 plugin-container[1650:8017] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:10:43 INFO - PID 1648 | RSS: Main: 268726272 15:10:43 INFO - PID 1648 | 15:13:45 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49228/tests/dromaeo/cssquery-ext.html) 15:13:47 INFO - PID 1648 | RSS: Main: 268042240 15:13:47 INFO - PID 1648 | 15:16:49 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-ext.html (next: http://localhost:49228/tests/dromaeo/cssquery-jquery.html) 15:16:50 INFO - PID 1648 | RSS: Main: 284553216 15:16:50 INFO - PID 1648 | 15:19:51 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49228/tests/dromaeo/cssquery-mootools.html) 15:19:52 INFO - PID 1648 | RSS: Main: 283430912 15:19:52 INFO - PID 1648 | 15:22:54 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49228/tests/dromaeo/cssquery-prototype.html) 15:22:54 INFO - PID 1648 | RSS: Main: 287641600 15:22:54 INFO - PID 1648 | 15:25:57 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49228/tests/dromaeo/cssquery-yui.html) 15:25:58 INFO - PID 1648 | RSS: Main: 287727616 15:25:58 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49228/tests/dromaeo/cssquery-yui.html (next: http://localhost:49228/tests/dromaeo/cssquery-dojo.html) 15:29:00 INFO - PID 1648 | RSS: Main: 289222656 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | __start_tp_report 15:29:00 INFO - PID 1648 | _x_x_mozilla_page_load 15:29:00 INFO - PID 1648 | _x_x_mozilla_page_load_details 15:29:00 INFO - PID 1648 | |i|pagename|runs| 15:29:00 INFO - PID 1648 | |0;dojo.html;2938.735513803758;3586.6592673696005;3612.8193590320484;3614.493970844084;3640.981795091024;5090.643654944155;5173.663711858731;6110.87778244436;6138.938610613893;6272.121902933587;4879.853604391865;6169.691515424222;6230.626162430257;6273.9334313166755;6292.433680968712;5318.760655770499;5435.483629055243;5440.3743569489425;5449.264349312852;5459.590530710193;5486.1222204447295;5550.86122846928;5559.054960656677;5575.888482230353;5608.214849921014;5310.973445132789;5406.729663516809;5419.160030195326;5468.152436372368;5487.122060470306;5433.510984011458;5446.591505637093;5471.151971444432;5486.533644640211;5497.532709719639;5396.568274538027;5399.946000539983;5414.539764120054;5428.864278393033;5429.972850135724;6153.98459254227;6234.968825155891;6308.621482711052;6349.523785716044;6362.809115726535;6506.512011599101;6575.901361479581;6654.86690266192;6667.899981500282;6681;6756.966215168942;6760.1549806274215;6766.830829229309;6770.356816102462;6813.148356455443;5290.497402746733;6360.236771587347;6369;6383.936160638381;6399.072134540512;6761.729530818712;6765.05289259504;6854.6229957352825;6889.827754306182;6924.4460443165435;6334.398232167937;6439.22729272481;6480.935190648128;6511.576747511396;6545.21457425112;5645.604807663506;6643.136392268974;6698.966505167443;6781.830454238683;6783.762568310085;5892.911606325909;6510.990796426467;6613.272540020594;6627.1716035495565;6655.900161497581;6484.092227088211;6560.573562718408;6560.737570497126;6608.306127856602;6646.60120392778;5353.7055461949185;5365.168398898177;5377.139657654835;5387.892242155135;5404.540614047772;4040.3131467649982;4788.090262850047;4789.473157952622;4792.2332426811545;4793.18515852306;5591.692456914909;5951.821445356646;6318.810435686937;6351.079093531504;6372.139761132188;6785.084013658192;6811.693473793691;6819.147606549182;6819.147606549182;6820.93179068203;6371.012493063583;6377.1390862233;6377.808665740035;6390.680465976682;6394.840128996812;5829.329627092939;5832.912506312408;5880.235569375954;5954.315253745788;5961.731722072517;5335;6855.6572171391235;6953.860922781617;6964.373206411447;6993.755218567326;6797.388235058868;6805.557638753465;6811.65941702903;6817.625030623363;6833.58998460094;6840.179178498612;6956.443484521125;6993.0908981832035;7003.26465721092;7008.194057683431;5533.474319939599;6459.773907913201;6519.8044058678315;6576.605403675795;6689.966550167218;6015.248093988252;6769.9168133098465;6811.829704257433;6824.249332573314;6837.931620683729;6382.106505089198;6446.548741588232;6499.057636642755;6537.1174891389055;6604.174478190226;5480.369757477941;6531.183602049744;6544.181977252843;6573.737050518117;6600.207975043026;6327.493800495858;6438.163038804925;6441.516886233645;6455.870882582416;6456;6384.3934826191435;6597.0434287029075;6597.934020659732;6607.2071351438135;6610.70251838678;5820.806734619308;5941.405859414024;6389.424951754364;6403.935960640334;6433.903491447726 15:29:00 INFO - PID 1648 | |1;ext.html;769.6921231507397;783.6395258181236;842.4187310755578;1047.9266451348399;1248.6566194296572;39439;39854.20291594166;39893.401598976;39942;39986.40020399693;40272.59727402725;40533.783986480375;40654.780356589275;40714;40755.79622101893;6583.60498370097;7860.567668778201;8389.748307550783;8529.360297977646;8530.402871798977;8447.113053129426;8533.74398768038;8593.355498337618;8593.656253749843;8600.827983440327;8534.487930724146;8548.572571371407;8582.527960962145;8591.441556298852;8605.39762216645;8476.618552165168;8526.829463410759;8566.486010839308;8579.699710510102;8587.871181932276;8205.056418511887;8223;8235.876461853077;8286.129956354556;8289.461185023014;37469.812650936845;37721.62278377209;37738.86783396502;37750.81124594387;37763.62236377629;3138.7018233267804;3354.765166438326;3428.434308339121;3473.3747925373423;3480.269143479847;30648.69351306503;30912.918047868217;30931;30972.38055238905;31012.379752404824;3008.9097327080003;3066.340736741589;3077.138401247654;3081.8921337753295;3082.7533797296164;232368;233748;263087.68456157786;268820;269768;48358;48512.514874851506;48527;48594.27108593374;48664.026719465415;40212.798936005136;43758.781206093765;49596.50403495919;49693.757656058166;54323.45676543263;12375.814362784566;14493.85506144925;14524.49164279245;14561.854381456262;14719.779203311959;11617.128715346384;11709.88290117105;11744.823827642593;11815;11831.408429578487;13898.58304250874;13911.930440347733;13924.721505569833;13947.790783138262;14033.78949315761;5525.861853453615;6066.878662426727;6269.122322874801;6314.463270622048;6338.397852204033;979.9706008819746;1020.5866624017285;1020.7346090016649;1043.780806030742;1044.7492601775518;22338.88305584714;24981.75018249794;31090.844545777578;31397.8430107848;31675.891343802858;1078.8489611454402;1081.2971568480393;1105.6848798092506;1107.4850194659523;1113.8719047309662;11040.668779936615;12584.622461326175;12622.179558328678;12677.366131693378;12847.293398862965;324617;327648;328446;328736;328794;55816.720916395156;56223;56291.87416251734;56353.30940071804;56419.71790141023;1427.7786943023848;1472.3153733513968;1473.5432016075051;1479.2899408284084;1484.1540322016347;23710.525789484458;23967.28098157058;24319.63520547158;24348.63477047881;24369.26892193237;29118.854405727834;39137.41293880537;39248;39325.41011884767;39358.803205983786;283218;283411;283466;283899;284550;34039;35087.47368789419;35356.823215883756;35463.64536354707;35686.821565892;45917.77041114773;46084.53915460803;46126.769366152956;46130.846728832046;46132.53867461282;23302.883485582464;25900.834462449613;28688.569671455363;29717.959871404397;32413.837930810194;27411.725882740917;29391.85304073466;31001;31323.84338078295;31676.732930682516;37140;37250.25499490049;37994.62005379911;38061.47754089806;38167.2366552673;30303.69696303009;30908;31680.5247921286;31824;31946.201344965633;21907.194593433072;25069.247922562354;25350.366240844127;25528.61707074358;25560.744392555836 15:29:00 INFO - PID 1648 | |2;jquery.html;371.5077522282976;398.76472881000205;417.50734133722193;441.1023567041072;444.4311281559604;17962.640747185065;22840.314790556287;22853.54292914143;23464.88267558664;24621.75378246217;24319.75680243197;24616.507669846593;24983.375415614573;25019.249422517307;25029.998800047975;5447.891042179154;6237.37626237376;6367.554271201017;6391.137196478462;6395.232572091356;6013.458788709016;6243.781467648633;6288.088227207052;6300.086487459339;6303.401176888188;6249.593776404542;6259.06114082889;6280.4975601951965;6303.590266632677;6308.905366419507;6271.62370257786;6284.811455656315;6302.338254483305;6309.526785491061;6312.905306420407;5575.637583557096;5594.608377413582;5624.718764061821;5625.353084395306;5626.437356264381;28897.277568060756;29874.40251194986;30155.246118846986;30166.698333016608;30216;2135.4661334666334;2174.08688350893;2175.695402643631;2177.3467959612212;2181.345596321097;14728.48450304245;16329.18354082291;18711.34510292147;19019.61960760791;19236.519087022938;2161.167950339115;2219.9778002220096;2237.44063984004;2247;2255.785700358463;153892.23053884658;159343.20328398285;175455;181188;188351.52418141352;13110;13157.210567365988;13486.12340197884;13493.932530337286;13783.173009619455;16601.750973735405;16758.162091895356;16845.915770421314;16851.74722379165;16905.577360566083;8362.247397734232;8365.749027529062;8387.245147936716;8395.748127556182;8533.232009119085;7661.808454788675;7676.731314403969;7699.345555627722;7702.537847729154;7770.067591889009;7460.29127232912;7725.806854828675;8298.668053277801;8465.576721163918;8530.274926631182;2264.8074913632195;2267.8525895817047;2270.273512475992;2272.4432514034165;2450.130203777669;914.6158613382395;915.9221466175317;937.7702462896634;976.7595154154185;988.3476905242505;21321.040553175146;21927.2325468611;21935.122595096018;21947.890260548913;22013.11947522113;922.7047344849584;985.4579981010415;990.0792263195153;998.9650362237287;1012.3976234140674;11286.604968826052;11488.483018264198;11531;11578.305301681927;11594.536218551331;564205;564949;573532;578665;579064;156517;161768.19115904346;162271;163239.551406729;163634;1104.6078642081945;1125.5891599566264;1213.4054313386553;1229.8708635593314;1234.938253087342;23309;23702;23740.52518949646;23845.880770596035;23977;62994.370056300686;69890.65054674694;70347;71380;71770.64114679393;71461.57076858537;95085.5245723767;95153.61833248593;95886;95897;10131.848022279819;10319.122874555747;10338.483075846329;10363.533640986325;10378;15166.938314317891;17899.73150402771;17955.730664039787;18235;18318.450446486626;14867.628309291922;15078.095314281178;15137.621559461102;15184.164870932205;15184.772228416361;13401.53094641683;13634.386452609855;13646.727065458834;13693.109947853358;13704.314784260947;15040.398384064514;15064;15292.923535382697;15293.311800969206;15318.770218446509;10119.392836429837;10168.220561401873;10184.388936663823;10270.948645256725;10282.691519254435;11928.403579821148;14830.703385932437;14876.553703388916;15026.398944042116;15027.924860376066 15:29:00 INFO - PID 1648 | |3;mootools.html;1242.869498702636;1250.6060590913867;1393.6655202751335;1458.1032664911077;1461.9620069750476;11792.997595204408;12100.757984840298;12303.815442768366;12388.442520086595;12472.937635311833;12131.272123672587;12337.198082124656;12344.5062197512;12357.32034738089;12358.949489293398;2546.681664791906;2576.1627471071884;2596.610508423733;2596.623489594008;2596.675415573053;2507.786838118753;2551.655526503926;2552.106762633073;2553.182981445929;2560.193539035198;2498.9875050624723;2517.672702548666;2527.911523096692;2529.936751581207;2533.6199570064455;2141.4753385420518;2160.7191065161505;2165.761766205716;2173.9782602174014;2177.9891100544555;2452.901883924641;2490.86300253486;2493.4763699622918;2555.9105431309813;2574.652421923035;11936.224145430519;12024.69938251542;12117.939410302892;12123.636290911287;12124.757504849942;6502.154719886385;6673.499487538454;6698;6723.092382528345;6750.054992301082;4092.9795351023354;4302.806373713159;4306.397104405386;4350.912981740379;4355.5208927018;1233.463443402114;1258.974820503594;1287.781077216866;1289.155603079984;1290.6257185416218;15514.61213469661;15969.441069562627;16829.747553786703;16904.746428803577;16994;4862.367892174066;5541.806036788774;5580.86047848807;5781.82654520365;5825.533957283407;12562.748745025048;12651.114421990373;12661.404412095499;12663.430145643284;12699.11106222574;6442.61344319333;6576.0793488911595;6578.539502234893;6588.044733513614;6590.9670451647435;5910.852228694317;6137.110119032715;6195.504359651217;6212.285587157535;6221.2845522765465;4360.389545463638;4425.269830478;4478.507364189936;4496.235639941153;4542.841000564964;1288.3815768431205;1299.5776372678918;1309.7053163038431;1315.9736805264033;1321.7422602592403;760.6957217113088;765.9425543084291;792.3819420851745;792.7027364738224;800.627708115729;6365.681715914186;6660.966695166493;7097.041899343626;7261.273872612696;7347.302006309392;783.2911215350117;930.4835816122118;936.5832204668857;947.8151760406793;949.9097585729345;4283.357496375568;4373.365861949999;4388.4201687392515;4560.72635641856;4578.519255478193;14820.777688334683;15209.391624335123;15369.308381122877;15425.614359640875;15474.613134671723;14705.691219475533;15176.468823591122;15325.386984520717;15357.15535645528;15560.533184004498;8547.487150770974;8652.524111173945;8687.348448866234;8687.695930642398;8731.388802783871;5790.594658373873;5820.4761571457075;6507.145463542867;6848.1097457330225;7060.823479413056;13140.934295328461;13175.077744558172;13177.538786142439;13384.19694818314;13907.165570065828;10754;13925.512607058705;14028.22844743549;14087;14133.222672752687;10947.014768670539;11016.669499914695;11115.72210694739;11156.274842135235;11174.776504470028;10638.989296016865;10936.343819370863;10942.905709428993;10950;10952.616658416917;7883.251091146332;9235.722928312161;9260.768480788034;9459.479728614991;9479.857802132836;8962.417442866194;9053.412050013058;9091.727248182566;9207.125323094295;9256.95371523138;8196.057453392936;8220.095789463036;8292.751217463485;8310.252077312829;8320.001599808062;6045.758169673164;6071.514278857592;6180.443760061616;6195.256569211723;6211.751529938751;6147.200863887666;6171.13604095418;6264.373562643881;6304.432601065742;6342 15:29:00 INFO - PID 1648 | |4;prototype.html;385.70108166171195;386.6442872557247;388.6521563200935;391.1472988884233;400.59990333783423;6045.455908968192;6196.101565273033;6200.503959683226;6289.49684025278;6438.549301548894;5820.912686309698;6135.754569817202;6154.676205082605;6202.131701561785;6255.843603909904;4453.821847126111;4537.0925814837;4538.478075021382;4538.909221815554;4561.361409402686;3982.6017398260233;4523.117991991563;4532.365468834366;4536.387587675671;4564.4522657281;4493.438320209973;4516.390287311204;4521.276595744682;4523.140603285382;4532.9320060199125;3939.763614183158;4116.917661646765;4520.751358675271;4536.115457485792;4549.47681016684;3851.306764782338;3893.377059670439;3905.785181814999;3907.9609203908167;3914.823832927525;6099.48154406876;6107.87784244317;6127.724252408652;6128.754849806002;6135.9693201534155;5433.935104660243;5438.211459338413;5441.782328706847;5446.509814116748;5477.808276710296;5595.776168953237;5775.422457754191;6209;6362.437035260223;6680.93319066808;4429.446319210098;4430.468343798732;4438.866833994985;4440.777961101932;4442.555744425532;5902.583379988815;6646.8005959821285;6891.104156459678;7188.317109874553;7198.892016619755;4098.508179018478;4160.209560183555;4404.801783919731;4466.307722303049;4494.820207191676;3978.4629075074495;4070.430139780433;4424.955750442518;4483.587669883977;4539.455265368111;5275.393329767049;5394.946050539523;5396.703181324986;5403.918941215885;5407.107512370639;5454.772676147835;6455.935440645628;6678.866422671519;6696.359391949004;6714.462842972551;1304.6412236635;1570.4974408189269;1575.2045217165257;1576.1331267802664;1584.8573628373501;917.9678711245074;1007.450939238117;1032.5301987595626;1041.6198087697935;1043.731865783062;6185.536084793658;6229.595076320024;6236.251649801962;6386.744530218833;6407.423331900151;6960.617166055814;6969.407600353925;6976.546524475994;6982.057422248033;6985;5366.382865970463;5444.754986025639;5610.270664813548;6283.088952102011;6377.469407342204;3635.8909232723063;3638.7634803737674;3648;3650.4889315495852;3670.871519496805;7076.256993015762;7095;7122.287771222836;7127.394171495481;7136.250693677193;6215.595986260969;6240.235593601093;6788.524803263724;7141.535800172972;7191.712331506681;6186.041163619646;6810.114685090906;7061.964690176516;7098.893516597255;7158.391536719437;5756.395578464369;6963.160603380602;6971.8605627886145;6979.651017449209;7014.754483593049;5918.644881307136;6821.89767153503;6826.965865170841;6844.965775171092;6855.897161542481;6045.521336252115;6757.425618822259;6951.409130223786;7069.71721131149;7134.467264121162;4155.792210389408;4316.870493885189;4492.056668099735;4551;4568.954310456853;5659.6890818871425;5877.647341159544;6076.574639775174;6127.816165515042;7021.262767409348;5321.255024296524;5960.284765828128;6381.361863813581;6498.935010649833;6592.846251905993;6676.566023208476;6683.899741503784;6684.0642310075655;6734.528582999339;6736.553874442844;5597.916031259453;6361.427471527585;6556.704948277435;6595.406413422815;6595.901061483985;5521.558275337883;6451.870962580816;6715.93284067153;6744.966275168593;6864.622445765531;5541.586895341662;6525.487687240352;6538.476921846146;6549.430560344719;6560.40956313934 15:29:00 INFO - PID 1648 | |5;yui.html;802.2338666573423;808.2604416958483;818.6929901287017;837.8031162676772;849.7254118821768;15229.543113706579;15515.922420387897;15541.844581554182;15588.610284742887;15595.376184952602;15093.320800563974;15745;15909.44316948907;15952;16156.83843161571;933.3979583168853;943.7734943613552;945.2815859946421;947.7867479817021;952.1478276942132;974.3666616699131;976.9413835169877;977.4770497783687;977.5014742481314;986.4377304936189;979.7109852593467;1034.1623285139062;1034.3690348887174;1035.0425856083132;1036.533559898049;1027.1679939249232;1028.511457057899;1028.5217373921125;1031.128696251666;1033.0857191385603;751.3012898004853;753.1075675324757;756.4213376766778;758.9051368578928;759.3735168486021;14658.853411465961;17311.13444327791;17581.296748130186;17645;17696;17044.744328835077;17575.648487030314;17976.550586235317;18076.54808629795;18097.36659216921;12895.548655797002;14679.926600367036;14824.481143159937;14854.2572871356;14862;3504.0539054455407;3525;3526.7531272810916;3530.5763308402898;3536.876209332687;114726;132383;135161.64838351688;135669;136587.95118073237;13507.729845403037;13919.791203131961;14006.649833754034;14231.644208894653;14235.074720143157;25050;25557.23328300154;25721.228363149134;25850.48299034009;26798.732012679622;7848.921510784933;7875.921240787633;7974.4417890748255;8064.991876015498;8072.434929554992;3389.508521264403;3673.68773654237;3819.0452386903276;4001.299772539794;4010.0776821331256;4385.495667998221;4435.1351486459835;4470.508244093147;4470.709403888802;4472.597466228055;854.6837670062117;882.8190221004679;891.1845661220041;919.4850883505259;949.0746522140857;644.7292137302344;652.6801867085094;669.9430548403343;670.2493207607511;674.8312921769558;11345.546178152781;11954.342511161969;12130.87869121315;12202.450889709984;12276.447559859827;611.6513587255222;620.2773768559578;624.9562530622812;630.9747610095636;651.4169818012947;4737.478877323491;5565.896037405402;5752.482276595043;5872.236609240771;5947.345791962881;114954;116550;116822.24766628507;147540.8852513122;148466;113643.4317828422;115793.4210328943;117400.41299793446;128487;128556;99905.50047249717;103632.48183759033;103758;105120;106220;12432.12975091735;12734.808977865154;12790.744185116431;12884.098113131991;12931.482740690268;41915.742527724215;43002.569974299855;45020;45186.322205167606;46108;61508.384916150266;61661.69169154126;62076.68961655163;62768.686156568925;63732.68133659302;15835.524934251991;16186.433474828324;16627.75058374101;16707.582310442336;16724.83275167233;18609;18639.974801386055;18737.71893421627;19014;19092.8090719091;17099.974001559945;17255;17525.386611468537;17559.824401755817;17627.20677569538;17180;17233.56916077108;17406.91296543509;17540;17682.115894205497;16386.344546218017;17169.399071032454;17198.656026879144;17202.483925482255;17244;11671.533138674698;11732.589359372381;12157.75684486323;12317.445714943029;12339.259644421005;13514.797278041035;14307.21310327942;14486;14586.00986797725;14606.70786584241 15:29:00 INFO - PID 1648 | __end_tp_report 15:29:00 INFO - PID 1648 | __start_cc_report 15:29:00 INFO - PID 1648 | _x_x_mozilla_cycle_collect,452 15:29:00 INFO - PID 1648 | __end_cc_report 15:29:00 INFO - PID 1648 | __startTimestamp1496356140300__endTimestamp 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | ------- Summary: start ------- 15:29:00 INFO - PID 1648 | Number of tests: 6 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#0] dojo.html Cycles:165 Average:6115.22 Median:6383.94 stddev:761.98 (11.9%) stddev-sans-first:722.43 15:29:00 INFO - PID 1648 | Values: 2938.7 3586.7 3612.8 3614.5 3641.0 5090.6 5173.7 6110.9 6138.9 6272.1 4879.9 6169.7 6230.6 6273.9 6292.4 5318.8 5435.5 5440.4 5449.3 5459.6 5486.1 5550.9 5559.1 5575.9 5608.2 5311.0 5406.7 5419.2 5468.2 5487.1 5433.5 5446.6 5471.2 5486.5 5497.5 5396.6 5399.9 5414.5 5428.9 5430.0 6154.0 6235.0 6308.6 6349.5 6362.8 6506.5 6575.9 6654.9 6667.9 6681.0 6757.0 6760.2 6766.8 6770.4 6813.1 5290.5 6360.2 6369.0 6383.9 6399.1 6761.7 6765.1 6854.6 6889.8 6924.4 6334.4 6439.2 6480.9 6511.6 6545.2 5645.6 6643.1 6699.0 6781.8 6783.8 5892.9 6511.0 6613.3 6627.2 6655.9 6484.1 6560.6 6560.7 6608.3 6646.6 5353.7 5365.2 5377.1 5387.9 5404.5 4040.3 4788.1 4789.5 4792.2 4793.2 5591.7 5951.8 6318.8 6351.1 6372.1 6785.1 6811.7 6819.1 6819.1 6820.9 6371.0 6377.1 6377.8 6390.7 6394.8 5829.3 5832.9 5880.2 5954.3 5961.7 5335.0 6855.7 6953.9 6964.4 6993.8 6797.4 6805.6 6811.7 6817.6 6833.6 6840.2 6956.4 6993.1 7003.3 7008.2 5533.5 6459.8 6519.8 6576.6 6690.0 6015.2 6769.9 6811.8 6824.2 6837.9 6382.1 6446.5 6499.1 6537.1 6604.2 5480.4 6531.2 6544.2 6573.7 6600.2 6327.5 6438.2 6441.5 6455.9 6456.0 6384.4 6597.0 6597.9 6607.2 6610.7 5820.8 5941.4 6389.4 6403.9 6433.9 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#1] ext.html Cycles:180 Average:44447.48 Median:328591.00 stddev:75983.99 (23.1%) stddev-sans-first:76126.37 15:29:00 INFO - PID 1648 | Values: 769.7 783.6 842.4 1047.9 1248.7 39439.0 39854.2 39893.4 39942.0 39986.4 40272.6 40533.8 40654.8 40714.0 40755.8 6583.6 7860.6 8389.7 8529.4 8530.4 8447.1 8533.7 8593.4 8593.7 8600.8 8534.5 8548.6 8582.5 8591.4 8605.4 8476.6 8526.8 8566.5 8579.7 8587.9 8205.1 8223.0 8235.9 8286.1 8289.5 37469.8 37721.6 37738.9 37750.8 37763.6 3138.7 3354.8 3428.4 3473.4 3480.3 30648.7 30912.9 30931.0 30972.4 31012.4 3008.9 3066.3 3077.1 3081.9 3082.8 232368.0 233748.0 263087.7 268820.0 269768.0 48358.0 48512.5 48527.0 48594.3 48664.0 40212.8 43758.8 49596.5 49693.8 54323.5 12375.8 14493.9 14524.5 14561.9 14719.8 11617.1 11709.9 11744.8 11815.0 11831.4 13898.6 13911.9 13924.7 13947.8 14033.8 5525.9 6066.9 6269.1 6314.5 6338.4 980.0 1020.6 1020.7 1043.8 1044.7 22338.9 24981.8 31090.8 31397.8 31675.9 1078.8 1081.3 1105.7 1107.5 1113.9 11040.7 12584.6 12622.2 12677.4 12847.3 324617.0 327648.0 328446.0 328736.0 328794.0 55816.7 56223.0 56291.9 56353.3 56419.7 1427.8 1472.3 1473.5 1479.3 1484.2 23710.5 23967.3 24319.6 24348.6 24369.3 29118.9 39137.4 39248.0 39325.4 39358.8 283218.0 283411.0 283466.0 283899.0 284550.0 34039.0 35087.5 35356.8 35463.6 35686.8 45917.8 46084.5 46126.8 46130.8 46132.5 23302.9 25900.8 28688.6 29718.0 32413.8 27411.7 29391.9 31001.0 31323.8 31676.7 37140.0 37250.3 37994.6 38061.5 38167.2 30303.7 30908.0 31680.5 31824.0 31946.2 21907.2 25069.2 25350.4 25528.6 25560.7 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#2] jquery.html Cycles:180 Average:39120.07 Median:12556.38 stddev:98423.44 (783.9%) stddev-sans-first:98656.55 15:29:00 INFO - PID 1648 | Values: 371.5 398.8 417.5 441.1 444.4 17962.6 22840.3 22853.5 23464.9 24621.8 24319.8 24616.5 24983.4 25019.2 25030.0 5447.9 6237.4 6367.6 6391.1 6395.2 6013.5 6243.8 6288.1 6300.1 6303.4 6249.6 6259.1 6280.5 6303.6 6308.9 6271.6 6284.8 6302.3 6309.5 6312.9 5575.6 5594.6 5624.7 5625.4 5626.4 28897.3 29874.4 30155.2 30166.7 30216.0 2135.5 2174.1 2175.7 2177.3 2181.3 14728.5 16329.2 18711.3 19019.6 19236.5 2161.2 2220.0 2237.4 2247.0 2255.8 153892.2 159343.2 175455.0 181188.0 188351.5 13110.0 13157.2 13486.1 13493.9 13783.2 16601.8 16758.2 16845.9 16851.7 16905.6 8362.2 8365.7 8387.2 8395.7 8533.2 7661.8 7676.7 7699.3 7702.5 7770.1 7460.3 7725.8 8298.7 8465.6 8530.3 2264.8 2267.9 2270.3 2272.4 2450.1 914.6 915.9 937.8 976.8 988.3 21321.0 21927.2 21935.1 21947.9 22013.1 922.7 985.5 990.1 999.0 1012.4 11286.6 11488.5 11531.0 11578.3 11594.5 564205.0 564949.0 573532.0 578665.0 579064.0 156517.0 161768.2 162271.0 163239.6 163634.0 1104.6 1125.6 1213.4 1229.9 1234.9 23309.0 23702.0 23740.5 23845.9 23977.0 62994.4 69890.7 70347.0 71380.0 71770.6 71461.6 95085.5 95153.6 95886.0 95897.0 10131.8 10319.1 10338.5 10363.5 10378.0 15166.9 17899.7 17955.7 18235.0 18318.5 14867.6 15078.1 15137.6 15184.2 15184.8 13401.5 13634.4 13646.7 13693.1 13704.3 15040.4 15064.0 15292.9 15293.3 15318.8 10119.4 10168.2 10184.4 10270.9 10282.7 11928.4 14830.7 14876.6 15026.4 15027.9 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#3] mootools.html Cycles:180 Average:7270.28 Median:2575.41 stddev:4624.80 (179.6%) stddev-sans-first:4615.59 15:29:00 INFO - PID 1648 | Values: 1242.9 1250.6 1393.7 1458.1 1462.0 11793.0 12100.8 12303.8 12388.4 12472.9 12131.3 12337.2 12344.5 12357.3 12358.9 2546.7 2576.2 2596.6 2596.6 2596.7 2507.8 2551.7 2552.1 2553.2 2560.2 2499.0 2517.7 2527.9 2529.9 2533.6 2141.5 2160.7 2165.8 2174.0 2178.0 2452.9 2490.9 2493.5 2555.9 2574.7 11936.2 12024.7 12117.9 12123.6 12124.8 6502.2 6673.5 6698.0 6723.1 6750.1 4093.0 4302.8 4306.4 4350.9 4355.5 1233.5 1259.0 1287.8 1289.2 1290.6 15514.6 15969.4 16829.7 16904.7 16994.0 4862.4 5541.8 5580.9 5781.8 5825.5 12562.7 12651.1 12661.4 12663.4 12699.1 6442.6 6576.1 6578.5 6588.0 6591.0 5910.9 6137.1 6195.5 6212.3 6221.3 4360.4 4425.3 4478.5 4496.2 4542.8 1288.4 1299.6 1309.7 1316.0 1321.7 760.7 765.9 792.4 792.7 800.6 6365.7 6661.0 7097.0 7261.3 7347.3 783.3 930.5 936.6 947.8 949.9 4283.4 4373.4 4388.4 4560.7 4578.5 14820.8 15209.4 15369.3 15425.6 15474.6 14705.7 15176.5 15325.4 15357.2 15560.5 8547.5 8652.5 8687.3 8687.7 8731.4 5790.6 5820.5 6507.1 6848.1 7060.8 13140.9 13175.1 13177.5 13384.2 13907.2 10754.0 13925.5 14028.2 14087.0 14133.2 10947.0 11016.7 11115.7 11156.3 11174.8 10639.0 10936.3 10942.9 10950.0 10952.6 7883.3 9235.7 9260.8 9459.5 9479.9 8962.4 9053.4 9091.7 9207.1 9257.0 8196.1 8220.1 8292.8 8310.3 8320.0 6045.8 6071.5 6180.4 6195.3 6211.8 6147.2 6171.1 6264.4 6304.4 6342.0 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#4] prototype.html Cycles:180 Average:5345.32 Median:6002.87 stddev:1686.44 (28.1%) stddev-sans-first:1649.57 15:29:00 INFO - PID 1648 | Values: 385.7 386.6 388.7 391.1 400.6 6045.5 6196.1 6200.5 6289.5 6438.5 5820.9 6135.8 6154.7 6202.1 6255.8 4453.8 4537.1 4538.5 4538.9 4561.4 3982.6 4523.1 4532.4 4536.4 4564.5 4493.4 4516.4 4521.3 4523.1 4532.9 3939.8 4116.9 4520.8 4536.1 4549.5 3851.3 3893.4 3905.8 3908.0 3914.8 6099.5 6107.9 6127.7 6128.8 6136.0 5433.9 5438.2 5441.8 5446.5 5477.8 5595.8 5775.4 6209.0 6362.4 6680.9 4429.4 4430.5 4438.9 4440.8 4442.6 5902.6 6646.8 6891.1 7188.3 7198.9 4098.5 4160.2 4404.8 4466.3 4494.8 3978.5 4070.4 4425.0 4483.6 4539.5 5275.4 5394.9 5396.7 5403.9 5407.1 5454.8 6455.9 6678.9 6696.4 6714.5 1304.6 1570.5 1575.2 1576.1 1584.9 918.0 1007.5 1032.5 1041.6 1043.7 6185.5 6229.6 6236.3 6386.7 6407.4 6960.6 6969.4 6976.5 6982.1 6985.0 5366.4 5444.8 5610.3 6283.1 6377.5 3635.9 3638.8 3648.0 3650.5 3670.9 7076.3 7095.0 7122.3 7127.4 7136.3 6215.6 6240.2 6788.5 7141.5 7191.7 6186.0 6810.1 7062.0 7098.9 7158.4 5756.4 6963.2 6971.9 6979.7 7014.8 5918.6 6821.9 6827.0 6845.0 6855.9 6045.5 6757.4 6951.4 7069.7 7134.5 4155.8 4316.9 4492.1 4551.0 4569.0 5659.7 5877.6 6076.6 6127.8 7021.3 5321.3 5960.3 6381.4 6498.9 6592.8 6676.6 6683.9 6684.1 6734.5 6736.6 5597.9 6361.4 6556.7 6595.4 6595.9 5521.6 6451.9 6715.9 6745.0 6864.6 5541.6 6525.5 6538.5 6549.4 6560.4 15:29:00 INFO - PID 1648 | 15:29:00 INFO - PID 1648 | [#5] yui.html Cycles:180 Average:24507.91 Median:17604.25 stddev:36678.12 (208.3%) stddev-sans-first:36737.83 15:29:00 INFO - PID 1648 | Values: 802.2 808.3 818.7 837.8 849.7 15229.5 15515.9 15541.8 15588.6 15595.4 15093.3 15745.0 15909.4 15952.0 16156.8 933.4 943.8 945.3 947.8 952.1 974.4 976.9 977.5 977.5 986.4 979.7 1034.2 1034.4 1035.0 1036.5 1027.2 1028.5 1028.5 1031.1 1033.1 751.3 753.1 756.4 758.9 759.4 14658.9 17311.1 17581.3 17645.0 17696.0 17044.7 17575.6 17976.6 18076.5 18097.4 12895.5 14679.9 14824.5 14854.3 14862.0 3504.1 3525.0 3526.8 3530.6 3536.9 114726.0 132383.0 135161.6 135669.0 136588.0 13507.7 13919.8 14006.6 14231.6 14235.1 25050.0 25557.2 25721.2 25850.5 26798.7 7848.9 7875.9 7974.4 8065.0 8072.4 3389.5 3673.7 3819.0 4001.3 4010.1 4385.5 4435.1 4470.5 4470.7 4472.6 854.7 882.8 891.2 919.5 949.1 644.7 652.7 669.9 670.2 674.8 11345.5 11954.3 12130.9 12202.5 12276.4 611.7 620.3 625.0 631.0 651.4 4737.5 5565.9 5752.5 5872.2 5947.3 114954.0 116550.0 116822.2 147540.9 148466.0 113643.4 115793.4 117400.4 128487.0 128556.0 99905.5 103632.5 103758.0 105120.0 106220.0 12432.1 12734.8 12790.7 12884.1 12931.5 41915.7 43002.6 45020.0 45186.3 46108.0 61508.4 61661.7 62076.7 62768.7 63732.7 15835.5 16186.4 16627.8 16707.6 16724.8 18609.0 18640.0 18737.7 19014.0 19092.8 17100.0 17255.0 17525.4 17559.8 17627.2 17180.0 17233.6 17406.9 17540.0 17682.1 16386.3 17169.4 17198.7 17202.5 17244.0 11671.5 11732.6 12157.8 12317.4 12339.3 13514.8 14307.2 14486.0 14586.0 14606.7 15:29:00 INFO - PID 1648 | -------- Summary: end -------- 15:29:00 INFO - PID 1648 | 15:29:00 INFO - TEST-INFO | 1648: exit 0 15:29:00 INFO - TEST-OK | dromaeo_css | took 1143223ms 15:29:00 INFO - TEST-START | kraken 15:29:00 INFO - operating with platform_type : mac_ 15:29:00 INFO - Initialising browser for kraken test... 15:29:00 INFO - Application command: /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpCfaarf/profile 15:29:00 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox http://localhost:49228/getInfo.html -foreground) 15:29:34 INFO - TEST-INFO | 1658: killed by SIGTERM 15:29:34 INFO - Browser initialized. 15:29:34 INFO - Running cycle 1/1 for kraken test... 15:29:34 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/tmpCfaarf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:29:36 INFO - PID 1662 | 2017-06-01 15:29:36.714 plugin-container[1663:10628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x993f, name = 'com.apple.tsm.portname' 15:29:36 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:36 INFO - PID 1662 | 2017-06-01 15:29:36.714 plugin-container[1663:10628] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4907, name = 'com.apple.CFPasteboardClient' 15:29:36 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:36 INFO - PID 1662 | 2017-06-01 15:29:36.715 plugin-container[1663:10628] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:29:37 INFO - PID 1662 | 2017-06-01 15:29:37.482 plugin-container[1664:10677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 15:29:37 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:37 INFO - PID 1662 | 2017-06-01 15:29:37.483 plugin-container[1664:10677] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4813, name = 'com.apple.CFPasteboardClient' 15:29:37 INFO - PID 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:29:37 INFO - PID 1662 | 2017-06-01 15:29:37.483 plugin-container[1664:10677] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:29:46 INFO - PID 1662 | RSS: Main: 260456448 15:29:46 INFO - PID 1662 | 15:29:49 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection) 15:29:50 INFO - PID 1662 | RSS: Main: 271028224 15:29:50 INFO - PID 1662 | 15:29:53 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-dft) 15:29:53 INFO - PID 1662 | RSS: Main: 282578944 15:29:53 INFO - PID 1662 | 15:29:57 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-fft) 15:29:57 INFO - PID 1662 | RSS: Main: 284520448 15:29:57 INFO - PID 1662 | 15:29:59 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator) 15:30:00 INFO - PID 1662 | RSS: Main: 287137792 15:30:00 INFO - PID 1662 | 15:30:02 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:30:03 INFO - PID 1662 | RSS: Main: 287334400 15:30:03 INFO - PID 1662 | 15:30:06 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom) 15:30:06 INFO - PID 1662 | RSS: Main: 295325696 15:30:06 INFO - PID 1662 | 15:30:09 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate) 15:30:10 INFO - PID 1662 | RSS: Main: 294957056 15:30:10 INFO - PID 1662 | 15:30:13 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial) 15:30:13 INFO - PID 1662 | RSS: Main: 293994496 15:30:13 INFO - PID 1662 | 15:30:15 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:30:16 INFO - PID 1662 | RSS: Main: 293441536 15:30:16 INFO - PID 1662 | 15:30:17 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:30:18 INFO - PID 1662 | RSS: Main: 293498880 15:30:18 INFO - PID 1662 | 15:30:20 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:30:21 INFO - PID 1662 | RSS: Main: 293351424 15:30:21 INFO - PID 1662 | 15:30:24 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:30:25 INFO - PID 1662 | RSS: Main: 293199872 15:30:25 INFO - PID 1662 | 15:30:28 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:30:28 INFO - PID 1662 | RSS: Main: 293486592 15:30:28 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | Cycle 1(1): loaded http://localhost:49228/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49228/tests/kraken/driver.html?testName=ai-astar) 15:30:30 INFO - PID 1662 | RSS: Main: 295145472 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | __start_tp_report 15:30:30 INFO - PID 1662 | _x_x_mozilla_page_load 15:30:30 INFO - PID 1662 | _x_x_mozilla_page_load_details 15:30:30 INFO - PID 1662 | |i|pagename|runs| 15:30:30 INFO - PID 1662 | |0;ai-astar;102;116;111;114;113;114;115;113;115;116 15:30:30 INFO - PID 1662 | |1;audio-beat-detection;132;110;108;145;115;107;129;155;160;133 15:30:30 INFO - PID 1662 | |2;audio-dft;205;262;274;222;213;207;200;200;196;197 15:30:30 INFO - PID 1662 | |3;audio-fft;58;81;86;81;59;59;58;59;75;58 15:30:30 INFO - PID 1662 | |4;audio-oscillator;79;70;111;102;103;74;88;73;72;95 15:30:30 INFO - PID 1662 | |5;imaging-gaussian-blur;90;117;126;92;90;90;110;99;137;89 15:30:30 INFO - PID 1662 | |6;imaging-darkroom;96;97;98;97;99;99;98;96;108;102 15:30:30 INFO - PID 1662 | |7;imaging-desaturate;91;90;91;91;95;90;91;93;91;91 15:30:30 INFO - PID 1662 | |8;json-parse-financial;52;51;50;53;52;51;50;54;65;73 15:30:30 INFO - PID 1662 | |9;json-stringify-tinderbox;62;56;61;53;60;59;55;56;56;55 15:30:30 INFO - PID 1662 | |10;stanford-crypto-aes;106;114;117;112;86;88;93;91;135;89 15:30:30 INFO - PID 1662 | |11;stanford-crypto-ccm;141;141;139;146;158;137;159;159;135;175 15:30:30 INFO - PID 1662 | |12;stanford-crypto-pbkdf2;186;189;192;182;196;240;187;182;200;182 15:30:30 INFO - PID 1662 | |13;stanford-crypto-sha256-iterative;69;65;69;71;64;68;65;66;69;70 15:30:30 INFO - PID 1662 | __end_tp_report 15:30:30 INFO - PID 1662 | __start_cc_report 15:30:30 INFO - PID 1662 | _x_x_mozilla_cycle_collect,1212 15:30:30 INFO - PID 1662 | __end_cc_report 15:30:30 INFO - PID 1662 | __startTimestamp1496356230884__endTimestamp 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | ------- Summary: start ------- 15:30:30 INFO - PID 1662 | Number of tests: 14 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#0] ai-astar Cycles:10 Average:112.90 Median:114.50 stddev:4.12 (3.6%) stddev-sans-first:1.62 15:30:30 INFO - PID 1662 | Values: 102.0 116.0 111.0 114.0 113.0 114.0 115.0 113.0 115.0 116.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#1] audio-beat-detection Cycles:10 Average:129.40 Median:132.50 stddev:19.43 (14.7%) stddev-sans-first:20.59 15:30:30 INFO - PID 1662 | Values: 132.0 110.0 108.0 145.0 115.0 107.0 129.0 155.0 160.0 133.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#2] audio-dft Cycles:10 Average:217.60 Median:210.00 stddev:27.84 (13.3%) stddev-sans-first:29.15 15:30:30 INFO - PID 1662 | Values: 205.0 262.0 274.0 222.0 213.0 207.0 200.0 200.0 196.0 197.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#3] audio-fft Cycles:10 Average:67.40 Median:67.00 stddev:11.79 (17.6%) stddev-sans-first:12.00 15:30:30 INFO - PID 1662 | Values: 58.0 81.0 86.0 81.0 59.0 59.0 58.0 59.0 75.0 58.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:73.50 stddev:15.14 (20.6%) stddev-sans-first:15.80 15:30:30 INFO - PID 1662 | Values: 79.0 70.0 111.0 102.0 103.0 74.0 88.0 73.0 72.0 95.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:104.00 Median:90.00 stddev:17.51 (19.5%) stddev-sans-first:17.83 15:30:30 INFO - PID 1662 | Values: 90.0 117.0 126.0 92.0 90.0 90.0 110.0 99.0 137.0 89.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:97.50 stddev:3.62 (3.7%) stddev-sans-first:3.67 15:30:30 INFO - PID 1662 | Values: 96.0 97.0 98.0 97.0 99.0 99.0 98.0 96.0 108.0 102.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:91.00 stddev:1.51 (1.7%) stddev-sans-first:1.59 15:30:30 INFO - PID 1662 | Values: 91.0 90.0 91.0 91.0 95.0 90.0 91.0 93.0 91.0 91.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:52.50 stddev:7.67 (14.6%) stddev-sans-first:8.05 15:30:30 INFO - PID 1662 | Values: 52.0 51.0 50.0 53.0 52.0 51.0 50.0 54.0 65.0 73.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:57.30 Median:57.50 stddev:2.98 (5.2%) stddev-sans-first:2.64 15:30:30 INFO - PID 1662 | Values: 62.0 56.0 61.0 53.0 60.0 59.0 55.0 56.0 56.0 55.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#10] stanford-crypto-aes Cycles:10 Average:103.10 Median:87.00 stddev:16.28 (18.7%) stddev-sans-first:17.23 15:30:30 INFO - PID 1662 | Values: 106.0 114.0 117.0 112.0 86.0 88.0 93.0 91.0 135.0 89.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:149.00 Median:152.00 stddev:13.06 (8.6%) stddev-sans-first:13.52 15:30:30 INFO - PID 1662 | Values: 141.0 141.0 139.0 146.0 158.0 137.0 159.0 159.0 135.0 175.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.60 Median:190.50 stddev:17.41 (9.1%) stddev-sans-first:18.25 15:30:30 INFO - PID 1662 | Values: 186.0 189.0 192.0 182.0 196.0 240.0 187.0 182.0 200.0 182.0 15:30:30 INFO - PID 1662 | 15:30:30 INFO - PID 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.60 Median:69.00 stddev:2.41 (3.5%) stddev-sans-first:2.51 15:30:30 INFO - PID 1662 | Values: 69.0 65.0 69.0 71.0 64.0 68.0 65.0 66.0 69.0 70.0 15:30:30 INFO - PID 1662 | -------- Summary: end -------- 15:30:30 INFO - PID 1662 | 15:30:31 INFO - TEST-INFO | 1662: exit 0 15:30:31 INFO - TEST-OK | kraken | took 90980ms 15:30:31 INFO - SUITE-END | took 1234s 15:30:32 INFO - Completed test suite (00:20:34) 15:30:32 INFO - javascript benchmark 15:30:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8057.584024608104, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2938.735513803758, 3586.6592673696005, 3612.8193590320484, 3614.493970844084, 3640.981795091024, 5090.643654944155, 5173.663711858731, 6110.87778244436, 6138.938610613893, 6272.121902933587, 4879.853604391865, 6169.691515424222, 6230.626162430257, 6273.9334313166755, 6292.433680968712, 5318.760655770499, 5435.483629055243, 5440.3743569489425, 5449.264349312852, 5459.590530710193, 5486.1222204447295, 5550.86122846928, 5559.054960656677, 5575.888482230353, 5608.214849921014, 5310.973445132789, 5406.729663516809, 5419.160030195326, 5468.152436372368, 5487.122060470306, 5433.510984011458, 5446.591505637093, 5471.151971444432, 5486.533644640211, 5497.532709719639, 5396.568274538027, 5399.946000539983, 5414.539764120054, 5428.864278393033, 5429.972850135724, 6153.98459254227, 6234.968825155891, 6308.621482711052, 6349.523785716044, 6362.809115726535, 6506.512011599101, 6575.901361479581, 6654.86690266192, 6667.899981500282, 6681.0, 6756.966215168942, 6760.1549806274215, 6766.830829229309, 6770.356816102462, 6813.148356455443, 5290.497402746733, 6360.236771587347, 6369.0, 6383.936160638381, 6399.072134540512, 6761.729530818712, 6765.05289259504, 6854.6229957352825, 6889.827754306182, 6924.4460443165435, 6334.398232167937, 6439.22729272481, 6480.935190648128, 6511.576747511396, 6545.21457425112, 5645.604807663506, 6643.136392268974, 6698.966505167443, 6781.830454238683, 6783.762568310085, 5892.911606325909, 6510.990796426467, 6613.272540020594, 6627.1716035495565, 6655.900161497581, 6484.092227088211, 6560.573562718408, 6560.737570497126, 6608.306127856602, 6646.60120392778, 5353.7055461949185, 5365.168398898177, 5377.139657654835, 5387.892242155135, 5404.540614047772, 4040.3131467649982, 4788.090262850047, 4789.473157952622, 4792.2332426811545, 4793.18515852306, 5591.692456914909, 5951.821445356646, 6318.810435686937, 6351.079093531504, 6372.139761132188, 6785.084013658192, 6811.693473793691, 6819.147606549182, 6819.147606549182, 6820.93179068203, 6371.012493063583, 6377.1390862233, 6377.808665740035, 6390.680465976682, 6394.840128996812, 5829.329627092939, 5832.912506312408, 5880.235569375954, 5954.315253745788, 5961.731722072517, 5335.0, 6855.6572171391235, 6953.860922781617, 6964.373206411447, 6993.755218567326, 6797.388235058868, 6805.557638753465, 6811.65941702903, 6817.625030623363, 6833.58998460094, 6840.179178498612, 6956.443484521125, 6993.0908981832035, 7003.26465721092, 7008.194057683431, 5533.474319939599, 6459.773907913201, 6519.8044058678315, 6576.605403675795, 6689.966550167218, 6015.248093988252, 6769.9168133098465, 6811.829704257433, 6824.249332573314, 6837.931620683729, 6382.106505089198, 6446.548741588232, 6499.057636642755, 6537.1174891389055, 6604.174478190226, 5480.369757477941, 6531.183602049744, 6544.181977252843, 6573.737050518117, 6600.207975043026, 6327.493800495858, 6438.163038804925, 6441.516886233645, 6455.870882582416, 6456.0, 6384.3934826191435, 6597.0434287029075, 6597.934020659732, 6607.2071351438135, 6610.70251838678, 5820.806734619308, 5941.405859414024, 6389.424951754364, 6403.935960640334, 6433.903491447726], "value": 6065.564409014601, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [769.6921231507397, 783.6395258181236, 842.4187310755578, 1047.9266451348399, 1248.6566194296572, 39439.0, 39854.20291594166, 39893.401598976, 39942.0, 39986.40020399693, 40272.59727402725, 40533.783986480375, 40654.780356589275, 40714.0, 40755.79622101893, 6583.60498370097, 7860.567668778201, 8389.748307550783, 8529.360297977646, 8530.402871798977, 8447.113053129426, 8533.74398768038, 8593.355498337618, 8593.656253749843, 8600.827983440327, 8534.487930724146, 8548.572571371407, 8582.527960962145, 8591.441556298852, 8605.39762216645, 8476.618552165168, 8526.829463410759, 8566.486010839308, 8579.699710510102, 8587.871181932276, 8205.056418511887, 8223.0, 8235.876461853077, 8286.129956354556, 8289.461185023014, 37469.812650936845, 37721.62278377209, 37738.86783396502, 37750.81124594387, 37763.62236377629, 3138.7018233267804, 3354.765166438326, 3428.434308339121, 3473.3747925373423, 3480.269143479847, 30648.69351306503, 30912.918047868217, 30931.0, 30972.38055238905, 31012.379752404824, 3008.9097327080003, 3066.340736741589, 3077.138401247654, 3081.8921337753295, 3082.7533797296164, 232368.0, 233748.0, 263087.68456157786, 268820.0, 269768.0, 48358.0, 48512.514874851506, 48527.0, 48594.27108593374, 48664.026719465415, 40212.798936005136, 43758.781206093765, 49596.50403495919, 49693.757656058166, 54323.45676543263, 12375.814362784566, 14493.85506144925, 14524.49164279245, 14561.854381456262, 14719.779203311959, 11617.128715346384, 11709.88290117105, 11744.823827642593, 11815.0, 11831.408429578487, 13898.58304250874, 13911.930440347733, 13924.721505569833, 13947.790783138262, 14033.78949315761, 5525.861853453615, 6066.878662426727, 6269.122322874801, 6314.463270622048, 6338.397852204033, 979.9706008819746, 1020.5866624017285, 1020.7346090016649, 1043.780806030742, 1044.7492601775518, 22338.88305584714, 24981.75018249794, 31090.844545777578, 31397.8430107848, 31675.891343802858, 1078.8489611454402, 1081.2971568480393, 1105.6848798092506, 1107.4850194659523, 1113.8719047309662, 11040.668779936615, 12584.622461326175, 12622.179558328678, 12677.366131693378, 12847.293398862965, 324617.0, 327648.0, 328446.0, 328736.0, 328794.0, 55816.720916395156, 56223.0, 56291.87416251734, 56353.30940071804, 56419.71790141023, 1427.7786943023848, 1472.3153733513968, 1473.5432016075051, 1479.2899408284084, 1484.1540322016347, 23710.525789484458, 23967.28098157058, 24319.63520547158, 24348.63477047881, 24369.26892193237, 29118.854405727834, 39137.41293880537, 39248.0, 39325.41011884767, 39358.803205983786, 283218.0, 283411.0, 283466.0, 283899.0, 284550.0, 34039.0, 35087.47368789419, 35356.823215883756, 35463.64536354707, 35686.821565892, 45917.77041114773, 46084.53915460803, 46126.769366152956, 46130.846728832046, 46132.53867461282, 23302.883485582464, 25900.834462449613, 28688.569671455363, 29717.959871404397, 32413.837930810194, 27411.725882740917, 29391.85304073466, 31001.0, 31323.84338078295, 31676.732930682516, 37140.0, 37250.25499490049, 37994.62005379911, 38061.47754089806, 38167.2366552673, 30303.69696303009, 30908.0, 31680.5247921286, 31824.0, 31946.201344965633, 21907.194593433072, 25069.247922562354, 25350.366240844127, 25528.61707074358, 25560.744392555836], "value": 17588.474769939487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [371.5077522282976, 398.76472881000205, 417.50734133722193, 441.1023567041072, 444.4311281559604, 17962.640747185065, 22840.314790556287, 22853.54292914143, 23464.88267558664, 24621.75378246217, 24319.75680243197, 24616.507669846593, 24983.375415614573, 25019.249422517307, 25029.998800047975, 5447.891042179154, 6237.37626237376, 6367.554271201017, 6391.137196478462, 6395.232572091356, 6013.458788709016, 6243.781467648633, 6288.088227207052, 6300.086487459339, 6303.401176888188, 6249.593776404542, 6259.06114082889, 6280.4975601951965, 6303.590266632677, 6308.905366419507, 6271.62370257786, 6284.811455656315, 6302.338254483305, 6309.526785491061, 6312.905306420407, 5575.637583557096, 5594.608377413582, 5624.718764061821, 5625.353084395306, 5626.437356264381, 28897.277568060756, 29874.40251194986, 30155.246118846986, 30166.698333016608, 30216.0, 2135.4661334666334, 2174.08688350893, 2175.695402643631, 2177.3467959612212, 2181.345596321097, 14728.48450304245, 16329.18354082291, 18711.34510292147, 19019.61960760791, 19236.519087022938, 2161.167950339115, 2219.9778002220096, 2237.44063984004, 2247.0, 2255.785700358463, 153892.23053884658, 159343.20328398285, 175455.0, 181188.0, 188351.52418141352, 13110.0, 13157.210567365988, 13486.12340197884, 13493.932530337286, 13783.173009619455, 16601.750973735405, 16758.162091895356, 16845.915770421314, 16851.74722379165, 16905.577360566083, 8362.247397734232, 8365.749027529062, 8387.245147936716, 8395.748127556182, 8533.232009119085, 7661.808454788675, 7676.731314403969, 7699.345555627722, 7702.537847729154, 7770.067591889009, 7460.29127232912, 7725.806854828675, 8298.668053277801, 8465.576721163918, 8530.274926631182, 2264.8074913632195, 2267.8525895817047, 2270.273512475992, 2272.4432514034165, 2450.130203777669, 914.6158613382395, 915.9221466175317, 937.7702462896634, 976.7595154154185, 988.3476905242505, 21321.040553175146, 21927.2325468611, 21935.122595096018, 21947.890260548913, 22013.11947522113, 922.7047344849584, 985.4579981010415, 990.0792263195153, 998.9650362237287, 1012.3976234140674, 11286.604968826052, 11488.483018264198, 11531.0, 11578.305301681927, 11594.536218551331, 564205.0, 564949.0, 573532.0, 578665.0, 579064.0, 156517.0, 161768.19115904346, 162271.0, 163239.551406729, 163634.0, 1104.6078642081945, 1125.5891599566264, 1213.4054313386553, 1229.8708635593314, 1234.938253087342, 23309.0, 23702.0, 23740.52518949646, 23845.880770596035, 23977.0, 62994.370056300686, 69890.65054674694, 70347.0, 71380.0, 71770.64114679393, 71461.57076858537, 95085.5245723767, 95153.61833248593, 95886.0, 95897.0, 10131.848022279819, 10319.122874555747, 10338.483075846329, 10363.533640986325, 10378.0, 15166.938314317891, 17899.73150402771, 17955.730664039787, 18235.0, 18318.450446486626, 14867.628309291922, 15078.095314281178, 15137.621559461102, 15184.164870932205, 15184.772228416361, 13401.53094641683, 13634.386452609855, 13646.727065458834, 13693.109947853358, 13704.314784260947, 15040.398384064514, 15064.0, 15292.923535382697, 15293.311800969206, 15318.770218446509, 10119.392836429837, 10168.220561401873, 10184.388936663823, 10270.948645256725, 10282.691519254435, 11928.403579821148, 14830.703385932437, 14876.553703388916, 15026.398944042116, 15027.924860376066], "value": 11265.843975044045, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1242.869498702636, 1250.6060590913867, 1393.6655202751335, 1458.1032664911077, 1461.9620069750476, 11792.997595204408, 12100.757984840298, 12303.815442768366, 12388.442520086595, 12472.937635311833, 12131.272123672587, 12337.198082124656, 12344.5062197512, 12357.32034738089, 12358.949489293398, 2546.681664791906, 2576.1627471071884, 2596.610508423733, 2596.623489594008, 2596.675415573053, 2507.786838118753, 2551.655526503926, 2552.106762633073, 2553.182981445929, 2560.193539035198, 2498.9875050624723, 2517.672702548666, 2527.911523096692, 2529.936751581207, 2533.6199570064455, 2141.4753385420518, 2160.7191065161505, 2165.761766205716, 2173.9782602174014, 2177.9891100544555, 2452.901883924641, 2490.86300253486, 2493.4763699622918, 2555.9105431309813, 2574.652421923035, 11936.224145430519, 12024.69938251542, 12117.939410302892, 12123.636290911287, 12124.757504849942, 6502.154719886385, 6673.499487538454, 6698.0, 6723.092382528345, 6750.054992301082, 4092.9795351023354, 4302.806373713159, 4306.397104405386, 4350.912981740379, 4355.5208927018, 1233.463443402114, 1258.974820503594, 1287.781077216866, 1289.155603079984, 1290.6257185416218, 15514.61213469661, 15969.441069562627, 16829.747553786703, 16904.746428803577, 16994.0, 4862.367892174066, 5541.806036788774, 5580.86047848807, 5781.82654520365, 5825.533957283407, 12562.748745025048, 12651.114421990373, 12661.404412095499, 12663.430145643284, 12699.11106222574, 6442.61344319333, 6576.0793488911595, 6578.539502234893, 6588.044733513614, 6590.9670451647435, 5910.852228694317, 6137.110119032715, 6195.504359651217, 6212.285587157535, 6221.2845522765465, 4360.389545463638, 4425.269830478, 4478.507364189936, 4496.235639941153, 4542.841000564964, 1288.3815768431205, 1299.5776372678918, 1309.7053163038431, 1315.9736805264033, 1321.7422602592403, 760.6957217113088, 765.9425543084291, 792.3819420851745, 792.7027364738224, 800.627708115729, 6365.681715914186, 6660.966695166493, 7097.041899343626, 7261.273872612696, 7347.302006309392, 783.2911215350117, 930.4835816122118, 936.5832204668857, 947.8151760406793, 949.9097585729345, 4283.357496375568, 4373.365861949999, 4388.4201687392515, 4560.72635641856, 4578.519255478193, 14820.777688334683, 15209.391624335123, 15369.308381122877, 15425.614359640875, 15474.613134671723, 14705.691219475533, 15176.468823591122, 15325.386984520717, 15357.15535645528, 15560.533184004498, 8547.487150770974, 8652.524111173945, 8687.348448866234, 8687.695930642398, 8731.388802783871, 5790.594658373873, 5820.4761571457075, 6507.145463542867, 6848.1097457330225, 7060.823479413056, 13140.934295328461, 13175.077744558172, 13177.538786142439, 13384.19694818314, 13907.165570065828, 10754.0, 13925.512607058705, 14028.22844743549, 14087.0, 14133.222672752687, 10947.014768670539, 11016.669499914695, 11115.72210694739, 11156.274842135235, 11174.776504470028, 10638.989296016865, 10936.343819370863, 10942.905709428993, 10950.0, 10952.616658416917, 7883.251091146332, 9235.722928312161, 9260.768480788034, 9459.479728614991, 9479.857802132836, 8962.417442866194, 9053.412050013058, 9091.727248182566, 9207.125323094295, 9256.95371523138, 8196.057453392936, 8220.095789463036, 8292.751217463485, 8310.252077312829, 8320.001599808062, 6045.758169673164, 6071.514278857592, 6180.443760061616, 6195.256569211723, 6211.751529938751, 6147.200863887666, 6171.13604095418, 6264.373562643881, 6304.432601065742, 6342.0], "value": 5461.207677410892, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.70108166171195, 386.6442872557247, 388.6521563200935, 391.1472988884233, 400.59990333783423, 6045.455908968192, 6196.101565273033, 6200.503959683226, 6289.49684025278, 6438.549301548894, 5820.912686309698, 6135.754569817202, 6154.676205082605, 6202.131701561785, 6255.843603909904, 4453.821847126111, 4537.0925814837, 4538.478075021382, 4538.909221815554, 4561.361409402686, 3982.6017398260233, 4523.117991991563, 4532.365468834366, 4536.387587675671, 4564.4522657281, 4493.438320209973, 4516.390287311204, 4521.276595744682, 4523.140603285382, 4532.9320060199125, 3939.763614183158, 4116.917661646765, 4520.751358675271, 4536.115457485792, 4549.47681016684, 3851.306764782338, 3893.377059670439, 3905.785181814999, 3907.9609203908167, 3914.823832927525, 6099.48154406876, 6107.87784244317, 6127.724252408652, 6128.754849806002, 6135.9693201534155, 5433.935104660243, 5438.211459338413, 5441.782328706847, 5446.509814116748, 5477.808276710296, 5595.776168953237, 5775.422457754191, 6209.0, 6362.437035260223, 6680.93319066808, 4429.446319210098, 4430.468343798732, 4438.866833994985, 4440.777961101932, 4442.555744425532, 5902.583379988815, 6646.8005959821285, 6891.104156459678, 7188.317109874553, 7198.892016619755, 4098.508179018478, 4160.209560183555, 4404.801783919731, 4466.307722303049, 4494.820207191676, 3978.4629075074495, 4070.430139780433, 4424.955750442518, 4483.587669883977, 4539.455265368111, 5275.393329767049, 5394.946050539523, 5396.703181324986, 5403.918941215885, 5407.107512370639, 5454.772676147835, 6455.935440645628, 6678.866422671519, 6696.359391949004, 6714.462842972551, 1304.6412236635, 1570.4974408189269, 1575.2045217165257, 1576.1331267802664, 1584.8573628373501, 917.9678711245074, 1007.450939238117, 1032.5301987595626, 1041.6198087697935, 1043.731865783062, 6185.536084793658, 6229.595076320024, 6236.251649801962, 6386.744530218833, 6407.423331900151, 6960.617166055814, 6969.407600353925, 6976.546524475994, 6982.057422248033, 6985.0, 5366.382865970463, 5444.754986025639, 5610.270664813548, 6283.088952102011, 6377.469407342204, 3635.8909232723063, 3638.7634803737674, 3648.0, 3650.4889315495852, 3670.871519496805, 7076.256993015762, 7095.0, 7122.287771222836, 7127.394171495481, 7136.250693677193, 6215.595986260969, 6240.235593601093, 6788.524803263724, 7141.535800172972, 7191.712331506681, 6186.041163619646, 6810.114685090906, 7061.964690176516, 7098.893516597255, 7158.391536719437, 5756.395578464369, 6963.160603380602, 6971.8605627886145, 6979.651017449209, 7014.754483593049, 5918.644881307136, 6821.89767153503, 6826.965865170841, 6844.965775171092, 6855.897161542481, 6045.521336252115, 6757.425618822259, 6951.409130223786, 7069.71721131149, 7134.467264121162, 4155.792210389408, 4316.870493885189, 4492.056668099735, 4551.0, 4568.954310456853, 5659.6890818871425, 5877.647341159544, 6076.574639775174, 6127.816165515042, 7021.262767409348, 5321.255024296524, 5960.284765828128, 6381.361863813581, 6498.935010649833, 6592.846251905993, 6676.566023208476, 6683.899741503784, 6684.0642310075655, 6734.528582999339, 6736.553874442844, 5597.916031259453, 6361.427471527585, 6556.704948277435, 6595.406413422815, 6595.901061483985, 5521.558275337883, 6451.870962580816, 6715.93284067153, 6744.966275168593, 6864.622445765531, 5541.586895341662, 6525.487687240352, 6538.476921846146, 6549.430560344719, 6560.40956313934], "value": 4813.824266189636, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [802.2338666573423, 808.2604416958483, 818.6929901287017, 837.8031162676772, 849.7254118821768, 15229.543113706579, 15515.922420387897, 15541.844581554182, 15588.610284742887, 15595.376184952602, 15093.320800563974, 15745.0, 15909.44316948907, 15952.0, 16156.83843161571, 933.3979583168853, 943.7734943613552, 945.2815859946421, 947.7867479817021, 952.1478276942132, 974.3666616699131, 976.9413835169877, 977.4770497783687, 977.5014742481314, 986.4377304936189, 979.7109852593467, 1034.1623285139062, 1034.3690348887174, 1035.0425856083132, 1036.533559898049, 1027.1679939249232, 1028.511457057899, 1028.5217373921125, 1031.128696251666, 1033.0857191385603, 751.3012898004853, 753.1075675324757, 756.4213376766778, 758.9051368578928, 759.3735168486021, 14658.853411465961, 17311.13444327791, 17581.296748130186, 17645.0, 17696.0, 17044.744328835077, 17575.648487030314, 17976.550586235317, 18076.54808629795, 18097.36659216921, 12895.548655797002, 14679.926600367036, 14824.481143159937, 14854.2572871356, 14862.0, 3504.0539054455407, 3525.0, 3526.7531272810916, 3530.5763308402898, 3536.876209332687, 114726.0, 132383.0, 135161.64838351688, 135669.0, 136587.95118073237, 13507.729845403037, 13919.791203131961, 14006.649833754034, 14231.644208894653, 14235.074720143157, 25050.0, 25557.23328300154, 25721.228363149134, 25850.48299034009, 26798.732012679622, 7848.921510784933, 7875.921240787633, 7974.4417890748255, 8064.991876015498, 8072.434929554992, 3389.508521264403, 3673.68773654237, 3819.0452386903276, 4001.299772539794, 4010.0776821331256, 4385.495667998221, 4435.1351486459835, 4470.508244093147, 4470.709403888802, 4472.597466228055, 854.6837670062117, 882.8190221004679, 891.1845661220041, 919.4850883505259, 949.0746522140857, 644.7292137302344, 652.6801867085094, 669.9430548403343, 670.2493207607511, 674.8312921769558, 11345.546178152781, 11954.342511161969, 12130.87869121315, 12202.450889709984, 12276.447559859827, 611.6513587255222, 620.2773768559578, 624.9562530622812, 630.9747610095636, 651.4169818012947, 4737.478877323491, 5565.896037405402, 5752.482276595043, 5872.236609240771, 5947.345791962881, 114954.0, 116550.0, 116822.24766628507, 147540.8852513122, 148466.0, 113643.4317828422, 115793.4210328943, 117400.41299793446, 128487.0, 128556.0, 99905.50047249717, 103632.48183759033, 103758.0, 105120.0, 106220.0, 12432.12975091735, 12734.808977865154, 12790.744185116431, 12884.098113131991, 12931.482740690268, 41915.742527724215, 43002.569974299855, 45020.0, 45186.322205167606, 46108.0, 61508.384916150266, 61661.69169154126, 62076.68961655163, 62768.686156568925, 63732.68133659302, 15835.524934251991, 16186.433474828324, 16627.75058374101, 16707.582310442336, 16724.83275167233, 18609.0, 18639.974801386055, 18737.71893421627, 19014.0, 19092.8090719091, 17099.974001559945, 17255.0, 17525.386611468537, 17559.824401755817, 17627.20677569538, 17180.0, 17233.56916077108, 17406.91296543509, 17540.0, 17682.115894205497, 16386.344546218017, 17169.399071032454, 17198.656026879144, 17202.483925482255, 17244.0, 11671.533138674698, 11732.589359372381, 12157.75684486323, 12317.445714943029, 12339.259644421005, 13514.797278041035, 14307.21310327942, 14486.0, 14586.00986797725, 14606.70786584241], "value": 8660.753450908884, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1534.0999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 116.0, 111.0, 114.0, 113.0, 114.0, 115.0, 113.0, 115.0, 116.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 110.0, 108.0, 145.0, 115.0, 107.0, 129.0, 155.0, 160.0, 133.0], "value": 129.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [205.0, 262.0, 274.0, 222.0, 213.0, 207.0, 200.0, 200.0, 196.0, 197.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 81.0, 86.0, 81.0, 59.0, 59.0, 58.0, 59.0, 75.0, 58.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 70.0, 111.0, 102.0, 103.0, 74.0, 88.0, 73.0, 72.0, 95.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 117.0, 126.0, 92.0, 90.0, 90.0, 110.0, 99.0, 137.0, 89.0], "value": 104.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 98.0, 97.0, 99.0, 99.0, 98.0, 96.0, 108.0, 102.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 90.0, 91.0, 91.0, 95.0, 90.0, 91.0, 93.0, 91.0, 91.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 51.0, 50.0, 53.0, 52.0, 51.0, 50.0, 54.0, 65.0, 73.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 56.0, 61.0, 53.0, 60.0, 59.0, 55.0, 56.0, 56.0, 55.0], "value": 57.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 114.0, 117.0, 112.0, 86.0, 88.0, 93.0, 91.0, 135.0, 89.0], "value": 103.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 141.0, 139.0, 146.0, 158.0, 137.0, 159.0, 159.0, 135.0, 175.0], "value": 149.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 189.0, 192.0, 182.0, 196.0, 240.0, 187.0, 182.0, 200.0, 182.0], "value": 193.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 65.0, 69.0, 71.0, 64.0, 68.0, 65.0, 66.0, 69.0, 70.0], "value": 67.6, "unit": "score"}]}]} 15:30:32 INFO - Return code: 0 15:30:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:30:32 INFO - # TBPL SUCCESS # 15:30:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:30:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:30:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:30:32 INFO - 2017-06-01 15:30:32.304 screenresolution[1671:11067] starting screenresolution argv=screenresolution get 15:30:32 INFO - 2017-06-01 15:30:32.307 screenresolution[1671:11067] Display 0: 1600x1200x32@60 15:30:32 INFO - 2017-06-01 15:30:32.314 screenresolution[1672:11070] starting screenresolution argv=screenresolution list 15:30:32 INFO - Available Modes on Display 0 15:30:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:30:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:30:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:30:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:30:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:30:32 INFO - Intel Iris: 15:30:32 INFO - Chipset Model: Intel Iris 15:30:32 INFO - Type: GPU 15:30:32 INFO - Bus: Built-In 15:30:32 INFO - VRAM (Dynamic, Max): 1536 MB 15:30:32 INFO - Vendor: Intel (0x8086) 15:30:32 INFO - Device ID: 0x0a2e 15:30:32 INFO - Revision ID: 0x0009 15:30:32 INFO - Displays: 15:30:32 INFO - Display: 15:30:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:30:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:30:32 INFO - Display Serial Number: 1600x1200 60 15:30:32 INFO - Main Display: Yes 15:30:32 INFO - Mirror: Off 15:30:32 INFO - Online: Yes 15:30:32 INFO - Rotation: Supported 15:30:32 INFO - Return code: 0 15:30:32 INFO - Running post-action listener: _resource_record_post_action 15:30:32 INFO - [mozharness: 2017-06-01 22:30:32.559535Z] Finished run-tests step (success) 15:30:32 INFO - Running post-run listener: _resource_record_post_run 15:30:32 INFO - Total resource usage - Wall time: 1256s; CPU: 53.0%; Read bytes: 40201216; Write bytes: 568763392; Read time: 261; Write time: 1719 15:30:32 INFO - TinderboxPrint: CPU usage
53.3% 15:30:32 INFO - TinderboxPrint: I/O read bytes / time
40,201,216 / 261 15:30:32 INFO - TinderboxPrint: I/O write bytes / time
568,763,392 / 1,719 15:30:32 INFO - TinderboxPrint: CPU idle
2,348.3 (46.7%) 15:30:32 INFO - TinderboxPrint: CPU system
1,018.2 (20.3%) 15:30:32 INFO - TinderboxPrint: CPU user
1,659.1 (33.0%) 15:30:32 INFO - TinderboxPrint: Swap in / out
286,789,632 / 0 15:30:32 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 228497920; Write bytes: 224008192; Read time: 16713; Write time: 444 15:30:32 INFO - setup-mitmproxy - Wall time: 0s; CPU: Can't collect data; Read bytes: 0; Write bytes: 0; Read time: 0; Write time: 0 15:30:32 INFO - run-tests - Wall time: 1235s; CPU: 53.0%; Read bytes: 39218176; Write bytes: 318520320; Read time: 247; Write time: 1209 15:30:33 INFO - Running post-run listener: _upload_blobber_files 15:30:33 INFO - Blob upload gear active. 15:30:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:30:33 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 15:30:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:30:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:30:33 INFO - (blobuploader) - INFO - Open directory for files ... 15:30:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:30:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:30:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:34 INFO - (blobuploader) - INFO - Done attempting. 15:30:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:30:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:30:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:35 INFO - (blobuploader) - INFO - Done attempting. 15:30:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:30:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:36 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:30:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:36 INFO - (blobuploader) - INFO - Done attempting. 15:30:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:30:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:30:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:30:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:30:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:30:38 INFO - (blobuploader) - INFO - Done attempting. 15:30:38 INFO - (blobuploader) - INFO - Iteration through files over. 15:30:38 INFO - Return code: 0 15:30:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:30:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:30:38 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70573c511bcd4e749b20147130b4743975ab507298b172a915713c9db7dce0c64dff665658b8481489160759ce3c0115c5157edfdf824129a0d8f22cd4d279b2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10a9cdee74b55881c2b7447c74f4fa6e1370ade84de1afc3b0df0ef45ce2c46b55bb38722437c8f58dfbeb9135702ddecbc537ff219b07589e7c39516ae891f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5175e02dcbbc9b9f04ab63dc59f7bf9079fb0a26f8ae7c9d5e0b8ef4f71dcddd5fc4e0ce82d3ad8c8f3a051a84c6f1b64d9f06bd3f3e7232468aaa63428e56a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8494b1a78b9c1db5a4f71bda0c34fa2adcf6e07e4269c8555a708e10633f01240354263073033c8560c9ffb9ef4c875455d3aaaa270c30e97de9ea6dc96ba1"} 15:30:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:30:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:30:38 INFO - Contents: 15:30:38 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70573c511bcd4e749b20147130b4743975ab507298b172a915713c9db7dce0c64dff665658b8481489160759ce3c0115c5157edfdf824129a0d8f22cd4d279b2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10a9cdee74b55881c2b7447c74f4fa6e1370ade84de1afc3b0df0ef45ce2c46b55bb38722437c8f58dfbeb9135702ddecbc537ff219b07589e7c39516ae891f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5175e02dcbbc9b9f04ab63dc59f7bf9079fb0a26f8ae7c9d5e0b8ef4f71dcddd5fc4e0ce82d3ad8c8f3a051a84c6f1b64d9f06bd3f3e7232468aaa63428e56a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8494b1a78b9c1db5a4f71bda0c34fa2adcf6e07e4269c8555a708e10633f01240354263073033c8560c9ffb9ef4c875455d3aaaa270c30e97de9ea6dc96ba1"} 15:30:38 INFO - Running post-run listener: copy_logs_to_upload_dir 15:30:38 INFO - Copying logs to upload dir... 15:30:38 INFO - mkdir: /builds/slave/test/build/upload/logs 15:30:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1338.666188 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 22 mins, 18 secs) (at 2017-06-01 15:30:38.468720) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 15:30:38.472690) ========= 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.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70573c511bcd4e749b20147130b4743975ab507298b172a915713c9db7dce0c64dff665658b8481489160759ce3c0115c5157edfdf824129a0d8f22cd4d279b2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10a9cdee74b55881c2b7447c74f4fa6e1370ade84de1afc3b0df0ef45ce2c46b55bb38722437c8f58dfbeb9135702ddecbc537ff219b07589e7c39516ae891f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5175e02dcbbc9b9f04ab63dc59f7bf9079fb0a26f8ae7c9d5e0b8ef4f71dcddd5fc4e0ce82d3ad8c8f3a051a84c6f1b64d9f06bd3f3e7232468aaa63428e56a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8494b1a78b9c1db5a4f71bda0c34fa2adcf6e07e4269c8555a708e10633f01240354263073033c8560c9ffb9ef4c875455d3aaaa270c30e97de9ea6dc96ba1"} build_url:https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011066 build_url: 'https://queue.taskcluster.net/v1/task/JRolW9joQC619GsvPdReow/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/70573c511bcd4e749b20147130b4743975ab507298b172a915713c9db7dce0c64dff665658b8481489160759ce3c0115c5157edfdf824129a0d8f22cd4d279b2", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f10a9cdee74b55881c2b7447c74f4fa6e1370ade84de1afc3b0df0ef45ce2c46b55bb38722437c8f58dfbeb9135702ddecbc537ff219b07589e7c39516ae891f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/5175e02dcbbc9b9f04ab63dc59f7bf9079fb0a26f8ae7c9d5e0b8ef4f71dcddd5fc4e0ce82d3ad8c8f3a051a84c6f1b64d9f06bd3f3e7232468aaa63428e56a0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4e8494b1a78b9c1db5a4f71bda0c34fa2adcf6e07e4269c8555a708e10633f01240354263073033c8560c9ffb9ef4c875455d3aaaa270c30e97de9ea6dc96ba1"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-06-01 15:30:38.507127) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:30:38.507526) ========= 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.CgMwagR147/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.jkPAjrZr0k/Listeners TMPDIR=/var/folders/2b/6mwmwf0s0bq77rvdxxl4xn9h00000w/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.005249 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-06-01 15:30:38.556548) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 15:30:38.556894) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-06-01 15:30:38.557363) ========= ========= Total master_lag: 0.17 =========